2025-12-23T15:37:26.573908Z node 18 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[18:7587077965330771416:2081];send_to=[0:7307199536658146131:7762515];
2025-12-23T15:37:26.574171Z node 18 :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-23T15:37:26.575944Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-23T15:37:26.576022Z node 18 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-23T15:37:26.576152Z node 19 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[19:7587077968423886508:2154];send_to=[0:7307199536658146131:7762515];
2025-12-23T15:37:26.576175Z node 19 :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;
test_client.cpp: SetPath for PDisk # /home/runner/.ya/build/build_root/75th/0022ec/r3tmp/tmpRI5Z0y/pdisk_1.dat
2025-12-23T15:37:26.578161Z node 19 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-23T15:37:26.578328Z node 19 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-23T15:37:26.596270Z node 18 :IMPORT WARN: schemeshard_import.cpp:440: Table profiles were not loaded
2025-12-23T15:37:26.600868Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(19, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-23T15:37:26.600893Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(19, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-23T15:37:26.602299Z node 18 :HIVE WARN: hive_impl.cpp:814: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 19 Cookie 19
2025-12-23T15:37:26.602549Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(19, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 9668, node 18
2025-12-23T15:37:26.610163Z node 18 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/75th/0022ec/r3tmp/yandexdboAfV.tmp
2025-12-23T15:37:26.610171Z node 18 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/75th/0022ec/r3tmp/yandexdboAfV.tmp
2025-12-23T15:37:26.610227Z node 18 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/75th/0022ec/r3tmp/yandexdboAfV.tmp
2025-12-23T15:37:26.610247Z node 18 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-23T15:37:26.613579Z INFO: TTestServer started on Port 14899 GrpcPort 9668
TClient is connected to server localhost:14899
PQClient connected to localhost:9668
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-23T15:37:26.675888Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(18, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-23T15:37:26.675917Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(18, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-23T15:37:26.677651Z node 18 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(18, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-23T15:37:26.677738Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976720657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
2025-12-23T15:37:26.691723Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
waiting...
waiting...
waiting...
2025-12-23T15:37:26.770511Z node 19 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-23T15:37:26.777415Z node 18 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-23T15:37:26.910095Z node 18 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [18:7587077965330772489:2330], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-23T15:37:26.910116Z node 18 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [18:7587077965330772518:2335], 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-23T15:37:26.910125Z node 18 :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-23T15:37:26.910204Z node 18 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [18:7587077965330772521:2337], 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-23T15:37:26.910220Z node 18 :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-23T15:37:26.910859Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976720662: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-23T15:37:26.910957Z node 18 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [18:7587077965330772551:2339], 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-23T15:37:26.910974Z node 18 :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-23T15:37:26.911023Z node 18 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [18:7587077965330772553:2340], 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-23T15:37:26.911032Z node 18 :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-23T15:37:26.915145Z node 18 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [18:7587077965330772520:2336], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976720662 completed, doublechecking }
2025-12-23T15:37:26.915757Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2025-12-23T15:37:26.929991Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2025-12-23T15:37:26.951203Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720665:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2025-12-23T15:37:26.955961Z node 19 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:625: Compilation failed, self: [19:7587077968423886816:2307], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:5:17: Error: At function: KiReadTable!
        <main>:5:17: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Versions]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-12-23T15:37:26.956748Z node 19 :KQP_SESSION WARN: kqp_session_actor.cpp:2579: SessionId: ydb://session/3?node_id=19&id=ZWIxOWEyMWMtODkxYTc1YjMtODliNDYxYjItOTZkZTYyMTM=, ActorId: [19:7587077968423886776:2300], ActorState: ExecuteState, TraceId: 01kd5xqbn7edxkgkk6km2sdmxc, ReplyQueryCompileError, status: SCHEME_ERROR, issues: { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 5 column: 17 } message: "At function: KiReadTable!" end_position { row: 5 column: 17 } severity: 1 issues { position { row: 5 column: 17 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Versions]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 5 column: 17 } issue_code: 2003 severity: 1 } } }, remove tx with tx_id: 
2025-12-23T15:37:26.956867Z node 19 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 5 column: 17 } message: "At function: KiReadTable!" end_position { row: 5 column: 17 } severity: 1 issues { position { row: 5 column: 17 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Versions]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 5 column: 17 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
=== Init DC: UPSERT INTO `/Root/PQ/Config/V2/Cluster` (name, balancer, local, enabled, weight) VALUES ("dc1", "localhost", true, true, 1000), ("dc2", "dc2.logbroker.yandex.net", false, true, 1000);
2025-12-23T15:37:26.970929Z node 18 :TX_PROXY ERROR: schemereq.cpp:624: Actor# [18:7587077965330772930:3022] txid# 281474976720666, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 9], type: EPathTypeResourcePool, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
=== CheckClustersList. Subcribe to ClusterTracker from [18:7587077965330772983:3048] 
2025-12-23T15:37:27.575857Z node 18 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-23T15:37:27.577888Z node 19 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-23T15:37:31.574101Z node 18 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[18:7587077965330771416:2081];send_to=[0:7307199536658146131:7762515];
2025-12-23T15:37:31.574142Z node 18 :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-23T15:37:31.576126Z node 19 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[19:7587077968423886508:2154];send_to=[0:7307199536658146131:7762515];
2025-12-23T15:37:31.576161Z node 19 :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:9668
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-23T15:37:32.203309Z node 18 :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:9668
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: 281474976720674
  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-23T15:37:32.209459Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2972: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-23T15:37:32.209892Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1657: [72075186224037893][] pipe [18:7587077991100577085:3227] connected; active server actors: 1
2025-12-23T15:37:32.210143Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1521: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions [1, 2, 0]
2025-12-23T15:37:32.210320Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:3004: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-23T15:37:32.210356Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:756: [72075186224037893][rt3.dc1--topic1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-12-23T15:37:32.210386Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-23T15:37:32.210401Z node 18 :PERSQUEUE NOTICE: pq_impl.cpp:926: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-23T15:37:32.210408Z node 18 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-23T15:37:32.210487Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577084:3226], now have 1 active actors on pipe
2025-12-23T15:37:32.210662Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:3568: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-23T15:37:32.210674Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [19:7587077994193691000:2386], now have 1 active actors on pipe
2025-12-23T15:37:32.210676Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:394: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 18, Generation 1
2025-12-23T15:37:32.210704Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:142: [72075186224037893][rt3.dc1--topic1] BALANCER INIT DONE for rt3.dc1--topic1: (0, 72075186224037892) (1, 72075186224037892) (2, 72075186224037892) 
2025-12-23T15:37:32.211411Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:1058: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-23T15:37:32.212887Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577151:3278], now have 1 active actors on pipe
2025-12-23T15:37:32.213159Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-12-23T15:37:32.213168Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-12-23T15:37:32.213170Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-12-23T15:37:32.213177Z node 18 :PERSQUEUE DEBUG: partition.cpp:1324: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-23T15:37:32.213180Z node 18 :PERSQUEUE DEBUG: partition.cpp:1324: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-23T15:37:32.213181Z node 18 :PERSQUEUE DEBUG: partition.cpp:1324: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-23T15:37:32.213252Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-23T15:37:32.213284Z node 18 :PERSQUEUE INFO: partition_init.cpp:1155: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [18:7587077991100577156:2440]
2025-12-23T15:37:32.213717Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-12-23T15:37:32.213724Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-12-23T15:37:32.213727Z node 18 :PERSQUEUE INFO: partition.cpp:712: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [18:7587077991100577156:2440]
2025-12-23T15:37:32.213732Z node 18 :PERSQUEUE DEBUG: partition.cpp:732: [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-23T15:37:32.213737Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.213949Z node 18 :PERSQUEUE DEBUG: partition.cpp:4479: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-12-23T15:37:32.213958Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.213962Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.213970Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.213972Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.213973Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.213974Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.213982Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.213988Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.213990Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.213991Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.213992Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.213997Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.214001Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:85: [72075186224037892][Partition][1][StateIdle] Process MLP pending events. Count 0
2025-12-23T15:37:32.214021Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-23T15:37:32.214060Z node 18 :PERSQUEUE INFO: partition_init.cpp:1155: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [18:7587077991100577157:2440]
2025-12-23T15:37:32.214406Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-12-23T15:37:32.214413Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-12-23T15:37:32.214415Z node 18 :PERSQUEUE INFO: partition.cpp:712: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [18:7587077991100577157:2440]
2025-12-23T15:37:32.214419Z node 18 :PERSQUEUE DEBUG: partition.cpp:732: [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-23T15:37:32.214421Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.214558Z node 18 :PERSQUEUE DEBUG: partition.cpp:4479: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-12-23T15:37:32.214565Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.214567Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.214571Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.214572Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.214573Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.214574Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.214576Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.214577Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.214578Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.214581Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.214582Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.214585Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.214588Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:85: [72075186224037892][Partition][2][StateIdle] Process MLP pending events. Count 0
2025-12-23T15:37:32.214611Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-23T15:37:32.214632Z node 18 :PERSQUEUE INFO: partition_init.cpp:1155: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [18:7587077991100577158:2440]
2025-12-23T15:37:32.214958Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-23T15:37:32.214969Z node 18 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-12-23T15:37:32.214972Z node 18 :PERSQUEUE INFO: partition.cpp:712: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [18:7587077991100577158:2440]
2025-12-23T15:37:32.214975Z node 18 :PERSQUEUE DEBUG: partition.cpp:732: [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-23T15:37:32.214980Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.215139Z node 18 :PERSQUEUE DEBUG: partition.cpp:4479: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-23T15:37:32.215145Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.215147Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.215151Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215152Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.215153Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215154Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.215157Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.215158Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215159Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.215160Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215161Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.215164Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.215166Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:85: [72075186224037892][Partition][0][StateIdle] Process MLP pending events. Count 0
2025-12-23T15:37:32.215217Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.215248Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.215310Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.215327Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252261, TxId 281474976720674
2025-12-23T15:37:32.215333Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.215335Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215335Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.215339Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.215370Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][0][StateIdle] Schedule reply tx done 281474976720674
2025-12-23T15:37:32.215375Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.215376Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-23T15:37:32.215377Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.215403Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252261, TxId 281474976720674
2025-12-23T15:37:32.215408Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.215409Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215409Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.215411Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.215417Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][1][StateIdle] Schedule reply tx done 281474976720674
2025-12-23T15:37:32.215419Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.215420Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-23T15:37:32.215421Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.215451Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252261, TxId 281474976720674
2025-12-23T15:37:32.215456Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.215457Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.215458Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.215459Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.215464Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][2][StateIdle] Schedule reply tx done 281474976720674
2025-12-23T15:37:32.215465Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.215466Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-23T15:37:32.215467Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.215481Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.215492Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.215498Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.216276Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.216308Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.216338Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.216345Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.216347Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216347Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.216348Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216349Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.216351Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.216355Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.216370Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.216379Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.216383Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.216384Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216385Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.216386Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216386Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.216388Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.216390Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.216400Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.216407Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.216408Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.216409Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216410Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.216411Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.216411Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.216413Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.216524Z node 18 :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-23T15:37:32.216541Z node 18 :PERSQUEUE NOTICE: pq_impl.cpp:926: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
===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: 281474976720674
    CreateStep: 1766504252261
    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: 281474976720674
    CreateStep: 1766504252261
    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:9668
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-12-23T15:37:32.226248Z node 18 :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-23T15:37:32.228100Z node 18 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:141: new alter topic request
2025-12-23T15:37:32.228672Z node 18 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterPersQueueGroup, opId: 281474976720676:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_pq.cpp:311)
2025-12-23T15:37:32.230380Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:3568: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-23T15:37:32.230400Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1521: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-12-23T15:37:32.230940Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:1058: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-23T15:37:32.233082Z node 18 :PERSQUEUE DEBUG: partition.cpp:1289: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233096Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233101Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233109Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233110Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233112Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233113Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.233125Z node 18 :PERSQUEUE DEBUG: partition.cpp:1289: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233126Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233128Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233130Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233131Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233132Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233133Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.233137Z node 18 :PERSQUEUE DEBUG: partition.cpp:1289: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233139Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233141Z node 18 :PERSQUEUE DEBUG: partition.cpp:2416: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233143Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233144Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233145Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233147Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.233187Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233193Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233194Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233194Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233196Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233234Z node 18 :PERSQUEUE DEBUG: partition.cpp:3836: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug reinit with generation 2 done
2025-12-23T15:37:32.233240Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][0][StateIdle] Schedule reply tx done 281474976720676
2025-12-23T15:37:32.233241Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.233243Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-23T15:37:32.233244Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.233271Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233276Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233277Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233277Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233279Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233286Z node 18 :PERSQUEUE DEBUG: partition.cpp:3836: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug reinit with generation 2 done
2025-12-23T15:37:32.233286Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][1][StateIdle] Schedule reply tx done 281474976720676
2025-12-23T15:37:32.233289Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.233290Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-23T15:37:32.233292Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.233304Z node 18 :PERSQUEUE DEBUG: partition.cpp:1433: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1766504252282, TxId 281474976720676
2025-12-23T15:37:32.233305Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.233306Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.233307Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.233309Z node 18 :PERSQUEUE DEBUG: partition.cpp:2480: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-23T15:37:32.233316Z node 18 :PERSQUEUE DEBUG: partition.cpp:3836: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug reinit with generation 2 done
2025-12-23T15:37:32.233327Z node 18 :PERSQUEUE DEBUG: partition.cpp:3950: [72075186224037892][Partition][2][StateIdle] Schedule reply tx done 281474976720676
2025-12-23T15:37:32.233330Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.233332Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-23T15:37:32.233333Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.233347Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.233358Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.233374Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.233975Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.234019Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.234331Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.234341Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.234352Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234353Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.234354Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234355Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.234359Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.234365Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.234381Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.234564Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.234571Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.234572Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234573Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.234574Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234574Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.234576Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.234580Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.234594Z node 18 :PERSQUEUE DEBUG: partition_mlp.cpp:122: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-12-23T15:37:32.234788Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.234797Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.234799Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234800Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.234802Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.234803Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.234807Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.234952Z node 18 :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-23T15:37:32.234961Z node 18 :PERSQUEUE NOTICE: pq_impl.cpp:926: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-23T15:37:32.237524Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-23T15:37:32.237535Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-23T15:37:32.237637Z node 18 :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-23T15:37:32.237661Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 1 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37624
2025-12-23T15:37:32.237665Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-12-23T15:37:32.237668Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:37624 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-23T15:37:32.237671Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-23T15:37:32.237992Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-23T15:37:32.238023Z node 18 :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-23T15:37:32.238043Z node 18 :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-23T15:37:32.238045Z node 18 :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-23T15:37:32.238052Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [18:7587077991100577297:2457] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-23T15:37:32.238215Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1657: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577298:2457] connected; active server actors: 1
2025-12-23T15:37:32.238321Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [18:7587077991100577297:2457] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-12-23T15:37:32.238328Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [18:7587077991100577297:2457] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-12-23T15:37:32.238336Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-12-23T15:37:32.238359Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1661: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577298:2457] disconnected.
2025-12-23T15:37:32.238367Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1672: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577298:2457] disconnected; active server actors: 1
2025-12-23T15:37:32.238369Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1697: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577298:2457] disconnected no session
2025-12-23T15:37:32.238428Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 18, Generation: 1
2025-12-23T15:37:32.238443Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577301:2457], now have 1 active actors on pipe
2025-12-23T15:37:32.238450Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.238456Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.238483Z node 18 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 generated for partition 1 topic 'rt3.dc1--topic1' owner a80f97a6-3ceb61b-e832fd34-199325e4
2025-12-23T15:37:32.238495Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.238497Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.238500Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.238501Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.238503Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.238509Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.238511Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-23T15:37:32.238513Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.238522Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-12-23T15:37:32.238534Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.238624Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.238646Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.238672Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.238715Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 1 MaxSeqNo: 0 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0
2025-12-23T15:37:32.239211Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-23T15:37:32.239287Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-23T15:37:32.239314Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-23T15:37:32.239363Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.239365Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-23T15:37:32.239375Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.239383Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-23T15:37:32.239387Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.239387Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-23T15:37:32.239390Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.239405Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-12-23T15:37:32.239416Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 2
2025-12-23T15:37:32.239429Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.239439Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.239467Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.239474Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.239491Z node 18 :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-23T15:37:32.239529Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 2 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37624
2025-12-23T15:37:32.239531Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 2 size 99 offset: -1
2025-12-23T15:37:32.239534Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 2 size 99 offset: -1
2025-12-23T15:37:32.239535Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-12-23T15:37:32.239538Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.239538Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:37624 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-23T15:37:32.239539Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.239541Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-23T15:37:32.239542Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 3 size 99 offset: -1
2025-12-23T15:37:32.239545Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-23T15:37:32.239554Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.239555Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.239562Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.239564Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-23T15:37:32.239565Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.239568Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.239590Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-23T15:37:32.239627Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [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-23T15:37:32.239635Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.239640Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-23T15:37:32.239643Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [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-23T15:37:32.239645Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-23T15:37:32.239647Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (2)
2025-12-23T15:37:32.239648Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.239691Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1704: [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 1766504252239
2025-12-23T15:37:32.239718Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-12-23T15:37:32.239725Z node 18 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][1][StateIdle] Writing. Can't process user action and tx events
2025-12-23T15:37:32.239729Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.239736Z node 18 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 2 size 288
2025-12-23T15:37:32.239844Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-23T15:37:32.239868Z node 18 :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-23T15:37:32.239874Z node 18 :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-23T15:37:32.239876Z node 18 :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-23T15:37:32.239880Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [18:7587077991100577306:2459] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-23T15:37:32.239966Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1657: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577308:2459] connected; active server actors: 1
2025-12-23T15:37:32.240054Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [18:7587077991100577306:2459] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-12-23T15:37:32.240062Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [18:7587077991100577306:2459] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-12-23T15:37:32.240069Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-12-23T15:37:32.240116Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1661: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577308:2459] disconnected.
2025-12-23T15:37:32.240124Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1672: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577308:2459] disconnected; active server actors: 1
2025-12-23T15:37:32.240127Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1697: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577308:2459] disconnected no session
2025-12-23T15:37:32.240153Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 18, Generation: 1
2025-12-23T15:37:32.240164Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577313:2459], now have 1 active actors on pipe
2025-12-23T15:37:32.240169Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.240173Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.240189Z node 18 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 generated for partition 2 topic 'rt3.dc1--topic1' owner 421d93e7-ddbdb1a4-3a414889-5b50de3b
2025-12-23T15:37:32.240198Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.240201Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.240204Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.240206Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.240208Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.240211Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.240213Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-23T15:37:32.240214Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.240220Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-12-23T15:37:32.240227Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.240251Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.240260Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.240315Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.240344Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 1 offset 0 count 2 size 288 actorID [18:7587077991100577109:2440]
2025-12-23T15:37:32.240353Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 2 MaxSeqNo: 0 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0
2025-12-23T15:37:32.240361Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.240378Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.240381Z node 18 :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-23T15:37:32.240388Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-23T15:37:32.240394Z node 18 :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-23T15:37:32.240399Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-23T15:37:32.240411Z node 18 :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-23T15:37:32.240437Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.240446Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.240451Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.240454Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.240456Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.240461Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.240468Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.240470Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.240486Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-23T15:37:32.240519Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [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-23T15:37:32.240527Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.240529Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-23T15:37:32.240531Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.240567Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1704: [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 1766504252240
2025-12-23T15:37:32.240592Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.240606Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 2  requestId: <none> cookie: 1
2025-12-23T15:37:32.240627Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.240631Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.240640Z node 18 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 2 partNo 0 count 1 size 154
2025-12-23T15:37:32.240683Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [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-23T15:37:32.240701Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-23T15:37:32.240709Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-12-23T15:37:32.240724Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: 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-23T15:37:32.000000Z
2025-12-23T15:37:32.240741Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-23T15:37:32.240764Z node 18 :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-23T15:37:32.240813Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.240826Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-23T15:37:32.240845Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1766504252239 queuesize 1 startOffset 0
2025-12-23T15:37:32.240851Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.240855Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.240870Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [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-23T15:37:32.240878Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-23T15:37:32.240882Z node 18 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-23T15:37:32.240886Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 288 count 2 last offset 0, current partition end offset: 2
2025-12-23T15:37:32.240888Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-12-23T15:37:32.240893Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: 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-23T15:37:32.000000Z
2025-12-23T15:37:32.240896Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-23T15:37:32.240899Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.240901Z node 18 :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-23T15:37:32.240905Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-23T15:37:32.240911Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1766504252239 queuesize 0 startOffset 0
2025-12-23T15:37:32.240920Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-23T15:37:32.240944Z node 18 :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-23T15:37:32.240965Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 3 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37624
2025-12-23T15:37:32.240968Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-23T15:37:32.240970Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-12-23T15:37:32.240972Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:37624 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-12-23T15:37:32.240974Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-23T15:37:32.241021Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-23T15:37:32.241034Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-23T15:37:32.241047Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241050Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.241057Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241061Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.241072Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-12-23T15:37:32.241078Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 2
2025-12-23T15:37:32.241083Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.241088Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.241122Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241128Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.241138Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 2 size 99 offset: -1
2025-12-23T15:37:32.241146Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241148Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.241152Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 3 size 99 offset: -1
2025-12-23T15:37:32.241155Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 3 size 99 offset: -1
2025-12-23T15:37:32.241158Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-23T15:37:32.241166Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.241168Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.241173Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.241174Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.241177Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.241191Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-23T15:37:32.241213Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [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-23T15:37:32.241220Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.241222Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-23T15:37:32.241225Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.241228Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-23T15:37:32.241245Z node 18 :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-23T15:37:32.241251Z node 18 :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-23T15:37:32.241253Z node 18 :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-23T15:37:32.241257Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [18:7587077991100577321:2461] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-12-23T15:37:32.241259Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1704: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,1 HeadOffset 0 endOffset 0 curOffset 1 d0000000002_00000000000000000000_00000_0000000001_00000? size 154 WTime 1766504252241
2025-12-23T15:37:32.241275Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-12-23T15:37:32.241282Z node 18 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][2][StateIdle] Writing. Can't process user action and tx events
2025-12-23T15:37:32.241308Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.241322Z node 18 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 1 size 154
2025-12-23T15:37:32.241332Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 1 offset 2 count 1 size 154 actorID [18:7587077991100577109:2440]
2025-12-23T15:37:32.241359Z node 18 :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-23T15:37:32.241361Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.241370Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.241374Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-12-23T15:37:32.241379Z node 18 :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-23T15:37:32.241367Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1657: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577322:2461] connected; active server actors: 1
2025-12-23T15:37:32.241392Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.241400Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.241401Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.241403Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.241405Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.241408Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.241418Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 3  requestId: <none> cookie: 2
2025-12-23T15:37:32.241427Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.241483Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [18:7587077991100577321:2461] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-12-23T15:37:32.241491Z node 18 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [18:7587077991100577321:2461] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-23T15:37:32.241500Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-23T15:37:32.241561Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1661: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577322:2461] disconnected.
2025-12-23T15:37:32.241568Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1672: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577322:2461] disconnected; active server actors: 1
2025-12-23T15:37:32.241579Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 18, Generation: 1
2025-12-23T15:37:32.241571Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1697: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577322:2461] disconnected no session
2025-12-23T15:37:32.241598Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577328:2461], now have 1 active actors on pipe
2025-12-23T15:37:32.241610Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241614Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.241630Z node 18 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 generated for partition 0 topic 'rt3.dc1--topic1' owner d5127cc4-bb08ac10-a694be22-741fe156
2025-12-23T15:37:32.241638Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.241640Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.241643Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.241644Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.241646Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.241651Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.241652Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-23T15:37:32.241653Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.241660Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-23T15:37:32.241667Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.241720Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.241728Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.241740Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.241766Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 0 MaxSeqNo: 0 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0
2025-12-23T15:37:32.241837Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 2 offset 0 count 1 size 154 actorID [18:7587077991100577109:2440]
2025-12-23T15:37:32.241850Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.241864Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 99 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.241866Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63' size 154
2025-12-23T15:37:32.241870Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-23T15:37:32.241874Z node 18 :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-23T15:37:32.241885Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.241888Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.241890Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.241892Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.241893Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.241896Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.241898Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-23T15:37:32.241899Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.241901Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.241912Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-23T15:37:32.241929Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 1 PartNo 0 PackedSize 166 count 1 nextOffset 2 batches 1
2025-12-23T15:37:32.241936Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.241940Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-23T15:37:32.241943Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 1 PartNo 0 PackedSize 300 count 2 nextOffset 3 batches 1
2025-12-23T15:37:32.241944Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-23T15:37:32.241945Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (2)
2025-12-23T15:37:32.241947Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.241969Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1704: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 1,2 HeadOffset 1 endOffset 1 curOffset 3 d0000000002_00000000000000000001_00000_0000000002_00000? size 288 WTime 1766504252241
2025-12-23T15:37:32.241985Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.241994Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 2  requestId: <none> cookie: 1
2025-12-23T15:37:32.242004Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.242013Z node 18 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 1 partNo 0 count 2 size 288
2025-12-23T15:37:32.242022Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.242063Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][2][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 2 user user offset 0 partno 0 count 1 size 1024000 endOffset 1 max time lag 0ms effective offset 0
2025-12-23T15:37:32.242079Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-23T15:37:32.242083Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-12-23T15:37:32.242097Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 0 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.242115Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-23T15:37:32.242146Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-23T15:37:32.242229Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.242249Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-23T15:37:32.242272Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1766504252241 queuesize 1 startOffset 0
2025-12-23T15:37:32.242276Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 grpc read done: success: 0 data: 
2025-12-23T15:37:32.242279Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 grpc read failed
2025-12-23T15:37:32.242281Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.242285Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.242311Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][2][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 1 size 1024000 endOffset 1 max time lag 0ms effective offset 0
2025-12-23T15:37:32.242350Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 154 count 1 last offset 0, current partition end offset: 1
2025-12-23T15:37:32.242361Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-12-23T15:37:32.242368Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 1 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.242375Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-23T15:37:32.242381Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.242385Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-23T15:37:32.242388Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:824: session v1 closed cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0
2025-12-23T15:37:32.242391Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-23T15:37:32.242396Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: a80f97a6-3ceb61b-e832fd34-199325e4|8ef27c99-1006c44c-7b96c17d-b65f34c1_0 is DEAD
2025-12-23T15:37:32.242399Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1766504252241 queuesize 0 startOffset 0
2025-12-23T15:37:32.242413Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-23T15:37:32.242490Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-23T15:37:32.242504Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-12-23T15:37:32.242523Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.242531Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.242540Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577301:2457] destroyed
2025-12-23T15:37:32.242556Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-12-23T15:37:32.242563Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.242565Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.242567Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.242569Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.242570Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.242577Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-23T15:37:32.242588Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.242623Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.242632Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.242642Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-23T15:37:32.242649Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-23T15:37:32.242652Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2023: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-12-23T15:37:32.242691Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-23T15:37:32.242705Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.242711Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.242715Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.242717Z node 18 :PERSQUEUE DEBUG: partition.cpp:2423: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-23T15:37:32.242720Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 3, PendingWrites: 0
2025-12-23T15:37:32.242721Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.242724Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.242740Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 1 partNo 0
2025-12-23T15:37:32.242761Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [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-23T15:37:32.242767Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.242772Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 2 partNo 0
2025-12-23T15:37:32.242776Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-12-23T15:37:32.242780Z node 18 :PERSQUEUE DEBUG: partition.cpp:2487: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-23T15:37:32.242784Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 3 partNo 0
2025-12-23T15:37:32.242792Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-12-23T15:37:32.242796Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 3
2025-12-23T15:37:32.242797Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][0][StateIdle] Batch completed (3)
2025-12-23T15:37:32.242799Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.242830Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:1704: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 0 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000000_00000000000000000000_00000_0000000003_00000? size 409 WTime 1766504252242
2025-12-23T15:37:32.242855Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 2 offset 1 count 2 size 288 actorID [18:7587077991100577109:2440]
2025-12-23T15:37:32.242871Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.242882Z node 18 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 3 size 409
2025-12-23T15:37:32.242886Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.242896Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 198 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.242904Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-23T15:37:32.242905Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 1 partno 0 count 2 parts 0 suffix '63' size 288
2025-12-23T15:37:32.242908Z node 18 :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-23T15:37:32.242910Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-12-23T15:37:32.242914Z node 18 :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-23T15:37:32.242925Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.242927Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.242929Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.242930Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.242932Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.242935Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.242960Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 3  requestId: <none> cookie: 2
2025-12-23T15:37:32.242982Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.243392Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 0 offset 0 count 3 size 409 actorID [18:7587077991100577109:2440]
2025-12-23T15:37:32.243409Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.243420Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.243424Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-23T15:37:32.243429Z node 18 :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-23T15:37:32.243430Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-12-23T15:37:32.243432Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-23T15:37:32.243434Z node 18 :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-23T15:37:32.243436Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-23T15:37:32.243439Z node 18 :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-23T15:37:32.243451Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.243455Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.243458Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.243460Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.243461Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.243463Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.243464Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.243466Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.243471Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.243478Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-23T15:37:32.243497Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][0][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 0 user user offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-23T15:37:32.243500Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-23T15:37:32.243507Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-23T15:37:32.243509Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-23T15:37:32.243514Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.243545Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-23T15:37:32.243553Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.243559Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-23T15:37:32.243562Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-23T15:37:32.243567Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1766504252242 queuesize 1 startOffset 0
2025-12-23T15:37:32.243570Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:934: [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-23T15:37:32.243572Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:976: [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-23T15:37:32.243582Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][0][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 partno 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-12-23T15:37:32.243593Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-23T15:37:32.243598Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-12-23T15:37:32.243602Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 1 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.243608Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-12-23T15:37:32.243611Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.243615Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-23T15:37:32.243618Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-23T15:37:32.243620Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1766504252242 queuesize 0 startOffset 0
2025-12-23T15:37:32.313940Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.313956Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.313964Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.313967Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.313969Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.314915Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.314922Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.314923Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.314924Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.314925Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.315441Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.315456Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.315458Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.315460Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.315462Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
Start reads
2025-12-23T15:37:32.342904Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 grpc read done: success: 0 data: 
2025-12-23T15:37:32.342920Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 grpc read failed
2025-12-23T15:37:32.342932Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 grpc closed
2025-12-23T15:37:32.342939Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: 421d93e7-ddbdb1a4-3a414889-5b50de3b|c9b3699-2e5736aa-d8b0992a-93404146_0 is DEAD
2025-12-23T15:37:32.343097Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-12-23T15:37:32.343097Z node 18 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 grpc read done: success: 0 data: 
2025-12-23T15:37:32.343102Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 grpc read failed
2025-12-23T15:37:32.343108Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 grpc closed
2025-12-23T15:37:32.343112Z node 18 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: d5127cc4-bb08ac10-a694be22-741fe156|b5481603-b9ffb9b8-321c045a-8d163a51_0 is DEAD
2025-12-23T15:37:32.343166Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577313:2459] destroyed
2025-12-23T15:37:32.343184Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-12-23T15:37:32.343191Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.343199Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.343201Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.343203Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.343205Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.343307Z node 18 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-12-23T15:37:32.343314Z node 18 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-12-23T15:37:32.343320Z node 18 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-23T15:37:32.343358Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577328:2461] destroyed
2025-12-23T15:37:32.343374Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-23T15:37:32.343381Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.343384Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.343386Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.343389Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.343391Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.343473Z node 18 :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-23T15:37:32.343526Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:941: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 read init: from# ipv6:[::1]:37624, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-12-23T15:37:32.343599Z node 18 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 auth for : debug
2025-12-23T15:37:32.343789Z node 18 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 Handle describe topics response
2025-12-23T15:37:32.343825Z node 18 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 auth is DEAD
2025-12-23T15:37:32.343832Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:1058: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 auth ok: topics# 1, initDone# 0
2025-12-23T15:37:32.344088Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:1229: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 register session: topic# rt3.dc1--topic1
2025-12-23T15:37:32.344299Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-12-23T15:37:32.344364Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 got read request: guid# b2c9ea89-ab62cd74-28ccc9a4-dc30e05a
2025-12-23T15:37:32.344359Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1657: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577342:2463] connected; active server actors: 1
2025-12-23T15:37:32.344485Z node 19 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1708: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [18:7587077991100577342:2463] session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.344512Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-12-23T15:37:32.344522Z node 19 :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-23T15:37:32.344526Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-12-23T15:37:32.344530Z node 19 :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-23T15:37:32.344535Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-12-23T15:37:32.344541Z node 19 :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-23T15:37:32.344549Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:871: [72075186224037893][rt3.dc1--topic1] consumer debug register reading session ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=0)
2025-12-23T15:37:32.344553Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1187: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-12-23T15:37:32.344564Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1259: [72075186224037893][rt3.dc1--topic1] consumer debug balancing. Sessions=1, Families=3, UnreadableFamilies=3 [3 (2), 2 (1), 1 (0), ], RequireBalancing=0 []
2025-12-23T15:37:32.344574Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1306: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=0)
2025-12-23T15:37:32.344588Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 3 status Active partitions [2] session "shared/debug_18_1_11628409129741738300_v1" sender [18:7587077991100577339:2463] lock partition 2 for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-12-23T15:37:32.344607Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1306: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=1)
2025-12-23T15:37:32.344616Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 2 status Active partitions [1] session "shared/debug_18_1_11628409129741738300_v1" sender [18:7587077991100577339:2463] lock partition 1 for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-12-23T15:37:32.344625Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1306: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=2)
2025-12-23T15:37:32.344645Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--topic1] consumer debug family 1 status Active partitions [0] session "shared/debug_18_1_11628409129741738300_v1" sender [18:7587077991100577339:2463] lock partition 0 for ReadingSession "shared/debug_18_1_11628409129741738300_v1" (Sender=[18:7587077991100577339:2463], Pipe=[18:7587077991100577342:2463], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-12-23T15:37:32.344660Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1326: [72075186224037893][rt3.dc1--topic1] consumer debug start rebalancing. familyCount=3, sessionCount=1, desiredFamilyCount=3, allowPlusOne=0
2025-12-23T15:37:32.344665Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1404: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.000097s
2025-12-23T15:37:32.344876Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_18_1_11628409129741738300_v1" ClientId: "debug" PipeClient { RawX1: 7587077991100577342 RawX2: 4503676936784287 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-23T15:37:32.344909Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_18_1_11628409129741738300_v1" ClientId: "debug" PipeClient { RawX1: 7587077991100577342 RawX2: 4503676936784287 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-23T15:37:32.344912Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-12-23T15:37:32.344929Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:1347: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_18_1_11628409129741738300_v1" ClientId: "debug" PipeClient { RawX1: 7587077991100577342 RawX2: 4503676936784287 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-12-23T15:37:32.344930Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-12-23T15:37:32.344941Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1144: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-12-23T15:37:32.344967Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: [18:7587077991100577345:2466]
2025-12-23T15:37:32.344973Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577345:2466], now have 1 active actors on pipe
2025-12-23T15:37:32.344978Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577347:2467], now have 1 active actors on pipe
2025-12-23T15:37:32.344979Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: [18:7587077991100577347:2467]
2025-12-23T15:37:32.344983Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:984: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: [18:7587077991100577348:2468]
2025-12-23T15:37:32.344985Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.344991Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.345005Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:1760: [PQ: 72075186224037892] Created session shared/debug_18_1_11628409129741738300_v1 on pipe: [18:7587077991100577345:2466]
2025-12-23T15:37:32.345021Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2733: [PQ: 72075186224037892] server connected, pipe [18:7587077991100577348:2468], now have 1 active actors on pipe
2025-12-23T15:37:32.345025Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_18_1_11628409129741738300_v1:1 with generation 1
2025-12-23T15:37:32.345025Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.345027Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.345029Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:1760: [PQ: 72075186224037892] Created session shared/debug_18_1_11628409129741738300_v1 on pipe: [18:7587077991100577347:2467]
2025-12-23T15:37:32.345032Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.345033Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_18_1_11628409129741738300_v1:2 with generation 1
2025-12-23T15:37:32.345034Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.345036Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:1760: [PQ: 72075186224037892] Created session shared/debug_18_1_11628409129741738300_v1 on pipe: [18:7587077991100577348:2468]
2025-12-23T15:37:32.345039Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_18_1_11628409129741738300_v1:3 with generation 1
2025-12-23T15:37:32.345045Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.345048Z node 18 :PERSQUEUE DEBUG: partition.cpp:2409: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345054Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.345056Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.345058Z node 18 :PERSQUEUE DEBUG: partition.cpp:2473: [72075186224037892][Partition][2][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345072Z node 18 :PERSQUEUE DEBUG: partition.cpp:3880: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug session is set to 0 (startOffset 0) session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.345077Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.345078Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-12-23T15:37:32.345080Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.345111Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.345116Z node 18 :PERSQUEUE DEBUG: partition.cpp:2409: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345119Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.345120Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.345122Z node 18 :PERSQUEUE DEBUG: partition.cpp:2473: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345126Z node 18 :PERSQUEUE DEBUG: partition.cpp:3880: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug session is set to 0 (startOffset 0) session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.345127Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.345128Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-12-23T15:37:32.345130Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.345142Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.345144Z node 18 :PERSQUEUE DEBUG: partition.cpp:2409: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345148Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-23T15:37:32.345149Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.345152Z node 18 :PERSQUEUE DEBUG: partition.cpp:2473: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2025-12-23T15:37:32.345158Z node 18 :PERSQUEUE DEBUG: partition.cpp:3880: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug session is set to 0 (startOffset 0) session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.345160Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-23T15:37:32.345161Z node 18 :PERSQUEUE DEBUG: partition.cpp:2342: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-23T15:37:32.345163Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.345178Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.345190Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.345198Z node 18 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-23T15:37:32.346376Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.346407Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.346415Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.346417Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346419Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.346422Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346423Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.346431Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.346435Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.346460Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.346463Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.346465Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346467Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.346468Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346471Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.346474Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.346477Z node 18 :PERSQUEUE DEBUG: partition.cpp:2153: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-23T15:37:32.346489Z node 18 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-23T15:37:32.346492Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.346494Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346495Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.346497Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.346498Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.346500Z node 18 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-23T15:37:32.346512Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-23T15:37:32.346527Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-23T15:37:32.346532Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-12-23T15:37:32.346567Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252239 CreateTimestampMS: 1766504252238 SizeLag: 576 WriteTimestampEstimateMS: 1766504252313 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-23T15:37:32.346570Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252241 CreateTimestampMS: 1766504252240 SizeLag: 308 WriteTimestampEstimateMS: 1766504252343 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-23T15:37:32.346580Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.346580Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.346592Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252242 CreateTimestampMS: 1766504252241 SizeLag: 409 WriteTimestampEstimateMS: 1766504252343 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-12-23T15:37:32.346594Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:695: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.346600Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 sending to client partition status
2025-12-23T15:37:32.346684Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 sending to client partition status
2025-12-23T15:37:32.346695Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1446: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 sending to client partition status
Got start stream event
Got start stream event
Got start stream event
2025-12-23T15:37:32.347063Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-12-23T15:37:32.347110Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347124Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-12-23T15:37:32.347145Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347144Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347149Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347154Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-12-23T15:37:32.347157Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347160Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347191Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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# 1766504252241, sizeLag# 308
2025-12-23T15:37:32.347196Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1TEvPartitionReady. Aval parts: 1
2025-12-23T15:37:32.347204Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 performing read request: guid# f0cbb7b-cd84a840-c7d3855b-3adf5d0a, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 369, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-23T15:37:32.347216Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347223Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 369 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid f0cbb7b-cd84a840-c7d3855b-3adf5d0a
2025-12-23T15:37:32.347224Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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# 1766504252239, sizeLag# 576
2025-12-23T15:37:32.347225Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1TEvPartitionReady. Aval parts: 1
2025-12-23T15:37:32.347228Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 performing read request: guid# cb321af3-db2955ed-70a0bb04-577f9fce, 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-23T15:37:32.347246Z node 18 :PQ_READ_PROXY INFO: partition_actor.cpp:1024: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347253Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:970: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347258Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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 cb321af3-db2955ed-70a0bb04-577f9fce
2025-12-23T15:37:32.347264Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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# 1766504252242, sizeLag# 409
2025-12-23T15:37:32.347272Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2354: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1TEvPartitionReady. Aval parts: 1
2025-12-23T15:37:32.347277Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.347281Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2277: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 performing read request: guid# b3d2948e-13f618ff-997806db-359f659d, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-12-23T15:37:32.347283Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-12-23T15:37:32.347300Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.347306Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1396: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid b3d2948e-13f618ff-997806db-359f659d
2025-12-23T15:37:32.347307Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-12-23T15:37:32.347338Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][2][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 partno 0 count 3 size 369 endOffset 3 max time lag 0ms effective offset 0
2025-12-23T15:37:32.347379Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 1, current partition end offset: 3
2025-12-23T15:37:32.347383Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-12-23T15:37:32.347388Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [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-23T15:37:32.347412Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 2 blobs, size 442 count 3 last offset 2, current partition end offset: 3
2025-12-23T15:37:32.347419Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-12-23T15:37:32.347425Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-12-23T15:37:32.347428Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2647: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-12-23T15:37:32.347438Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 2 offset 0 partno 0 count 1 parts_count 0 source 1 size 154 accessed 2 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.347441Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 2 offset 1 partno 0 count 2 parts_count 0 source 1 size 288 accessed 0 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.347460Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-23T15:37:32.347470Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: 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-23T15:37:32.000000Z
2025-12-23T15:37:32.347472Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: 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-23T15:37:32.000000Z
2025-12-23T15:37:32.347475Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-23T15:37:32.347478Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 2 blobs are from cache.
2025-12-23T15:37:32.347479Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 1 partno 0 count 2 parts 0 suffix '63'
2025-12-23T15:37:32.347486Z node 18 :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-23T15:37:32.347487Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 2 blobs
2025-12-23T15:37:32.347490Z node 18 :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-23T15:37:32.347497Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-23T15:37:32.347506Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 1 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-23T15:37:32.347522Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 2 blobs
2025-12-23T15:37:32.347530Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 2 count 2 size 268 from pos 0 cbcount 2
2025-12-23T15:37:32.347541Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 2 totakecount 1 count 1 size 134 from pos 0 cbcount 1
2025-12-23T15:37:32.347560Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.347578Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.347588Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][0][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 partno 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-12-23T15:37:32.347600Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-12-23T15:37:32.347606Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-12-23T15:37:32.347633Z node 18 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-12-23T15:37:32.000000Z
2025-12-23T15:37:32.347644Z node 18 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-12-23T15:37:32.347646Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2025-12-23T15:37:32.347651Z node 18 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-12-23T15:37:32.347652Z node 18 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-12-23T15:37:32.347663Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-23T15:37:32.347688Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252241 CreateTimestampMS: 1766504252240 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1766504252241 CreateTimestampMS: 1766504252240 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1766504252241 CreateTimestampMS: 1766504252240 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 18446744073709551522 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-23T15:37:32.347727Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347733Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252239 CreateTimestampMS: 1766504252238 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1766504252239 CreateTimestampMS: 1766504252238 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1766504252240 CreateTimestampMS: 1766504252238 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 2 SizeLag: 174 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-23T15:37:32.347739Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid f0cbb7b-cd84a840-c7d3855b-3adf5d0a has messages 1
2025-12-23T15:37:32.347759Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347764Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid cb321af3-db2955ed-70a0bb04-577f9fce has messages 1
2025-12-23T15:37:32.347768Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:664: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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: 1766504252242 CreateTimestampMS: 1766504252241 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1766504252242 CreateTimestampMS: 1766504252241 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1766504252242 CreateTimestampMS: 1766504252241 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-12-23T15:37:32.347777Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1278: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_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-23T15:37:32.347779Z node 18 :PQ_READ_PROXY DEBUG: partition_actor.cpp:902: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid b3d2948e-13f618ff-997806db-359f659d has messages 1
2025-12-23T15:37:32.347782Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 read done: guid# f0cbb7b-cd84a840-c7d3855b-3adf5d0a, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 272
2025-12-23T15:37:32.347789Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 response to read: guid# f0cbb7b-cd84a840-c7d3855b-3adf5d0a
2025-12-23T15:37:32.347860Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 Process answer. Aval parts: 0
2025-12-23T15:37:32.347883Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 read done: guid# cb321af3-db2955ed-70a0bb04-577f9fce, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 381
2025-12-23T15:37:32.347888Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 response to read: guid# cb321af3-db2955ed-70a0bb04-577f9fce
2025-12-23T15:37:32.347923Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 Process answer. Aval parts: 0
2025-12-23T15:37:32.347939Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1951: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 read done: guid# b3d2948e-13f618ff-997806db-359f659d, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 272
2025-12-23T15:37:32.347946Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2113: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 response to read: guid# b3d2948e-13f618ff-997806db-359f659d
2025-12-23T15:37:32.347978Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2156: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 Process answer. Aval parts: 0
2025-12-23T15:37:32.348209Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { read_request { bytes_size: 272 } }
Got data event with total 3 messages, current total messages: 3
2025-12-23T15:37:32.348267Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 got read request: guid# 17bc890e-64a24651-3ae3dd54-a1c374e
Got data event with total 3 messages, current total messages: 6
Got data event with total 3 messages, current total messages: 9
2025-12-23T15:37:32.348325Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 1, data# { read_request { bytes_size: 381 } }
2025-12-23T15:37:32.348351Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1850: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 got read request: guid# 110770a0-5b712e50-db605149-dd130371
2025-12-23T15:37:32.349226Z node 18 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read done: success# 0, data# {  }
2025-12-23T15:37:32.349241Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc read failed
2025-12-23T15:37:32.349246Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 grpc closed
2025-12-23T15:37:32.349262Z node 18 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_18_1_11628409129741738300_v1 is DEAD
2025-12-23T15:37:32.349339Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2270: [PQ: 72075186224037892] Destroy direct read session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349349Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577348:2468] destroyed
2025-12-23T15:37:32.349351Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2270: [PQ: 72075186224037892] Destroy direct read session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349353Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577347:2467] destroyed
2025-12-23T15:37:32.349354Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2270: [PQ: 72075186224037892] Destroy direct read session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349357Z node 18 :PERSQUEUE DEBUG: pq_impl.cpp:2758: [PQ: 72075186224037892] server disconnected, pipe [18:7587077991100577345:2466] destroyed
2025-12-23T15:37:32.349366Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349369Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349370Z node 18 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.349599Z node 19 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1661: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577342:2463] disconnected.
2025-12-23T15:37:32.349616Z node 19 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1672: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577342:2463] disconnected; active server actors: 1
2025-12-23T15:37:32.349621Z node 19 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1681: [72075186224037893][rt3.dc1--topic1] pipe [18:7587077991100577342:2463] client debug disconnected session shared/debug_18_1_11628409129741738300_v1
2025-12-23T15:37:32.414268Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.414283Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.414286Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.414288Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.414290Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.415265Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.415277Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.415279Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.415281Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.415282Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.415758Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.415765Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.415766Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.415768Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.415769Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-23T15:37:32.514624Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2025-12-23T15:37:32.514649Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.514651Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.514653Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.514655Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][1][StateIdle] Try persist
2025-12-23T15:37:32.515605Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][2][StateIdle] Process user action and tx events
2025-12-23T15:37:32.515612Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.515613Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][2][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.515615Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][2][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.515616Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][2][StateIdle] Try persist
2025-12-23T15:37:32.516145Z node 18 :PERSQUEUE DEBUG: partition.cpp:2329: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-23T15:37:32.516160Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.516163Z node 18 :PERSQUEUE DEBUG: partition.cpp:2337: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-23T15:37:32.516165Z node 18 :PERSQUEUE DEBUG: partition.cpp:2388: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-23T15:37:32.516167Z node 18 :PERSQUEUE DEBUG: partition.cpp:2347: [72075186224037892][Partition][0][StateIdle] Try persist
