2025-12-08T22:12:06.740921Z node 16 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[16:7581613396087086409:2260];send_to=[0:7307199536658146131:7762515];
2025-12-08T22:12:06.740979Z 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-08T22:12:06.742851Z node 17 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[17:7581613395740207280:2075];send_to=[0:7307199536658146131:7762515];
2025-12-08T22:12:06.742877Z 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=scheme_cache_undelivered_message;
2025-12-08T22:12:06.744305Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/lefg/00021d/r3tmp/tmpq4Mf9A/pdisk_1.dat
2025-12-08T22:12:06.748936Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-08T22:12:06.750091Z 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-08T22:12:06.750641Z 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-08T22:12:06.768439Z node 16 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
TServer::EnableGrpc on GrpcPort 19476, node 16
2025-12-08T22:12:06.790051Z 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/lefg/00021d/r3tmp/yandexbd5FY9.tmp
2025-12-08T22:12:06.790064Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/lefg/00021d/r3tmp/yandexbd5FY9.tmp
2025-12-08T22:12:06.790130Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/lefg/00021d/r3tmp/yandexbd5FY9.tmp
2025-12-08T22:12:06.790158Z node 16 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-08T22:12:06.795185Z INFO: TTestServer started on Port 9492 GrpcPort 19476
TClient is connected to server localhost:9492
PQClient connected to localhost:19476
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-08T22:12:06.841079Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-08T22:12:06.841105Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-08T22:12:06.843026Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-08T22:12:06.850879Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976715657: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...
2025-12-08T22:12:06.857814Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-08T22:12:06.857842Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-08T22:12:06.860577Z node 16 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 17 Cookie 17
2025-12-08T22:12:06.862503Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-08T22:12:06.865944Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__notify.cpp:122: NotifyTxCompletion, unknown transaction, txId: 281474976715657, at schemeshard: 72057594046644480
waiting...
2025-12-08T22:12:06.878355Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__notify.cpp:122: NotifyTxCompletion, unknown transaction, txId: 281474976715658, at schemeshard: 72057594046644480
2025-12-08T22:12:06.879854Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715659: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-08T22:12:07.024384Z 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-08T22:12:07.033449Z 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-08T22:12:07.254955Z node 16 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:566: Compilation failed, self: [16:7581613400382054521:2319], 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-08T22:12:07.256162Z node 16 :KQP_SESSION WARN: kqp_session_actor.cpp:2448: SessionId: ydb://session/3?node_id=16&id=MjkwZjVjYTItOWE1ZjJiNTItMWI0M2U5YmQtYmVmYzUxMDU=, ActorId: [16:7581613400382054518:2317], ActorState: ExecuteState, TraceId: 01kc00b7yg2gjz60ddf5revmpn, ReplyQueryCompileError, status: SCHEME_ERROR, issues: { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } }, remove tx with tx_id: 
2025-12-08T22:12:07.256216Z node 17 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:566: Compilation failed, self: [17:7581613400035174924:2293], 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-08T22:12:07.256290Z 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-08T22:12:07.256545Z node 17 :KQP_SESSION WARN: kqp_session_actor.cpp:2448: SessionId: ydb://session/3?node_id=17&id=ZTNhMmM3M2MtOTc0NjUwNGEtZjQ5NzMzMmYtNDhlNWZjMDA=, ActorId: [17:7581613400035174922:2292], ActorState: ExecuteState, TraceId: 01kc00b7yd4fk3rgcp4n9081fd, ReplyQueryCompileError, status: SCHEME_ERROR, issues: { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } }, remove tx with tx_id: 
2025-12-08T22:12:07.256628Z 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: 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-08T22:12:07.259890Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715662: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-08T22:12:07.315175Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715663: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-08T22:12:07.370846Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715664: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-08T22:12:07.486788Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:124: TxId: 281474976715665. Ctx: { TraceId: 01kc00b84e1waxhz8nzpkbxbm6, Database: , SessionId: ydb://session/3?node_id=16&id=ZjIwNjJmOTEtOGVhZmViMTAtOGUwMGI2MmItZDJkNmIwMDA=, PoolId: , DatabaseId: /Root, IsStreamingQuery: 0}. Database not set, use /Root
=== CheckClustersList. Subcribe to ClusterTracker from [16:7581613400382054941:2991] 
2025-12-08T22:12:07.741346Z 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-08T22:12:07.745104Z node 17 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-08T22:12:11.741312Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[16:7581613396087086409:2260];send_to=[0:7307199536658146131:7762515];
2025-12-08T22:12:11.741357Z 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-08T22:12:11.744495Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[17:7581613395740207280:2075];send_to=[0:7307199536658146131:7762515];
2025-12-08T22:12:11.746083Z 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=timeout;
=== CheckClustersList. Ok
PQ Client: create topic: rt3.dc1--topic1 with 3 partitions
CallPersQueueGRPC request to localhost:19476
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-08T22:12:12.765914Z 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:19476
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: 281474976715677
  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-08T22:12:12.782982Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][] pipe [16:7581613421856891918:3247] connected; active server actors: 1
2025-12-08T22:12:12.783070Z 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-08T22:12:12.784829Z 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-08T22:12:12.787958Z 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-08T22:12:12.792573Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2959: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-08T22:12:12.798125Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2991: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-08T22:12:12.798217Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:785: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-08T22:12:12.798226Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-08T22:12:12.798239Z node 17 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-08T22:12:12.798423Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613421856891939:2444], now have 1 active actors on pipe
2025-12-08T22:12:12.798436Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613421856891917:3246], now have 1 active actors on pipe
2025-12-08T22:12:12.799258Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:12.799679Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:402: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 17, Generation 1
2025-12-08T22:12:12.801558Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:12.803766Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613421856891974:3289], now have 1 active actors on pipe
2025-12-08T22:12:12.804160Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:12.805295Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:12.805736Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-12-08T22:12:12.805756Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-12-08T22:12:12.805760Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-12-08T22:12:12.805771Z node 17 :PERSQUEUE DEBUG: partition.cpp:1296: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-08T22:12:12.805775Z node 17 :PERSQUEUE DEBUG: partition.cpp:1296: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-08T22:12:12.805778Z node 17 :PERSQUEUE DEBUG: partition.cpp:1296: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-08T22:12:12.805933Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-08T22:12:12.805978Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [17:7581613421510011783:2369]
2025-12-08T22:12:12.806792Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-12-08T22:12:12.806796Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-12-08T22:12:12.806800Z node 17 :PERSQUEUE INFO: partition.cpp:693: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [17:7581613421510011783:2369]
2025-12-08T22:12:12.806807Z node 17 :PERSQUEUE DEBUG: partition.cpp:713: [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-08T22:12:12.807128Z node 17 :PERSQUEUE DEBUG: partition.cpp:4385: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-12-08T22:12:12.807134Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:12.807139Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:12.807150Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.807152Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.807154Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.807156Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:12.807166Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:12.807169Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.807171Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.807173Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.807174Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:12.807182Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:12.807214Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-08T22:12:12.807258Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [17:7581613421510011784:2369]
2025-12-08T22:12:12.807839Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-12-08T22:12:12.807842Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-12-08T22:12:12.807845Z node 17 :PERSQUEUE INFO: partition.cpp:693: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [17:7581613421510011784:2369]
2025-12-08T22:12:12.807849Z node 17 :PERSQUEUE DEBUG: partition.cpp:713: [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-08T22:12:12.808087Z node 17 :PERSQUEUE DEBUG: partition.cpp:4385: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-12-08T22:12:12.808089Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:12.808092Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:12.808097Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808099Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.808101Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808102Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:12.808105Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:12.808107Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808109Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.808111Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808112Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:12.808117Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:12.808138Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-08T22:12:12.808162Z node 17 :PERSQUEUE INFO: partition_init.cpp:1142: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [17:7581613421510011785:2369]
2025-12-08T22:12:12.808633Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:82: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-08T22:12:12.808637Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:62: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-12-08T22:12:12.808640Z node 17 :PERSQUEUE INFO: partition.cpp:693: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [17:7581613421510011785:2369]
2025-12-08T22:12:12.808643Z node 17 :PERSQUEUE DEBUG: partition.cpp:713: [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-08T22:12:12.808894Z node 17 :PERSQUEUE DEBUG: partition.cpp:4385: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-08T22:12:12.808898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:12.808900Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:12.808906Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808909Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.808911Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808913Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:12.808917Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:12.808919Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808920Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.808922Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.808923Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:12.808928Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:12.808985Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.809024Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.809092Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:12.809112Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.813322Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:12.813383Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231932849, TxId 281474976715677
2025-12-08T22:12:12.813390Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:12.813394Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.813397Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.813405Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:12.813429Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:12.813438Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-08T22:12:12.813441Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:12.813480Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231932849, TxId 281474976715677
2025-12-08T22:12:12.813490Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:12.813492Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.813493Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.813497Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:12.813510Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:12.813511Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-08T22:12:12.813513Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:12.813531Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231932849, TxId 281474976715677
2025-12-08T22:12:12.813533Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:12.813535Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:12.813536Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.813539Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:12.813549Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:12.813551Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-08T22:12:12.813553Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:12.813568Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:12.813576Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:12.813581Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:12.815808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:12.815896Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:12.815902Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:12.815906Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.815907Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.815909Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.815911Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:12.815918Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.815922Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:12.815959Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:12.815961Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:12.815962Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.815964Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.815965Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.815966Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:12.815968Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.815971Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:12.816010Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:12.816012Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:12.816013Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.816014Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.816015Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.816016Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:12.816018Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:12.816145Z 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-08T22:12:12.816161Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-08T22:12:12.816209Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:12.828095Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:12.828132Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:12.833032Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:12.840461Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:124: TxId: 281474976715678. Ctx: { TraceId: 01kc00bdbg9rt1zx47kpjhnqdf, Database: , SessionId: ydb://session/3?node_id=16&id=YzA2YTYwNTItYmQ3YzNkYzMtNjc5MWEyMGUtZjhhMjA5MmU=, PoolId: , DatabaseId: /Root, IsStreamingQuery: 0}. Database not set, use /Root
===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: 281474976715677
    CreateStep: 1765231932849
    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: 281474976715677
    CreateStep: 1765231932849
    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:19476
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-08T22:12:12.851150Z 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-08T22:12:12.907020Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:12.907037Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.907040Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.907045Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.907048Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:12.907070Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:12.907081Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.907089Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.907091Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.907093Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:12.909052Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:12.909064Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.909067Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:12.909070Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:12.909073Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.007845Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.007864Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.007867Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.007872Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.007874Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.007895Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.007898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.007899Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.007906Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.007907Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.009955Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.009966Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.009968Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.009971Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.009973Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.109180Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.109208Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.109211Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.109214Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.109216Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.109236Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.109238Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.109240Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.109242Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.109247Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.113803Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.113820Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.113823Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.113827Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.113829Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.210072Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.210092Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.210095Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.210099Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.210101Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.210123Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.210125Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.210126Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.210128Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.210130Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.213972Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.213986Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.213988Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.213992Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.213994Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.310033Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.310052Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.310055Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.310059Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.310062Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.310528Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.310532Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.310535Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.310538Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.310541Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.314364Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.314381Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.314383Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.314386Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.314388Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
CallPersQueueGRPC request to localhost:19476
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-08T22:12:13.358319Z 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-08T22:12:13.410316Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.410331Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.410334Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.410337Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.410339Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.410757Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.410762Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.410763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.410765Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.410766Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.414348Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.414366Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.414369Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.414372Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.414375Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.510898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.510922Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.510926Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.510931Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.510934Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.520665Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.520688Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.520691Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.520695Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.520698Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.520718Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.520721Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.520723Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.520726Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.520728Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.611015Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.611044Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.611047Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.611051Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.611055Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.627078Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.627108Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.627110Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.627113Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.627115Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.627134Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.627137Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.627138Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.627140Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.627142Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.712393Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.712416Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.712419Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.712423Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.712425Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.727235Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.727263Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.727266Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.727269Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.727272Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.727311Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.727313Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.727316Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.727318Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.727319Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.812722Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.812740Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.812742Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.812746Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.812749Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.827709Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.827727Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.827735Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.827739Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.827741Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.827761Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.827763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.827764Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.827766Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.827767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
CallPersQueueGRPC request to localhost:19476
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-08T22:12:13.861867Z 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-08T22:12:13.870155Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:141: new alter topic request
2025-12-08T22:12:13.870992Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterPersQueueGroup, opId: 281474976715682: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-08T22:12:13.883583Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1517: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-12-08T22:12:13.883623Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:13.888560Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:13.892401Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:13.895803Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:13.895911Z node 17 :PERSQUEUE DEBUG: partition.cpp:1261: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.895916Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.895920Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:13.895931Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895933Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.895935Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895938Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.895960Z node 17 :PERSQUEUE DEBUG: partition.cpp:1261: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.895962Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.895964Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:13.895968Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895969Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.895971Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895972Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.895976Z node 17 :PERSQUEUE DEBUG: partition.cpp:1261: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.895979Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.895981Z node 17 :PERSQUEUE DEBUG: partition.cpp:2373: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-08T22:12:13.895985Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895986Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.895987Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.895989Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.896043Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:13.898492Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:13.898542Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.898546Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.898549Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.898550Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.898555Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:13.898583Z node 17 :PERSQUEUE DEBUG: partition.cpp:3746: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug reinit with generation 2 done
2025-12-08T22:12:13.898585Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.898588Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-08T22:12:13.898601Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.898644Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.898645Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.898647Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.898648Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.898652Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:13.898664Z node 17 :PERSQUEUE DEBUG: partition.cpp:3746: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug reinit with generation 2 done
2025-12-08T22:12:13.898666Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.898668Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-08T22:12:13.898671Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.898697Z node 17 :PERSQUEUE DEBUG: partition.cpp:1401: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765231933941, TxId 281474976715682
2025-12-08T22:12:13.898699Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.898701Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.898702Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.898705Z node 17 :PERSQUEUE DEBUG: partition.cpp:2437: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-08T22:12:13.898716Z node 17 :PERSQUEUE DEBUG: partition.cpp:3746: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug reinit with generation 2 done
2025-12-08T22:12:13.898718Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.898719Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-08T22:12:13.898721Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.898740Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.898749Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.898753Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.900452Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.900461Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.900464Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.902166Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:486: [72075186224037892][Partition][0][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-08T22:12:13.902736Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.902755Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.902759Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.902760Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.902763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.902766Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.902775Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.902789Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:486: [72075186224037892][Partition][1][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-08T22:12:13.903070Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.903072Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.903074Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.903076Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.903077Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.903079Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.903081Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.903086Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:486: [72075186224037892][Partition][2][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-08T22:12:13.903359Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.903362Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.903363Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.903365Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.903367Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.903368Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.903371Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.903561Z 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-08T22:12:13.903566Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:911: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-08T22:12:13.903629Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:13.906496Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:13.906524Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3569: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-08T22:12:13.911270Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1043: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-08T22:12:13.912709Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-08T22:12:13.912724Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-08T22:12:13.913084Z 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-08T22:12:13.913117Z 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]:52352
2025-12-08T22:12:13.913119Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-12-08T22:12:13.913123Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:52352 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-08T22:12:13.913128Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-08T22:12:13.913680Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-08T22:12:13.913716Z 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-08T22:12:13.913718Z 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-08T22:12:13.913720Z 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-08T22:12:13.913725Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581613426151859506:2482] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-08T22:12:13.913973Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859507:2482] connected; active server actors: 1
2025-12-08T22:12:13.914051Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581613426151859506:2482] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-12-08T22:12:13.914059Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581613426151859506:2482] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-12-08T22:12:13.914073Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-12-08T22:12:13.914251Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859507:2482] disconnected; active server actors: 1
2025-12-08T22:12:13.914256Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859507:2482] disconnected no session
2025-12-08T22:12:13.914550Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-08T22:12:13.914792Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.914811Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.914814Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.914817Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.914819Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.914853Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613426151859511:2482], now have 1 active actors on pipe
2025-12-08T22:12:13.914876Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.914884Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.914918Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 generated for partition 2 topic 'rt3.dc1--topic1' owner 9c55746f-117dc4ca-ec83778b-ea4e5768
2025-12-08T22:12:13.914932Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.914935Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.914940Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.914942Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.914947Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.914957Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.914964Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-08T22:12:13.914967Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.914977Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-12-08T22:12:13.914996Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.915996Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.916012Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.916106Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.916725Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 2 MaxSeqNo: 0 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0
2025-12-08T22:12:13.917614Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-08T22:12:13.917700Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-08T22:12:13.918188Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-08T22:12:13.918264Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-08T22:12:13.918715Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-08T22:12:13.918724Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-08T22:12:13.918886Z 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-08T22:12:13.918912Z 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]:52352
2025-12-08T22:12:13.918914Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-12-08T22:12:13.918919Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:52352 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-08T22:12:13.918922Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-08T22:12:13.919400Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-08T22:12:13.919435Z 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-08T22:12:13.919438Z 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-08T22:12:13.919439Z 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-08T22:12:13.919444Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581613426151859515:2484] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-08T22:12:13.919830Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859516:2484] connected; active server actors: 1
2025-12-08T22:12:13.919902Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581613426151859515:2484] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-12-08T22:12:13.919910Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581613426151859515:2484] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-08T22:12:13.919923Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-08T22:12:13.920123Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859516:2484] disconnected; active server actors: 1
2025-12-08T22:12:13.920128Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859516:2484] disconnected no session
2025-12-08T22:12:13.920388Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-08T22:12:13.920634Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.920651Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.920664Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.920666Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.920674Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613426151859520:2484], now have 1 active actors on pipe
2025-12-08T22:12:13.920679Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.920682Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:13.920712Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 generated for partition 0 topic 'rt3.dc1--topic1' owner 8daa2ad0-b49d56b3-87c496f9-101acb6b
2025-12-08T22:12:13.920720Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.920724Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.920728Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.920730Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.920734Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.920744Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.920746Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-08T22:12:13.920749Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.920764Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-08T22:12:13.920777Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-12-08T22:12:13.920789Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 2
2025-12-08T22:12:13.920795Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.921254Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-08T22:12:13.921267Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-08T22:12:13.921559Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.921571Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:13.921586Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.921589Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.921686Z 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: 2 size 99 offset: -1
2025-12-08T22:12:13.921699Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.921701Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:13.921710Z 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: 3 size 99 offset: -1
2025-12-08T22:12:13.921714Z 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: 3 size 99 offset: -1
2025-12-08T22:12:13.921725Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-08T22:12:13.921740Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.921743Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.921750Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.921753Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.921758Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.921779Z 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-08T22:12:13.921831Z 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-08T22:12:13.921837Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.921839Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-08T22:12:13.921842Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.921885Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,1 HeadOffset 0 endOffset 0 curOffset 1 d0000000002_00000000000000000000_00000_0000000001_00000? size 154 WTime 1765231933921
2025-12-08T22:12:13.921923Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-08T22:12:13.921929Z node 17 :PERSQUEUE DEBUG: partition.cpp:2262: [72075186224037892][Partition][2][StateIdle] Writing. Can't process user action and tx events
2025-12-08T22:12:13.921938Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.921954Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.921964Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 1 size 154
2025-12-08T22:12:13.923226Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 0 MaxSeqNo: 0 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0
2025-12-08T22:12:13.923642Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 0 count 1 size 154 actorID [17:7581613421510011766:2369]
2025-12-08T22:12:13.923675Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.923692Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-08T22:12:13.923698Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.923708Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-08T22:12:13.923719Z 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-08T22:12:13.923755Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.923762Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.923766Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.923769Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.923772Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.923778Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.923783Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-08T22:12:13.923785Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.923789Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.923808Z 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-08T22:12:13.923848Z 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 1 PartNo 0 PackedSize 166 count 1 nextOffset 2 batches 1
2025-12-08T22:12:13.923865Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.923869Z 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-08T22:12:13.923874Z 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 1 PartNo 0 PackedSize 300 count 2 nextOffset 3 batches 1
2025-12-08T22:12:13.923876Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-08T22:12:13.923878Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (2)
2025-12-08T22:12:13.923881Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.923930Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 1,2 HeadOffset 1 endOffset 1 curOffset 3 d0000000002_00000000000000000001_00000_0000000002_00000? size 288 WTime 1765231933922
2025-12-08T22:12:13.923960Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-08T22:12:13.924041Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-08T22:12:13.923953Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.923979Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 2  requestId: <none> cookie: 1
2025-12-08T22:12:13.924005Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.924017Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 1 partNo 0 count 2 size 288
2025-12-08T22:12:13.924080Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][2][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 2 user user offset 0 partno 0 count 1 size 1024000 endOffset 1 max time lag 0ms effective offset 0
2025-12-08T22:12:13.924097Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-08T22:12:13.924099Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-12-08T22:12:13.924122Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:13.924136Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-08T22:12:13.924267Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.924283Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-08T22:12:13.924302Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1765231933921 queuesize 1 startOffset 0
2025-12-08T22:12:13.924306Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.924310Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.924328Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][2][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 1 size 1024000 endOffset 1 max time lag 0ms effective offset 0
2025-12-08T22:12:13.924339Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-08T22:12:13.924341Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-12-08T22:12:13.924346Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:13.924349Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-08T22:12:13.924353Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.924360Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-08T22:12:13.924367Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1765231933921 queuesize 0 startOffset 0
2025-12-08T22:12:13.924437Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-08T22:12:13.924446Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-08T22:12:13.924869Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-08T22:12:13.924998Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-08T22:12:13.925004Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-08T22:12:13.925361Z 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-08T22:12:13.925386Z 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]:52352
2025-12-08T22:12:13.925388Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-12-08T22:12:13.925392Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:52352 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-08T22:12:13.925395Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-08T22:12:13.925417Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-12-08T22:12:13.925198Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.925213Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:13.925277Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 1 count 2 size 288 actorID [17:7581613421510011766:2369]
2025-12-08T22:12:13.925291Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-08T22:12:13.925300Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.925310Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 1 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-08T22:12:13.925602Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.925606Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:13.925620Z 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-08T22:12:13.925623Z 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-08T22:12:13.925637Z 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-08T22:12:13.925644Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-08T22:12:13.925659Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.925661Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.925667Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.925669Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.925673Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-08T22:12:13.925674Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.925678Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.925697Z 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-08T22:12:13.925737Z 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-08T22:12:13.925742Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.925746Z 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-08T22:12:13.925752Z 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-08T22:12:13.925754Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.925757Z 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-08T22:12:13.925762Z 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-08T22:12:13.925764Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-08T22:12:13.925767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][0][StateIdle] Batch completed (3)
2025-12-08T22:12:13.925769Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.925812Z 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 1765231933925
2025-12-08T22:12:13.925832Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:486: [72075186224037892][Partition][2][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-08T22:12:13.925845Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.925851Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-08T22:12:13.925856Z 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-08T22:12:13.925859Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-08T22:12:13.925863Z 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-08T22:12:13.925880Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.925882Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.925884Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.925886Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.925887Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.925907Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.925911Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.925922Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 3 size 409
2025-12-08T22:12:13.925935Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 3  requestId: <none> cookie: 2
2025-12-08T22:12:13.925874Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-08T22:12:13.925918Z 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-08T22:12:13.925920Z 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-08T22:12:13.925921Z 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-08T22:12:13.925925Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581613426151859525:2486] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-08T22:12:13.926440Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-12-08T22:12:13.926706Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859526:2486] connected; active server actors: 1
2025-12-08T22:12:13.926782Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581613426151859525:2486] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-12-08T22:12:13.926786Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581613426151859525:2486] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-12-08T22:12:13.926796Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-12-08T22:12:13.927086Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 3 size 409 actorID [17:7581613421510011766:2369]
2025-12-08T22:12:13.927101Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.927110Z 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-08T22:12:13.927116Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.927122Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-08T22:12:13.927130Z 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-08T22:12:13.927134Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-08T22:12:13.927138Z 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-08T22:12:13.927141Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-08T22:12:13.927144Z 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-08T22:12:13.927166Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.927171Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.927175Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.927177Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.927180Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927182Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.927184Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927186Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.927192Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.927200Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613426151859531:2486], now have 1 active actors on pipe
2025-12-08T22:12:13.927208Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-08T22:12:13.927236Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [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-08T22:12:13.927250Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:13.927253Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-08T22:12:13.927261Z 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-08T22:12:13.000000Z
2025-12-08T22:12:13.927266Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-08T22:12:13.927275Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.927310Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:13.927325Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1765231933922 queuesize 1 startOffset 0
2025-12-08T22:12:13.927329Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.927332Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.927344Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [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-08T22:12:13.927353Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:13.927354Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-12-08T22:12:13.927359Z 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-08T22:12:13.000000Z
2025-12-08T22:12:13.927361Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-08T22:12:13.927364Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.927376Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:13.927382Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1765231933922 queuesize 0 startOffset 0
2025-12-08T22:12:13.926962Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859526:2486] disconnected; active server actors: 1
2025-12-08T22:12:13.926967Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613426151859526:2486] disconnected no session
2025-12-08T22:12:13.927538Z 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-08T22:12:13.927550Z 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-08T22:12:13.927590Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-12-08T22:12:13.927611Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-12-08T22:12:13.927817Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.927828Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:13.927848Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 generated for partition 1 topic 'rt3.dc1--topic1' owner 8f575fc6-834bc53e-2e73cbb9-5bd5728e
2025-12-08T22:12:13.927855Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.927857Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.927860Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:13.927862Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.927865Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.927873Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:13.927875Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-08T22:12:13.927877Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.927886Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-12-08T22:12:13.927894Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.927895Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927897Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.927898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927899Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.927905Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.927907Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927909Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.927910Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.927912Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.927919Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.928493Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.928504Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:13.928521Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:13.928718Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 1 MaxSeqNo: 0 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0
2025-12-08T22:12:13.929915Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-08T22:12:13.929930Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 grpc read done: success: 0 data: 
2025-12-08T22:12:13.930965Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.929933Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 grpc read failed
2025-12-08T22:12:13.929939Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 grpc closed
2025-12-08T22:12:13.929943Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: 9c55746f-117dc4ca-ec83778b-ea4e5768|6cd9141d-4cb72bd6-7be7be30-10d83c7b_0 is DEAD
2025-12-08T22:12:13.930979Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:13.930007Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-08T22:12:13.930994Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613426151859511:2482] destroyed
2025-12-08T22:12:13.930030Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 grpc read done: success: 0 data: 
2025-12-08T22:12:13.930997Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613426151859520:2484] destroyed
2025-12-08T22:12:13.930032Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 grpc read failed
2025-12-08T22:12:13.930036Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 grpc closed
2025-12-08T22:12:13.930039Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: 8daa2ad0-b49d56b3-87c496f9-101acb6b|22991f6c-c35cef4e-5b64516d-a4c3472f_0 is DEAD
2025-12-08T22:12:13.930129Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-12-08T22:12:13.931012Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-12-08T22:12:13.931019Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:13.931021Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.930191Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-08T22:12:13.931023Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.931659Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-08T22:12:13.931025Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.934891Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-12-08T22:12:13.931027Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:13.931038Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-08T22:12:13.931041Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:13.931043Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.931044Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.931046Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.931047Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:13.931055Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-12-08T22:12:13.931837Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:13.931852Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:13.931867Z 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: 1 size 99 offset: -1
2025-12-08T22:12:13.931872Z 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: 1 size 99 offset: -1
2025-12-08T22:12:13.931876Z 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: 1 size 99 offset: -1
2025-12-08T22:12:13.931884Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:629: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-08T22:12:13.931898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.931900Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.931906Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.931910Z node 17 :PERSQUEUE DEBUG: partition.cpp:2380: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-08T22:12:13.931913Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-08T22:12:13.931915Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.931919Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.931938Z 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-08T22:12:13.931976Z 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-08T22:12:13.931982Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.931985Z 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-08T22:12:13.931991Z 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-08T22:12:13.931994Z node 17 :PERSQUEUE DEBUG: partition.cpp:2444: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-08T22:12:13.931996Z 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-08T22:12:13.932001Z 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 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-12-08T22:12:13.932003Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-08T22:12:13.932006Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][1][StateIdle] Batch completed (3)
2025-12-08T22:12:13.932008Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.932053Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1676: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000001_00000000000000000000_00000_0000000003_00000? size 409 WTime 1765231933931
2025-12-08T22:12:13.932073Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:13.932084Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 3 size 409
2025-12-08T22:12:13.933071Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 0 count 3 size 409 actorID [17:7581613421510011766:2369]
2025-12-08T22:12:13.933087Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:13.933101Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-12-08T22:12:13.934459Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:486: [72075186224037892][Partition][1][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-08T22:12:13.934506Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:13.934517Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-08T22:12:13.934534Z 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-08T22:12:13.934554Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-08T22:12:13.934559Z 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-08T22:12:13.934562Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-08T22:12:13.934566Z 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-08T22:12:13.934626Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.934633Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.934638Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.934642Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:13.934646Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.934648Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:13.934651Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:13.934653Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:13.934664Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-08T22:12:13.934689Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 1
2025-12-08T22:12:13.934745Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][1][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 1 user user offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-08T22:12:13.934767Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:13.934771Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-12-08T22:12:13.934793Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:13.934810Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-08T22:12:13.934838Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-08T22:12:13.934840Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.934882Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:13.934917Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1765231933930 queuesize 1 startOffset 0
2025-12-08T22:12:13.934922Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:915: [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-08T22:12:13.934925Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:957: [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-08T22:12:13.934941Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][1][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-08T22:12:13.934954Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:13.934956Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-12-08T22:12:13.934962Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 1 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:13.934982Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-08T22:12:13.934988Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:13.935004Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:13.935014Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1007: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1765231933930 queuesize 0 startOffset 0
2025-12-08T22:12:13.935668Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-08T22:12:14.014453Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:14.014486Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.014488Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.014492Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.014494Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
Start reads
2025-12-08T22:12:14.030755Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 grpc read done: success: 0 data: 
2025-12-08T22:12:14.030771Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 grpc read failed
2025-12-08T22:12:14.030781Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 grpc closed
2025-12-08T22:12:14.030788Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: 8f575fc6-834bc53e-2e73cbb9-5bd5728e|e8115e84-56d5aa0b-ad2c48a5-f9e712a3_0 is DEAD
2025-12-08T22:12:14.031005Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-12-08T22:12:14.031058Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-12-08T22:12:14.031061Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-12-08T22:12:14.031472Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613426151859531:2486] destroyed
2025-12-08T22:12:14.031499Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-12-08T22:12:14.031507Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:14.031512Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.031514Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.031517Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.031519Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:14.031812Z 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-08T22:12:14.031866Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:941: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 read init: from# ipv6:[::1]:52352, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-08T22:12:14.031965Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 auth for : debug
2025-12-08T22:12:14.033011Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:14.033030Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.033033Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.033036Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.033039Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:14.033057Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:14.033059Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.033061Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.033064Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.033066Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:14.032502Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 Handle describe topics response
2025-12-08T22:12:14.032527Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 auth is DEAD
2025-12-08T22:12:14.032543Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1058: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 auth ok: topics# 1, initDone# 0
2025-12-08T22:12:14.032825Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1229: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 register session: topic# rt3.dc1--topic1
2025-12-08T22:12:14.033206Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613430446826840:2490] connected; active server actors: 1
2025-12-08T22:12:14.033332Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1700: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [16:7581613430446826840:2490] session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.033345Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-12-08T22:12:14.033355Z 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-08T22:12:14.033357Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-12-08T22:12:14.033363Z 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-08T22:12:14.033366Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-12-08T22:12:14.033372Z 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-08T22:12:14.033378Z 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_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=0)
2025-12-08T22:12:14.033383Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1184: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-12-08T22:12:14.033394Z 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-08T22:12:14.033403Z 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_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=0)
2025-12-08T22:12:14.033414Z 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_17291019158374764834_v1" sender [16:7581613430446826837:2490] lock partition 2 for ReadingSession "shared/debug_16_1_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-12-08T22:12:14.033423Z 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_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=1)
2025-12-08T22:12:14.033428Z 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_17291019158374764834_v1" sender [16:7581613430446826837:2490] lock partition 1 for ReadingSession "shared/debug_16_1_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-12-08T22:12:14.033437Z 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_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=2)
2025-12-08T22:12:14.033442Z 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_17291019158374764834_v1" sender [16:7581613430446826837:2490] lock partition 0 for ReadingSession "shared/debug_16_1_17291019158374764834_v1" (Sender=[16:7581613430446826837:2490], Pipe=[16:7581613430446826840:2490], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-12-08T22:12:14.033448Z 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-08T22:12:14.033452Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1400: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.000055s
2025-12-08T22:12:14.033678Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_16_1_17291019158374764834_v1" ClientId: "debug" PipeClient { RawX1: 7581613430446826840 RawX2: 4503668346849722 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-08T22:12:14.033701Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_16_1_17291019158374764834_v1" ClientId: "debug" PipeClient { RawX1: 7581613430446826840 RawX2: 4503668346849722 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-08T22:12:14.033721Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_16_1_17291019158374764834_v1" ClientId: "debug" PipeClient { RawX1: 7581613430446826840 RawX2: 4503668346849722 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-08T22:12:14.033735Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-12-08T22:12:14.033757Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-12-08T22:12:14.033765Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-12-08T22:12:14.034043Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-12-08T22:12:14.034329Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613430446826845:2493], now have 1 active actors on pipe
2025-12-08T22:12:14.034339Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613430446826846:2494], now have 1 active actors on pipe
2025-12-08T22:12:14.034344Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581613430446826847:2495], now have 1 active actors on pipe
2025-12-08T22:12:14.034089Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 got read request: guid# ee9eae09-7439c9c6-d80592fb-c85505a8
2025-12-08T22:12:14.034489Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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:7581613430446826845:2493]
2025-12-08T22:12:14.034503Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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:7581613430446826846:2494]
2025-12-08T22:12:14.034514Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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:7581613430446826847:2495]
2025-12-08T22:12:14.034647Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.034657Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:14.034670Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_17291019158374764834_v1 on pipe: [16:7581613430446826845:2493]
2025-12-08T22:12:14.034692Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.034694Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:14.034699Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_17291019158374764834_v1 on pipe: [16:7581613430446826846:2494]
2025-12-08T22:12:14.034705Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.034707Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:14.034711Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1746: [PQ: 72075186224037892] Created session shared/debug_16_1_17291019158374764834_v1 on pipe: [16:7581613430446826847:2495]
2025-12-08T22:12:14.034724Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:14.034728Z node 17 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034735Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:14.034737Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.034741Z node 17 :PERSQUEUE DEBUG: partition.cpp:2430: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034763Z node 17 :PERSQUEUE DEBUG: partition.cpp:3790: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.034765Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:14.034767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-08T22:12:14.034770Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:14.034801Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:14.034803Z node 17 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034806Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:14.034807Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.034811Z node 17 :PERSQUEUE DEBUG: partition.cpp:2430: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034819Z node 17 :PERSQUEUE DEBUG: partition.cpp:3790: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.034821Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:14.034822Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-08T22:12:14.034844Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:14.034858Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:14.034859Z node 17 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034862Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-08T22:12:14.034863Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.034866Z node 17 :PERSQUEUE DEBUG: partition.cpp:2430: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-08T22:12:14.034874Z node 17 :PERSQUEUE DEBUG: partition.cpp:3790: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.034877Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-08T22:12:14.034878Z node 17 :PERSQUEUE DEBUG: partition.cpp:2299: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-08T22:12:14.034880Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:14.034894Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:14.034902Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:14.034906Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-08T22:12:14.035169Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_17291019158374764834_v1:1 with generation 1
2025-12-08T22:12:14.035178Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_17291019158374764834_v1:2 with generation 1
2025-12-08T22:12:14.035182Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_17291019158374764834_v1:3 with generation 1
2025-12-08T22:12:14.036832Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:14.036882Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:14.036890Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:14.036892Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036894Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.036896Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:14.036917Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-08T22:12:14.036921Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:14.036935Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:14.036938Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:14.036941Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036942Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.036944Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036946Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:14.036949Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-08T22:12:14.036952Z node 17 :PERSQUEUE DEBUG: partition.cpp:2110: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-08T22:12:14.036974Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:566: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-08T22:12:14.036978Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:14.036979Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036980Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.036982Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.036983Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-08T22:12:14.036986Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-08T22:12:14.036997Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-08T22:12:14.037018Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-08T22:12:14.037037Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-08T22:12:14.037360Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933921 CreateTimestampMS: 1765231933917 SizeLag: 308 WriteTimestampEstimateMS: 1765231934032 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-08T22:12:14.037383Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.037398Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933930 CreateTimestampMS: 1765231933929 SizeLag: 409 WriteTimestampEstimateMS: 1765231934026 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-08T22:12:14.037402Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.037414Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933922 CreateTimestampMS: 1765231933923 SizeLag: 409 WriteTimestampEstimateMS: 1765231934032 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-08T22:12:14.037418Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.037437Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 sending to client partition status
2025-12-08T22:12:14.037528Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 sending to client partition status
2025-12-08T22:12:14.037534Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 sending to client partition status
Got start stream event
Got start stream event
Got start stream event
2025-12-08T22:12:14.038209Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-12-08T22:12:14.038254Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.038268Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.038273Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.038285Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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# 1765231933921, sizeLag# 308
2025-12-08T22:12:14.038288Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1TEvPartitionReady. Aval parts: 1
2025-12-08T22:12:14.038300Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 performing read request: guid# 457de391-41df6e41-51fb5f69-2b1e3454, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 369, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-08T22:12:14.038320Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 369 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 457de391-41df6e41-51fb5f69-2b1e3454
2025-12-08T22:12:14.038958Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-12-08T22:12:14.039012Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039019Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039023Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039032Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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# 1765231933930, sizeLag# 409
2025-12-08T22:12:14.039034Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1TEvPartitionReady. Aval parts: 1
2025-12-08T22:12:14.039044Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 performing read request: guid# 4cffcb74-37fbc6e4-fcf37a18-a9859dc3, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-08T22:12:14.039062Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 4cffcb74-37fbc6e4-fcf37a18-a9859dc3
2025-12-08T22:12:14.039330Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-12-08T22:12:14.039382Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039390Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039394Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.039405Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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# 1765231933922, sizeLag# 409
2025-12-08T22:12:14.039408Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1TEvPartitionReady. Aval parts: 1
2025-12-08T22:12:14.039417Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 performing read request: guid# 955a133c-88cd5730-f8ce2ed9-3b023337, 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-08T22:12:14.039429Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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 955a133c-88cd5730-f8ce2ed9-3b023337
2025-12-08T22:12:14.040139Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933921 CreateTimestampMS: 1765231933917 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765231933922 CreateTimestampMS: 1765231933917 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765231933922 CreateTimestampMS: 1765231933917 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 18446744073709551522 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-08T22:12:14.040186Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.040193Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid 457de391-41df6e41-51fb5f69-2b1e3454 has messages 1
2025-12-08T22:12:14.040220Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 read done: guid# 457de391-41df6e41-51fb5f69-2b1e3454, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 386
2025-12-08T22:12:14.040228Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 response to read: guid# 457de391-41df6e41-51fb5f69-2b1e3454
2025-12-08T22:12:14.040322Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 Process answer. Aval parts: 0
2025-12-08T22:12:14.038659Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.038671Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-08T22:12:14.038719Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][2][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 3 size 369 endOffset 3 max time lag 0ms effective offset 0
2025-12-08T22:12:14.038755Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 1, current partition end offset: 3
2025-12-08T22:12:14.038759Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-12-08T22:12:14.038770Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 2 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:14.038774Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 1 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:14.038782Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-08T22:12:14.038797Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 2 blobs
2025-12-08T22:12:14.038814Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-08T22:12:14.038844Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 1 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-08T22:12:14.038868Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:14.039198Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-08T22:12:14.039202Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 1 partno 0 count 2 parts 0 suffix '63'
2025-12-08T22:12:14.039688Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.039693Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-08T22:12:14.039708Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-08T22:12:14.039711Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-08T22:12:14.039746Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [72075186224037892][Partition][1][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 partno 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-12-08T22:12:14.039765Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:14.039768Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-12-08T22:12:14.039774Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:872: [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-08T22:12:14.039787Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1097: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-08T22:12:14.039789Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1113: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-12-08T22:12:14.039797Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-12-08T22:12:13.000000Z
2025-12-08T22:12:14.039805Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-08T22:12:14.039813Z 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-08T22:12:13.000000Z
2025-12-08T22:12:14.039816Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-08T22:12:14.039821Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:14.039854Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:14.039870Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:569: FormAnswer for 1 blobs
2025-12-08T22:12:14.039882Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-08T22:12:14.039903Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:14.039912Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-08T22:12:14.039993Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-08T22:12:14.040011Z 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'
Got data event with total 3 messages, current total messages: 3
Got data event with total 3 messages, current total messages: 6
Got data event with total 3 messages, current total messages: 9
2025-12-08T22:12:14.041222Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-08T22:12:14.041267Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 got read request: guid# 37dbff3e-ed7e7fd1-96f8fdd7-6781d058
2025-12-08T22:12:14.041353Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933930 CreateTimestampMS: 1765231933929 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765231933931 CreateTimestampMS: 1765231933929 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765231933931 CreateTimestampMS: 1765231933929 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-08T22:12:14.041380Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.041386Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid 4cffcb74-37fbc6e4-fcf37a18-a9859dc3 has messages 1
2025-12-08T22:12:14.041458Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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: 1765231933922 CreateTimestampMS: 1765231933923 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765231933922 CreateTimestampMS: 1765231933923 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765231933922 CreateTimestampMS: 1765231933923 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-08T22:12:14.041473Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_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-08T22:12:14.041476Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid 955a133c-88cd5730-f8ce2ed9-3b023337 has messages 1
2025-12-08T22:12:14.041494Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 read done: guid# 4cffcb74-37fbc6e4-fcf37a18-a9859dc3, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 386
2025-12-08T22:12:14.041501Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 response to read: guid# 4cffcb74-37fbc6e4-fcf37a18-a9859dc3
2025-12-08T22:12:14.041566Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 Process answer. Aval parts: 0
2025-12-08T22:12:14.041582Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 read done: guid# 955a133c-88cd5730-f8ce2ed9-3b023337, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 276
2025-12-08T22:12:14.041585Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 response to read: guid# 955a133c-88cd5730-f8ce2ed9-3b023337
2025-12-08T22:12:14.041589Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 Process answer. Aval parts: 0
2025-12-08T22:12:14.043016Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-08T22:12:14.043086Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 got read request: guid# 5e46fae9-38ffb266-754445ee-41fcc6cf
2025-12-08T22:12:14.051058Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read done: success# 0, data# {  }
2025-12-08T22:12:14.051068Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc read failed
2025-12-08T22:12:14.051075Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 grpc closed
2025-12-08T22:12:14.051096Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_16_1_17291019158374764834_v1 is DEAD
2025-12-08T22:12:14.051655Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.051679Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613430446826847:2495] destroyed
2025-12-08T22:12:14.051685Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.051689Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613430446826846:2494] destroyed
2025-12-08T22:12:14.051692Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2256: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.051696Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581613430446826845:2493] destroyed
2025-12-08T22:12:14.051712Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.051715Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.051718Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.052991Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613430446826840:2490] disconnected; active server actors: 1
2025-12-08T22:12:14.053000Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1674: [72075186224037893][rt3.dc1--topic1] pipe [16:7581613430446826840:2490] client debug disconnected session shared/debug_16_1_17291019158374764834_v1
2025-12-08T22:12:14.117397Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-08T22:12:14.117427Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.117431Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.117435Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.117439Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-08T22:12:14.134109Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-08T22:12:14.134129Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.134135Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.134141Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.134144Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-08T22:12:14.134178Z node 17 :PERSQUEUE DEBUG: partition.cpp:2286: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-08T22:12:14.134181Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.134183Z node 17 :PERSQUEUE DEBUG: partition.cpp:2294: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-08T22:12:14.134188Z node 17 :PERSQUEUE DEBUG: partition.cpp:2345: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-08T22:12:14.134192Z node 17 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
