2025-12-04T20:34:55.624377Z node 16 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[16:7580104011463091201:2080];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:34:55.624401Z 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-04T20:34:55.627245Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-04T20:34:55.627498Z node 17 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[17:7580104007578010778:2156];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:34:55.627525Z 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-04T20:34:55.627630Z 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
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/6mdl/003d3a/r3tmp/tmpQtZ4Yr/pdisk_1.dat
2025-12-04T20:34:55.629404Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-04T20:34:55.629471Z 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-04T20:34:55.641238Z node 16 :IMPORT WARN: schemeshard_import.cpp:423: Table profiles were not loaded
2025-12-04T20:34:55.645979Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-04T20:34:55.646003Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-04T20:34:55.647342Z node 16 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 17 Cookie 17
2025-12-04T20:34:55.647569Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 1532, node 16
2025-12-04T20:34:55.654379Z 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/6mdl/003d3a/r3tmp/yandexZGsoHk.tmp
2025-12-04T20:34:55.654391Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/6mdl/003d3a/r3tmp/yandexZGsoHk.tmp
2025-12-04T20:34:55.654462Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/6mdl/003d3a/r3tmp/yandexZGsoHk.tmp
2025-12-04T20:34:55.654487Z node 16 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-04T20:34:55.658068Z INFO: TTestServer started on Port 5091 GrpcPort 1532
TClient is connected to server localhost:5091
PQClient connected to localhost:1532
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-04T20:34:55.674487Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976710657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
2025-12-04T20:34:55.688412Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
waiting...
waiting...
waiting...
2025-12-04T20:34:55.726065Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-04T20:34:55.726098Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-04T20:34:55.727643Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-04T20:34:55.893733Z 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-04T20:34:55.911767Z 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-04T20:34:56.014757Z node 17 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [17:7580104011872978366:2303], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.014757Z node 17 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [17:7580104011872978334:2300], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.014805Z node 17 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015130Z node 17 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [17:7580104011872978372:2305], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015146Z node 17 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015347Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7580104015758059519:2326], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015373Z node 16 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015541Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7580104015758059551:2329], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015546Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7580104015758059552:2330], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.015558Z node 16 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.016137Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976720657:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2025-12-04T20:34:56.016773Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7580104015758059588:2333], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.016794Z node 16 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.016952Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7580104015758059591:2334], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.016971Z node 16 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:34:56.017471Z node 16 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [16:7580104015758059556:2693] txid# 281474976710662, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exists but creating right now (id: [OwnerId: 72057594046644480, LocalPathId: 9], type: EPathTypeResourcePool, state: EPathStateCreate), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
2025-12-04T20:34:56.022004Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:34:56.028575Z node 16 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [16:7580104015758059555:2331], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976720657 completed, doublechecking }
2025-12-04T20:34:56.032999Z node 17 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [17:7580104011872978371:2304], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976720657 completed, doublechecking }
2025-12-04T20:34:56.063210Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:34:56.087347Z node 17 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [17:7580104011872978401:2175] txid# 281474976720658, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 9], type: EPathTypeResourcePool, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
2025-12-04T20:34:56.088319Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710665:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:34:56.092461Z node 17 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:625: Compilation failed, self: [17:7580104011872978408:2309], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:5:17: Error: At function: KiReadTable!
        <main>:5:17: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Versions]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-12-04T20:34:56.093403Z node 17 :KQP_SESSION WARN: kqp_session_actor.cpp:2544: SessionId: ydb://session/3?node_id=17&id=NWM1ZWQ4NmUtNzJkYmVjZjItMTRkNGMyNjQtNDkyNzg1YmE=, ActorId: [17:7580104011872978332:2299], ActorState: ExecuteState, TraceId: 01kbnh6dcd9j2kwmkx503efxjm, ReplyQueryCompileError, status: SCHEME_ERROR, issues: { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 5 column: 17 } message: "At function: KiReadTable!" end_position { row: 5 column: 17 } severity: 1 issues { position { row: 5 column: 17 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Versions]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 5 column: 17 } issue_code: 2003 severity: 1 } } }, remove tx with tx_id: 
2025-12-04T20:34:56.093533Z node 17 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 5 column: 17 } message: "At function: KiReadTable!" end_position { row: 5 column: 17 } severity: 1 issues { position { row: 5 column: 17 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Versions]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 5 column: 17 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
=== 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-04T20:34:56.125164Z node 16 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [16:7580104015758060030:3037] txid# 281474976710667, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 9], type: EPathTypeResourcePool, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
=== CheckClustersList. Subcribe to ClusterTracker from [16:7580104015758060068:3058] 
2025-12-04T20:34:56.626538Z 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-04T20:34:56.629095Z 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-04T20:35:00.624744Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[16:7580104011463091201:2080];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:35:00.624784Z 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-04T20:35:00.627809Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[17:7580104007578010778:2156];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:35:00.627845Z 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:1532
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-04T20:35:01.368107Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1524: 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:1532
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: 281474976710674
  SchemeShardTabletId: 72057594046644480
  PathId: 13
}
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-04T20:35:01.374624Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2959: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-04T20:35:01.375032Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][] pipe [16:7580104037232896940:3273] connected; active server actors: 1
2025-12-04T20:35:01.375322Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1520: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions [1, 2, 0]
2025-12-04T20:35:01.375319Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2991: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-04T20:35:01.375375Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-04T20:35:01.375389Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-04T20:35:01.375406Z node 16 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-04T20:35:01.375426Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:942: [72075186224037893][rt3.dc1--topic1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-12-04T20:35:01.375460Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232896939:3272], now have 1 active actors on pipe
2025-12-04T20:35:01.375603Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.375631Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [17:7580104033347815225:2380], now have 1 active actors on pipe
2025-12-04T20:35:01.375665Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:404: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 16, Generation 1
2025-12-04T20:35:01.375882Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:140: [72075186224037893][rt3.dc1--topic1] BALANCER INIT DONE for rt3.dc1--topic1: (0, 72075186224037892) (1, 72075186224037892) (2, 72075186224037892) 
2025-12-04T20:35:01.376081Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.377548Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897010:3327], now have 1 active actors on pipe
2025-12-04T20:35:01.377878Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-12-04T20:35:01.377895Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-12-04T20:35:01.377900Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-12-04T20:35:01.377914Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-04T20:35:01.377923Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-04T20:35:01.377925Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-04T20:35:01.378023Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-04T20:35:01.378070Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [16:7580104037232897015:2454]
2025-12-04T20:35:01.378863Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-12-04T20:35:01.378873Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-12-04T20:35:01.378878Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [16:7580104037232897015:2454]
2025-12-04T20:35:01.378886Z node 16 :PERSQUEUE DEBUG: partition.cpp:727: [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-04T20:35:01.378895Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.379210Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-12-04T20:35:01.379223Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.379228Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.379239Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.379246Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.379248Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.379251Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.379262Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.379268Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.379269Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.379271Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.379273Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.379281Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.379293Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][1][StateIdle] Process MLP pending events. Count 0
2025-12-04T20:35:01.379322Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-04T20:35:01.379359Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [16:7580104037232897016:2454]
2025-12-04T20:35:01.379865Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-12-04T20:35:01.379873Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-12-04T20:35:01.379875Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [16:7580104037232897016:2454]
2025-12-04T20:35:01.379880Z node 16 :PERSQUEUE DEBUG: partition.cpp:727: [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-04T20:35:01.379884Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.380140Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-12-04T20:35:01.380150Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.380153Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.380160Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.380164Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.380166Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.380168Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.380172Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.380174Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.380176Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.380178Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.380180Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.380185Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.380190Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][2][StateIdle] Process MLP pending events. Count 0
2025-12-04T20:35:01.380209Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-04T20:35:01.380246Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [16:7580104037232897017:2454]
2025-12-04T20:35:01.380744Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-04T20:35:01.380755Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-12-04T20:35:01.380757Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [16:7580104037232897017:2454]
2025-12-04T20:35:01.380761Z node 16 :PERSQUEUE DEBUG: partition.cpp:727: [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-04T20:35:01.380765Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.380998Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-04T20:35:01.381006Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.381009Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.381014Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381015Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.381017Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381019Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.381022Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.381024Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381026Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.381028Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381029Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.381034Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.381039Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][0][StateIdle] Process MLP pending events. Count 0
2025-12-04T20:35:01.381092Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.381136Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.381196Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.381224Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.381842Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.381890Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501426, TxId 281474976710674
2025-12-04T20:35:01.381898Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.381900Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381902Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.381908Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.381926Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.381934Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:35:01.381936Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.381974Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501426, TxId 281474976710674
2025-12-04T20:35:01.381981Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.381983Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.381985Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.381988Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.381997Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.381999Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-04T20:35:01.382002Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.382019Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501426, TxId 281474976710674
2025-12-04T20:35:01.382025Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.382027Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.382029Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.382032Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.382041Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.382047Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-04T20:35:01.382050Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.382071Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.382084Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.382092Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.382638Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.382672Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.382703Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.382714Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.382720Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382722Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.382724Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382725Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.382729Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.382734Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.382748Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.382762Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.382764Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.382766Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382767Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.382769Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382771Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.382773Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.382776Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.382791Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.382803Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.382806Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.382807Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382809Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.382810Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.382812Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.382814Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.382949Z node 16 :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-04T20:35:01.382973Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-04T20:35:01.383016Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.383400Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.383443Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.384111Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
===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: 13
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710674
    CreateStep: 1764880501426
    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: 13
    TotalGroupCount: 3
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 600000...
(TRUNCATED)
GetTopicVersionFromPath:  record Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--topic1"
    PathId: 13
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710674
    CreateStep: 1764880501426
    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: 13
    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: 12
    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:1532
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-04T20:35:01.394865Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1524: 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-04T20:35:01.397358Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:141: new alter topic request
2025-12-04T20:35:01.398121Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterPersQueueGroup, opId: 281474976710676:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_pq.cpp:311)
2025-12-04T20:35:01.400048Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.400104Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1520: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-12-04T20:35:01.400597Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.401703Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.401714Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.401718Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.401727Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401729Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.401732Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401733Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.401745Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.401747Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.401749Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.401754Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401755Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.401757Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401759Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.401764Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.401766Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.401768Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:35:01.401771Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401772Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.401774Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.401775Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.401812Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.402190Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.402223Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.402229Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.402231Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.402232Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.402235Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.402253Z node 16 :PERSQUEUE DEBUG: partition.cpp:3765: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug reinit with generation 2 done
2025-12-04T20:35:01.402258Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.402260Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:35:01.402262Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.402290Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.402295Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.402296Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.402297Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.402298Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.402307Z node 16 :PERSQUEUE DEBUG: partition.cpp:3765: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug reinit with generation 2 done
2025-12-04T20:35:01.402308Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.402309Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-04T20:35:01.402310Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.402323Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880501447, TxId 281474976710676
2025-12-04T20:35:01.402324Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.402325Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.402326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.402328Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:35:01.402352Z node 16 :PERSQUEUE DEBUG: partition.cpp:3765: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug reinit with generation 2 done
2025-12-04T20:35:01.402360Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.402362Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-04T20:35:01.402364Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.402384Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.402397Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.402406Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.402897Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.402933Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.403323Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.403342Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.403344Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403346Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.403348Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403349Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.403357Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.403374Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.403420Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.403575Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.403582Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.403584Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403584Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.403585Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403587Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.403590Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.403594Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.403614Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:35:01.403733Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.403741Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.403742Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403744Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.403746Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.403747Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.403750Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.403913Z node 16 :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 Type: CONSUMER_TYPE_STREAMING } Consumers { Name: "debug" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 2 Type: CONSUMER_TYPE_STREAMING } MonitoringProjectId: ""
2025-12-04T20:35:01.403925Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-04T20:35:01.403971Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.404502Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.404521Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:35:01.405182Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:35:01.406982Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:35:01.407006Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-04T20:35:01.407116Z 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-04T20:35:01.407141Z 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]:38824
2025-12-04T20:35:01.407146Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-12-04T20:35:01.407149Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:38824 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-04T20:35:01.407153Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:35:01.407517Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-04T20:35:01.407552Z 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-04T20:35:01.407559Z 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-04T20:35:01.407561Z 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-04T20:35:01.407567Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580104037232897183:2472] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-04T20:35:01.407773Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897184:2472] connected; active server actors: 1
2025-12-04T20:35:01.407841Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580104037232897183:2472] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-12-04T20:35:01.407850Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580104037232897183:2472] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-12-04T20:35:01.407862Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-12-04T20:35:01.407902Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897184:2472] disconnected.
2025-12-04T20:35:01.407911Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897184:2472] disconnected; active server actors: 1
2025-12-04T20:35:01.407915Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897184:2472] disconnected no session
2025-12-04T20:35:01.407975Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-04T20:35:01.407990Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897187:2472], now have 1 active actors on pipe
2025-12-04T20:35:01.407999Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.408007Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.408027Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 generated for partition 2 topic 'rt3.dc1--topic1' owner f2767bc1-d7ed0284-fb3764b0-1ca094d1
2025-12-04T20:35:01.408038Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.408045Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.408049Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.408050Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.408053Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.408060Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.408062Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-04T20:35:01.408063Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.408074Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-12-04T20:35:01.408086Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.408115Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.408123Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.408134Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.408163Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 2 MaxSeqNo: 0 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0
2025-12-04T20:35:01.408672Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:35:01.408708Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:35:01.408724Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-04T20:35:01.408762Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:35:01.408808Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.408817Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.408825Z 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-04T20:35:01.408828Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:35:01.408836Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.408842Z 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]:38824
2025-12-04T20:35:01.408843Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-12-04T20:35:01.408846Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:38824 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-04T20:35:01.408849Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:35:01.408854Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.408857Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.408932Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.408941Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.408944Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.408949Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:35:01.408959Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.408961Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.408967Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.408968Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.408970Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-04T20:35:01.408972Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.408974Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.408995Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-04T20:35:01.409054Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.409065Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.409069Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-04T20:35:01.409073Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-12-04T20:35:01.409075Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.409078Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-04T20:35:01.409081Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-12-04T20:35:01.409083Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-04T20:35:01.409085Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (3)
2025-12-04T20:35:01.409087Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.409119Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000002_00000000000000000000_00000_0000000003_00000? size 409 WTime 1764880501409
2025-12-04T20:35:01.409129Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-04T20:35:01.409142Z 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-04T20:35:01.409144Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.409149Z 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-04T20:35:01.409150Z 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-04T20:35:01.409153Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 3 size 409
2025-12-04T20:35:01.409153Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580104037232897191:2474] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-04T20:35:01.409309Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897192:2474] connected; active server actors: 1
2025-12-04T20:35:01.409422Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580104037232897191:2474] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-12-04T20:35:01.409431Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580104037232897191:2474] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-04T20:35:01.409438Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-04T20:35:01.409481Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897192:2474] disconnected.
2025-12-04T20:35:01.409491Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897192:2474] disconnected; active server actors: 1
2025-12-04T20:35:01.409494Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897192:2474] disconnected no session
2025-12-04T20:35:01.409508Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-04T20:35:01.409520Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897199:2474], now have 1 active actors on pipe
2025-12-04T20:35:01.409522Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.409524Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.409534Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 generated for partition 0 topic 'rt3.dc1--topic1' owner 60ff8d1d-d420edda-a7a9665e-8764ac42
2025-12-04T20:35:01.409538Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.409539Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.409541Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.409542Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.409544Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.409549Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.409550Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:35:01.409553Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.409560Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-04T20:35:01.409568Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.409591Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.409598Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.409606Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.409622Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 0 MaxSeqNo: 0 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0
2025-12-04T20:35:01.409839Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 0 count 3 size 409 actorID [16:7580104037232896968:2454]
2025-12-04T20:35:01.409855Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.409868Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-12-04T20:35:01.409870Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.409876Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-04T20:35:01.409882Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.409887Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-04T20:35:01.409900Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.409902Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-04T20:35:01.409907Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.409931Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.409943Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.409947Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.409950Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.409952Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.409954Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.409956Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.409958Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.409962Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.409971Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:35:01.410002Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [72075186224037892][Partition][2][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 2 user user offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-04T20:35:01.410016Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.410018Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-12-04T20:35:01.410019Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.410024Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-04T20:35:01.000000Z
2025-12-04T20:35:01.410030Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-04T20:35:01.410040Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-04T20:35:01.410041Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.410069Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.410085Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1764880501408 queuesize 1 startOffset 0
2025-12-04T20:35:01.410091Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.410093Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.410102Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [72075186224037892][Partition][2][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-04T20:35:01.410112Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.410114Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-12-04T20:35:01.410117Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 1 times before, last time 2025-12-04T20:35:01.000000Z
2025-12-04T20:35:01.410126Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-04T20:35:01.410129Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.410136Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.410138Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-04T20:35:01.410146Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1764880501408 queuesize 0 startOffset 0
2025-12-04T20:35:01.410180Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:35:01.410187Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-04T20:35:01.410207Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:35:01.410245Z 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-04T20:35:01.410263Z 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]:38824
2025-12-04T20:35:01.410268Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-12-04T20:35:01.410269Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:35:01.410271Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:38824 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-04T20:35:01.410273Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:35:01.410302Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.410309Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.410318Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:35:01.410331Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.410360Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.410370Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.410389Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.410395Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.410398Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.410409Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:35:01.410432Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.410439Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.410446Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.410454Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.410458Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-04T20:35:01.410464Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.410468Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.410503Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-04T20:35:01.410529Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-04T20:35:01.410560Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.410566Z 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-04T20:35:01.410567Z 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-04T20:35:01.410568Z 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-04T20:35:01.410571Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.410572Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7580104037232897203:2476] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-04T20:35:01.410577Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-04T20:35:01.410582Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.410585Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.410587Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-04T20:35:01.410591Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.410593Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-04T20:35:01.410594Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (3)
2025-12-04T20:35:01.410596Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.410628Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [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 1764880501410
2025-12-04T20:35:01.410654Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.410666Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 3 size 409
2025-12-04T20:35:01.410717Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897204:2476] connected; active server actors: 1
2025-12-04T20:35:01.410849Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7580104037232897203:2476] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-12-04T20:35:01.410861Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7580104037232897203:2476] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-12-04T20:35:01.410871Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-12-04T20:35:01.410904Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897204:2476] disconnected.
2025-12-04T20:35:01.410914Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897204:2476] disconnected; active server actors: 1
2025-12-04T20:35:01.410916Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897204:2476] disconnected no session
2025-12-04T20:35:01.410962Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-04T20:35:01.410979Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897210:2476], now have 1 active actors on pipe
2025-12-04T20:35:01.410984Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.410993Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.411012Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 generated for partition 1 topic 'rt3.dc1--topic1' owner ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1
2025-12-04T20:35:01.411035Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.411041Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.411044Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.411046Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.411048Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.411054Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.411056Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-04T20:35:01.411058Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.411066Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-12-04T20:35:01.411091Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.411122Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.411130Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.411139Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.411178Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 1 MaxSeqNo: 0 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0
2025-12-04T20:35:01.411190Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 3 size 409 actorID [16:7580104037232896968:2454]
2025-12-04T20:35:01.411208Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.411223Z node 16 :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-04T20:35:01.411231Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.411235Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:35:01.411240Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.411245Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:35:01.411254Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.411256Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:35:01.411260Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.411282Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.411286Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.411290Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.411293Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.411295Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.411297Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.411300Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.411301Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.411306Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.411313Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:35:01.411331Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.411331Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.411352Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.411355Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-04T20:35:01.411362Z node 16 :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-04T20:35:01.000000Z
2025-12-04T20:35:01.411371Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-04T20:35:01.411379Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.411395Z node 16 :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-04T20:35:01.411401Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.411412Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1764880501410 queuesize 1 startOffset 0
2025-12-04T20:35:01.411414Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.411417Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.411429Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.411440Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.411442Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-12-04T20:35:01.411446Z node 16 :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 1 times before, last time 2025-12-04T20:35:01.000000Z
2025-12-04T20:35:01.411453Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-04T20:35:01.411456Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.411465Z node 16 :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-04T20:35:01.411468Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.411475Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1764880501410 queuesize 0 startOffset 0
2025-12-04T20:35:01.411753Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 grpc read done: success: 0 data: 
2025-12-04T20:35:01.411767Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 grpc read failed
2025-12-04T20:35:01.411771Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 grpc closed
2025-12-04T20:35:01.411772Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:35:01.411774Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: f2767bc1-d7ed0284-fb3764b0-1ca094d1|a0f8d8e2-8a40fb05-43e86a28-cc3f2037_0 is DEAD
2025-12-04T20:35:01.411816Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:35:01.411855Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.411862Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.411881Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:35:01.411892Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.411917Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.411922Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.411928Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.411931Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.411934Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-04T20:35:01.411939Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:35:01.411947Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.411952Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.411957Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.411963Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:35:01.411965Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-04T20:35:01.411967Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.411970Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.411983Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-04T20:35:01.411986Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:35:01.412008Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.412014Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.412018Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-04T20:35:01.412025Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.412027Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:35:01.412034Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-04T20:35:01.412040Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1446: [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-04T20:35:01.412043Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-04T20:35:01.412044Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (3)
2025-12-04T20:35:01.412046Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.412069Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [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 1764880501411
2025-12-04T20:35:01.412090Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897187:2472] destroyed
2025-12-04T20:35:01.412099Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.412108Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 3 size 409
2025-12-04T20:35:01.412117Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-12-04T20:35:01.412125Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.412128Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.412129Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.412131Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.412132Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.412659Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 0 count 3 size 409 actorID [16:7580104037232896968:2454]
2025-12-04T20:35:01.412676Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.412690Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.412689Z node 16 :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-04T20:35:01.412700Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-04T20:35:01.412704Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.412707Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-04T20:35:01.412709Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.412711Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-04T20:35:01.412713Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:365: [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-04T20:35:01.412727Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.412736Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.412739Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.412742Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.412744Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.412746Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.412750Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.412758Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.412761Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.412771Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:35:01.412794Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.412800Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:35:01.412806Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.412808Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-12-04T20:35:01.412813Z node 16 :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-04T20:35:01.000000Z
2025-12-04T20:35:01.412823Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-04T20:35:01.412829Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.412832Z node 16 :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-04T20:35:01.412848Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.412862Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1764880501411 queuesize 1 startOffset 0
2025-12-04T20:35:01.412886Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:930: [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-04T20:35:01.412890Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:972: [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-04T20:35:01.412900Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.412912Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.412913Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-12-04T20:35:01.412916Z node 16 :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-04T20:35:01.000000Z
2025-12-04T20:35:01.412921Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-04T20:35:01.412930Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.412931Z node 16 :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-04T20:35:01.412942Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.412953Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1764880501411 queuesize 0 startOffset 0
2025-12-04T20:35:01.479298Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.479324Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.479326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.479328Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.479330Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.480259Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.480269Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.480271Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.480274Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.480276Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.481270Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.481281Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.481284Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.481285Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.481287Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
Start reads
2025-12-04T20:35:01.511991Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 grpc read done: success: 0 data: 
2025-12-04T20:35:01.512009Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 grpc read failed
2025-12-04T20:35:01.512019Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 grpc closed
2025-12-04T20:35:01.512033Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: 60ff8d1d-d420edda-a7a9665e-8764ac42|c336612f-4c54ff75-69908108-aa845186_0 is DEAD
2025-12-04T20:35:01.512086Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 grpc read done: success: 0 data: 
2025-12-04T20:35:01.512096Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 grpc read failed
2025-12-04T20:35:01.512102Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 grpc closed
2025-12-04T20:35:01.512107Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: ebdf76cb-9433f2f4-f8ed4ae2-8eb25fc1|759f47f9-339a91da-d92d8e76-93b357d9_0 is DEAD
2025-12-04T20:35:01.512280Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:35:01.512373Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897210:2476] destroyed
2025-12-04T20:35:01.512391Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-12-04T20:35:01.512398Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:35:01.512399Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.512402Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.512404Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.512406Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.512407Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.512434Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-12-04T20:35:01.512440Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-12-04T20:35:01.512449Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897199:2474] destroyed
2025-12-04T20:35:01.512460Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-04T20:35:01.512473Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.512475Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.512482Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.512485Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.512487Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.512641Z 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-04T20:35:01.512689Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:941: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 read init: from# ipv6:[::1]:38824, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-04T20:35:01.512760Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 auth for : debug
2025-12-04T20:35:01.512959Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 Handle describe topics response
2025-12-04T20:35:01.512995Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 auth is DEAD
2025-12-04T20:35:01.512998Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1058: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 auth ok: topics# 1, initDone# 0
2025-12-04T20:35:01.513225Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1229: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 register session: topic# rt3.dc1--topic1
2025-12-04T20:35:01.513477Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897220:2478] connected; active server actors: 1
2025-12-04T20:35:01.513536Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-12-04T20:35:01.513582Z node 17 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1707: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [16:7580104037232897220:2478] session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.513600Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-12-04T20:35:01.513588Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 got read request: guid# ef57039a-5edc003e-d1da4862-4826a2a2
2025-12-04T20:35:01.513612Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=1 (Status=Free, Partitions=[0])
2025-12-04T20:35:01.513620Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-12-04T20:35:01.513625Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=2 (Status=Free, Partitions=[1])
2025-12-04T20:35:01.513630Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-12-04T20:35:01.513636Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=3 (Status=Free, Partitions=[2])
2025-12-04T20:35:01.513643Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:871: [72075186224037893][rt3.dc1--topic1] consumer debug register reading session ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=0)
2025-12-04T20:35:01.513647Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1186: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-12-04T20:35:01.513660Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1258: [72075186224037893][rt3.dc1--topic1] consumer debug balancing. Sessions=1, Families=3, UnreadableFamilies=3 [3 (2), 2 (1), 1 (0), ], RequireBalancing=0 []
2025-12-04T20:35:01.513669Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=0)
2025-12-04T20:35:01.513684Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 3 status Active partitions [2] session "shared/debug_16_1_12319354939965189374_v1" sender [16:7580104037232897217:2478] lock partition 2 for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-12-04T20:35:01.513708Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=1)
2025-12-04T20:35:01.513717Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 2 status Active partitions [1] session "shared/debug_16_1_12319354939965189374_v1" sender [16:7580104037232897217:2478] lock partition 1 for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-12-04T20:35:01.513724Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=2)
2025-12-04T20:35:01.513729Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 1 status Active partitions [0] session "shared/debug_16_1_12319354939965189374_v1" sender [16:7580104037232897217:2478] lock partition 0 for ReadingSession "shared/debug_16_1_12319354939965189374_v1" (Sender=[16:7580104037232897217:2478], Pipe=[16:7580104037232897220:2478], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-12-04T20:35:01.513736Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1325: [72075186224037893][rt3.dc1--topic1] consumer debug start rebalancing. familyCount=3, sessionCount=1, desiredFamilyCount=3, allowPlusOne=0
2025-12-04T20:35:01.513746Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1403: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.000082s
2025-12-04T20:35:01.513938Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_16_1_12319354939965189374_v1" ClientId: "debug" PipeClient { RawX1: 7580104037232897220 RawX2: 4503668346849710 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-04T20:35:01.513970Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_16_1_12319354939965189374_v1" ClientId: "debug" PipeClient { RawX1: 7580104037232897220 RawX2: 4503668346849710 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-04T20:35:01.513985Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-12-04T20:35:01.513994Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_16_1_12319354939965189374_v1" ClientId: "debug" PipeClient { RawX1: 7580104037232897220 RawX2: 4503668346849710 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-04T20:35:01.514004Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-12-04T20:35:01.514007Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-12-04T20:35:01.514038Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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:7580104037232897223:2481]
2025-12-04T20:35:01.514043Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897223:2481], now have 1 active actors on pipe
2025-12-04T20:35:01.514048Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897226:2483], now have 1 active actors on pipe
2025-12-04T20:35:01.514051Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7580104037232897225:2482], now have 1 active actors on pipe
2025-12-04T20:35:01.514052Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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:7580104037232897226:2483]
2025-12-04T20:35:01.514059Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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:7580104037232897225:2482]
2025-12-04T20:35:01.514059Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.514065Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.514077Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_12319354939965189374_v1 on pipe: [16:7580104037232897223:2481]
2025-12-04T20:35:01.514100Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.514106Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_12319354939965189374_v1:1 with generation 1
2025-12-04T20:35:01.514119Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.514127Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_12319354939965189374_v1 on pipe: [16:7580104037232897226:2483]
2025-12-04T20:35:01.514131Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_12319354939965189374_v1:3 with generation 1
2025-12-04T20:35:01.514132Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.514135Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.514139Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_12319354939965189374_v1 on pipe: [16:7580104037232897225:2482]
2025-12-04T20:35:01.514143Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_12319354939965189374_v1:2 with generation 1
2025-12-04T20:35:01.514155Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.514163Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514172Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.514174Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.514178Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514201Z node 16 :PERSQUEUE DEBUG: partition.cpp:3809: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.514208Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.514211Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-04T20:35:01.514214Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.514261Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.514267Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514270Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.514273Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.514276Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514283Z node 16 :PERSQUEUE DEBUG: partition.cpp:3809: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.514287Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.514289Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:35:01.514291Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.514306Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.514311Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514313Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:35:01.514315Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.514317Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-04T20:35:01.514323Z node 16 :PERSQUEUE DEBUG: partition.cpp:3809: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.514325Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:35:01.514326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-04T20:35:01.514328Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.514342Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.514358Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.514366Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:35:01.515430Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.515468Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.515480Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.515482Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515483Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.515485Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515486Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.515492Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.515495Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.515504Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.515509Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.515511Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515513Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.515514Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515515Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.515517Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.515519Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:35:01.515528Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:35:01.515533Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.515534Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515535Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.515536Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.515537Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.515538Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-04T20:35:01.515547Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-04T20:35:01.515560Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-04T20:35:01.515566Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-04T20:35:01.515616Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501410 CreateTimestampMS: 1764880501409 SizeLag: 409 WriteTimestampEstimateMS: 1764880501511 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-04T20:35:01.515626Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501408 CreateTimestampMS: 1764880501408 SizeLag: 409 WriteTimestampEstimateMS: 1764880501480 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-04T20:35:01.515629Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.515634Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.515639Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501411 CreateTimestampMS: 1764880501411 SizeLag: 409 WriteTimestampEstimateMS: 1764880501511 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-04T20:35:01.515641Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.515658Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 sending to client partition status
2025-12-04T20:35:01.515758Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 sending to client partition status
2025-12-04T20:35:01.515776Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 sending to client partition status
Got start stream event
2025-12-04T20:35:01.516076Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
Got start stream event
2025-12-04T20:35:01.516123Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516137Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516140Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516154Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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# 1764880501410, sizeLag# 409
2025-12-04T20:35:01.516158Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1TEvPartitionReady. Aval parts: 1
2025-12-04T20:35:01.516166Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 performing read request: guid# b6f42743-2b4b8021-13fbc52f-ffe73f1d, 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-04T20:35:01.516184Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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 b6f42743-2b4b8021-13fbc52f-ffe73f1d
2025-12-04T20:35:01.516196Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
Got start stream event
2025-12-04T20:35:01.516224Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516232Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516232Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.516234Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516237Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-04T20:35:01.516238Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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# 1764880501408, sizeLag# 409
2025-12-04T20:35:01.516239Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1TEvPartitionReady. Aval parts: 1
2025-12-04T20:35:01.516242Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 performing read request: guid# 2beec3e7-162e1aaa-4fc90a5c-d70924c1, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-04T20:35:01.516256Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 2beec3e7-162e1aaa-4fc90a5c-d70924c1
2025-12-04T20:35:01.516273Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.516280Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-04T20:35:01.516293Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.516319Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.516327Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-12-04T20:35:01.516336Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-12-04T20:35:01.516342Z node 16 :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-04T20:35:01.000000Z
2025-12-04T20:35:01.516352Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-04T20:35:01.516357Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516361Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516362Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516367Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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# 1764880501411, sizeLag# 409
2025-12-04T20:35:01.516367Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1TEvPartitionReady. Aval parts: 1
2025-12-04T20:35:01.516370Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 performing read request: guid# c1f8a429-4058498a-74243993-63b98163, 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-04T20:35:01.516371Z node 16 :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-04T20:35:01.516374Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.516380Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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 c1f8a429-4058498a-74243993-63b98163
2025-12-04T20:35:01.516411Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.516440Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [72075186224037892][Partition][2][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-12-04T20:35:01.516456Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.516461Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-12-04T20:35:01.516469Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-04T20:35:01.516471Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-04T20:35:01.516482Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.516498Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-12-04T20:35:01.000000Z
2025-12-04T20:35:01.516515Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-04T20:35:01.516509Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-04T20:35:01.516544Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.516556Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.516575Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.516586Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:887: [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-04T20:35:01.516601Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-04T20:35:01.516604Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501410 CreateTimestampMS: 1764880501409 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764880501410 CreateTimestampMS: 1764880501409 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764880501410 CreateTimestampMS: 1764880501409 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-04T20:35:01.516607Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-12-04T20:35:01.516620Z node 16 :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-04T20:35:01.000000Z
2025-12-04T20:35:01.516627Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-04T20:35:01.516633Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516635Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:35:01.516637Z node 16 :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-04T20:35:01.516639Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid b6f42743-2b4b8021-13fbc52f-ffe73f1d has messages 1
2025-12-04T20:35:01.516646Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-04T20:35:01.516664Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501408 CreateTimestampMS: 1764880501408 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764880501409 CreateTimestampMS: 1764880501408 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764880501409 CreateTimestampMS: 1764880501408 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-04T20:35:01.516664Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:35:01.516680Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516685Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid 2beec3e7-162e1aaa-4fc90a5c-d70924c1 has messages 1
2025-12-04T20:35:01.516691Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 read done: guid# b6f42743-2b4b8021-13fbc52f-ffe73f1d, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 276
2025-12-04T20:35:01.516698Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 response to read: guid# b6f42743-2b4b8021-13fbc52f-ffe73f1d
2025-12-04T20:35:01.516707Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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: 1764880501411 CreateTimestampMS: 1764880501411 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1764880501411 CreateTimestampMS: 1764880501411 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1764880501411 CreateTimestampMS: 1764880501411 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-04T20:35:01.516719Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_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-04T20:35:01.516724Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid c1f8a429-4058498a-74243993-63b98163 has messages 1
2025-12-04T20:35:01.516766Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 Process answer. Aval parts: 0
2025-12-04T20:35:01.516787Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 read done: guid# 2beec3e7-162e1aaa-4fc90a5c-d70924c1, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 386
2025-12-04T20:35:01.516794Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 response to read: guid# 2beec3e7-162e1aaa-4fc90a5c-d70924c1
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-04T20:35:01.516885Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 Process answer. Aval parts: 0
2025-12-04T20:35:01.516906Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 read done: guid# c1f8a429-4058498a-74243993-63b98163, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 276
2025-12-04T20:35:01.516908Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 response to read: guid# c1f8a429-4058498a-74243993-63b98163
2025-12-04T20:35:01.516950Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 Process answer. Aval parts: 0
2025-12-04T20:35:01.517182Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { read_request { bytes_size: 276 } }
2025-12-04T20:35:01.517224Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 got read request: guid# aff72c19-487bbb8-311e7795-f2e117eb
2025-12-04T20:35:01.517329Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-04T20:35:01.517356Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 got read request: guid# f25a8093-6968a615-33bc2d4d-e42e074f
2025-12-04T20:35:01.517637Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read done: success# 0, data# {  }
2025-12-04T20:35:01.517641Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc read failed
2025-12-04T20:35:01.517648Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 grpc closed
2025-12-04T20:35:01.517658Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_16_1_12319354939965189374_v1 is DEAD
2025-12-04T20:35:01.517767Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.517780Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897226:2483] destroyed
2025-12-04T20:35:01.517784Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.517787Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897225:2482] destroyed
2025-12-04T20:35:01.517789Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.517791Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7580104037232897223:2481] destroyed
2025-12-04T20:35:01.517805Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.517812Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.517815Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.518043Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897220:2478] disconnected.
2025-12-04T20:35:01.518059Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897220:2478] disconnected; active server actors: 1
2025-12-04T20:35:01.518065Z node 17 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1680: [72075186224037893][rt3.dc1--topic1] pipe [16:7580104037232897220:2478] client debug disconnected session shared/debug_16_1_12319354939965189374_v1
2025-12-04T20:35:01.579624Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.579643Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.579646Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.579649Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.579652Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.580597Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.580605Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.580607Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.580610Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.580616Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.581669Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.581681Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.581683Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.581685Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.581687Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.679974Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.679993Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.679995Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.679998Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.679999Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.680959Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.680970Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.680973Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.680976Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.680978Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.681996Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.682003Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.682005Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.682006Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.682008Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:35:01.780333Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-04T20:35:01.780350Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.780353Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.780356Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.780358Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-04T20:35:01.781308Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-04T20:35:01.781315Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.781317Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.781320Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.781321Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-04T20:35:01.782323Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:35:01.782333Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.782335Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:35:01.782337Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:35:01.782338Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
