2025-12-05T13:15:48.317554Z node 16 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[16:7580361933579235170:2261];send_to=[0:7307199536658146131:7762515];
2025-12-05T13:15:48.317656Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2025-12-05T13:15:48.436751Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/kvke/0019ff/r3tmp/tmpQLbPZj/pdisk_1.dat
2025-12-05T13:15:48.468550Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-05T13:15:48.536126Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-05T13:15:48.537706Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-05T13:15:48.537890Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=incorrect path status: LookupError;
2025-12-05T13:15:48.648559Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-05T13:15:48.779734Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-05T13:15:49.026635Z node 16 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-12-05T13:15:49.057921Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-05T13:15:49.058074Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-05T13:15:49.061838Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-05T13:15:49.061959Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-05T13:15:49.087999Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-05T13:15:49.088298Z node 16 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 17 Cookie 17
2025-12-05T13:15:49.089750Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 19611, node 16
2025-12-05T13:15:49.167911Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-05T13:15:49.199963Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/kvke/0019ff/r3tmp/yandexsUCJmD.tmp
2025-12-05T13:15:49.199993Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/kvke/0019ff/r3tmp/yandexsUCJmD.tmp
2025-12-05T13:15:49.200223Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/kvke/0019ff/r3tmp/yandexsUCJmD.tmp
2025-12-05T13:15:49.200355Z node 16 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-05T13:15:49.268319Z INFO: TTestServer started on Port 4408 GrpcPort 19611
2025-12-05T13:15:49.313053Z node 16 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-05T13:15:49.381424Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-05T13:15:49.511442Z node 17 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
TClient is connected to server localhost:4408
PQClient connected to localhost:19611
WaitRootIsUp 'Root'...
TClient::Ls request: Root
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "Root"
    PathId: 1
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 1
    CreateStep: 0
    ParentPathId: 1
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 1
      SubDomainVersion: 0
      SecurityStateVersion: 0
    }
    ChildrenExist: false
  }
  Children {
    Name: ".sys"
    PathId: 18446744073709551615
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 0
    CreateStep: 0
    ParentPathId: 18446744073709551615
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 0
      Pl...
(TRUNCATED)
WaitRootIsUp 'Root' success.
2025-12-05T13:15:49.714263Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976710657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
2025-12-05T13:15:49.777978Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
waiting...
waiting...
waiting...
2025-12-05T13:15:53.320902Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[16:7580361933579235170:2261];send_to=[0:7307199536658146131:7762515];
2025-12-05T13:15:53.321006Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2025-12-05T13:15:55.242823Z node 16 :KQP_PROXY ERROR: kqp_proxy_service.cpp:1316: TraceId: "01kbqaf3vdewxtjak37hex4as5", Request deadline has expired for 0.266307s seconds
2025-12-05T13:15:55.316461Z node 16 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:565: Compilation failed, self: [16:7580361963644007160:2329], 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
2025-12-05T13:15:55.321113Z node 16 :KQP_SESSION WARN: kqp_session_actor.cpp:2417: SessionId: ydb://session/3?node_id=16&id=MmI5ZWY5NDctZWYwNzJhYTgtZDlmZGE0NTYtNjcyNzgzM2I=, ActorId: [16:7580361963644007157:2327], ActorState: ExecuteState, TraceId: 01kbqaf8zga458gpch2krxdb8y, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-12-05T13:15:55.321774Z node 16 :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 { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-12-05T13:15:55.403275Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710662:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-05T13:15:55.515601Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-05T13:15:55.714574Z node 17 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:565: Compilation failed, self: [17:7580361966196584686:2301], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:5:17: Error: At function: KiReadTable!
        <main>:5:17: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Versions]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-12-05T13:15:55.715235Z node 17 :KQP_SESSION WARN: kqp_session_actor.cpp:2417: SessionId: ydb://session/3?node_id=17&id=ZTgwZGQ1YmQtYTc3OGY0ODgtNjQwZTYxMTktYzExYWFhNDE=, ActorId: [17:7580361966196584681:2299], ActorState: ExecuteState, TraceId: 01kbqaf9akcwdxe5bz6xrxnf11, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-12-05T13:15:55.715847Z node 17 :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: 5 column: 17 } message: "At function: KiReadTable!" end_position { row: 5 column: 17 } severity: 1 issues { position { row: 5 column: 17 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Versions]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 5 column: 17 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-12-05T13:15:55.879361Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
=== 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);
2025-12-05T13:15:56.712545Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976710665. Ctx: { TraceId: 01kbqaf9t4ddmxs74s44qnr3ap, Database: , SessionId: ydb://session/3?node_id=16&id=OGQwNGE1MWMtOWM3YTQyZmUtOWE1MmNmNWMtNTczYTQwY2E=, PoolId: , DatabaseId: /Root}. Database not set, use /Root
=== CheckClustersList. Subcribe to ClusterTracker from [16:7580361967938974921:3063] 
=== CheckClustersList. Ok
PQ Client: create topic: rt3.dc1--topic1 with 3 partitions
CallPersQueueGRPC request to localhost:19611
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-05T13:16:02.456833Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
CallPersQueueGRPC request to localhost:19611
MetaRequest {
  CmdCreateTopic {
    Topic: "rt3.dc1--topic1"
    NumPartitions: 3
    Config {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
      }
      LocalDC: true
      Codecs {
        Ids: 0
        Ids: 1
        Ids: 2
        Codecs: "raw"
        Codecs: "gzip"
        Codecs: "lzop"
      }
      Consumers {
        Name: "user"
        ReadFromTimestampsMs: 0
        FormatVersion: 0
        Version: 0
      }
    }
  }
}
CallPersQueueGRPC response:
Status: 129
ProxyErrorCode: 53
SchemeStatus: 1
FlatTxId {
  TxId: 281474976710676
  SchemeShardTabletId: 72057594046644480
  PathId: 9
}
ErrorCode: OK
AddTopic: rt3.dc1--topic1
===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 = topic1, dc = dc1
2025-12-05T13:16:02.498206Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][] pipe [16:7580361993708779135:3300] connected; active server actors: 1
2025-12-05T13:16:02.498499Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1517: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions [1, 2, 0]
2025-12-05T13:16:02.500286Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:969: [72075186224037893][rt3.dc1--topic1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-12-05T13:16:02.503940Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:138: [72075186224037893][rt3.dc1--topic1] BALANCER INIT DONE for rt3.dc1--topic1: (0, 72075186224037892) (1, 72075186224037892) (2, 72075186224037892) 
2025-12-05T13:16:02.511117Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2959: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-05T13:16:02.521897Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2991: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-05T13:16:02.522263Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:785: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-05T13:16:02.522312Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-05T13:16:02.522368Z node 17 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-05T13:16:02.523133Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361993708779132:3298], now have 1 active actors on pipe
2025-12-05T13:16:02.523172Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361993708779152:2443], now have 1 active actors on pipe
2025-12-05T13:16:02.523536Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:402: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 17, Generation 1
2025-12-05T13:16:02.525042Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:02.539350Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:02.551066Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361993708779181:3338], now have 1 active actors on pipe
2025-12-05T13:16:02.553709Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:02.570062Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:02.573757Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-12-05T13:16:02.573832Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-12-05T13:16:02.573860Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-12-05T13:16:02.573913Z node 17 :PERSQUEUE DEBUG: partition.cpp:1294: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-05T13:16:02.573944Z node 17 :PERSQUEUE DEBUG: partition.cpp:1294: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-05T13:16:02.573968Z node 17 :PERSQUEUE DEBUG: partition.cpp:1294: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-05T13:16:02.574717Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-05T13:16:02.575097Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [17:7580361996261356078:2368]
2025-12-05T13:16:02.579752Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-12-05T13:16:02.579793Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-12-05T13:16:02.579824Z node 17 :PERSQUEUE INFO: partition.cpp:692: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [17:7580361996261356078:2368]
2025-12-05T13:16:02.579862Z node 17 :PERSQUEUE DEBUG: partition.cpp:712: [72075186224037892][Partition][1][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 1 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-12-05T13:16:02.582133Z node 17 :PERSQUEUE DEBUG: partition.cpp:4382: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-12-05T13:16:02.582184Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.582210Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:02.582258Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.582270Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.582287Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.582299Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.582336Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.582347Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.582359Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.582377Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.582388Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.582437Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:02.582824Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-05T13:16:02.583222Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [17:7580361996261356079:2368]
2025-12-05T13:16:02.586378Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-12-05T13:16:02.586417Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-12-05T13:16:02.586446Z node 17 :PERSQUEUE INFO: partition.cpp:692: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [17:7580361996261356079:2368]
2025-12-05T13:16:02.586481Z node 17 :PERSQUEUE DEBUG: partition.cpp:712: [72075186224037892][Partition][2][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 2 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-12-05T13:16:02.588071Z node 17 :PERSQUEUE DEBUG: partition.cpp:4382: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-12-05T13:16:02.588127Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.588152Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:02.588200Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.588211Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.588228Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.588240Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.588276Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.588289Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.588300Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.588324Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.588339Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.588389Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:02.588783Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-05T13:16:02.589132Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [17:7580361996261356080:2368]
2025-12-05T13:16:02.592294Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-05T13:16:02.592334Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-12-05T13:16:02.592365Z node 17 :PERSQUEUE INFO: partition.cpp:692: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [17:7580361996261356080:2368]
2025-12-05T13:16:02.592397Z node 17 :PERSQUEUE DEBUG: partition.cpp:712: [72075186224037892][Partition][0][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 0 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-12-05T13:16:02.597230Z node 17 :PERSQUEUE DEBUG: partition.cpp:4382: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-05T13:16:02.597288Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.597312Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:02.597360Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.597372Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.597391Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.597402Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.597445Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.597457Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.597469Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.597487Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.597498Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.597545Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:02.597916Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.598174Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.598607Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:02.598789Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.612503Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:02.612750Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940562588, TxId 281474976710676
2025-12-05T13:16:02.612780Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.612796Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.612808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.612848Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:02.612999Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:02.613021Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-05T13:16:02.613038Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.613338Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940562588, TxId 281474976710676
2025-12-05T13:16:02.613360Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.613372Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.613381Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.613400Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:02.613476Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:02.613486Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-05T13:16:02.613499Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.613627Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940562588, TxId 281474976710676
2025-12-05T13:16:02.613646Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.613656Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:02.613663Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.613681Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:02.613744Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:02.613752Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-05T13:16:02.613763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.613883Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:02.613937Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:02.613965Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:02.618317Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:02.618869Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:02.618910Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.618931Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.618949Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.618977Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.618998Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.619031Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.619062Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:02.619388Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:02.619411Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.619422Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.619432Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.619445Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.619460Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.619478Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.619505Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:02.619741Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:02.619761Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.619771Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.619780Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.619793Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.619803Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.619818Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-05T13:16:02.620404Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { LifetimeSeconds: 86400 LowWatermark: 8388608 SourceIdLifetimeSeconds: 86400 WriteSpeedInBytesPerSecond: 20000000 BurstSize: 20000000 TotalPartitions: 3 SourceIdMaxCounts: 6000000 } PartitionIds: 1 PartitionIds: 2 PartitionIds: 0 TopicName: "rt3.dc1--topic1" Version: 0 LocalDC: true Codecs { Ids: 0 Ids: 1 Ids: 2 Codecs: "raw" Codecs: "gzip" Codecs: "lzop" } TopicPath: "/Root/PQ/rt3.dc1--topic1" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Version: 0 Generation: 0 } MonitoringProjectId: ""
2025-12-05T13:16:02.620596Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-05T13:16:02.621114Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:02.627862Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:02.628014Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:02.632070Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:02.682828Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.682869Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.682887Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.682912Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.682944Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.688459Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.688494Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.688512Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.688534Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.688554Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.697667Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.697704Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.697723Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.697748Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.697768Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.766402Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976710677. Ctx: { TraceId: 01kbqafg3060g0dghap6vdb1np, Database: , SessionId: ydb://session/3?node_id=16&id=Njk1MDE0NjMtZDM5NDI1MjQtMzgzZjcwMjEtMjdiZDMxZDA=, PoolId: , DatabaseId: /Root}. Database not set, use /Root
2025-12-05T13:16:02.783163Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.783197Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.783215Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.783238Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.783259Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.788882Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.788916Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.788932Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.788955Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.788975Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.798027Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.798062Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.798081Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.798106Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.798125Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
===Query complete
TClient::Ls request: /Root/PQ/rt3.dc1--topic1
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--topic1"
    PathId: 9
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710676
    CreateStep: 1764940562588
    ParentPathId: 2
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 0
      PQVersion: 1
    }
    ChildrenExist: false
  }
  PersQueueGroup {
    Name: "rt3.dc1--topic1"
    PathId: 9
    TotalGroupCount: 3
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
...
(TRUNCATED)
GetTopicVersionFromPath:  record Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--topic1"
    PathId: 9
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710676
    CreateStep: 1764940562588
    ParentPathId: 2
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 0
      PQVersion: 1
    }
    ChildrenExist: false
  }
  PersQueueGroup {
    Name: "rt3.dc1--topic1"
    PathId: 9
    TotalGroupCount: 3
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
      }
      LocalDC: true
      Codecs {
        Ids: 0
        Ids: 1
        Ids: 2
        Codecs: "raw"
        Codecs: "gzip"
        Codecs: "lzop"
      }
      YdbDatabasePath: "/Root"
      Consumers {
        Name: "user"
        ReadFromTimestampsMs: 0
        FormatVersion: 0
        Version: 0
      }
    }
    Partitions {
      PartitionId: 0
      TabletId: 72075186224037892
      Status: Active
    }
    Partitions {
      PartitionId: 1
      TabletId: 72075186224037892
      Status: Active
    }
    Partitions {
      PartitionId: 2
      TabletId: 72075186224037892
      Status: Active
    }
    AlterVersion: 1
    BalancerTabletID: 72075186224037893
    NextPartitionId: 3
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 1
      PlanResolution: 7
      Coordinators: 72057594046316545
      TimeCastBucketsPerMediator: 2
      Mediators: 72057594046382081
    }
    DomainKey {
      SchemeShard: 72057594046644480
      PathId: 1
    }
    PathsInside: 8
    PathsLimit: 10000
    ShardsInside: 6
    ShardsLimit: 200000
    ResourcesDomainKey {
      SchemeShard: 72057594046644480
      PathId: 1
    }
    DiskSpaceUsage {
      Tables {
        TotalSize: 0
        DataSize: 0
        IndexSize: 0
      }
      Topics {
        ReserveSize: 0
        AccountSize: 0
        DataSize: 0
        UsedReserveSize: 0
      }
    }
    PQPartitionsInside: 3
    PQPartitionsLimit: 1000000
    SchemeLimits {
      MaxDepth: 32
      MaxPaths: 10000
      MaxChildrenInDir: 100000
      MaxAclBytesSize: 10240
      MaxTableColumns: 200
      MaxTableColumnNameLength: 255
      MaxTableKeyColumns: 30
      MaxTableIndices: 20
      MaxShards: 200000
      MaxShardsInPath: 35000
      MaxConsistentCopyTargets: 10000
      MaxPathElementLength: 255
      ExtraPathSymbolsAllowed: "!\"#$%&\'()*+,-.:;<=>?@[\\]^_`{|}~"
      MaxPQPartitions: 1000000
      MaxTableCdcStreams: 5
      MaxExports: 10
      MaxImports: 10
      MaxColumnTableColumns: 10000
    }
  }
}
Path: "/Root/PQ/rt3.dc1--topic1"
 name rt3.dc1--topic1 version1
CallPersQueueGRPC request to localhost:19611
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-05T13:16:02.814005Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
2025-12-05T13:16:02.883539Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.883569Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.883591Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.883619Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.883644Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.892172Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.892208Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.892226Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.892251Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.892271Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:02.898361Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:02.898393Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.898411Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.898434Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.898455Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:02.984892Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:02.984933Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.984956Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.984999Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.985023Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:02.992027Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:02.992064Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.992084Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:02.992117Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:02.992141Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.000349Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.000390Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.000424Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.000451Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.000474Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.086357Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.086402Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.086425Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.086454Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.086478Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.093563Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.093606Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.093627Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.093658Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.093683Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.100754Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.100788Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.100804Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.100829Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.100853Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.186691Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.186733Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.186753Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.186782Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.186805Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.193288Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.193321Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.193340Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.193368Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.193391Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.200963Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.200995Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.201014Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.201040Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.201063Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.286958Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.286994Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.287013Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.287043Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.287066Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.294493Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.294530Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.294548Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.294576Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.294597Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.301743Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.301778Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.301797Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.301822Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.301844Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
CallPersQueueGRPC request to localhost:19611
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-05T13:16:03.324850Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 1
ErrorCode: OK
MetaResponse {
  CmdGetTopicMetadataResult {
    TopicInfo {
      Topic: "rt3.dc1--topic1"
      NumPartitions: 3
      Config {
        PartitionConfig {
          LifetimeSeconds: 86400
          LowWatermark: 8388608
          SourceIdLifetimeSeconds: 86400
          WriteSpeedInBytesPerSecond: 20000000
          BurstSize: 20000000
          SourceIdMaxCounts: 6000000
        }
        Version: 1
        LocalDC: true
        Codecs {
          Ids: 0
          Ids: 1
          Ids: 2
          Codecs: "raw"
          Codecs: "gzip"
          Codecs: "lzop"
        }
        TopicPath: "/Root/PQ/rt3.dc1--topic1"
        YdbDatabasePath: "/Root"
        Consumers {
          Name: "user"
          ReadFromTimestampsMs: 0
          FormatVersion: 0
          Version: 0
        }
      }
      ErrorCode: OK
    }
  }
}
=== Topic created, have version: 1
2025-12-05T13:16:03.365901Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:140: new alter topic request
2025-12-05T13:16:03.370640Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterPersQueueGroup, opId: 281474976710681:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_pq.cpp:313)
2025-12-05T13:16:03.381474Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1517: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-12-05T13:16:03.380998Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:03.388601Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.388637Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.388659Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.388686Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.388708Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.390371Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:03.394730Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.394754Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.394773Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.394798Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.394818Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.404701Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.404740Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.404760Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.404787Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.404808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.414443Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:03.429824Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:03.430475Z node 17 :PERSQUEUE DEBUG: partition.cpp:1259: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.430522Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.430558Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:03.430630Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430657Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.430687Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430712Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.430747Z node 17 :PERSQUEUE DEBUG: partition.cpp:1259: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.430766Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.430780Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:03.430803Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430812Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.430824Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430832Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.430857Z node 17 :PERSQUEUE DEBUG: partition.cpp:1259: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.430872Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.430884Z node 17 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-05T13:16:03.430907Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430915Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.430928Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.430937Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.431301Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:03.439435Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:03.439718Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.439755Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.439771Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.439786Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.439829Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:03.440034Z node 17 :PERSQUEUE DEBUG: partition.cpp:3748: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug reinit with generation 2 done
2025-12-05T13:16:03.440065Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.440078Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-05T13:16:03.440098Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.440524Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.440553Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.440566Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.440577Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.440609Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:03.440737Z node 17 :PERSQUEUE DEBUG: partition.cpp:3748: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug reinit with generation 2 done
2025-12-05T13:16:03.440751Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.440763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-05T13:16:03.440777Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.441004Z node 17 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764940563442, TxId 281474976710681
2025-12-05T13:16:03.441032Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.441043Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.441054Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.441081Z node 17 :PERSQUEUE DEBUG: partition.cpp:2435: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-05T13:16:03.441178Z node 17 :PERSQUEUE DEBUG: partition.cpp:3748: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug reinit with generation 2 done
2025-12-05T13:16:03.441202Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.441212Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-05T13:16:03.441226Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.441476Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.441545Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.441583Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.448840Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.451291Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.451326Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.451350Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.451371Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.451397Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.451419Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.451457Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.451516Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.454978Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.455026Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.455051Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.455071Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.455097Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.455118Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.455155Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.455209Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.457422Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.457470Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.457492Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.457512Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.457537Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.457559Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.457593Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.468862Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { LifetimeSeconds: 86400 LowWatermark: 8388608 SourceIdLifetimeSeconds: 86400 WriteSpeedInBytesPerSecond: 20000000 BurstSize: 20000000 TotalPartitions: 3 SourceIdMaxCounts: 6000000 } PartitionIds: 1 PartitionIds: 2 PartitionIds: 0 TopicName: "rt3.dc1--topic1" Version: 2 LocalDC: true Codecs { Ids: 0 Ids: 1 Ids: 2 Codecs: "raw" Codecs: "gzip" Codecs: "lzop" } TopicPath: "/Root/PQ/rt3.dc1--topic1" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } AllPartitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } AllPartitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 } Consumers { Name: "debug" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 2 } MonitoringProjectId: ""
2025-12-05T13:16:03.468931Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-05T13:16:03.469431Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:03.474329Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:03.474472Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-05T13:16:03.478291Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-05T13:16:03.488703Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.488744Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.488762Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.488789Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.488808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.494973Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.495010Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.495027Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.495062Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.495082Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.505489Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.505523Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.505541Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.505567Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.505586Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.560855Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-05T13:16:03.560892Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-05T13:16:03.561432Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-12-05T13:16:03.561634Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 1 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:38410
2025-12-05T13:16:03.561660Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-12-05T13:16:03.561678Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:38410 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-05T13:16:03.561700Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-05T13:16:03.564356Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-05T13:16:03.564590Z node 16 :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;
2025-12-05T13:16:03.564607Z node 16 :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);
2025-12-05T13:16:03.564626Z node 16 :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;
2025-12-05T13:16:03.564666Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580361998003746698:2475] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-05T13:16:03.565508Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746699:2475] connected; active server actors: 1
2025-12-05T13:16:03.565770Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580361998003746698:2475] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-12-05T13:16:03.565812Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580361998003746698:2475] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-05T13:16:03.565886Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-05T13:16:03.566903Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746699:2475] disconnected; active server actors: 1
2025-12-05T13:16:03.566946Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746699:2475] disconnected no session
2025-12-05T13:16:03.567676Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-05T13:16:03.568066Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746703:2475], now have 1 active actors on pipe
2025-12-05T13:16:03.568140Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.568179Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.568278Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 generated for partition 0 topic 'rt3.dc1--topic1' owner 23d5351a-4c329ef6-5129db36-eb14ec82
2025-12-05T13:16:03.568323Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.568341Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.568368Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.568390Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.568431Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.568472Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.568504Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-05T13:16:03.568532Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.568607Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-05T13:16:03.568684Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.569533Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.569583Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.569687Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.570418Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 0 MaxSeqNo: 0 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0
2025-12-05T13:16:03.577716Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-05T13:16:03.578166Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-05T13:16:03.581484Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.581553Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.581614Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-05T13:16:03.581639Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-05T13:16:03.581675Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-05T13:16:03.582965Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-12-05T13:16:03.583084Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 2 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:38410
2025-12-05T13:16:03.583093Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-12-05T13:16:03.583109Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:38410 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-05T13:16:03.583119Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-05T13:16:03.585112Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-05T13:16:03.585294Z node 16 :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;
2025-12-05T13:16:03.585307Z node 16 :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);
2025-12-05T13:16:03.585322Z node 16 :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;
2025-12-05T13:16:03.585338Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580361998003746706:2477] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-05T13:16:03.585852Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-12-05T13:16:03.586384Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746707:2477] connected; active server actors: 1
2025-12-05T13:16:03.586631Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.586685Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.586554Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580361998003746706:2477] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-12-05T13:16:03.586574Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580361998003746706:2477] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-12-05T13:16:03.586621Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-12-05T13:16:03.587128Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.587169Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.587192Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.587275Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-05T13:16:03.587359Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.587381Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.587411Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.587434Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.587471Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-05T13:16:03.587489Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.587527Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.587718Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746707:2477] disconnected; active server actors: 1
2025-12-05T13:16:03.587662Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-05T13:16:03.587760Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746707:2477] disconnected no session
2025-12-05T13:16:03.588479Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-05T13:16:03.692848Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-12-05T13:16:03.692941Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.692980Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-05T13:16:03.693023Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-12-05T13:16:03.693039Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.693055Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-05T13:16:03.693077Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-12-05T13:16:03.693103Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-05T13:16:03.693122Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][0][StateIdle] Batch completed (3)
2025-12-05T13:16:03.693150Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.694046Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 0 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000000_00000000000000000000_00000_0000000003_00000? size 409 WTime 1764940563693
2025-12-05T13:16:03.694347Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746711:2477], now have 1 active actors on pipe
2025-12-05T13:16:03.694418Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.694456Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.694518Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.694535Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.694552Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.694574Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.694591Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.694624Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.694635Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.694643Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.694655Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.694664Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.694735Z node 17 :PERSQUEUE DEBUG: partition.cpp:2260: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-05T13:16:03.694763Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.694981Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 3 size 409
2025-12-05T13:16:03.695097Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 generated for partition 1 topic 'rt3.dc1--topic1' owner ee188f3d-74518d91-ca25e9aa-b4d38b0b
2025-12-05T13:16:03.695131Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.695152Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.695178Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.695197Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.695225Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.695265Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.695290Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-05T13:16:03.695316Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.695360Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-12-05T13:16:03.695571Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.697785Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.697842Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.697954Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.698441Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 1 MaxSeqNo: 0 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0
2025-12-05T13:16:03.705296Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 3 size 409 actorID [17:7580361996261356061:2368]
2025-12-05T13:16:03.705378Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.705459Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.705516Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-05T13:16:03.705579Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-12-05T13:16:03.705603Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-05T13:16:03.705628Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-12-05T13:16:03.705641Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-05T13:16:03.705664Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-12-05T13:16:03.705995Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:03.706039Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-12-05T13:16:03.706061Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.706090Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.706112Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.706132Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.706154Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.706174Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.706210Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.706306Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-05T13:16:03.706445Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 0 user user offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.706802Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.706842Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-05T13:16:03.706904Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.706947Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-05T13:16:03.707013Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.707396Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.707518Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1764940563587 queuesize 1 startOffset 0
2025-12-05T13:16:03.707575Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-12-05T13:16:03.707613Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.707745Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.708027Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.708055Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-12-05T13:16:03.708103Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.708138Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-05T13:16:03.708183Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.704745Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-05T13:16:03.705020Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-05T13:16:03.705086Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-05T13:16:03.705334Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-05T13:16:03.708563Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.705422Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-05T13:16:03.705437Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-05T13:16:03.706674Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-12-05T13:16:03.706913Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 3 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:38410
2025-12-05T13:16:03.706923Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-12-05T13:16:03.706938Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:38410 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-05T13:16:03.706949Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-05T13:16:03.708652Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1764940563587 queuesize 0 startOffset 0
2025-12-05T13:16:03.709328Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-05T13:16:03.709517Z node 16 :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;
2025-12-05T13:16:03.709531Z node 16 :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);
2025-12-05T13:16:03.712597Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-12-05T13:16:03.709546Z node 16 :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;
2025-12-05T13:16:03.712727Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.712771Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.709562Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580361998003746720:2479] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-05T13:16:03.710044Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746721:2479] connected; active server actors: 1
2025-12-05T13:16:03.710154Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580361998003746720:2479] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-12-05T13:16:03.710171Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580361998003746720:2479] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-12-05T13:16:03.712920Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.710223Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-12-05T13:16:03.712975Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.710744Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746721:2479] disconnected; active server actors: 1
2025-12-05T13:16:03.713039Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.710773Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746721:2479] disconnected no session
2025-12-05T13:16:03.713072Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.713143Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746725:2479], now have 1 active actors on pipe
2025-12-05T13:16:03.713241Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-12-05T13:16:03.713291Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 2
2025-12-05T13:16:03.714154Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.714196Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.714302Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0 generated for partition 2 topic 'rt3.dc1--topic1' owner 2c1033a3-6f382f20-67306da-9c2ed009
2025-12-05T13:16:03.714351Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.714378Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.714407Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.714428Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.714453Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.714492Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.714515Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-05T13:16:03.714542Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.714596Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-12-05T13:16:03.714656Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.715522Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.715565Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.715628Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 2 size 99 offset: -1
2025-12-05T13:16:03.715659Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 2 size 99 offset: -1
2025-12-05T13:16:03.715735Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.715759Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.715786Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 3 size 99 offset: -1
2025-12-05T13:16:03.715830Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.715845Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.713250Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-12-05T13:16:03.713913Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-05T13:16:03.713947Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-05T13:16:03.714036Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-05T13:16:03.715896Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-05T13:16:03.715974Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.715998Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.716024Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.716050Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-05T13:16:03.716065Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.716093Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.716225Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-05T13:16:03.717172Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-12-05T13:16:03.717229Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.717262Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-05T13:16:03.717299Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-12-05T13:16:03.717323Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-05T13:16:03.717342Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (2)
2025-12-05T13:16:03.717368Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.718270Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 0,2 HeadOffset 0 endOffset 0 curOffset 2 d0000000001_00000000000000000000_00000_0000000002_00000? size 288 WTime 1764940563717
2025-12-05T13:16:03.718490Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-05T13:16:03.718538Z node 17 :PERSQUEUE DEBUG: partition.cpp:2260: [72075186224037892][Partition][1][StateIdle] Writing. Can't process user action and tx events
2025-12-05T13:16:03.718606Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.718817Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 2 size 288
2025-12-05T13:16:03.718890Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.719783Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 2 MaxSeqNo: 0 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0
2025-12-05T13:16:03.726325Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 0 count 2 size 288 actorID [17:7580361996261356061:2368]
2025-12-05T13:16:03.726428Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.726525Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.726570Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-05T13:16:03.726629Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-12-05T13:16:03.726653Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-05T13:16:03.726677Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-12-05T13:16:03.727009Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:03.727047Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-12-05T13:16:03.727070Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.727092Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.727113Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.727151Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.727171Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.727198Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.727346Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-05T13:16:03.730256Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 grpc read done: success: 0 data: 
2025-12-05T13:16:03.730284Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 grpc read failed
2025-12-05T13:16:03.730322Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 grpc closed
2025-12-05T13:16:03.730344Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: 23d5351a-4c329ef6-5129db36-eb14ec82|7f5d6166-85300635-9df5027e-d3d00127_0 is DEAD
2025-12-05T13:16:03.730667Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-05T13:16:03.731167Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-05T13:16:03.731294Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-05T13:16:03.731518Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-05T13:16:03.842687Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 2 PartNo 0 PackedSize 166 count 1 nextOffset 3 batches 1
2025-12-05T13:16:03.842767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.842786Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-05T13:16:03.842814Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.843681Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 2,1 HeadOffset 2 endOffset 2 curOffset 3 d0000000001_00000000000000000002_00000_0000000001_00000? size 154 WTime 1764940563843
2025-12-05T13:16:03.843918Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.844092Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 2  requestId: <none> cookie: 1
2025-12-05T13:16:03.844220Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746703:2475] destroyed
2025-12-05T13:16:03.844300Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.844346Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.844843Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-05T13:16:03.846846Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-05T13:16:03.844462Z node 17 :PERSQUEUE DEBUG: partition.cpp:2260: [72075186224037892][Partition][1][StateIdle] Writing. Can't process user action and tx events
2025-12-05T13:16:03.844502Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.844520Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.844535Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.844558Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.844579Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.844615Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.844625Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.844635Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.844646Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.844654Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.844706Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.844929Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 2 partNo 0 count 1 size 154
2025-12-05T13:16:03.845045Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-05T13:16:03.845086Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.845109Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.845127Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.845152Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.845169Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.845408Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 1 user user offset 0 partno 0 count 1 size 1024000 endOffset 2 max time lag 0ms effective offset 0
2025-12-05T13:16:03.845770Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-05T13:16:03.845806Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-12-05T13:16:03.845856Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-12-05T13:16:03.845992Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.846042Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-05T13:16:03.846210Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-05T13:16:03.846565Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.846816Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-05T13:16:03.846928Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1764940563715 queuesize 1 startOffset 0
2025-12-05T13:16:03.846953Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-12-05T13:16:03.846972Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.847138Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 partno 0 count 1 size 1024000 endOffset 2 max time lag 0ms effective offset 0
2025-12-05T13:16:03.847456Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-05T13:16:03.847481Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-12-05T13:16:03.847536Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 1 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.847573Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-05T13:16:03.847621Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.847641Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-05T13:16:03.847793Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-05T13:16:03.847939Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.847980Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.848046Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.848070Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.848094Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2009: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-05T13:16:03.848151Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1764940563715 queuesize 0 startOffset 0
2025-12-05T13:16:03.848203Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-05T13:16:03.848283Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.848307Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.848336Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.848349Z node 17 :PERSQUEUE DEBUG: partition.cpp:2378: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-05T13:16:03.848390Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-05T13:16:03.849591Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.849644Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.849782Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-05T13:16:03.850728Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-12-05T13:16:03.850798Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.850830Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-05T13:16:03.850867Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-12-05T13:16:03.850886Z node 17 :PERSQUEUE DEBUG: partition.cpp:2442: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-05T13:16:03.850905Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1320: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-05T13:16:03.850928Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1424: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-12-05T13:16:03.850956Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-05T13:16:03.850982Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][2][StateIdle] Batch completed (3)
2025-12-05T13:16:03.851010Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.851924Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000002_00000000000000000000_00000_0000000003_00000? size 409 WTime 1764940563851
2025-12-05T13:16:03.852365Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.854197Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-05T13:16:03.852621Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 3 size 409
2025-12-05T13:16:03.852717Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 2 count 1 size 154 actorID [17:7580361996261356061:2368]
2025-12-05T13:16:03.852922Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.852995Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.853032Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-05T13:16:03.853087Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-12-05T13:16:03.853397Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.853420Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.853437Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.853464Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.853485Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.853521Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.853622Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 3  requestId: <none> cookie: 2
2025-12-05T13:16:03.853908Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 2 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-05T13:16:03.856848Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 0 count 3 size 409 actorID [17:7580361996261356061:2368]
2025-12-05T13:16:03.856936Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.857005Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-12-05T13:16:03.857026Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.857075Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-05T13:16:03.857156Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-12-05T13:16:03.857197Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-05T13:16:03.857227Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-12-05T13:16:03.857240Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-05T13:16:03.857263Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-12-05T13:16:03.857627Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-05T13:16:03.858224Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-05T13:16:03.857675Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-12-05T13:16:03.857698Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.857721Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.857747Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.857767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.857796Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.857839Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.857880Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.857994Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 1
2025-12-05T13:16:03.858149Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 2 user user offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.858525Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.858563Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-12-05T13:16:03.858627Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.858673Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-05T13:16:03.858746Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.858831Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.859139Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.859249Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1764940563849 queuesize 1 startOffset 0
2025-12-05T13:16:03.859276Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-12-05T13:16:03.859295Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-12-05T13:16:03.859388Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.859617Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.859636Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-12-05T13:16:03.859669Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 1 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.859699Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-05T13:16:03.859738Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.859773Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.859931Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.859988Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1764940563849 queuesize 0 startOffset 0
2025-12-05T13:16:03.929696Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 grpc read done: success: 0 data: 
2025-12-05T13:16:03.929742Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 grpc read failed
2025-12-05T13:16:03.929817Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:824: session v1 closed cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0
2025-12-05T13:16:03.929870Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: ee188f3d-74518d91-ca25e9aa-b4d38b0b|e0c898d7-40bd205e-8e010f15-d90a11f9_0 is DEAD
2025-12-05T13:16:03.930364Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-12-05T13:16:03.930516Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0 grpc read done: success: 0 data: 
2025-12-05T13:16:03.930533Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0 grpc read failed
2025-12-05T13:16:03.930793Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746711:2477] destroyed
2025-12-05T13:16:03.930859Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-12-05T13:16:03.930909Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.930943Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.930965Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.930992Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.931012Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.931095Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:824: session v1 closed cookie: 3 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0
2025-12-05T13:16:03.931125Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: 2c1033a3-6f382f20-67306da-9c2ed009|8e219326-195d07a0-9e0d81bc-9e442264_0 is DEAD
2025-12-05T13:16:03.931402Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-12-05T13:16:03.931630Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746725:2479] destroyed
2025-12-05T13:16:03.931664Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-12-05T13:16:03.931694Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.931712Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.931725Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.931743Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.931755Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
Start reads
2025-12-05T13:16:03.933745Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:111: new grpc connection
2025-12-05T13:16:03.933775Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:133: new session created cookie 1
2025-12-05T13:16:03.934504Z node 16 :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: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-05T13:16:03.934905Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:941: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 read init: from# ipv6:[::1]:38410, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-05T13:16:03.935640Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 auth for : debug
2025-12-05T13:16:03.936453Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Handle describe topics response
2025-12-05T13:16:03.936698Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 auth is DEAD
2025-12-05T13:16:03.936863Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1058: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 auth ok: topics# 1, initDone# 0
2025-12-05T13:16:03.938706Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1229: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 register session: topic# rt3.dc1--topic1
2025-12-05T13:16:03.939693Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746735:2481] connected; active server actors: 1
2025-12-05T13:16:03.940024Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1700: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [16:7580361998003746735:2481] session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.940174Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-12-05T13:16:03.940349Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=1 (Status=Free, Partitions=[0])
2025-12-05T13:16:03.940439Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-12-05T13:16:03.940704Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=2 (Status=Free, Partitions=[1])
2025-12-05T13:16:03.940753Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-12-05T13:16:03.940802Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=3 (Status=Free, Partitions=[2])
2025-12-05T13:16:03.940980Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:869: [72075186224037893][rt3.dc1--topic1] consumer debug register reading session ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=0)
2025-12-05T13:16:03.941053Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1184: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-12-05T13:16:03.941182Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1256: [72075186224037893][rt3.dc1--topic1] consumer debug balancing. Sessions=1, Families=3, UnradableFamilies=3 [3 (2), 2 (1), 1 (0), ], RequireBalancing=0 []
2025-12-05T13:16:03.941287Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=0)
2025-12-05T13:16:03.941565Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 3 status Active partitions [2] session "shared/debug_16_1_14815722998182111742_v1" sender [16:7580361998003746732:2481] lock partition 2 for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-12-05T13:16:03.944395Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.944457Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.944478Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.944516Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.944550Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.945480Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.945503Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.945528Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.945546Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.945560Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.945613Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.945625Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.945634Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.945646Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.945657Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.948707Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=1)
2025-12-05T13:16:03.949196Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 2 status Active partitions [1] session "shared/debug_16_1_14815722998182111742_v1" sender [16:7580361998003746732:2481] lock partition 1 for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-12-05T13:16:03.949461Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=2)
2025-12-05T13:16:03.949567Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 1 status Active partitions [0] session "shared/debug_16_1_14815722998182111742_v1" sender [16:7580361998003746732:2481] lock partition 0 for ReadingSession "shared/debug_16_1_14815722998182111742_v1" (Sender=[16:7580361998003746732:2481], Pipe=[16:7580361998003746735:2481], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-12-05T13:16:03.949659Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1323: [72075186224037893][rt3.dc1--topic1] consumer debug start rebalancing. familyCount=3, sessionCount=1, desiredFamilyCount=3, allowPlusOne=0
2025-12-05T13:16:03.949744Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1400: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.008494s
2025-12-05T13:16:03.954556Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_16_1_14815722998182111742_v1" ClientId: "debug" PipeClient { RawX1: 7580361998003746735 RawX2: 4503668346849713 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-05T13:16:03.954726Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-12-05T13:16:03.954977Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_16_1_14815722998182111742_v1" ClientId: "debug" PipeClient { RawX1: 7580361998003746735 RawX2: 4503668346849713 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-05T13:16:03.955131Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_16_1_14815722998182111742_v1" ClientId: "debug" PipeClient { RawX1: 7580361998003746735 RawX2: 4503668346849713 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-05T13:16:03.955374Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got read request: guid# ea69e682-5567c637-a9106448-a4bcddd4
2025-12-05T13:16:03.955568Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-12-05T13:16:03.964364Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-12-05T13:16:03.964555Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-12-05T13:16:03.966170Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746746:2485], now have 1 active actors on pipe
2025-12-05T13:16:03.966235Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746747:2486], now have 1 active actors on pipe
2025-12-05T13:16:03.966259Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580361998003746743:2484], now have 1 active actors on pipe
2025-12-05T13:16:03.966926Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7580361998003746743:2484]
2025-12-05T13:16:03.967227Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.967292Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.967376Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_14815722998182111742_v1 on pipe: [16:7580361998003746743:2484]
2025-12-05T13:16:03.967491Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_14815722998182111742_v1:1 with generation 1
2025-12-05T13:16:03.967518Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.967541Z node 17 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.967590Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.967612Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.967640Z node 17 :PERSQUEUE DEBUG: partition.cpp:2428: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.968467Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7580361998003746746:2485]
2025-12-05T13:16:03.968615Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7580361998003746747:2486]
2025-12-05T13:16:03.967731Z node 17 :PERSQUEUE DEBUG: partition.cpp:3792: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.967765Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.967784Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-05T13:16:03.967810Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.968080Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.969732Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.969792Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.969841Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_14815722998182111742_v1 on pipe: [16:7580361998003746746:2485]
2025-12-05T13:16:03.969918Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.969921Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_14815722998182111742_v1:2 with generation 1
2025-12-05T13:16:03.969933Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.969953Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_14815722998182111742_v1 on pipe: [16:7580361998003746747:2486]
2025-12-05T13:16:03.969973Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_14815722998182111742_v1:3 with generation 1
2025-12-05T13:16:03.970015Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.970044Z node 17 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.970074Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.970090Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.970119Z node 17 :PERSQUEUE DEBUG: partition.cpp:2428: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.970189Z node 17 :PERSQUEUE DEBUG: partition.cpp:3792: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.970209Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.970224Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-05T13:16:03.970257Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.970444Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.970456Z node 17 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.970487Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-05T13:16:03.970497Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.970512Z node 17 :PERSQUEUE DEBUG: partition.cpp:2428: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-05T13:16:03.970558Z node 17 :PERSQUEUE DEBUG: partition.cpp:3792: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.970571Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-05T13:16:03.970581Z node 17 :PERSQUEUE DEBUG: partition.cpp:2297: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-05T13:16:03.970592Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.970698Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.970736Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-05T13:16:03.973212Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.973342Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.973374Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:03.973396Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.973416Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.973443Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.973464Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:03.973500Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.973573Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-05T13:16:03.974866Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.974966Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.974991Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:03.975019Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.975035Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.975057Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.975076Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:03.975105Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.975127Z node 17 :PERSQUEUE DEBUG: partition.cpp:2108: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-05T13:16:03.975190Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-05T13:16:03.975206Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:03.975217Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.975226Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:03.975246Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:03.975257Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:03.975276Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-05T13:16:03.975317Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-05T13:16:03.975370Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-05T13:16:03.975050Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1764940563849 CreateTimestampMS: 1764940563724 SizeLag: 409 WriteTimestampEstimateMS: 1764940563945 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-05T13:16:03.975127Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 readOffset 0 committedOffset 0
2025-12-05T13:16:03.975250Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 sending to client partition status
2025-12-05T13:16:03.975938Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1764940563715 CreateTimestampMS: 1764940563699 SizeLag: 576 WriteTimestampEstimateMS: 1764940563944 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-05T13:16:03.975995Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 readOffset 0 committedOffset 0
2025-12-05T13:16:03.976118Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1764940563587 CreateTimestampMS: 1764940563575 SizeLag: 409 WriteTimestampEstimateMS: 1764940563945 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-05T13:16:03.976150Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 readOffset 0 committedOffset 0
2025-12-05T13:16:03.976195Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 sending to client partition status
2025-12-05T13:16:03.976221Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 sending to client partition status
Got start stream event
Got start stream event
Got start stream event
2025-12-05T13:16:03.984059Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-12-05T13:16:03.984327Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), readOffset# 0, commitOffset# (empty maybe)
2025-12-05T13:16:03.984425Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-12-05T13:16:03.984524Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), readOffset# 0, commitOffset# (empty maybe)
2025-12-05T13:16:03.984583Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-12-05T13:16:03.984703Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), readOffset# 0, commitOffset# (empty maybe)
2025-12-05T13:16:03.984802Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-12-05T13:16:03.984860Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) ready for read with readOffset 0 endOffset 3
2025-12-05T13:16:03.984929Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-12-05T13:16:03.984957Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) ready for read with readOffset 0 endOffset 3
2025-12-05T13:16:03.984996Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-12-05T13:16:03.985017Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) ready for read with readOffset 0 endOffset 3
2025-12-05T13:16:03.985112Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), readOffset# 0, endOffset# 3, WTime# 1764940563849, sizeLag# 409
2025-12-05T13:16:03.985155Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1TEvPartitionReady. Aval parts: 1
2025-12-05T13:16:03.985258Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 performing read request: guid# c49c9815-b90f0435-3450cfbd-d42238b9, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-05T13:16:03.985364Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), readOffset# 0, endOffset# 3, WTime# 1764940563715, sizeLag# 576
2025-12-05T13:16:03.985385Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1TEvPartitionReady. Aval parts: 1
2025-12-05T13:16:03.985417Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 performing read request: guid# 86162d8a-2bd84e2d-da0ac1b9-88d85ead, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), count# 3, size# 691, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-05T13:16:03.985470Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), readOffset# 0, endOffset# 3, WTime# 1764940563587, sizeLag# 409
2025-12-05T13:16:03.985488Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1TEvPartitionReady. Aval parts: 1
2025-12-05T13:16:03.985518Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 performing read request: guid# 95c5a399-fbf4746b-157afec7-b5bc9ac6, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-05T13:16:03.985635Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 95c5a399-fbf4746b-157afec7-b5bc9ac6
2025-12-05T13:16:03.985904Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid c49c9815-b90f0435-3450cfbd-d42238b9
2025-12-05T13:16:03.986027Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)maxCount 3 maxSize 691 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 86162d8a-2bd84e2d-da0ac1b9-88d85ead
2025-12-05T13:16:03.986514Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.986594Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-05T13:16:03.986752Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.986788Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-05T13:16:03.986853Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-05T13:16:03.986868Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-05T13:16:03.986987Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 partno 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.987291Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.987323Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-12-05T13:16:03.987360Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.987517Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-05T13:16:03.987536Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-12-05T13:16:03.987559Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 partno 0 count 3 size 691 endOffset 3 max time lag 0ms effective offset 0
2025-12-05T13:16:03.987979Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1093: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 2, current partition end offset: 3
2025-12-05T13:16:03.987998Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1109: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-12-05T13:16:03.988050Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.988098Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-05T13:16:03.988155Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.988174Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-05T13:16:03.988206Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 2 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.988219Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.988226Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 2 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-05T13:16:03.000000Z
2025-12-05T13:16:03.988244Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-05T13:16:03.988289Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-05T13:16:03.988293Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.988322Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-05T13:16:03.988350Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 2 partno 0 count 1 parts 0 suffix '63'
2025-12-05T13:16:03.988690Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.988836Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 1 blobs
2025-12-05T13:16:03.989082Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-05T13:16:03.989197Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:565: FormAnswer for 2 blobs
2025-12-05T13:16:03.989370Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-05T13:16:03.989460Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:487: FormAnswer processing batch offset 2 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-05T13:16:03.989573Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.989677Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.989738Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-05T13:16:03.990670Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1764940563587 CreateTimestampMS: 1764940563575 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764940563692 CreateTimestampMS: 1764940563575 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764940563692 CreateTimestampMS: 1764940563575 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-05T13:16:03.991035Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) wait data in partition inited, cookie 1 from offset 3
2025-12-05T13:16:03.991089Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid 95c5a399-fbf4746b-157afec7-b5bc9ac6 has messages 1
2025-12-05T13:16:03.991562Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1764940563849 CreateTimestampMS: 1764940563724 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764940563850 CreateTimestampMS: 1764940563724 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764940563850 CreateTimestampMS: 1764940563724 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-05T13:16:03.991712Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) wait data in partition inited, cookie 1 from offset 3
2025-12-05T13:16:03.991750Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid c49c9815-b90f0435-3450cfbd-d42238b9 has messages 1
2025-12-05T13:16:03.992104Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1764940563715 CreateTimestampMS: 1764940563699 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764940563716 CreateTimestampMS: 1764940563699 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764940563727 CreateTimestampMS: 1764940563699 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 174 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-05T13:16:03.992247Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) wait data in partition inited, cookie 1 from offset 3
2025-12-05T13:16:03.992285Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid 86162d8a-2bd84e2d-da0ac1b9-88d85ead has messages 1
2025-12-05T13:16:03.992479Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 read done: guid# 95c5a399-fbf4746b-157afec7-b5bc9ac6, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 386
2025-12-05T13:16:03.992539Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 response to read: guid# 95c5a399-fbf4746b-157afec7-b5bc9ac6
2025-12-05T13:16:03.992894Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Process answer. Aval parts: 0
2025-12-05T13:16:03.993116Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 read done: guid# c49c9815-b90f0435-3450cfbd-d42238b9, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 386
2025-12-05T13:16:03.993146Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 response to read: guid# c49c9815-b90f0435-3450cfbd-d42238b9
2025-12-05T13:16:03.993173Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Process answer. Aval parts: 0
2025-12-05T13:16:03.993227Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 read done: guid# 86162d8a-2bd84e2d-da0ac1b9-88d85ead, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 496
2025-12-05T13:16:03.993248Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 response to read: guid# 86162d8a-2bd84e2d-da0ac1b9-88d85ead
2025-12-05T13:16:03.993267Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 Process answer. Aval parts: 0
Got data event with total 3 messages, current total messages: 3
2025-12-05T13:16:03.994595Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-05T13:16:03.994755Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got read request: guid# c3c218ad-8f469c69-f1ce5705-315c2f43
Got data event with total 3 messages, current total messages: 6
2025-12-05T13:16:03.995572Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
Got data event with total 3 messages, current total messages: 9
2025-12-05T13:16:03.995702Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 got read request: guid# c88e14ce-12bac1f2-d5c74cf0-7b75cd36
2025-12-05T13:16:03.997707Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read done: success# 0, data# {  }
2025-12-05T13:16:03.997754Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc read failed
2025-12-05T13:16:03.997790Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 grpc closed
2025-12-05T13:16:03.997867Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_16_1_14815722998182111742_v1 is DEAD
2025-12-05T13:16:03.999613Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746735:2481] disconnected; active server actors: 1
2025-12-05T13:16:03.999669Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1674: [72075186224037893][rt3.dc1--topic1] pipe [16:7580361998003746735:2481] client debug disconnected session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.999804Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.999872Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746747:2486] destroyed
2025-12-05T13:16:03.999905Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.999933Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746746:2485] destroyed
2025-12-05T13:16:03.999954Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:03.999987Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580361998003746743:2484] destroyed
2025-12-05T13:16:04.000051Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:04.000074Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:04.000093Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_14815722998182111742_v1
2025-12-05T13:16:04.022727Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard_impl.cpp:7718: Cannot get console configs
2025-12-05T13:16:04.022759Z node 16 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-12-05T13:16:04.044718Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:04.044772Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.044792Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.044818Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.044849Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:04.045909Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:04.045937Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.045953Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.045973Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.046009Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:04.046047Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:04.046058Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.046077Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.046090Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.046099Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:04.145099Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:04.145131Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.145150Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.145169Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.145185Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:04.146048Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:04.146071Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.146093Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.146110Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.146124Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:04.146165Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:04.146177Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.146186Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.146199Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.146208Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:04.245573Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:04.245617Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.245635Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.245661Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.245682Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:04.250042Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:04.250085Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.250103Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.250129Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.250152Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:04.250212Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:04.250223Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.250235Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.250248Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.250257Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-05T13:16:04.345818Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-05T13:16:04.345856Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.345874Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.345898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.345920Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-05T13:16:04.351053Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-05T13:16:04.351094Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.351113Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.351141Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.351164Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-05T13:16:04.351215Z node 17 :PERSQUEUE DEBUG: partition.cpp:2284: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-05T13:16:04.351226Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.351236Z node 17 :PERSQUEUE DEBUG: partition.cpp:2292: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-05T13:16:04.351249Z node 17 :PERSQUEUE DEBUG: partition.cpp:2343: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-05T13:16:04.351260Z node 17 :PERSQUEUE DEBUG: partition.cpp:2302: [72075186224037892][Partition][0][StateIdle] Try persist
