2025-12-09T13:06:30.966720Z node 16 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[16:7581843877875607621:2157];send_to=[0:7307199536658146131:7762515];
2025-12-09T13:06:30.966740Z 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-09T13:06:30.967896Z node 17 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[17:7581843877317928486:2155];send_to=[0:7307199536658146131:7762515];
2025-12-09T13:06:30.967914Z 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-09T13:06:30.968148Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/d95e/005201/r3tmp/tmpspKTEq/pdisk_1.dat
2025-12-09T13:06:30.968546Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-09T13:06:30.969381Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-09T13:06:30.969449Z 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-09T13:06:30.982419Z node 16 :IMPORT WARN: schemeshard_import.cpp:432: Table profiles were not loaded
2025-12-09T13:06:30.985570Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-09T13:06:30.985595Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-09T13:06:30.986618Z node 16 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 17 Cookie 17
2025-12-09T13:06:30.986846Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 10456, node 16
2025-12-09T13:06:30.992853Z 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/d95e/005201/r3tmp/yandexVTErAM.tmp
2025-12-09T13:06:30.992861Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/d95e/005201/r3tmp/yandexVTErAM.tmp
2025-12-09T13:06:30.992931Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/d95e/005201/r3tmp/yandexVTErAM.tmp
2025-12-09T13:06:30.992953Z node 16 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-09T13:06:30.995263Z INFO: TTestServer started on Port 6687 GrpcPort 10456
TClient is connected to server localhost:6687
PQClient connected to localhost:10456
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-09T13:06:31.008101Z 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-09T13:06:31.014475Z 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-09T13:06:31.068339Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-09T13:06:31.068365Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-09T13:06:31.069992Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-09T13:06:31.221688Z 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-09T13:06:31.245460Z 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-09T13:06:31.259315Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7581843882170575872:2325], 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-09T13:06:31.259333Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7581843882170575877:2328], 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-09T13:06:31.259339Z 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-09T13:06:31.259473Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7581843882170575889:2331], 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-09T13:06:31.259492Z 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-09T13:06:31.260111Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976710662: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-09T13:06:31.260425Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7581843882170575921: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-09T13:06:31.260441Z 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-09T13:06:31.260464Z node 16 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [16:7581843882170575923: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-09T13:06:31.260474Z 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-09T13:06:31.263886Z node 16 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [16:7581843882170575888:2330], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976710662 completed, doublechecking }
2025-12-09T13:06:31.265136Z 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-09T13:06:31.277987Z 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-09T13:06:31.295124Z 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)
=== 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);
=== CheckClustersList. Subcribe to ClusterTracker from [16:7581843882170576402:3059] 
2025-12-09T13:06:31.334936Z node 16 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [16:7581843882170576403:3060] 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 }
2025-12-09T13:06:31.967830Z 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-09T13:06:31.969359Z 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-09T13:06:35.967074Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[16:7581843877875607621:2157];send_to=[0:7307199536658146131:7762515];
2025-12-09T13:06:35.967104Z 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-09T13:06:35.968126Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[17:7581843877317928486:2155];send_to=[0:7307199536658146131:7762515];
2025-12-09T13:06:35.968149Z 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:10456
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-09T13:06:36.516584Z 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:10456
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-09T13:06:36.521330Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2959: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-09T13:06:36.521411Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][] pipe [16:7581843903645413292:3285] connected; active server actors: 1
2025-12-09T13:06:36.521575Z 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-09T13:06:36.521638Z 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-09T13:06:36.521957Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2991: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-09T13:06:36.521967Z 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-09T13:06:36.522005Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-09T13:06:36.522015Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-09T13:06:36.522020Z node 16 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-09T13:06:36.522213Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413291:3284], now have 1 active actors on pipe
2025-12-09T13:06:36.522255Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [17:7581843903087732910:2377], now have 1 active actors on pipe
2025-12-09T13:06:36.522326Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:404: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 16, Generation 1
2025-12-09T13:06:36.522344Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.522593Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.523444Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413357:3335], now have 1 active actors on pipe
2025-12-09T13:06:36.523653Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-12-09T13:06:36.523662Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-12-09T13:06:36.523664Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-12-09T13:06:36.523671Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-09T13:06:36.523674Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-09T13:06:36.523675Z node 16 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-09T13:06:36.523739Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-09T13:06:36.523764Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [16:7581843903645413362:2456]
2025-12-09T13:06:36.524187Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-12-09T13:06:36.524193Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-12-09T13:06:36.524196Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [16:7581843903645413362:2456]
2025-12-09T13:06:36.524200Z 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-09T13:06:36.524205Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.524365Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-12-09T13:06:36.524373Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.524376Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.524383Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524384Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.524386Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524387Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.524394Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.524395Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524396Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.524397Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524398Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.524402Z 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-09T13:06:36.524406Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][1][StateIdle] Process MLP pending events. Count 0
2025-12-09T13:06:36.524423Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-09T13:06:36.524443Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [16:7581843903645413363:2456]
2025-12-09T13:06:36.524708Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-12-09T13:06:36.524713Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-12-09T13:06:36.524714Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [16:7581843903645413363:2456]
2025-12-09T13:06:36.524716Z 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-09T13:06:36.524718Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.524847Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-12-09T13:06:36.524852Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.524854Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.524857Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524858Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.524859Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524860Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.524862Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.524863Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524864Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.524865Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.524866Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.524869Z 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-09T13:06:36.524871Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][2][StateIdle] Process MLP pending events. Count 0
2025-12-09T13:06:36.524891Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-09T13:06:36.524907Z node 16 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [16:7581843903645413364:2456]
2025-12-09T13:06:36.525166Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-09T13:06:36.525171Z node 16 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-12-09T13:06:36.525172Z node 16 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [16:7581843903645413364:2456]
2025-12-09T13:06:36.525174Z 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-09T13:06:36.525176Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.525276Z node 16 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-09T13:06:36.525281Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.525282Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.525285Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525286Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.525286Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525287Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.525289Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.525290Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525290Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.525291Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525292Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.525294Z 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-09T13:06:36.525296Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][0][StateIdle] Process MLP pending events. Count 0
2025-12-09T13:06:36.525324Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.525345Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.525383Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.525395Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.525748Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.525770Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596571, TxId 281474976710674
2025-12-09T13:06:36.525772Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.525774Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525774Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.525777Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.525786Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.525787Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-09T13:06:36.525789Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.525813Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596571, TxId 281474976710674
2025-12-09T13:06:36.525819Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.525820Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525821Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.525822Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.525826Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.525827Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-09T13:06:36.525828Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.525837Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596571, TxId 281474976710674
2025-12-09T13:06:36.525838Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.525840Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.525841Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.525842Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.525846Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.525847Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-09T13:06:36.525848Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.525857Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.525874Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.525880Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.526184Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.526203Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.526226Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.526235Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.526236Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526238Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.526239Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526240Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.526243Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.526246Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.526256Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.526266Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.526270Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.526271Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526272Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.526273Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526273Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.526274Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.526276Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.526284Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.526290Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.526295Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.526296Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526296Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.526297Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.526298Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.526299Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.526368Z 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-09T13:06:36.526387Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-09T13:06:36.526410Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.526673Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.526687Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.526993Z 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: 1765285596571
    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: 1765285596571
    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:10456
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-09T13:06:36.533327Z 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-09T13:06:36.535027Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:141: new alter topic request
2025-12-09T13:06:36.535355Z 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-09T13:06:36.536254Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.536282Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1520: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-12-09T13:06:36.536516Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.542869Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.542877Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.542880Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.542887Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542888Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.542889Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542891Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.542899Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.542900Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.542902Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.542904Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542905Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.542906Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542907Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.542911Z node 16 :PERSQUEUE DEBUG: partition.cpp:1280: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.542913Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.542914Z node 16 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-09T13:06:36.542916Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542917Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.542918Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.542918Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.542943Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.543178Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.543198Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.543203Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.543204Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.543205Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.543207Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.543221Z 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-09T13:06:36.543225Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.543227Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-09T13:06:36.543228Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.543252Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.543256Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.543257Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.543258Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.543259Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.543264Z 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-09T13:06:36.543265Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.543267Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-09T13:06:36.543267Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.543277Z node 16 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1765285596592, TxId 281474976710676
2025-12-09T13:06:36.543281Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.543282Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.543283Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.543285Z node 16 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-09T13:06:36.543290Z 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-09T13:06:36.543291Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.543292Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-09T13:06:36.543293Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.543306Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.543315Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.543319Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.543612Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.543631Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.543840Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.543848Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.543849Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.543850Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.543851Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.543852Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.543855Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.543859Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.543869Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.543976Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.543981Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.543982Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.543982Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.543983Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.543984Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.543985Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.543988Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.543998Z node 16 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-09T13:06:36.544098Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.544102Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.544103Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.544104Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.544104Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.544105Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.544106Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.544184Z 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-09T13:06:36.544189Z node 16 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-09T13:06:36.544212Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.544411Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.544423Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-09T13:06:36.544740Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-09T13:06:36.545913Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-09T13:06:36.545921Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-09T13:06:36.546016Z 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-09T13:06:36.546038Z 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]:59086
2025-12-09T13:06:36.546041Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-12-09T13:06:36.546045Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:59086 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-09T13:06:36.546047Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-09T13:06:36.546335Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-09T13:06:36.546362Z 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-09T13:06:36.546367Z 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-09T13:06:36.546368Z 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-09T13:06:36.546372Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581843903645413528:2473] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-09T13:06:36.546468Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413529:2473] connected; active server actors: 1
2025-12-09T13:06:36.546532Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581843903645413528:2473] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-12-09T13:06:36.546539Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581843903645413528:2473] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-12-09T13:06:36.546547Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-12-09T13:06:36.546567Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413529:2473] disconnected.
2025-12-09T13:06:36.546572Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413529:2473] disconnected; active server actors: 1
2025-12-09T13:06:36.546574Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413529:2473] disconnected no session
2025-12-09T13:06:36.546630Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-09T13:06:36.546640Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413532:2473], now have 1 active actors on pipe
2025-12-09T13:06:36.546647Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.546650Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.546671Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 generated for partition 1 topic 'rt3.dc1--topic1' owner 50f4e13b-c972b8d8-27a20969-91dc00b4
2025-12-09T13:06:36.546679Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.546683Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.546686Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.546687Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.546689Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.546694Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.546696Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-09T13:06:36.546697Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.546706Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-12-09T13:06:36.546717Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.546744Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.546749Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.546757Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.546786Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 1 MaxSeqNo: 0 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0
2025-12-09T13:06:36.547236Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.547292Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.547322Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.547326Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.547336Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-12-09T13:06:36.547346Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.547364Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-09T13:06:36.547369Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-09T13:06:36.547372Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.547376Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.547414Z 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-09T13:06:36.547425Z 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]:59086
2025-12-09T13:06:36.547425Z 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-09T13:06:36.547429Z 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-09T13:06:36.547429Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-12-09T13:06:36.547431Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:59086 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-09T13:06:36.547432Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-09T13:06:36.547438Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.547451Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.547457Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.547462Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.547465Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-09T13:06:36.547466Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.547468Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.547487Z 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-09T13:06:36.547523Z 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-09T13:06:36.547533Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.547542Z 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-09T13:06:36.547550Z 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-09T13:06:36.547557Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-09T13:06:36.547559Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (2)
2025-12-09T13:06:36.547561Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.547590Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-09T13:06:36.547600Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 0,2 HeadOffset 0 endOffset 0 curOffset 2 d0000000001_00000000000000000000_00000_0000000002_00000? size 288 WTime 1765285596547
2025-12-09T13:06:36.547605Z 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-09T13:06:36.547606Z 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-09T13:06:36.547607Z 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-09T13:06:36.547609Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581843903645413536:2475] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-09T13:06:36.547628Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.547642Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 2 size 288
2025-12-09T13:06:36.547681Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413537:2475] connected; active server actors: 1
2025-12-09T13:06:36.547765Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581843903645413536:2475] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-12-09T13:06:36.547772Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581843903645413536:2475] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-12-09T13:06:36.547783Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-12-09T13:06:36.547813Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413537:2475] disconnected.
2025-12-09T13:06:36.547817Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.547818Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413537:2475] disconnected; active server actors: 1
2025-12-09T13:06:36.547820Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413537:2475] disconnected no session
2025-12-09T13:06:36.547846Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-09T13:06:36.547854Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413544:2475], now have 1 active actors on pipe
2025-12-09T13:06:36.547857Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.547861Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.547874Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 generated for partition 2 topic 'rt3.dc1--topic1' owner af0b49b-211290b-2de203c8-631d81a7
2025-12-09T13:06:36.547882Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.547889Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.547892Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.547896Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.547905Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.547910Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.547913Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.547915Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-09T13:06:36.547916Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.547921Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-12-09T13:06:36.547929Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.547933Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.547939Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.547952Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 2  requestId: <none> cookie: 2
2025-12-09T13:06:36.547962Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.547967Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.547971Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.548004Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.548011Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.548019Z 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: 3 size 99 offset: -1
2025-12-09T13:06:36.548035Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 0 count 2 size 288 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.548050Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.548061Z node 16 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Writing. Can't process user action and tx events
2025-12-09T13:06:36.548062Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-09T13:06:36.548070Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.548080Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.548091Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 2 MaxSeqNo: 0 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0
2025-12-09T13:06:36.548095Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.548100Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-09T13:06:36.548106Z 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-09T13:06:36.548114Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-09T13:06:36.548122Z 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-09T13:06:36.548141Z 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-09T13:06:36.548150Z 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-09T13:06:36.548157Z 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-09T13:06:36.548159Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.548161Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.548169Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.548171Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.548174Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.548191Z 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-09T13:06:36.548212Z 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 2 PartNo 0 PackedSize 166 count 1 nextOffset 3 batches 1
2025-12-09T13:06:36.548220Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.548222Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-09T13:06:36.548224Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.548252Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 2,1 HeadOffset 2 endOffset 2 curOffset 3 d0000000001_00000000000000000002_00000_0000000001_00000? size 154 WTime 1765285596548
2025-12-09T13:06:36.548271Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.548285Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 1
2025-12-09T13:06:36.548302Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.548309Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.548313Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 2 partNo 0 count 1 size 154
2025-12-09T13:06:36.548349Z 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 2 max time lag 0ms effective offset 0
2025-12-09T13:06:36.548365Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-09T13:06:36.548370Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-12-09T13:06:36.548383Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.548395Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-09T13:06:36.548402Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.548437Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.548462Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.548477Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.548481Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.548493Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.548499Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.548507Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1765285596547 queuesize 1 startOffset 0
2025-12-09T13:06:36.548512Z 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-09T13:06:36.548516Z 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-09T13:06:36.548528Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-12-09T13:06:36.548540Z 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 2 max time lag 0ms effective offset 0
2025-12-09T13:06:36.548548Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.548552Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-09T13:06:36.548553Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-12-09T13:06:36.548558Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 1 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.548562Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.548564Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-09T13:06:36.548567Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.548572Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.548574Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.548579Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1765285596547 queuesize 0 startOffset 0
2025-12-09T13:06:36.548595Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-09T13:06:36.548601Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-09T13:06:36.548619Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.548633Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.548639Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.548647Z 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-09T13:06:36.548652Z 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-09T13:06:36.548656Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.548657Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.548684Z 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-09T13:06:36.548685Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.548692Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.548693Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.548696Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.548698Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-09T13:06:36.548701Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.548703Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.548704Z 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]:59086
2025-12-09T13:06:36.548705Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-12-09T13:06:36.548707Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:59086 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-09T13:06:36.548709Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-09T13:06:36.548716Z 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-09T13:06:36.548732Z 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-09T13:06:36.548740Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.548743Z 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-09T13:06:36.548747Z 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-09T13:06:36.548749Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-09T13:06:36.548750Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (2)
2025-12-09T13:06:36.548751Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.548772Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,2 HeadOffset 0 endOffset 0 curOffset 2 d0000000002_00000000000000000000_00000_0000000002_00000? size 288 WTime 1765285596548
2025-12-09T13:06:36.548793Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 2 count 1 size 154 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.548805Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.548807Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 2 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-09T13:06:36.548812Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 2 size 288
2025-12-09T13:06:36.548817Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 2  requestId: <none> cookie: 2
2025-12-09T13:06:36.548824Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.548831Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.548837Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-09T13:06:36.548839Z 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-09T13:06:36.548847Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.548851Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.548852Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.548853Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.548854Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.548857Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.548873Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 3  requestId: <none> cookie: 2
2025-12-09T13:06:36.548908Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-09T13:06:36.548924Z 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-09T13:06:36.548928Z 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-09T13:06:36.548929Z 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-09T13:06:36.548932Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7581843903645413553:2477] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-09T13:06:36.548935Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.548941Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.548945Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.548947Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.548952Z 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: 3 size 99 offset: -1
2025-12-09T13:06:36.548959Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.548962Z node 16 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Writing. Can't process user action and tx events
2025-12-09T13:06:36.549017Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413554:2477] connected; active server actors: 1
2025-12-09T13:06:36.549046Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7581843903645413553:2477] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-12-09T13:06:36.549051Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7581843903645413553:2477] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-09T13:06:36.549057Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-09T13:06:36.549086Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413554:2477] disconnected.
2025-12-09T13:06:36.549098Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413554:2477] disconnected; active server actors: 1
2025-12-09T13:06:36.549101Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1696: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413554:2477] disconnected no session
2025-12-09T13:06:36.549124Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413560:2477], now have 1 active actors on pipe
2025-12-09T13:06:36.549131Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 0 count 2 size 288 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.549133Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 16, Generation: 1
2025-12-09T13:06:36.549135Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.549136Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-09T13:06:36.549136Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.549140Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.549147Z node 16 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 generated for partition 0 topic 'rt3.dc1--topic1' owner e5ae2982-61be76d6-20ef7bc8-51fe75a9
2025-12-09T13:06:36.549152Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.549154Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.549155Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.549156Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.549158Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.549160Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.549161Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-09T13:06:36.549162Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.549167Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-09T13:06:36.549174Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.549188Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:492: [72075186224037892][Partition][2][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2025-12-09T13:06:36.549200Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.549206Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-09T13:06:36.549209Z 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-09T13:06:36.549213Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-09T13:06:36.549215Z 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-09T13:06:36.549226Z 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-09T13:06:36.549231Z 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-09T13:06:36.549233Z 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-09T13:06:36.549236Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.549238Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.549240Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.549241Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.549243Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.549252Z 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-09T13:06:36.549269Z 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 2 PartNo 0 PackedSize 166 count 1 nextOffset 3 batches 1
2025-12-09T13:06:36.549274Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.549275Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-09T13:06:36.549276Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.549295Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 2,1 HeadOffset 2 endOffset 2 curOffset 3 d0000000002_00000000000000000002_00000_0000000001_00000? size 154 WTime 1765285596549
2025-12-09T13:06:36.549308Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.549315Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.549317Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.549329Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 1
2025-12-09T13:06:36.549341Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.549344Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.549348Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 2 partNo 0 count 1 size 154
2025-12-09T13:06:36.549371Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.549380Z 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 2 max time lag 0ms effective offset 0
2025-12-09T13:06:36.549388Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-09T13:06:36.549393Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-12-09T13:06:36.549401Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.549410Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-09T13:06:36.549419Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.549421Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 0 MaxSeqNo: 0 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0
2025-12-09T13:06:36.549461Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.549470Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.549479Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1765285596548 queuesize 1 startOffset 0
2025-12-09T13:06:36.549484Z 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-09T13:06:36.549485Z 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-09T13:06:36.549493Z 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 2 max time lag 0ms effective offset 0
2025-12-09T13:06:36.549502Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-09T13:06:36.549506Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-12-09T13:06:36.549509Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 1 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.549510Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-09T13:06:36.549513Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.549514Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.549517Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.549520Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1765285596548 queuesize 0 startOffset 0
2025-12-09T13:06:36.549707Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 2 count 1 size 154 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.549732Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.549732Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 2 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-09T13:06:36.549743Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 grpc read done: success: 0 data: 
2025-12-09T13:06:36.549745Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.549748Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 grpc read failed
2025-12-09T13:06:36.549749Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-09T13:06:36.549752Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 grpc closed
2025-12-09T13:06:36.549754Z 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-09T13:06:36.549754Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: 50f4e13b-c972b8d8-27a20969-91dc00b4|f5da1d98-3316d459-860b293f-d083e4ce_0 is DEAD
2025-12-09T13:06:36.549769Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.549777Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.549778Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.549780Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.549782Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.549786Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.549795Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 3  requestId: <none> cookie: 2
2025-12-09T13:06:36.549811Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.549840Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.549863Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.549893Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-09T13:06:36.549895Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-12-09T13:06:36.549906Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.549911Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-09T13:06:36.549915Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.549923Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413532:2473] destroyed
2025-12-09T13:06:36.549930Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.549932Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.549941Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-12-09T13:06:36.549948Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.549950Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.549955Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.549957Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.549958Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.549963Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-09T13:06:36.549968Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 2
2025-12-09T13:06:36.549970Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.549975Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.549992Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.549997Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.550002Z 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: 2 size 99 offset: -1
2025-12-09T13:06:36.550008Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.550009Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.550011Z 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: 3 size 99 offset: -1
2025-12-09T13:06:36.550014Z 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: 3 size 99 offset: -1
2025-12-09T13:06:36.550017Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.550027Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.550028Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.550030Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.550034Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.550036Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.550047Z 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-09T13:06:36.550065Z 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-09T13:06:36.550071Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.550072Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-09T13:06:36.550074Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.550093Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 0 compactOffset 0,1 HeadOffset 0 endOffset 0 curOffset 1 d0000000000_00000000000000000000_00000_0000000001_00000? size 154 WTime 1765285596549
2025-12-09T13:06:36.550107Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-09T13:06:36.550112Z node 16 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-09T13:06:36.550115Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.550120Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 1 size 154
2025-12-09T13:06:36.550387Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 1 size 154 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.550397Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.550401Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-09T13:06:36.550404Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.550407Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-09T13:06:36.550410Z 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-09T13:06:36.550420Z 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-09T13:06:36.550425Z 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-09T13:06:36.550427Z 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-09T13:06:36.550429Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.550430Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.550431Z node 16 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-09T13:06:36.550433Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-09T13:06:36.550434Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.550435Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.550443Z 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-09T13:06:36.550455Z 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 1 PartNo 0 PackedSize 166 count 1 nextOffset 2 batches 1
2025-12-09T13:06:36.550460Z node 16 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-09T13:06:36.550463Z 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-09T13:06:36.550465Z 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 1 PartNo 0 PackedSize 300 count 2 nextOffset 3 batches 1
2025-12-09T13:06:36.550467Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-09T13:06:36.550468Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (2)
2025-12-09T13:06:36.550469Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.550486Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 0 compactOffset 1,2 HeadOffset 1 endOffset 1 curOffset 3 d0000000000_00000000000000000001_00000_0000000002_00000? size 288 WTime 1765285596550
2025-12-09T13:06:36.550498Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.550504Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 2  requestId: <none> cookie: 1
2025-12-09T13:06:36.550513Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.550520Z node 16 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 1 partNo 0 count 2 size 288
2025-12-09T13:06:36.550521Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.550542Z 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 1 max time lag 0ms effective offset 0
2025-12-09T13:06:36.550552Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-09T13:06:36.550556Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-09T13:06:36.550563Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.550571Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-09T13:06:36.550576Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-09T13:06:36.550606Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.550615Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-09T13:06:36.550622Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1765285596549 queuesize 1 startOffset 0
2025-12-09T13:06:36.550633Z 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-09T13:06:36.550638Z 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-09T13:06:36.550646Z 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 1 max time lag 0ms effective offset 0
2025-12-09T13:06:36.550655Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-09T13:06:36.550658Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-12-09T13:06:36.550661Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 1 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.550663Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-09T13:06:36.550665Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-09T13:06:36.550669Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-09T13:06:36.550669Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-09T13:06:36.550675Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1765285596549 queuesize 0 startOffset 0
2025-12-09T13:06:36.550784Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 1 count 2 size 288 actorID [16:7581843903645413316:2456]
2025-12-09T13:06:36.550792Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.550793Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 1 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-09T13:06:36.550796Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.550799Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-09T13:06:36.550802Z 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-09T13:06:36.550803Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-09T13:06:36.550805Z 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-09T13:06:36.550811Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.550812Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.550813Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.550815Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.550815Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.550817Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.550821Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 3  requestId: <none> cookie: 2
2025-12-09T13:06:36.550830Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-09T13:06:36.624490Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.624505Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.624507Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.624510Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.624513Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.624966Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.624972Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.624977Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.624978Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.624979Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.625481Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.625486Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.625488Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.625489Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.625490Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.650067Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 grpc read done: success: 0 data: 
Start reads
2025-12-09T13:06:36.650079Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 grpc read failed
2025-12-09T13:06:36.650088Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 grpc closed
2025-12-09T13:06:36.650093Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: af0b49b-211290b-2de203c8-631d81a7|c113c223-278c47e0-a00d55b4-e00c3b3b_0 is DEAD
2025-12-09T13:06:36.650186Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 grpc read done: success: 0 data: 
2025-12-09T13:06:36.650196Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 grpc read failed
2025-12-09T13:06:36.650201Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 grpc closed
2025-12-09T13:06:36.650205Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: e5ae2982-61be76d6-20ef7bc8-51fe75a9|f0ba025c-98f05baf-8f738c7-28c71a59_0 is DEAD
2025-12-09T13:06:36.650240Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-12-09T13:06:36.650280Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413544:2475] destroyed
2025-12-09T13:06:36.650300Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-09T13:06:36.650306Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-12-09T13:06:36.650314Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.650317Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.650319Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.650324Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.650326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.650342Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413560:2477] destroyed
2025-12-09T13:06:36.650348Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-09T13:06:36.650356Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.650357Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-12-09T13:06:36.650358Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-12-09T13:06:36.650358Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.650359Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.650361Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.650362Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.650483Z 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-09T13:06:36.650525Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:941: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 read init: from# ipv6:[::1]:59086, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-09T13:06:36.650586Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 auth for : debug
2025-12-09T13:06:36.650714Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 Handle describe topics response
2025-12-09T13:06:36.650747Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 auth is DEAD
2025-12-09T13:06:36.650750Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1058: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 auth ok: topics# 1, initDone# 0
2025-12-09T13:06:36.650916Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1229: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 register session: topic# rt3.dc1--topic1
2025-12-09T13:06:36.651027Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413578:2479] connected; active server actors: 1
2025-12-09T13:06:36.651107Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-12-09T13:06:36.651117Z node 17 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1707: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [16:7581843903645413578:2479] session shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.651133Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-12-09T13:06:36.651132Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 got read request: guid# c5e5c31e-f3dde1a0-8aebf96e-126ee49a
2025-12-09T13:06:36.651141Z 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-09T13:06:36.651148Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-12-09T13:06:36.651152Z 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-09T13:06:36.651156Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-12-09T13:06:36.651166Z 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-09T13:06:36.651176Z 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_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=0)
2025-12-09T13:06:36.651180Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1186: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-12-09T13:06:36.651189Z 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-09T13:06:36.651199Z 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_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=0)
2025-12-09T13:06:36.651209Z 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_9001428137861717446_v1" sender [16:7581843903645413575:2479] lock partition 2 for ReadingSession "shared/debug_16_1_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-12-09T13:06:36.651223Z 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_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=1)
2025-12-09T13:06:36.651229Z 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_9001428137861717446_v1" sender [16:7581843903645413575:2479] lock partition 1 for ReadingSession "shared/debug_16_1_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-12-09T13:06:36.651233Z 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_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=2)
2025-12-09T13:06:36.651236Z 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_9001428137861717446_v1" sender [16:7581843903645413575:2479] lock partition 0 for ReadingSession "shared/debug_16_1_9001428137861717446_v1" (Sender=[16:7581843903645413575:2479], Pipe=[16:7581843903645413578:2479], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-12-09T13:06:36.651243Z 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-09T13:06:36.651246Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1403: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.000052s
2025-12-09T13:06:36.651388Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_16_1_9001428137861717446_v1" ClientId: "debug" PipeClient { RawX1: 7581843903645413578 RawX2: 4503668346849711 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-09T13:06:36.651405Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-12-09T13:06:36.651407Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_16_1_9001428137861717446_v1" ClientId: "debug" PipeClient { RawX1: 7581843903645413578 RawX2: 4503668346849711 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-09T13:06:36.651424Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_16_1_9001428137861717446_v1" ClientId: "debug" PipeClient { RawX1: 7581843903645413578 RawX2: 4503668346849711 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-09T13:06:36.651428Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-12-09T13:06:36.651436Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-12-09T13:06:36.651450Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413580:2482], now have 1 active actors on pipe
2025-12-09T13:06:36.651456Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413583:2483], now have 1 active actors on pipe
2025-12-09T13:06:36.651457Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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:7581843903645413580:2482]
2025-12-09T13:06:36.651461Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.651462Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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:7581843903645413583:2483]
2025-12-09T13:06:36.651465Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.651473Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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:7581843903645413584:2484]
2025-12-09T13:06:36.651474Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_9001428137861717446_v1 on pipe: [16:7581843903645413580:2482]
2025-12-09T13:06:36.651482Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.651483Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.651483Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_9001428137861717446_v1:1 with generation 1
2025-12-09T13:06:36.651484Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_9001428137861717446_v1 on pipe: [16:7581843903645413583:2483]
2025-12-09T13:06:36.651487Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [16:7581843903645413584:2484], now have 1 active actors on pipe
2025-12-09T13:06:36.651488Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.651489Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.651491Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:1747: [PQ: 72075186224037892] Created session shared/debug_16_1_9001428137861717446_v1 on pipe: [16:7581843903645413584:2484]
2025-12-09T13:06:36.651491Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_9001428137861717446_v1:2 with generation 1
2025-12-09T13:06:36.651494Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_9001428137861717446_v1:3 with generation 1
2025-12-09T13:06:36.651499Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.651501Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651506Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.651510Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.651511Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651525Z 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_9001428137861717446_v1
2025-12-09T13:06:36.651530Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.651531Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-09T13:06:36.651533Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.651561Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.651565Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651567Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.651568Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.651570Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651574Z 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_9001428137861717446_v1
2025-12-09T13:06:36.651576Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.651577Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-09T13:06:36.651578Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.651586Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.651590Z node 16 :PERSQUEUE DEBUG: partition.cpp:2385: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651591Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-09T13:06:36.651593Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.651595Z node 16 :PERSQUEUE DEBUG: partition.cpp:2449: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-09T13:06:36.651599Z 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_9001428137861717446_v1
2025-12-09T13:06:36.651600Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-09T13:06:36.651600Z node 16 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-09T13:06:36.651601Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.651613Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.651623Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.651627Z node 16 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-09T13:06:36.652277Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.652301Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.652312Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.652316Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652317Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.652318Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652319Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.652323Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.652326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.652334Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.652340Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.652341Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652341Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.652342Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652343Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.652344Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.652346Z node 16 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-09T13:06:36.652352Z node 16 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-09T13:06:36.652357Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.652358Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652359Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.652360Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.652360Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.652362Z node 16 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-09T13:06:36.652368Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-09T13:06:36.652379Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-09T13:06:36.652385Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-09T13:06:36.652404Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596547 CreateTimestampMS: 1765285596546 SizeLag: 576 WriteTimestampEstimateMS: 1765285596624 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-09T13:06:36.652405Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596548 CreateTimestampMS: 1765285596548 SizeLag: 576 WriteTimestampEstimateMS: 1765285596650 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-09T13:06:36.652410Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652411Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652419Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596549 CreateTimestampMS: 1765285596549 SizeLag: 308 WriteTimestampEstimateMS: 1765285596650 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-09T13:06:36.652420Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652423Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 sending to client partition status
2025-12-09T13:06:36.652481Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 sending to client partition status
2025-12-09T13:06:36.652487Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 sending to client partition status
Got start stream event
Got start stream event
Got start stream event
2025-12-09T13:06:36.652732Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-12-09T13:06:36.652756Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652763Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652768Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652777Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-12-09T13:06:36.652810Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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# 1765285596548, sizeLag# 576
2025-12-09T13:06:36.652814Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1TEvPartitionReady. Aval parts: 1
2025-12-09T13:06:36.652822Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 performing read request: guid# 73458068-e378df1d-7508d21e-49bfc315, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 691, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-09T13:06:36.652836Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652837Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 691 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 73458068-e378df1d-7508d21e-49bfc315
2025-12-09T13:06:36.652848Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652853Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652855Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-12-09T13:06:36.652872Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.652877Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-09T13:06:36.652892Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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# 1765285596547, sizeLag# 576
2025-12-09T13:06:36.652896Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1TEvPartitionReady. Aval parts: 1
2025-12-09T13:06:36.652899Z 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 691 endOffset 3 max time lag 0ms effective offset 0
2025-12-09T13:06:36.652899Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 performing read request: guid# 5d241afa-9ec3d4b2-2ebd7584-63959ad7, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), count# 3, size# 691, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-09T13:06:36.652905Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652917Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)maxCount 3 maxSize 691 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 5d241afa-9ec3d4b2-2ebd7584-63959ad7
2025-12-09T13:06:36.652917Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 2, current partition end offset: 3
2025-12-09T13:06:36.652919Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-12-09T13:06:36.652926Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652928Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 2 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.652929Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.652930Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 2 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.652933Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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# 1765285596549, sizeLag# 308
2025-12-09T13:06:36.652934Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1TEvPartitionReady. Aval parts: 1
2025-12-09T13:06:36.652934Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-09T13:06:36.652938Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 performing read request: guid# 271aa6d4-115933cf-4c8b3e3d-d006ca39, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), count# 3, size# 369, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-09T13:06:36.652942Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.652943Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.652944Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 2 partno 0 count 1 parts 0 suffix '63'
2025-12-09T13:06:36.652945Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-09T13:06:36.652948Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)maxCount 3 maxSize 369 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 271aa6d4-115933cf-4c8b3e3d-d006ca39
2025-12-09T13:06:36.652951Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 2 blobs
2025-12-09T13:06:36.652961Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.652972Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 2 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-09T13:06:36.652990Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-09T13:06:36.652996Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-09T13:06:36.653002Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.653027Z 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 691 endOffset 3 max time lag 0ms effective offset 0
2025-12-09T13:06:36.653041Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 2, current partition end offset: 3
2025-12-09T13:06:36.653045Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-12-09T13:06:36.653050Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 2 parts_count 0 source 1 size 288 accessed 2 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.653053Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 2 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.653060Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-09T13:06:36.653063Z 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 369 endOffset 3 max time lag 0ms effective offset 0
2025-12-09T13:06:36.653064Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596548 CreateTimestampMS: 1765285596548 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765285596548 CreateTimestampMS: 1765285596548 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765285596549 CreateTimestampMS: 1765285596548 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 174 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-09T13:06:36.653067Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.653069Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 2 partno 0 count 1 parts 0 suffix '63'
2025-12-09T13:06:36.653075Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 1, current partition end offset: 3
2025-12-09T13:06:36.653079Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-12-09T13:06:36.653081Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 2 blobs
2025-12-09T13:06:36.653085Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.653090Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 2 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-09T13:06:36.653091Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.653095Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid 73458068-e378df1d-7508d21e-49bfc315 has messages 1
2025-12-09T13:06:36.653099Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 2 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.653101Z node 16 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 1 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-09T13:06:36.000000Z
2025-12-09T13:06:36.653106Z node 16 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-09T13:06:36.653108Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-09T13:06:36.653109Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 read done: guid# 73458068-e378df1d-7508d21e-49bfc315, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 386
2025-12-09T13:06:36.653110Z node 16 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 1 partno 0 count 2 parts 0 suffix '63'
2025-12-09T13:06:36.653113Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.653114Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 response to read: guid# 73458068-e378df1d-7508d21e-49bfc315
2025-12-09T13:06:36.653118Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 2 blobs
2025-12-09T13:06:36.653122Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-09T13:06:36.653126Z node 16 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 1 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-09T13:06:36.653140Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-09T13:06:36.653161Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 Process answer. Aval parts: 0
2025-12-09T13:06:36.653173Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596547 CreateTimestampMS: 1765285596546 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765285596547 CreateTimestampMS: 1765285596546 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765285596548 CreateTimestampMS: 1765285596547 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 174 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-09T13:06:36.653195Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.653202Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid 5d241afa-9ec3d4b2-2ebd7584-63959ad7 has messages 1
2025-12-09T13:06:36.653202Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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: 1765285596549 CreateTimestampMS: 1765285596549 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1765285596550 CreateTimestampMS: 1765285596549 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1765285596550 CreateTimestampMS: 1765285596549 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 18446744073709551522 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-09T13:06:36.653213Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_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-09T13:06:36.653215Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 read done: guid# 5d241afa-9ec3d4b2-2ebd7584-63959ad7, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 386
2025-12-09T13:06:36.653218Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 response to read: guid# 5d241afa-9ec3d4b2-2ebd7584-63959ad7
2025-12-09T13:06:36.653220Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid 271aa6d4-115933cf-4c8b3e3d-d006ca39 has messages 1
2025-12-09T13:06:36.653271Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 Process answer. Aval parts: 0
2025-12-09T13:06:36.653288Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 read done: guid# 271aa6d4-115933cf-4c8b3e3d-d006ca39, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 386
2025-12-09T13:06:36.653290Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 response to read: guid# 271aa6d4-115933cf-4c8b3e3d-d006ca39
2025-12-09T13:06:36.653312Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 Process answer. Aval parts: 0
2025-12-09T13:06:36.653380Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
Got data event with total 2025-12-09T13:06:36.653409Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 got read request: guid# 4a0b5b85-616650ca-7f377dd1-50ea7f14
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-09T13:06:36.653477Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-09T13:06:36.653499Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 got read request: guid# d2f34fa-964501d8-a1aaf86-50e49f6c
2025-12-09T13:06:36.653513Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-12-09T13:06:36.653531Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 got read request: guid# b77e2946-b73ade3-ad1d6a4-bcead68a
2025-12-09T13:06:36.653602Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read done: success# 0, data# {  }
2025-12-09T13:06:36.653608Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc read failed
2025-12-09T13:06:36.653611Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 grpc closed
2025-12-09T13:06:36.653623Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_16_1_9001428137861717446_v1 is DEAD
2025-12-09T13:06:36.653662Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653670Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413584:2484] destroyed
2025-12-09T13:06:36.653673Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653674Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413583:2483] destroyed
2025-12-09T13:06:36.653676Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2257: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653677Z node 16 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [16:7581843903645413580:2482] destroyed
2025-12-09T13:06:36.653684Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653685Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653686Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.653834Z node 17 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1660: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413578:2479] disconnected.
2025-12-09T13:06:36.653843Z node 17 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1671: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413578:2479] disconnected; active server actors: 1
2025-12-09T13:06:36.653845Z node 17 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1680: [72075186224037893][rt3.dc1--topic1] pipe [16:7581843903645413578:2479] client debug disconnected session shared/debug_16_1_9001428137861717446_v1
2025-12-09T13:06:36.724849Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.724867Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.724869Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.724872Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.724874Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.725319Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.725324Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.725326Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.725328Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.725329Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.725835Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.725840Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.725842Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.725843Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.725844Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.825175Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.825190Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.825192Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.825195Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.825197Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.825670Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.825679Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.825682Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.825683Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.825684Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.826191Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.826199Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.826201Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.826203Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.826204Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-09T13:06:36.925556Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-09T13:06:36.925573Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.925575Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.925578Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.925579Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-09T13:06:36.926024Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-09T13:06:36.926030Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.926032Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.926034Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.926035Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-09T13:06:36.926535Z node 16 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-09T13:06:36.926541Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.926546Z node 16 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-09T13:06:36.926548Z node 16 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-09T13:06:36.926549Z node 16 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
