2025-10-31T11:11:21.002495Z :GetAllStartPartitionSessions INFO: Random seed for debugging is 1761909081002469
2025-10-31T11:11:21.401590Z node 1 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[1:7567341885190991925:2153];send_to=[0:7307199536658146131:7762515];
2025-10-31T11:11:21.414174Z node 1 :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-10-31T11:11:21.469871Z node 1 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-10-31T11:11:21.470349Z node 2 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[2:7567341884478685508:2263];send_to=[0:7307199536658146131:7762515];
2025-10-31T11:11:21.470428Z node 2 :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 # /home/runner/.ya/build/build_root/vt0y/000ebe/r3tmp/tmpbe735A/pdisk_1.dat
2025-10-31T11:11:21.496267Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-10-31T11:11:21.684219Z node 2 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T11:11:21.701746Z node 1 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T11:11:21.745332Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-10-31T11:11:21.745481Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-10-31T11:11:21.747195Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(2, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-10-31T11:11:21.747282Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(2, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-10-31T11:11:21.752489Z node 1 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 2 Cookie 2
2025-10-31T11:11:21.752658Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-10-31T11:11:21.754148Z node 1 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(2, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-10-31T11:11:21.824041Z node 1 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
TServer::EnableGrpc on GrpcPort 9971, node 1
2025-10-31T11:11:21.894249Z node 1 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/vt0y/000ebe/r3tmp/yandexemPALj.tmp
2025-10-31T11:11:21.894450Z node 1 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/vt0y/000ebe/r3tmp/yandexemPALj.tmp
2025-10-31T11:11:21.894769Z node 1 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/vt0y/000ebe/r3tmp/yandexemPALj.tmp
2025-10-31T11:11:21.894898Z node 1 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-10-31T11:11:21.928236Z INFO: TTestServer started on Port 9486 GrpcPort 9971
2025-10-31T11:11:21.932797Z node 1 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-10-31T11:11:21.955641Z node 2 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
TClient is connected to server localhost:9486
PQClient connected to localhost:9971
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-10-31T11:11:22.251893Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976715657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
waiting...
waiting...
2025-10-31T11:11:22.423574Z node 1 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-10-31T11:11:22.466412Z node 2 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-10-31T11:11:24.455799Z node 2 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [2:7567341897363587520:2301], 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-10-31T11:11:24.455901Z node 2 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [2:7567341897363587509:2298], 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-10-31T11:11:24.456131Z node 2 :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-10-31T11:11:24.463025Z node 2 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [2:7567341897363587533:2303], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-10-31T11:11:24.463093Z node 2 :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-10-31T11:11:24.470472Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976720657:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2025-10-31T11:11:24.490515Z node 2 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [2:7567341897363587532:2302], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976720657 completed, doublechecking }
2025-10-31T11:11:24.591173Z node 2 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [2:7567341897363587564:2139] txid# 281474976720658, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 8], type: EPathTypeResourcePool, state: EPathStateNoChanges)" severity: 1 }
2025-10-31T11:11:24.835116Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715661:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-10-31T11:11:24.864965Z node 1 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:570: Compilation failed, self: [1:7567341898075894776:2332], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Cluster]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-31T11:11:24.865244Z node 1 :KQP_SESSION WARN: kqp_session_actor.cpp:2500: SessionId: ydb://session/3?node_id=1&id=MTc5ZGNiY2UtNjhhNjFiNDktOTZiMDRiNDAtNTRiMGFlOGY=, ActorId: [1:7567341898075894747:2326], ActorState: ExecuteState, TraceId: 01k8wze4dff9qnw4jsn2sjqkjh, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-10-31T11:11:24.875925Z node 1 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-10-31T11:11:24.881079Z node 2 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:570: Compilation failed, self: [2:7567341897363587579:2308], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Cluster]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2025-10-31T11:11:24.881417Z node 2 :KQP_SESSION WARN: kqp_session_actor.cpp:2500: SessionId: ydb://session/3?node_id=2&id=M2E0MDAzNDEtODZiMDY1NmYtODQ2MDJiZGUtNTU3MzM4N2I=, ActorId: [2:7567341897363587500:2297], ActorState: ExecuteState, TraceId: 01k8wze490apbnzs2fbhc1ewp7, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-10-31T11:11:24.881838Z node 2 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-10-31T11:11:24.952309Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715662:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-10-31T11:11:25.075858Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
=== Init DC: UPSERT INTO `/Root/PQ/Config/V2/Cluster` (name, balancer, local, enabled, weight) VALUES ("dc1", "localhost:9971", true, true, 1000), ("dc2", "dc2.logbroker.yandex.net", false, false, 1000);
2025-10-31T11:11:25.373200Z node 1 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976715664. Ctx: { TraceId: 01k8wze513ekkcayznf39qrefq, Database: , SessionId: ydb://session/3?node_id=1&id=OTI3ZDFmMzAtODEzYjVkYjUtNDExOThhMTYtMmMxYTRkMmQ=, PoolId: default, DatabaseId: /Root}. Database not set, use /Root
=== CheckClustersList. Subcribe to ClusterTracker from [1:7567341902370862466:2960] 
2025-10-31T11:11:26.390336Z node 1 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[1:7567341885190991925:2153];send_to=[0:7307199536658146131:7762515];
2025-10-31T11:11:26.390443Z node 1 :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-10-31T11:11:26.470189Z node 2 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[2:7567341884478685508:2263];send_to=[0:7307199536658146131:7762515];
2025-10-31T11:11:26.470265Z node 2 :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
2025-10-31T11:11:31.469808Z node 1 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715669:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
waiting...
PQ Client: create topic: rt3.dc1--test-topic with 5 partitions
CallPersQueueGRPC request to localhost:9971
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--test-topic"
  }
}
2025-10-31T11:11:31.565396Z node 1 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1524: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--test-topic, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
CallPersQueueGRPC request to localhost:9971
MetaRequest {
  CmdCreateTopic {
    Topic: "rt3.dc1--test-topic"
    NumPartitions: 5
    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: 281474976715670
  SchemeShardTabletId: 72057594046644480
  PathId: 13
}
ErrorCode: OK
AddTopic: rt3.dc1--test-topic
===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 = test-topic, dc = dc1
2025-10-31T11:11:31.603044Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1655: [72075186224037893][] pipe [1:7567341928140666635:3213] connected; active server actors: 1
2025-10-31T11:11:31.603675Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1519: [72075186224037893][rt3.dc1--test-topic] updating configuration. Deleted partitions []. Added partitions [1, 2, 3, 4, 0]
2025-10-31T11:11:31.604488Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:971: [72075186224037893][rt3.dc1--test-topic] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-10-31T11:11:31.605826Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:140: [72075186224037893][rt3.dc1--test-topic] BALANCER INIT DONE for rt3.dc1--test-topic: (0, 72075186224037892) (1, 72075186224037892) (2, 72075186224037892) (3, 72075186224037892) (4, 72075186224037892) 
2025-10-31T11:11:31.620399Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2960: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-10-31T11:11:31.626252Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2992: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-10-31T11:11:31.626511Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-10-31T11:11:31.626549Z node 2 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-10-31T11:11:31.626593Z node 2 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-10-31T11:11:31.626986Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341928140666672:2429], now have 1 active actors on pipe
2025-10-31T11:11:31.627015Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341928140666634:3212], now have 1 active actors on pipe
2025-10-31T11:11:31.627434Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:404: [72075186224037893][rt3.dc1--test-topic] TEvClientConnected TabletId 72075186224037892, NodeId 2, Generation 1
2025-10-31T11:11:31.628019Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:3570: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T11:11:31.634603Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T11:11:31.641166Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341928140666710:3271], now have 1 active actors on pipe
2025-10-31T11:11:31.641428Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:3570: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T11:11:31.644468Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T11:11:31.649046Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:1:Initializer] Start initializing step TInitConfigStep
2025-10-31T11:11:31.649140Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:2:Initializer] Start initializing step TInitConfigStep
2025-10-31T11:11:31.649157Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:3:Initializer] Start initializing step TInitConfigStep
2025-10-31T11:11:31.649172Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:4:Initializer] Start initializing step TInitConfigStep
2025-10-31T11:11:31.649187Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:0:Initializer] Start initializing step TInitConfigStep
2025-10-31T11:11:31.649234Z node 2 :PERSQUEUE DEBUG: partition.cpp:1295: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T11:11:31.649256Z node 2 :PERSQUEUE DEBUG: partition.cpp:1295: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T11:11:31.649272Z node 2 :PERSQUEUE DEBUG: partition.cpp:1295: [72075186224037892][Partition][3][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T11:11:31.649283Z node 2 :PERSQUEUE DEBUG: partition.cpp:1295: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T11:11:31.649294Z node 2 :PERSQUEUE DEBUG: partition.cpp:1295: [72075186224037892][Partition][4][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T11:11:31.649959Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T11:11:31.650387Z node 2 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [2:7567341927428359092:2384]
2025-10-31T11:11:31.652803Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:1:Initializer] Start initializing step TInitFieldsStep
2025-10-31T11:11:31.652825Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--test-topic:1:Initializer] Initializing completed.
2025-10-31T11:11:31.652852Z node 2 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--test-topic' partition 1 generation 1 [2:7567341927428359092:2384]
2025-10-31T11:11:31.652881Z node 2 :PERSQUEUE DEBUG: partition.cpp:721: [72075186224037892][Partition][1][StateInit] SYNC INIT topic rt3.dc1--test-topic partitition 1 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T11:11:31.652922Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.655786Z node 2 :PERSQUEUE DEBUG: partition.cpp:4256: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-10-31T11:11:31.655843Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.655953Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.655964Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T11:11:31.655986Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][1][StateIdle] Still pending for come commits
2025-10-31T11:11:31.655999Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.656014Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.656025Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][1][StateIdle] Still pending for come commits
2025-10-31T11:11:31.656054Z node 2 :PERSQUEUE DEBUG: partition_read.cpp:926: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--test-topic' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T11:11:31.656097Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:60: [72075186224037892][Partition][1][StateIdle] Process MLP pending events. Count 0
2025-10-31T11:11:31.656263Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T11:11:31.656472Z node 2 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [2:7567341927428359093:2384]
2025-10-31T11:11:31.658648Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:2:Initializer] Start initializing step TInitFieldsStep
2025-10-31T11:11:31.658668Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--test-topic:2:Initializer] Initializing completed.
2025-10-31T11:11:31.658683Z node 2 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--test-topic' partition 2 generation 1 [2:7567341927428359093:2384]
2025-10-31T11:11:31.658700Z node 2 :PERSQUEUE DEBUG: partition.cpp:721: [72075186224037892][Partition][2][StateInit] SYNC INIT topic rt3.dc1--test-topic partitition 2 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T11:11:31.658721Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.659583Z node 2 :PERSQUEUE DEBUG: partition.cpp:4256: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-10-31T11:11:31.659604Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.659665Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.659678Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T11:11:31.659699Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][2][StateIdle] Still pending for come commits
2025-10-31T11:11:31.659708Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.659717Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.659725Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][2][StateIdle] Still pending for come commits
2025-10-31T11:11:31.659748Z node 2 :PERSQUEUE DEBUG: partition_read.cpp:926: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--test-topic' partition 2 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T11:11:31.659774Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:60: [72075186224037892][Partition][2][StateIdle] Process MLP pending events. Count 0
2025-10-31T11:11:31.659895Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:3:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T11:11:31.660055Z node 2 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][3][StateInit] bootstrapping 3 [2:7567341927428359094:2384]
2025-10-31T11:11:31.662211Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:3:Initializer] Start initializing step TInitFieldsStep
2025-10-31T11:11:31.662233Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--test-topic:3:Initializer] Initializing completed.
2025-10-31T11:11:31.662247Z node 2 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][3][StateInit] init complete for topic 'rt3.dc1--test-topic' partition 3 generation 1 [2:7567341927428359094:2384]
2025-10-31T11:11:31.662264Z node 2 :PERSQUEUE DEBUG: partition.cpp:721: [72075186224037892][Partition][3][StateInit] SYNC INIT topic rt3.dc1--test-topic partitition 3 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T11:11:31.662284Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][3][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.663212Z node 2 :PERSQUEUE DEBUG: partition.cpp:4256: [72075186224037892][Partition][3][StateIdle] Process pending events. Count 1
2025-10-31T11:11:31.663251Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.663331Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.663347Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][3][StateIdle] Batch completed (1)
2025-10-31T11:11:31.663363Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][3][StateIdle] Still pending for come commits
2025-10-31T11:11:31.663373Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.663381Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.663388Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][3][StateIdle] Still pending for come commits
2025-10-31T11:11:31.663409Z node 2 :PERSQUEUE DEBUG: partition_read.cpp:926: [72075186224037892][Partition][3][StateIdle] Topic 'rt3.dc1--test-topic' partition 3 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T11:11:31.663432Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:60: [72075186224037892][Partition][3][StateIdle] Process MLP pending events. Count 0
2025-10-31T11:11:31.663535Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:4:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T11:11:31.663733Z node 2 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][4][StateInit] bootstrapping 4 [2:7567341927428359095:2384]
2025-10-31T11:11:31.665414Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:4:Initializer] Start initializing step TInitFieldsStep
2025-10-31T11:11:31.665441Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--test-topic:4:Initializer] Initializing completed.
2025-10-31T11:11:31.665456Z node 2 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][4][StateInit] init complete for topic 'rt3.dc1--test-topic' partition 4 generation 1 [2:7567341927428359095:2384]
2025-10-31T11:11:31.665473Z node 2 :PERSQUEUE DEBUG: partition.cpp:721: [72075186224037892][Partition][4][StateInit] SYNC INIT topic rt3.dc1--test-topic partitition 4 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T11:11:31.665495Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][4][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.666354Z node 2 :PERSQUEUE DEBUG: partition.cpp:4256: [72075186224037892][Partition][4][StateIdle] Process pending events. Count 1
2025-10-31T11:11:31.666377Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.666448Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.666457Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][4][StateIdle] Batch completed (1)
2025-10-31T11:11:31.666470Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][4][StateIdle] Still pending for come commits
2025-10-31T11:11:31.666479Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.666487Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.666497Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][4][StateIdle] Still pending for come commits
2025-10-31T11:11:31.666516Z node 2 :PERSQUEUE DEBUG: partition_read.cpp:926: [72075186224037892][Partition][4][StateIdle] Topic 'rt3.dc1--test-topic' partition 4 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T11:11:31.666539Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:60: [72075186224037892][Partition][4][StateIdle] Process MLP pending events. Count 0
2025-10-31T11:11:31.666663Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T11:11:31.666866Z node 2 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [2:7567341927428359096:2384]
2025-10-31T11:11:31.668462Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--test-topic:0:Initializer] Start initializing step TInitFieldsStep
2025-10-31T11:11:31.668477Z node 2 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--test-topic:0:Initializer] Initializing completed.
2025-10-31T11:11:31.668492Z node 2 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--test-topic' partition 0 generation 1 [2:7567341927428359096:2384]
2025-10-31T11:11:31.668509Z node 2 :PERSQUEUE DEBUG: partition.cpp:721: [72075186224037892][Partition][0][StateInit] SYNC INIT topic rt3.dc1--test-topic partitition 0 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T11:11:31.668530Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.670133Z node 2 :PERSQUEUE DEBUG: partition.cpp:4256: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-10-31T11:11:31.670165Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.670220Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.670229Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T11:11:31.670243Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][0][StateIdle] Still pending for come commits
2025-10-31T11:11:31.670252Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.670261Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.670270Z node 2 :PERSQUEUE DEBUG: partition.cpp:2301: [72075186224037892][Partition][0][StateIdle] Still pending for come commits
2025-10-31T11:11:31.670292Z node 2 :PERSQUEUE DEBUG: partition_read.cpp:926: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--test-topic' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T11:11:31.670315Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:60: [72075186224037892][Partition][0][StateIdle] Process MLP pending events. Count 0
2025-10-31T11:11:31.670622Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.671097Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.671273Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][3][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.671415Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][4][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.671663Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:3570: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T11:11:31.671784Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.679716Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T11:11:31.679892Z node 2 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761909091684, TxId 281474976715670
2025-10-31T11:11:31.679922Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.679940Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680102Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:31.680360Z node 2 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761909091684, TxId 281474976715670
2025-10-31T11:11:31.680372Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680380Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680452Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:31.680540Z node 2 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761909091684, TxId 281474976715670
2025-10-31T11:11:31.680548Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680555Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680610Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:31.680721Z node 2 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][3][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761909091684, TxId 281474976715670
2025-10-31T11:11:31.680734Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680743Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680795Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:31.680890Z node 2 :PERSQUEUE DEBUG: partition.cpp:1399: [72075186224037892][Partition][4][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761909091684, TxId 281474976715670
2025-10-31T11:11:31.680900Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680906Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.680961Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:31.681064Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:31.681130Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:31.681146Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:31.681368Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:31.681406Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:31.685520Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:31.685754Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.685879Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:31.685899Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.685912Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.685926Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:31.685956Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.685973Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:31.686066Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.686117Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:31.686127Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.686135Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.686142Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:31.686153Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.686166Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:31.686243Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][2][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.686287Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:31.686295Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.686303Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.686309Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:31.686319Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.686330Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][3][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:31.687010Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][3][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.687074Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][3][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:31.687086Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.687093Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.687102Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:31.687116Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][3][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.687134Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][4][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:31.687235Z node 2 :PERSQUEUE DEBUG: partition_mlp.cpp:83: [72075186224037892][Partition][4][StateIdle] Initializing MLP Consumers: 0
2025-10-31T11:11:31.687281Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][4][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:31.687290Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.687297Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.687303Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:31.687313Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][4][StateIdle] No data for blobs compaction
2025-10-31T11:11:31.688246Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { LifetimeSeconds: 86400 LowWatermark: 8388608 SourceIdLifetimeSeconds: 86400 WriteSpeedInBytesPerSecond: 20000000 BurstSize: 20000000 TotalPartitions: 5 SourceIdMaxCounts: 6000000 } PartitionIds: 1 PartitionIds: 2 PartitionIds: 3 PartitionIds: 4 PartitionIds: 0 TopicName: "rt3.dc1--test-topic" Version: 0 LocalDC: true Codecs { Ids: 0 Ids: 1 Ids: 2 Codecs: "raw" Codecs: "gzip" Codecs: "lzop" } TopicPath: "/Root/PQ/rt3.dc1--test-topic" 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: 3 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 4 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: 3 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 4 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-10-31T11:11:31.688392Z node 2 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-10-31T11:11:31.688737Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:3570: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T11:11:31.694464Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T11:11:31.694567Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:3570: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T11:11:31.696752Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T11:11:31.695548Z node 1 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976715671. Ctx: { TraceId: 01k8wzeb8jchbe7r5351q7teha, Database: , SessionId: ydb://session/3?node_id=1&id=NGIwYzYzZTItNjg5OGU0ZS0zYzUzZTJmMi1kYzZhYjJlNg==, PoolId: default, DatabaseId: /Root}. Database not set, use /Root
===Query complete
TClient::Ls request: /Root/PQ/rt3.dc1--test-topic
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--test-topic"
    PathId: 13
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976715670
    CreateStep: 1761909091684
    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--test-topic"
    PathId: 13
    TotalGroupCount: 5
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts...
(TRUNCATED)
GetTopicVersionFromPath:  record Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--test-topic"
    PathId: 13
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976715670
    CreateStep: 1761909091684
    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--test-topic"
    PathId: 13
    TotalGroupCount: 5
    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
    }
    Partitions {
      PartitionId: 3
      TabletId: 72075186224037892
      Status: Active
    }
    Partitions {
      PartitionId: 4
      TabletId: 72075186224037892
      Status: Active
    }
    AlterVersion: 1
    BalancerTabletID: 72075186224037893
    NextPartitionId: 5
  }
  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: 5
    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--test-topic"
 name rt3.dc1--test-topic version1
CallPersQueueGRPC request to localhost:9971
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--test-topic"
  }
}
2025-10-31T11:11:31.743134Z node 1 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1524: proxy answer <none>
CallPersQueueGRPC response:
Status: 1
ErrorCode: OK
MetaResponse {
  CmdGetTopicMetadataResult {
    TopicInfo {
      Topic: "rt3.dc1--test-topic"
      NumPartitions: 5
      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--test-topic"
        YdbDatabasePath: "/Root"
        Consumers {
          Name: "user"
          ReadFromTimestampsMs: 0
          FormatVersion: 0
          Version: 0
        }
      }
      ErrorCode: OK
    }
  }
}
=== Topic created, have version: 1
2025-10-31T11:11:31.756090Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.756118Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.756163Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:31.756842Z :DEBUG: [] MessageGroupId [src] SessionId [] Write session: try to update token
2025-10-31T11:11:31.757358Z :INFO: [] MessageGroupId [src] SessionId [] Write session: Do CDS request
2025-10-31T11:11:31.757391Z :INFO: [] MessageGroupId [src] SessionId [] Start write session. Will connect to endpoint: localhost:9971
2025-10-31T11:11:31.759711Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.759742Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.759751Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:31.763726Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.763745Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.763763Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:31.766785Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.766804Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.766814Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:31.767319Z node 1 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-10-31T11:11:31.767361Z node 1 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-10-31T11:11:31.770441Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.770464Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.770482Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:31.776491Z :DEBUG: [] MessageGroupId [src] SessionId [] Write session: send init request: init_request { topic: "test-topic" message_group_id: "src" }
2025-10-31T11:11:31.777394Z node 1 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId:  grpc read done: success: 1 data: init_request { topic: "test-topic" message_group_id: "src" }
2025-10-31T11:11:31.777760Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 1 topic: "test-topic" message_group_id: "src" from ipv6:[::1]:44158
2025-10-31T11:11:31.777791Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:44158 proto=v1  topic=test-topic durationSec=0
2025-10-31T11:11:31.777805Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-10-31T11:11:31.779603Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-10-31T11:11:31.779763Z node 1 :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-10-31T11:11:31.779782Z node 1 :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-10-31T11:11:31.779796Z node 1 :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-10-31T11:11:31.779828Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) StartKqpSession
2025-10-31T11:11:31.782517Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) Select from the table
2025-10-31T11:11:31.857864Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.857891Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.857902Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:31.860174Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.860198Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.860209Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:31.864242Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.864269Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.864280Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:31.867154Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.867177Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.867186Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:31.870756Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.870781Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.870794Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:31.951393Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) RequestPQRB
2025-10-31T11:11:31.951841Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1655: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341928140666825:2446] connected; active server actors: 1
2025-10-31T11:11:31.951894Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=src
2025-10-31T11:11:31.951920Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) Update the table
2025-10-31T11:11:31.952383Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1659: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341928140666825:2446] disconnected.
2025-10-31T11:11:31.952413Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1670: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341928140666825:2446] disconnected; active server actors: 1
2025-10-31T11:11:31.952438Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1695: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341928140666825:2446] disconnected no session
2025-10-31T11:11:31.958340Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.958372Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.958396Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:31.960377Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.960395Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.960401Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:31.964434Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.964452Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.964462Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:31.967502Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.967523Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.967562Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:31.971059Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:31.971080Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:31.971089Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.037466Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2025-10-31T11:11:32.037503Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=(NULL)
2025-10-31T11:11:32.037522Z node 1 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [1:7567341928140666784:2446] (SourceId=src, PreferedPartition=(NULL)) Start idle
2025-10-31T11:11:32.037554Z node 1 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-10-31T11:11:32.039093Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341932435634145:2446], now have 1 active actors on pipe
2025-10-31T11:11:32.039311Z node 1 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 2, Generation: 1
2025-10-31T11:11:32.039545Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:32.039583Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 0
2025-10-31T11:11:32.039681Z node 2 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie src|20e8cc45-35d23acd-d6492994-1a47359e_0 generated for partition 0 topic 'rt3.dc1--test-topic' owner src
2025-10-31T11:11:32.039738Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.039763Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.039784Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T11:11:32.039842Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.039885Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-10-31T11:11:32.039961Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T11:11:32.040649Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:32.040671Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 0
2025-10-31T11:11:32.040754Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T11:11:32.041222Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 0 MaxSeqNo: 0 sessionId: src|20e8cc45-35d23acd-d6492994-1a47359e_0
2025-10-31T11:11:32.045668Z :INFO: [] MessageGroupId [src] SessionId [] Counters: { Errors: 0 CurrentSessionLifetimeMs: 1761909092045 BytesWritten: 0 MessagesWritten: 0 BytesWrittenCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:32.045834Z :INFO: [] MessageGroupId [src] SessionId [] Write session established. Init response: session_id: "src|20e8cc45-35d23acd-d6492994-1a47359e_0" topic: "test-topic" cluster: "dc1" supported_codecs: CODEC_RAW supported_codecs: CODEC_GZIP supported_codecs: CODEC_LZOP
2025-10-31T11:11:32.046059Z :INFO: [] MessageGroupId [src] SessionId [src|20e8cc45-35d23acd-d6492994-1a47359e_0] Write session: close. Timeout = 0 ms
2025-10-31T11:11:32.046102Z :INFO: [] MessageGroupId [src] SessionId [src|20e8cc45-35d23acd-d6492994-1a47359e_0] Write session will now close
2025-10-31T11:11:32.046147Z :DEBUG: [] MessageGroupId [src] SessionId [src|20e8cc45-35d23acd-d6492994-1a47359e_0] Write session: aborting
2025-10-31T11:11:32.046589Z :INFO: [] MessageGroupId [src] SessionId [src|20e8cc45-35d23acd-d6492994-1a47359e_0] Write session: gracefully shut down, all writes complete
2025-10-31T11:11:32.046626Z :DEBUG: [] MessageGroupId [src] SessionId [src|20e8cc45-35d23acd-d6492994-1a47359e_0] Write session: destroy
2025-10-31T11:11:32.052520Z node 1 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: src|20e8cc45-35d23acd-d6492994-1a47359e_0 grpc read done: success: 0 data: 
2025-10-31T11:11:32.052563Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: src|20e8cc45-35d23acd-d6492994-1a47359e_0 grpc read failed
2025-10-31T11:11:32.052598Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: src|20e8cc45-35d23acd-d6492994-1a47359e_0 grpc closed
2025-10-31T11:11:32.052614Z node 1 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: src|20e8cc45-35d23acd-d6492994-1a47359e_0 is DEAD
2025-10-31T11:11:32.054094Z node 1 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-10-31T11:11:32.054694Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341932435634145:2446] destroyed
2025-10-31T11:11:32.054759Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-10-31T11:11:32.054790Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.054808Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.054826Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.058697Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.058726Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.058738Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.061139Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.061167Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.061178Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.064821Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.064849Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.064860Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
Session was created
2025-10-31T11:11:32.067870Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.067893Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.067903Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.071480Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.071515Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.071537Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.159041Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.159073Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.159085Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.161038Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.161052Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.161070Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.165340Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.165366Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.165377Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.168204Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.168237Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.168248Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.171857Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.171879Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.171889Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.259414Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.259448Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.259461Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.261432Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.261455Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.261464Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.265731Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.265776Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.265790Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.268609Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.268638Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.268648Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.272133Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.272159Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.272168Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.359775Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.359809Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.359821Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.361881Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.361905Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.361915Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.365849Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.365887Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.365899Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.369024Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.369045Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.369055Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.372515Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.372542Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.372552Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.461301Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.461335Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.461347Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.465193Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.465233Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.465245Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.469212Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.469238Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.469250Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.469292Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.469302Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.469309Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.473185Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.473211Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.473221Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.561424Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.561453Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.561462Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.564066Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.564090Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.564099Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.569145Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.569168Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.569174Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.569612Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.569673Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.569685Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.573218Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.573260Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.573270Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.661801Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.661845Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.661857Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.664339Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.664364Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.664374Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.669488Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.669515Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.669526Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.669886Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.669901Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.669910Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.673555Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.673579Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.673589Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.762612Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.762649Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.762660Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.764671Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.764694Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.764705Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.773225Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.773265Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.773278Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.773324Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.773344Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.773351Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.774151Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.774183Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.774191Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.863028Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.863060Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.863071Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.865029Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.865050Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.865058Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.873791Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.873820Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.873830Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.873874Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.873882Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.873888Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.874220Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.874234Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.874241Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:32.963398Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.963431Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.963444Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:32.965419Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.965440Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.965449Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:32.974120Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.974149Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.974160Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:32.974200Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.974219Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.974226Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:32.974564Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:32.974578Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:32.974585Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:33.063746Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.063775Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.063787Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:33.065752Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.065789Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.065799Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
>>> Ready to answer: ok
2025-10-31T11:11:33.070024Z :INFO: [/Root] [] [2fa9952d-69530089-25c1b4fd-d2006e2e] Open read subsessions to databases: { name: dc1, endpoint: localhost:9971, path: /Root }, { name: dc2, endpoint: localhost:9971, path: /Root }
2025-10-31T11:11:33.074456Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.074482Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.074493Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:33.074541Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.074550Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.074556Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:33.074938Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.074961Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.074968Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:33.078401Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Starting read session
2025-10-31T11:11:33.078454Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Starting single session
2025-10-31T11:11:33.079083Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] In Reconnect, ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.079172Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] New values: ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.079243Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Reconnecting session to cluster  in 0.000000s
2025-10-31T11:11:33.082326Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Starting read session
2025-10-31T11:11:33.082380Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Starting single session
2025-10-31T11:11:33.082478Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] In Reconnect, ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.082506Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] New values: ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.082527Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] Reconnecting session to cluster  in 0.000000s
2025-10-31T11:11:33.083361Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Successfully connected. Initializing session
2025-10-31T11:11:33.084268Z node 1 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-10-31T11:11:33.084321Z node 1 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-10-31T11:11:33.084738Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] Successfully connected. Initializing session
2025-10-31T11:11:33.085288Z node 1 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-10-31T11:11:33.085307Z node 1 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 2
2025-10-31T11:11:33.092484Z node 1 :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: "test-topic" } consumer: "shared/user" } }
2025-10-31T11:11:33.092507Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 2 consumer  session  grpc read done: success# 1, data# { init_request { topics_read_settings { path: "test-topic" } consumer: "shared/user" } }
2025-10-31T11:11:33.092784Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:929: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 read init: from# ipv6:[::1]:44186, request# { init_request { topics_read_settings { path: "test-topic" } consumer: "shared/user" } }
2025-10-31T11:11:33.092784Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:929: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 read init: from# ipv6:[::1]:44186, request# { init_request { topics_read_settings { path: "test-topic" } consumer: "shared/user" } }
2025-10-31T11:11:33.093459Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 auth for : user
2025-10-31T11:11:33.093469Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 auth for : user
2025-10-31T11:11:33.093989Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 Handle describe topics response
2025-10-31T11:11:33.094005Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 Handle describe topics response
2025-10-31T11:11:33.094099Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 auth is DEAD
2025-10-31T11:11:33.094103Z node 1 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 auth is DEAD
2025-10-31T11:11:33.094155Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1046: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 auth ok: topics# 1, initDone# 0
2025-10-31T11:11:33.094156Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1046: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 auth ok: topics# 1, initDone# 0
2025-10-31T11:11:33.095261Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1217: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 register session: topic# rt3.dc1--test-topic
2025-10-31T11:11:33.095264Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1217: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 register session: topic# rt3.dc1--test-topic
2025-10-31T11:11:33.095588Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1655: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601512:2470] connected; active server actors: 1
2025-10-31T11:11:33.095631Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1655: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601511:2471] connected; active server actors: 1
2025-10-31T11:11:33.095657Z node 1 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1706: [72075186224037893][rt3.dc1--test-topic] consumer "user" register session for pipe [1:7567341936730601512:2470] session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.095702Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--test-topic] consumer user register readable partition 0
2025-10-31T11:11:33.095752Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--test-topic] consumer user family created family=1 (Status=Free, Partitions=[0])
2025-10-31T11:11:33.095764Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--test-topic] consumer user register readable partition 1
2025-10-31T11:11:33.095776Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--test-topic] consumer user family created family=2 (Status=Free, Partitions=[1])
2025-10-31T11:11:33.095784Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--test-topic] consumer user register readable partition 2
2025-10-31T11:11:33.095825Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] Got InitResponse. ReadSessionId: shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.095825Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Got InitResponse. ReadSessionId: shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.095803Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--test-topic] consumer user family created family=3 (Status=Free, Partitions=[2])
2025-10-31T11:11:33.095912Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] In ContinueReadingDataImpl, ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.095811Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--test-topic] consumer user register readable partition 3
2025-10-31T11:11:33.095914Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] In ContinueReadingDataImpl, ReadSizeBudget = 524288, ReadSizeServerDelta = 0
2025-10-31T11:11:33.095828Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--test-topic] consumer user family created family=4 (Status=Free, Partitions=[3])
2025-10-31T11:11:33.095839Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037893][rt3.dc1--test-topic] consumer user register readable partition 4
2025-10-31T11:11:33.095856Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037893][rt3.dc1--test-topic] consumer user family created family=5 (Status=Free, Partitions=[4])
2025-10-31T11:11:33.096086Z :DEBUG: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 524288
2025-10-31T11:11:33.095895Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:871: [72075186224037893][rt3.dc1--test-topic] consumer user register reading session ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=0)
2025-10-31T11:11:33.096086Z :DEBUG: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 524288
2025-10-31T11:11:33.095926Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1186: [72075186224037893][rt3.dc1--test-topic] consumer user rebalancing was scheduled
2025-10-31T11:11:33.095955Z node 1 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1706: [72075186224037893][rt3.dc1--test-topic] consumer "user" register session for pipe [1:7567341936730601511:2471] session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.095980Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:871: [72075186224037893][rt3.dc1--test-topic] consumer user register reading session ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=0)
2025-10-31T11:11:33.096027Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1258: [72075186224037893][rt3.dc1--test-topic] consumer user balancing. Sessions=2, Families=5, UnradableFamilies=5 [5 (4), 4 (3), 3 (2), 2 (1), 1 (0), ], RequireBalancing=0 []
2025-10-31T11:11:33.096070Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=5 (Status=Free, Partitions=[4]) for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=0)
2025-10-31T11:11:33.096157Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 5 status Active partitions [4] session "shared/user_1_1_17768121930662765008_v1" sender [1:7567341936730601505:2470] lock partition 4 for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-10-31T11:11:33.096232Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=4 (Status=Free, Partitions=[3]) for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=0)
2025-10-31T11:11:33.096252Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 4 status Active partitions [3] session "shared/user_1_2_10724727762600438365_v1" sender [1:7567341936730601506:2471] lock partition 3 for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=1) generation 1 step 2
2025-10-31T11:11:33.096271Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=1)
2025-10-31T11:11:33.096289Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 3 status Active partitions [2] session "shared/user_1_1_17768121930662765008_v1" sender [1:7567341936730601505:2470] lock partition 2 for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=2) generation 1 step 3
2025-10-31T11:11:33.096308Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=1)
2025-10-31T11:11:33.096395Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 2 status Active partitions [1] session "shared/user_1_2_10724727762600438365_v1" sender [1:7567341936730601506:2471] lock partition 1 for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=2) generation 1 step 4
2025-10-31T11:11:33.096418Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=2)
2025-10-31T11:11:33.096439Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 1 status Active partitions [0] session "shared/user_1_1_17768121930662765008_v1" sender [1:7567341936730601505:2470] lock partition 0 for ReadingSession "shared/user_1_1_17768121930662765008_v1" (Sender=[1:7567341936730601505:2470], Pipe=[1:7567341936730601512:2470], Partitions=[], ActiveFamilyCount=3) generation 1 step 5
2025-10-31T11:11:33.096489Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1325: [72075186224037893][rt3.dc1--test-topic] consumer user start rebalancing. familyCount=5, sessionCount=2, desiredFamilyCount=2, allowPlusOne=1
2025-10-31T11:11:33.096527Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1402: [72075186224037893][rt3.dc1--test-topic] consumer user balancing duration: 0.000474s
2025-10-31T11:11:33.097115Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 assign: record# { Partition: 3 TabletId: 72075186224037892 Topic: "rt3.dc1--test-topic" Generation: 1 Step: 2 Session: "shared/user_1_2_10724727762600438365_v1" ClientId: "user" PipeClient { RawX1: 7567341936730601511 RawX2: 4503603922340263 } Path: "/Root/PQ/rt3.dc1--test-topic" }
2025-10-31T11:11:33.097117Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 assign: record# { Partition: 4 TabletId: 72075186224037892 Topic: "rt3.dc1--test-topic" Generation: 1 Step: 1 Session: "shared/user_1_1_17768121930662765008_v1" ClientId: "user" PipeClient { RawX1: 7567341936730601512 RawX2: 4503603922340262 } Path: "/Root/PQ/rt3.dc1--test-topic" }
2025-10-31T11:11:33.097191Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--test-topic" Generation: 1 Step: 4 Session: "shared/user_1_2_10724727762600438365_v1" ClientId: "user" PipeClient { RawX1: 7567341936730601511 RawX2: 4503603922340263 } Path: "/Root/PQ/rt3.dc1--test-topic" }
2025-10-31T11:11:33.097209Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--test-topic" Generation: 1 Step: 3 Session: "shared/user_1_1_17768121930662765008_v1" ClientId: "user" PipeClient { RawX1: 7567341936730601512 RawX2: 4503603922340262 } Path: "/Root/PQ/rt3.dc1--test-topic" }
2025-10-31T11:11:33.097225Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 grpc read done: success# 1, data# { read_request { bytes_size: 524288 } }
2025-10-31T11:11:33.097293Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--test-topic" Generation: 1 Step: 5 Session: "shared/user_1_1_17768121930662765008_v1" ClientId: "user" PipeClient { RawX1: 7567341936730601512 RawX2: 4503603922340262 } Path: "/Root/PQ/rt3.dc1--test-topic" }
2025-10-31T11:11:33.097338Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read done: success# 1, data# { read_request { bytes_size: 524288 } }
2025-10-31T11:11:33.097351Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1839: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 got read request: guid# 4c8c3a24-7cee49e1-bceca65-419b4a3e
2025-10-31T11:11:33.097387Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1839: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 got read request: guid# 96235bbb-c47fb4e4-7f9375f0-6cf77a23
2025-10-31T11:11:33.097425Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 INITING TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1)
2025-10-31T11:11:33.097442Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INITING TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1)
2025-10-31T11:11:33.097478Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 INITING TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2)
2025-10-31T11:11:33.097507Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INITING TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2)
2025-10-31T11:11:33.097548Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INITING TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3)
2025-10-31T11:11:33.097951Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341936730601521:2476], now have 1 active actors on pipe
2025-10-31T11:11:33.097980Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341936730601520:2477], now have 1 active actors on pipe
2025-10-31T11:11:33.097996Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341936730601522:2478], now have 1 active actors on pipe
2025-10-31T11:11:33.098008Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341936730601523:2479], now have 1 active actors on pipe
2025-10-31T11:11:33.098036Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2721: [PQ: 72075186224037892] server connected, pipe [1:7567341936730601524:2480], now have 1 active actors on pipe
2025-10-31T11:11:33.098143Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [1:7567341936730601522:2478]
2025-10-31T11:11:33.098156Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [1:7567341936730601521:2476]
2025-10-31T11:11:33.098201Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [1:7567341936730601523:2479]
2025-10-31T11:11:33.098217Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [1:7567341936730601520:2477]
2025-10-31T11:11:33.098267Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [1:7567341936730601524:2480]
2025-10-31T11:11:33.098366Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:33.098401Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 1
2025-10-31T11:11:33.098438Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1748: [PQ: 72075186224037892] Created session shared/user_1_2_10724727762600438365_v1 on pipe: [1:7567341936730601522:2478]
2025-10-31T11:11:33.098507Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:33.098527Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_1_2_10724727762600438365_v1:2 with generation 1
2025-10-31T11:11:33.098555Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 4
2025-10-31T11:11:33.098573Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1748: [PQ: 72075186224037892] Created session shared/user_1_1_17768121930662765008_v1 on pipe: [1:7567341936730601521:2476]
2025-10-31T11:11:33.098619Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_1_1_17768121930662765008_v1:1 with generation 1
2025-10-31T11:11:33.098642Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:33.098658Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 2
2025-10-31T11:11:33.098671Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1748: [PQ: 72075186224037892] Created session shared/user_1_1_17768121930662765008_v1 on pipe: [1:7567341936730601523:2479]
2025-10-31T11:11:33.098712Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_1_1_17768121930662765008_v1:2 with generation 1
2025-10-31T11:11:33.098730Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:33.098745Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 3
2025-10-31T11:11:33.098758Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1748: [PQ: 72075186224037892] Created session shared/user_1_2_10724727762600438365_v1 on pipe: [1:7567341936730601520:2477]
2025-10-31T11:11:33.098781Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_1_2_10724727762600438365_v1:1 with generation 1
2025-10-31T11:11:33.098792Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--test-topic' requestId: <none>
2025-10-31T11:11:33.098801Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2635: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--test-topic' partition 0
2025-10-31T11:11:33.098813Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:1748: [PQ: 72075186224037892] Created session shared/user_1_1_17768121930662765008_v1 on pipe: [1:7567341936730601524:2480]
2025-10-31T11:11:33.098826Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_1_1_17768121930662765008_v1:3 with generation 1
2025-10-31T11:11:33.098880Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.098915Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.098931Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T11:11:33.098997Z node 2 :PERSQUEUE DEBUG: partition.cpp:3649: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--test-topic' partition 1 user user session is set to 0 (startOffset 0) session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.099028Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:33.099159Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.099177Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.099185Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][4][StateIdle] Batch completed (1)
2025-10-31T11:11:33.099209Z node 2 :PERSQUEUE DEBUG: partition.cpp:3649: [72075186224037892][Partition][4][StateIdle] Topic 'rt3.dc1--test-topic' partition 4 user user session is set to 0 (startOffset 0) session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.099217Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:33.099266Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.099276Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.099282Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T11:11:33.099301Z node 2 :PERSQUEUE DEBUG: partition.cpp:3649: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--test-topic' partition 2 user user session is set to 0 (startOffset 0) session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.099321Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:33.099377Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.099388Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.099394Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][3][StateIdle] Batch completed (1)
2025-10-31T11:11:33.099424Z node 2 :PERSQUEUE DEBUG: partition.cpp:3649: [72075186224037892][Partition][3][StateIdle] Topic 'rt3.dc1--test-topic' partition 3 user user session is set to 0 (startOffset 0) session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.099432Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:33.099481Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.099496Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.099503Z node 2 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T11:11:33.099526Z node 2 :PERSQUEUE DEBUG: partition.cpp:3649: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--test-topic' partition 0 user user session is set to 0 (startOffset 0) session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.099532Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:33.099579Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:33.099609Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:33.099628Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:33.099646Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:33.099665Z node 2 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T11:11:33.102874Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:33.102949Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:33.102976Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.103007Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.103019Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:33.103043Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T11:11:33.103060Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][4][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:33.103096Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][4][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:33.103107Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.103135Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.103143Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:33.103175Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][4][StateIdle] No data for blobs compaction
2025-10-31T11:11:33.103193Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:33.103221Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:33.103232Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.103257Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.103266Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:33.103277Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T11:11:33.103291Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][3][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:33.103319Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][3][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:33.103329Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.103350Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.103358Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:33.103369Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][3][StateIdle] No data for blobs compaction
2025-10-31T11:11:33.103384Z node 2 :PERSQUEUE DEBUG: partition.cpp:2099: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T11:11:33.103434Z node 2 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T11:11:33.103446Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.103453Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.103458Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:33.103469Z node 2 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T11:11:33.103498Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T11:11:33.103552Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 4 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T11:11:33.103571Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T11:11:33.103587Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 3 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T11:11:33.103607Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--test-topic' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T11:11:33.103891Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 0 SizeLag: 0 WriteTimestampEstimateMS: 1761909093063 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T11:11:33.103893Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 0 SizeLag: 0 WriteTimestampEstimateMS: 1761909093074 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T11:11:33.103950Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 INIT DONE TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 0 readOffset 0 committedOffset 0
2025-10-31T11:11:33.103951Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INIT DONE TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1) EndOffset 0 readOffset 0 committedOffset 0
2025-10-31T11:11:33.104050Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 0 SizeLag: 0 WriteTimestampEstimateMS: 1761909093065 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T11:11:33.104060Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 0 SizeLag: 0 WriteTimestampEstimateMS: 1761909093074 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T11:11:33.104071Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INIT DONE TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2) EndOffset 0 readOffset 0 committedOffset 0
2025-10-31T11:11:33.104113Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 INIT DONE TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1) EndOffset 0 readOffset 0 committedOffset 0
2025-10-31T11:11:33.104147Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 0 SizeLag: 0 WriteTimestampEstimateMS: 1761909093074 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T11:11:33.104164Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 INIT DONE TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 0 readOffset 0 committedOffset 0
2025-10-31T11:11:33.104191Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 sending to client partition status
2025-10-31T11:11:33.104217Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 sending to client partition status
2025-10-31T11:11:33.104398Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 sending to client partition status
2025-10-31T11:11:33.104409Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 sending to client partition status
2025-10-31T11:11:33.104434Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 sending to client partition status
Got new read session event: StartPartitionSession { Partition session id: 1 Topic: "test-topic" Partition: 4 Database name: dc1 Database path: /Root Database id: account-dc1 CommittedOffset: 0 EndOffset: 0 }
2025-10-31T11:11:33.105300Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "test-topic". Partition: 4. Read offset: (NULL)
Got new read session event: StartPartitionSession { Partition session id: 1 Topic: "test-topic" Partition: 1 Database name: dc2 Database path: /Root Database id: account-dc2 CommittedOffset: 0 EndOffset: 0 }
2025-10-31T11:11:33.105497Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "test-topic". Partition: 1. Read offset: (NULL)
Got new read session event: StartPartitionSession { Partition session id: 2 Topic: "test-topic" Partition: 2 Database name: dc1 Database path: /Root Database id: account-dc1 CommittedOffset: 0 EndOffset: 0 }
2025-10-31T11:11:33.105590Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Confirm partition stream create. Partition stream id: 2. Cluster: "-". Topic: "test-topic". Partition: 2. Read offset: (NULL)
Got new read session event: StartPartitionSession { Partition session id: 2 Topic: "test-topic" Partition: 3 Database name: dc2 Database path: /Root Database id: account-dc2 CommittedOffset: 0 EndOffset: 0 }
2025-10-31T11:11:33.105716Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] [] Confirm partition stream create. Partition stream id: 2. Cluster: "-". Topic: "test-topic". Partition: 3. Read offset: (NULL)
2025-10-31T11:11:33.105710Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
Got new read session event: StartPartitionSession { Partition session id: 3 Topic: "test-topic" Partition: 0 Database name: dc1 Database path: /Root Database id: account-dc1 CommittedOffset: 0 EndOffset: 0 }
2025-10-31T11:11:33.105774Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-10-31T11:11:33.105850Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] [] Confirm partition stream create. Partition stream id: 3. Cluster: "-". Topic: "test-topic". Partition: 0. Read offset: (NULL)
2025-10-31T11:11:33.105817Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T11:11:33.105855Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T11:11:33.105869Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 Start reading TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1) EndOffset 0 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T11:11:33.105900Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 4(assignId:1) wait data in partition inited, cookie 1 from offset 0
2025-10-31T11:11:33.105891Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 Start reading TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 0 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T11:11:33.105927Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 1(assignId:2) wait data in partition inited, cookie 1 from offset 0
2025-10-31T11:11:33.106016Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-10-31T11:11:33.106034Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Closing read session. Close timeout: 0.010000s
2025-10-31T11:11:33.106027Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-10-31T11:11:33.106086Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:0:3:0:0 -:test-topic:2:2:0:0 -:test-topic:4:1:0:0
2025-10-31T11:11:33.106136Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Counters: { Errors: 0 CurrentSessionLifetimeMs: 27 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.106097Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T11:11:33.106130Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T11:11:33.106145Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-10-31T11:11:33.106159Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 Start reading TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2) EndOffset 0 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T11:11:33.106173Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T11:11:33.106176Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 2(assignId:2) wait data in partition inited, cookie 1 from offset 0
2025-10-31T11:11:33.106198Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 Start reading TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1) EndOffset 0 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T11:11:33.106216Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 3(assignId:1) wait data in partition inited, cookie 1 from offset 0
2025-10-31T11:11:33.106229Z node 1 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 Start reading TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 0 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T11:11:33.106244Z node 1 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 TopicId: Topic rt3.dc1--test-topic in dc dc1 in database: Root, partition 0(assignId:3) wait data in partition inited, cookie 1 from offset 0
2025-10-31T11:11:33.106571Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Closing read session. Close timeout: 0.010000s
2025-10-31T11:11:33.106607Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:3:2:0:0 -:test-topic:1:1:0:0
2025-10-31T11:11:33.106633Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Counters: { Errors: 0 CurrentSessionLifetimeMs: 24 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.106862Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Closing read session. Close timeout: 0.000000s
2025-10-31T11:11:33.106914Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:0:3:0:0 -:test-topic:2:2:0:0 -:test-topic:4:1:0:0
2025-10-31T11:11:33.106951Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Counters: { Errors: 0 CurrentSessionLifetimeMs: 28 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.106980Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Closing read session. Close timeout: 0.000000s
2025-10-31T11:11:33.107004Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:3:2:0:0 -:test-topic:1:1:0:0
2025-10-31T11:11:33.107024Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Counters: { Errors: 0 CurrentSessionLifetimeMs: 24 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.107043Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Closing read session. Close timeout: 0.000000s
2025-10-31T11:11:33.107065Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:3:2:0:0 -:test-topic:1:1:0:0
2025-10-31T11:11:33.107089Z :INFO: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Counters: { Errors: 0 CurrentSessionLifetimeMs: 24 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.107188Z :NOTICE: [/Root] [/Root] [5bff5109-67deee26-d0300009-d674c0f] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
2025-10-31T11:11:33.107114Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read done: success# 0, data# {  }
2025-10-31T11:11:33.107145Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 grpc read failed
2025-10-31T11:11:33.107168Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1666: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 closed
2025-10-31T11:11:33.107238Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/user session shared/user_1_1_17768121930662765008_v1 is DEAD
2025-10-31T11:11:33.107537Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2258: [PQ: 72075186224037892] Destroy direct read session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107578Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341936730601524:2480] destroyed
2025-10-31T11:11:33.107600Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2258: [PQ: 72075186224037892] Destroy direct read session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107616Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341936730601523:2479] destroyed
2025-10-31T11:11:33.107628Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2258: [PQ: 72075186224037892] Destroy direct read session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107640Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341936730601521:2476] destroyed
2025-10-31T11:11:33.107757Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107778Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107786Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107933Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Closing read session. Close timeout: 0.000000s
2025-10-31T11:11:33.107976Z :INFO: [/Root] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:test-topic:0:3:0:0 -:test-topic:2:2:0:0 -:test-topic:4:1:0:0
2025-10-31T11:11:33.108003Z :INFO: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Counters: { Errors: 0 CurrentSessionLifetimeMs: 29 BytesRead: 0 MessagesRead: 0 BytesReadCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-10-31T11:11:33.107622Z node 1 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 grpc read done: success# 0, data# {  }
2025-10-31T11:11:33.108050Z :NOTICE: [/Root] [/Root] [1372b241-2e60d128-6150582e-9cc1106c] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
2025-10-31T11:11:33.108019Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2258: [PQ: 72075186224037892] Destroy direct read session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.107639Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1659: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601512:2470] disconnected.
2025-10-31T11:11:33.108064Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341936730601522:2478] destroyed
2025-10-31T11:11:33.107650Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 grpc read failed
2025-10-31T11:11:33.108077Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2258: [PQ: 72075186224037892] Destroy direct read session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.108087Z node 2 :PERSQUEUE DEBUG: pq_impl.cpp:2746: [PQ: 72075186224037892] server disconnected, pipe [1:7567341936730601520:2477] destroyed
2025-10-31T11:11:33.107656Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1670: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601512:2470] disconnected; active server actors: 1
2025-10-31T11:11:33.108098Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.108105Z node 2 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.107667Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:1666: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 closed
2025-10-31T11:11:33.107687Z node 1 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1679: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601512:2470] client user disconnected session shared/user_1_1_17768121930662765008_v1
2025-10-31T11:11:33.107710Z node 1 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 2 consumer shared/user session shared/user_1_2_10724727762600438365_v1 is DEAD
2025-10-31T11:11:33.107784Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1186: [72075186224037893][rt3.dc1--test-topic] consumer user rebalancing was scheduled
2025-10-31T11:11:33.107837Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1258: [72075186224037893][rt3.dc1--test-topic] consumer user balancing. Sessions=1, Families=5, UnradableFamilies=3 [1 (0), 3 (2), 5 (4), ], RequireBalancing=0 []
2025-10-31T11:11:33.107881Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=5 (Status=Free, Partitions=[4]) for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=2)
2025-10-31T11:11:33.107942Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 5 status Active partitions [4] session "shared/user_1_2_10724727762600438365_v1" sender [1:7567341936730601506:2471] lock partition 4 for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=3) generation 1 step 6
2025-10-31T11:11:33.107984Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=3)
2025-10-31T11:11:33.108038Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 3 status Active partitions [2] session "shared/user_1_2_10724727762600438365_v1" sender [1:7567341936730601506:2471] lock partition 2 for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=4) generation 1 step 7
2025-10-31T11:11:33.108070Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1305: [72075186224037893][rt3.dc1--test-topic] consumer user balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=4)
2025-10-31T11:11:33.108094Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037893][rt3.dc1--test-topic] consumer user family 1 status Active partitions [0] session "shared/user_1_2_10724727762600438365_v1" sender [1:7567341936730601506:2471] lock partition 0 for ReadingSession "shared/user_1_2_10724727762600438365_v1" (Sender=[1:7567341936730601506:2471], Pipe=[1:7567341936730601511:2471], Partitions=[], ActiveFamilyCount=5) generation 1 step 8
2025-10-31T11:11:33.108118Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1325: [72075186224037893][rt3.dc1--test-topic] consumer user start rebalancing. familyCount=5, sessionCount=1, desiredFamilyCount=5, allowPlusOne=0
2025-10-31T11:11:33.108142Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1402: [72075186224037893][rt3.dc1--test-topic] consumer user balancing duration: 0.000289s
2025-10-31T11:11:33.108157Z node 1 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1659: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601511:2471] disconnected.
2025-10-31T11:11:33.108167Z node 1 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1670: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601511:2471] disconnected; active server actors: 1
2025-10-31T11:11:33.108181Z node 1 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1679: [72075186224037893][rt3.dc1--test-topic] pipe [1:7567341936730601511:2471] client user disconnected session shared/user_1_2_10724727762600438365_v1
2025-10-31T11:11:33.164057Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.164096Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.164133Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:33.169458Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.169496Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.169507Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:33.174809Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.174844Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.174856Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:33.174929Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.174947Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.174953Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:33.175276Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.175290Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.175297Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T11:11:33.264453Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.264483Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.264494Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T11:11:33.269564Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.269587Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.269598Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T11:11:33.275212Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][3][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.275253Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][3][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.275266Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][3][StateIdle] Try persist
2025-10-31T11:11:33.275328Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][4][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.275340Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][4][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.275346Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][4][StateIdle] Try persist
2025-10-31T11:11:33.275631Z node 2 :PERSQUEUE DEBUG: partition.cpp:2272: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T11:11:33.275643Z node 2 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T11:11:33.275649Z node 2 :PERSQUEUE DEBUG: partition.cpp:2304: [72075186224037892][Partition][0][StateIdle] Try persist
