2025-10-31T08:23:24.677341Z node 16 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[16:7567298602293095821:2076];send_to=[0:7307199536658146131:7762515];
2025-10-31T08:23:24.677423Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2025-10-31T08:23:24.736166Z node 16 :BS_NODE WARN: {NWDC01@distconf.cpp:397} StateFunc too long Type# 131077 Duration# 0.008151s
2025-10-31T08:23:24.872693Z node 16 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-10-31T08:23:24.900335Z node 17 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[17:7567298601870803616:2148];send_to=[0:7307199536658146131:7762515];
2025-10-31T08:23:24.900890Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/7ydd/001057/r3tmp/tmpyeZEMG/pdisk_1.dat
2025-10-31T08:23:24.951449Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-10-31T08:23:24.943802Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:24.996102Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:25.184012Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:25.216052Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:25.568831Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:25.603146Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-10-31T08:23:25.699035Z node 16 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-10-31T08:23:25.716229Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=incorrect path status: LookupError;
2025-10-31T08:23:25.752156Z node 16 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-10-31T08:23:25.789197Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-10-31T08:23:25.789353Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-10-31T08:23:25.801632Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-10-31T08:23:25.801755Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-10-31T08:23:25.834224Z node 16 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 17 Cookie 17
2025-10-31T08:23:25.834453Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(16, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-10-31T08:23:25.846586Z node 16 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(17, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-10-31T08:23:25.896197Z node 17 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
TServer::EnableGrpc on GrpcPort 29895, node 16
2025-10-31T08:23:26.141093Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/7ydd/001057/r3tmp/yandexBWNftr.tmp
2025-10-31T08:23:26.141127Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/7ydd/001057/r3tmp/yandexBWNftr.tmp
2025-10-31T08:23:26.141347Z node 16 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/7ydd/001057/r3tmp/yandexBWNftr.tmp
2025-10-31T08:23:26.141559Z node 16 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-10-31T08:23:26.306690Z INFO: TTestServer started on Port 28885 GrpcPort 29895
2025-10-31T08:23:26.421566Z node 17 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-10-31T08:23:26.529511Z node 16 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
TClient is connected to server localhost:28885
PQClient connected to localhost:29895
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-31T08:23:27.170001Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976710657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
2025-10-31T08:23:27.243360Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
waiting...
waiting...
waiting...
2025-10-31T08:23:29.677617Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[16:7567298602293095821:2076];send_to=[0:7307199536658146131:7762515];
2025-10-31T08:23:29.677710Z node 16 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2025-10-31T08:23:29.836108Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[17:7567298601870803616:2148];send_to=[0:7307199536658146131:7762515];
2025-10-31T08:23:29.836206Z node 17 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2025-10-31T08:23:34.142270Z node 16 :KQP_PROXY ERROR: kqp_proxy_service.cpp:1316: TraceId: "01k8wntkk54d24ey25y5gj1z2p", Request deadline has expired for 1.554340s seconds
2025-10-31T08:23:34.257170Z node 16 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:566: Compilation failed, self: [16:7567298645242769937:2335], 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-31T08:23:34.261699Z node 16 :KQP_SESSION WARN: kqp_session_actor.cpp:2408: SessionId: ydb://session/3?node_id=16&id=ZmU3ZjEwMzktNDc0ZGRjZjktNWRjZjQ3MzYtNzc3MzdkMTc=, ActorId: [16:7567298645242769931:2333], ActorState: ExecuteState, TraceId: 01k8wntt062z1rkqxjrg6g0r1v, ReplyQueryCompileError, status SCHEME_ERROR remove tx with tx_id: 
2025-10-31T08:23:34.262305Z node 16 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } TxMeta { } } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2025-10-31T08:23:34.285973Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710662: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-31T08:23:34.506206Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-10-31T08:23:34.876559Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976710664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
=== Init DC: UPSERT INTO `/Root/PQ/Config/V2/Cluster` (name, balancer, local, enabled, weight) VALUES ("dc1", "localhost", true, true, 1000), ("dc2", "dc2.logbroker.yandex.net", false, true, 1000);
2025-10-31T08:23:35.646079Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976710665. Ctx: { TraceId: 01k8wnttzp9ebcs4hkhjzv7dhm, Database: , SessionId: ydb://session/3?node_id=16&id=NTY0NmZlMTgtODBhZDZhNTAtZTc1NWQ2MWEtOGM1M2VjOTM=, PoolId: , DatabaseId: /Root}. Database not set, use /Root
=== CheckClustersList. Subcribe to ClusterTracker from [16:7567298649537737699:3074] 
2025-10-31T08:23:40.512115Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard_impl.cpp:7717: Cannot get console configs
2025-10-31T08:23:40.512152Z node 16 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
=== CheckClustersList. Ok
PQ Client: create topic: rt3.dc1--topic1 with 3 partitions
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-10-31T08:23:41.196804Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdCreateTopic {
    Topic: "rt3.dc1--topic1"
    NumPartitions: 3
    Config {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
      }
      LocalDC: true
      Codecs {
        Ids: 0
        Ids: 1
        Ids: 2
        Codecs: "raw"
        Codecs: "gzip"
        Codecs: "lzop"
      }
      Consumers {
        Name: "user"
        ReadFromTimestampsMs: 0
        FormatVersion: 0
        Version: 0
      }
    }
  }
}
CallPersQueueGRPC response:
Status: 129
ProxyErrorCode: 53
SchemeStatus: 1
FlatTxId {
  TxId: 281474976710673
  SchemeShardTabletId: 72057594046644480
  PathId: 9
}
ErrorCode: OK
AddTopic: rt3.dc1--topic1
===Run query:``DECLARE $version as Int64; DECLARE $path AS Utf8; DECLARE $cluster as Utf8; UPSERT INTO `/Root/PQ/Config/V2/Topics` (path, dc) VALUES ($path, $cluster); UPSERT INTO `/Root/PQ/Config/V2/Versions` (name, version) VALUES ("Topics", $version);`` with topic = topic1, dc = dc1
2025-10-31T08:23:41.323138Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][] pipe [16:7567298675307541837:3271] connected; active server actors: 1
2025-10-31T08:23:41.323453Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1517: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions [1, 2, 0]
2025-10-31T08:23:41.331789Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:969: [72075186224037893][rt3.dc1--topic1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-10-31T08:23:41.333776Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2962: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-10-31T08:23:41.336070Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:138: [72075186224037893][rt3.dc1--topic1] BALANCER INIT DONE for rt3.dc1--topic1: (0, 72075186224037892) (1, 72075186224037892) (2, 72075186224037892) 
2025-10-31T08:23:41.347563Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2994: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-10-31T08:23:41.347923Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-10-31T08:23:41.347977Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-10-31T08:23:41.348028Z node 17 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-10-31T08:23:41.353309Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298675307541877:2443], now have 1 active actors on pipe
2025-10-31T08:23:41.353346Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298675307541836:3270], now have 1 active actors on pipe
2025-10-31T08:23:41.353997Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:402: [72075186224037893][rt3.dc1--topic1] TEvClientConnected TabletId 72075186224037892, NodeId 17, Generation 1
2025-10-31T08:23:41.355878Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:41.368053Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:41.378222Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298675307541903:3321], now have 1 active actors on pipe
2025-10-31T08:23:41.379911Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:41.389336Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:41.392199Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitConfigStep
2025-10-31T08:23:41.392259Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitConfigStep
2025-10-31T08:23:41.392283Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitConfigStep
2025-10-31T08:23:41.392318Z node 17 :PERSQUEUE DEBUG: partition.cpp:1279: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T08:23:41.392339Z node 17 :PERSQUEUE DEBUG: partition.cpp:1279: [72075186224037892][Partition][2][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T08:23:41.392356Z node 17 :PERSQUEUE DEBUG: partition.cpp:1279: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-10-31T08:23:41.392995Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T08:23:41.393289Z node 17 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [17:7567298674885248294:2366]
2025-10-31T08:23:41.401018Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:1:Initializer] Start initializing step TInitFieldsStep
2025-10-31T08:23:41.401055Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--topic1:1:Initializer] Initializing completed.
2025-10-31T08:23:41.401093Z node 17 :PERSQUEUE INFO: partition.cpp:696: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--topic1' partition 1 generation 1 [17:7567298674885248294:2366]
2025-10-31T08:23:41.401127Z node 17 :PERSQUEUE DEBUG: partition.cpp:710: [72075186224037892][Partition][1][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 1 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T08:23:41.402919Z node 17 :PERSQUEUE DEBUG: partition.cpp:4239: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2025-10-31T08:23:41.402960Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.403093Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.403105Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T08:23:41.403126Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][1][StateIdle] Still pending for come commits
2025-10-31T08:23:41.403138Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.403151Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.403162Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][1][StateIdle] Still pending for come commits
2025-10-31T08:23:41.403203Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:41.403485Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T08:23:41.403784Z node 17 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][2][StateInit] bootstrapping 2 [17:7567298674885248295:2366]
2025-10-31T08:23:41.407382Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:2:Initializer] Start initializing step TInitFieldsStep
2025-10-31T08:23:41.407428Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--topic1:2:Initializer] Initializing completed.
2025-10-31T08:23:41.407459Z node 17 :PERSQUEUE INFO: partition.cpp:696: [72075186224037892][Partition][2][StateInit] init complete for topic 'rt3.dc1--topic1' partition 2 generation 1 [17:7567298674885248295:2366]
2025-10-31T08:23:41.407490Z node 17 :PERSQUEUE DEBUG: partition.cpp:710: [72075186224037892][Partition][2][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 2 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T08:23:41.409410Z node 17 :PERSQUEUE DEBUG: partition.cpp:4239: [72075186224037892][Partition][2][StateIdle] Process pending events. Count 1
2025-10-31T08:23:41.409461Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.409588Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.409600Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T08:23:41.409620Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][2][StateIdle] Still pending for come commits
2025-10-31T08:23:41.409634Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.409648Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.409660Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][2][StateIdle] Still pending for come commits
2025-10-31T08:23:41.409700Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:41.409981Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-10-31T08:23:41.410301Z node 17 :PERSQUEUE INFO: partition_init.cpp:1101: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [17:7567298674885248296:2366]
2025-10-31T08:23:41.414078Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:81: [rt3.dc1--topic1:0:Initializer] Start initializing step TInitFieldsStep
2025-10-31T08:23:41.414123Z node 17 :PERSQUEUE DEBUG: partition_init.cpp:61: [rt3.dc1--topic1:0:Initializer] Initializing completed.
2025-10-31T08:23:41.414158Z node 17 :PERSQUEUE INFO: partition.cpp:696: [72075186224037892][Partition][0][StateInit] init complete for topic 'rt3.dc1--topic1' partition 0 generation 1 [17:7567298674885248296:2366]
2025-10-31T08:23:41.414189Z node 17 :PERSQUEUE DEBUG: partition.cpp:710: [72075186224037892][Partition][0][StateInit] SYNC INIT topic rt3.dc1--topic1 partitition 0 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-10-31T08:23:41.415767Z node 17 :PERSQUEUE DEBUG: partition.cpp:4239: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-10-31T08:23:41.415808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.416335Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.416355Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T08:23:41.416377Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Still pending for come commits
2025-10-31T08:23:41.416393Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.416406Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.416417Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Still pending for come commits
2025-10-31T08:23:41.416464Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:41.417158Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.417393Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.417798Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:41.417928Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.425218Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:41.425422Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899021421, TxId 281474976710673
2025-10-31T08:23:41.425443Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.425456Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.425662Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.426030Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899021421, TxId 281474976710673
2025-10-31T08:23:41.426047Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.426056Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.426177Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.426329Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899021421, TxId 281474976710673
2025-10-31T08:23:41.426342Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.426351Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.426437Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.426583Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:41.426622Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:41.426642Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:41.432820Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:41.433252Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:41.433279Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.433299Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.433317Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.433350Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.433383Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:41.433587Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:41.433604Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.433615Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.433624Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.433642Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.433663Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:41.433860Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:41.433873Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.433883Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.433893Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.433908Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T08:23:41.434433Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { LifetimeSeconds: 86400 LowWatermark: 8388608 SourceIdLifetimeSeconds: 86400 WriteSpeedInBytesPerSecond: 20000000 BurstSize: 20000000 TotalPartitions: 3 SourceIdMaxCounts: 6000000 } PartitionIds: 1 PartitionIds: 2 PartitionIds: 0 TopicName: "rt3.dc1--topic1" Version: 0 LocalDC: true Codecs { Ids: 0 Ids: 1 Ids: 2 Codecs: "raw" Codecs: "gzip" Codecs: "lzop" } TopicPath: "/Root/PQ/rt3.dc1--topic1" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Version: 0 Generation: 0 } MonitoringProjectId: ""
2025-10-31T08:23:41.434592Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-10-31T08:23:41.435092Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:41.448704Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:41.448865Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:41.453018Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:41.504012Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.504054Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.504073Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.509777Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.509816Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.509833Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.520551Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.520600Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.520623Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.604297Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.604335Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.604352Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.612171Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.612224Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.612245Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.624162Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.624201Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.624223Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.708197Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.708233Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.708250Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.716259Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.716298Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.716317Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.724353Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.724388Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.724407Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.812583Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.812619Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.812639Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.817532Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.817575Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.817591Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.828142Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.828177Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.828196Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.912495Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.912536Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.912556Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:41.920150Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.920191Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.920212Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:41.932164Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:41.932198Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:41.932224Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:41.995910Z node 16 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976710676. Ctx: { TraceId: 01k8wnv11957zev83134gwj20g, Database: , SessionId: ydb://session/3?node_id=16&id=NzkzN2E2NTAtZTAyZGRlOWMtYzY2YmQyMmMtYmNlYjA3ZGM=, PoolId: , DatabaseId: /Root}. Database not set, use /Root
2025-10-31T08:23:42.014736Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.014769Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.014786Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:42.024368Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.024415Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.024435Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:42.035538Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.035575Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.035592Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
===Query complete
TClient::Ls request: /Root/PQ/rt3.dc1--topic1
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--topic1"
    PathId: 9
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710673
    CreateStep: 1761899021421
    ParentPathId: 2
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 0
      PQVersion: 1
    }
    ChildrenExist: false
  }
  PersQueueGroup {
    Name: "rt3.dc1--topic1"
    PathId: 9
    TotalGroupCount: 3
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
...
(TRUNCATED)
GetTopicVersionFromPath:  record Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "rt3.dc1--topic1"
    PathId: 9
    SchemeshardId: 72057594046644480
    PathType: EPathTypePersQueueGroup
    CreateFinished: true
    CreateTxId: 281474976710673
    CreateStep: 1761899021421
    ParentPathId: 2
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 0
      PQVersion: 1
    }
    ChildrenExist: false
  }
  PersQueueGroup {
    Name: "rt3.dc1--topic1"
    PathId: 9
    TotalGroupCount: 3
    PartitionPerTablet: 5
    PQTabletConfig {
      PartitionConfig {
        LifetimeSeconds: 86400
        LowWatermark: 8388608
        SourceIdLifetimeSeconds: 86400
        WriteSpeedInBytesPerSecond: 20000000
        BurstSize: 20000000
        SourceIdMaxCounts: 6000000
      }
      LocalDC: true
      Codecs {
        Ids: 0
        Ids: 1
        Ids: 2
        Codecs: "raw"
        Codecs: "gzip"
        Codecs: "lzop"
      }
      YdbDatabasePath: "/Root"
      Consumers {
        Name: "user"
        ReadFromTimestampsMs: 0
        FormatVersion: 0
        Version: 0
      }
    }
    Partitions {
      PartitionId: 0
      TabletId: 72075186224037892
      Status: Active
    }
    Partitions {
      PartitionId: 1
      TabletId: 72075186224037892
      Status: Active
    }
    Partitions {
      PartitionId: 2
      TabletId: 72075186224037892
      Status: Active
    }
    AlterVersion: 1
    BalancerTabletID: 72075186224037893
    NextPartitionId: 3
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 1
      PlanResolution: 7
      Coordinators: 72057594046316545
      TimeCastBucketsPerMediator: 2
      Mediators: 72057594046382081
    }
    DomainKey {
      SchemeShard: 72057594046644480
      PathId: 1
    }
    PathsInside: 8
    PathsLimit: 10000
    ShardsInside: 6
    ShardsLimit: 200000
    ResourcesDomainKey {
      SchemeShard: 72057594046644480
      PathId: 1
    }
    DiskSpaceUsage {
      Tables {
        TotalSize: 0
        DataSize: 0
        IndexSize: 0
      }
      Topics {
        ReserveSize: 0
        AccountSize: 0
        DataSize: 0
        UsedReserveSize: 0
      }
    }
    PQPartitionsInside: 3
    PQPartitionsLimit: 1000000
    SchemeLimits {
      MaxDepth: 32
      MaxPaths: 10000
      MaxChildrenInDir: 100000
      MaxAclBytesSize: 10240
      MaxTableColumns: 200
      MaxTableColumnNameLength: 255
      MaxTableKeyColumns: 30
      MaxTableIndices: 20
      MaxShards: 200000
      MaxShardsInPath: 35000
      MaxConsistentCopyTargets: 10000
      MaxPathElementLength: 255
      ExtraPathSymbolsAllowed: "!\"#$%&\'()*+,-.:;<=>?@[\\]^_`{|}~"
      MaxPQPartitions: 1000000
      MaxTableCdcStreams: 5
      MaxExports: 10
      MaxImports: 10
      MaxColumnTableColumns: 10000
    }
  }
}
Path: "/Root/PQ/rt3.dc1--topic1"
 name rt3.dc1--topic1 version1
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-10-31T08:23:42.095988Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
2025-10-31T08:23:42.120018Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.120074Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.120100Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:42.124193Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.124232Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.124262Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:42.136105Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.136142Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.136160Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:42.224121Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.224155Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.224172Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:42.228285Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.228315Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.228331Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:42.240148Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.240183Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.240198Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:42.324370Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.324402Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.324418Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:42.332146Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.332178Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.332195Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:42.340604Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.340646Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.340664Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:42.428459Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:42.428495Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:42.428513Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-10-31T08:23:42.605638Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
2025-10-31T08:23:43.047065Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.047097Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.047111Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.047155Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.047165Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.047173Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.047197Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.047207Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.047215Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-10-31T08:23:43.110484Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 128
ErrorReason: "the following topics are not created: rt3.dc1--topic1, Marker# PQ95"
ErrorCode: UNKNOWN_TOPIC
2025-10-31T08:23:43.169781Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.169821Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.169836Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.169879Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.169888Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.169895Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.169919Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.169927Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.169934Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.265718Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.265767Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.265784Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.265842Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.265851Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.265859Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.265885Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.265894Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.265902Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.368409Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.368453Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.368470Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.368523Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.368534Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.368543Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.368568Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.368577Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.368585Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.475996Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.476036Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.476052Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.476103Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.476111Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.476119Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.476154Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.476162Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.476169Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.576106Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.576154Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.576175Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.582361Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.582401Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.582420Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.582478Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.582487Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.582497Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
CallPersQueueGRPC request to localhost:29895
MetaRequest {
  CmdGetTopicMetadata {
    Topic: "rt3.dc1--topic1"
  }
}
2025-10-31T08:23:43.616581Z node 16 :PERSQUEUE INFO: msgbus_server_persqueue.cpp:1531: proxy answer <none>
CallPersQueueGRPC response:
Status: 1
ErrorCode: OK
MetaResponse {
  CmdGetTopicMetadataResult {
    TopicInfo {
      Topic: "rt3.dc1--topic1"
      NumPartitions: 3
      Config {
        PartitionConfig {
          LifetimeSeconds: 86400
          LowWatermark: 8388608
          SourceIdLifetimeSeconds: 86400
          WriteSpeedInBytesPerSecond: 20000000
          BurstSize: 20000000
          SourceIdMaxCounts: 6000000
        }
        Version: 1
        LocalDC: true
        Codecs {
          Ids: 0
          Ids: 1
          Ids: 2
          Codecs: "raw"
          Codecs: "gzip"
          Codecs: "lzop"
        }
        TopicPath: "/Root/PQ/rt3.dc1--topic1"
        YdbDatabasePath: "/Root"
        Consumers {
          Name: "user"
          ReadFromTimestampsMs: 0
          FormatVersion: 0
          Version: 0
        }
      }
      ErrorCode: OK
    }
  }
}
=== Topic created, have version: 1
2025-10-31T08:23:43.657997Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:141: new alter topic request
2025-10-31T08:23:43.661091Z node 16 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterPersQueueGroup, opId: 281474976710681:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_pq.cpp:313)
2025-10-31T08:23:43.680343Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.680378Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.680398Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.680445Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.680454Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.680462Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.680484Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.680494Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.680502Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.693977Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1517: [72075186224037893][rt3.dc1--topic1] updating configuration. Deleted partitions []. Added partitions []
2025-10-31T08:23:43.695054Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:43.702941Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:43.724776Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:43.749225Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:43.749775Z node 17 :PERSQUEUE DEBUG: partition.cpp:1244: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.749830Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.749945Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.749965Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T08:23:43.750008Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][0][StateIdle] Still pending for come commits
2025-10-31T08:23:43.750027Z node 17 :PERSQUEUE DEBUG: partition.cpp:1244: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.750042Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.750090Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.750098Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T08:23:43.750109Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][2][StateIdle] Still pending for come commits
2025-10-31T08:23:43.750125Z node 17 :PERSQUEUE DEBUG: partition.cpp:1244: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvProposePartitionConfig Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.750139Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.750185Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.750193Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T08:23:43.750203Z node 17 :PERSQUEUE DEBUG: partition.cpp:2285: [72075186224037892][Partition][1][StateIdle] Still pending for come commits
2025-10-31T08:23:43.750541Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:43.759425Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:43.759656Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.759674Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.759689Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.759967Z node 17 :PERSQUEUE DEBUG: partition.cpp:3588: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug reinit with generation 2 done
2025-10-31T08:23:43.759986Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.760413Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.760428Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.760438Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.760578Z node 17 :PERSQUEUE DEBUG: partition.cpp:3588: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug reinit with generation 2 done
2025-10-31T08:23:43.760591Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.760771Z node 17 :PERSQUEUE DEBUG: partition.cpp:1383: [72075186224037892][Partition][2][StateIdle] Handle TEvPQ::TEvTxCommit Step 1761899023759, TxId 281474976710681
2025-10-31T08:23:43.760783Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.760790Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.760887Z node 17 :PERSQUEUE DEBUG: partition.cpp:3588: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug reinit with generation 2 done
2025-10-31T08:23:43.760898Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.761057Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:43.761095Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:43.761112Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:43.767082Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:43.768983Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:43.769016Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.769038Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.769056Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.769088Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T08:23:43.769132Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:43.770744Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:43.770773Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.770790Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.770804Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.770832Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T08:23:43.770869Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:43.780749Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:43.780801Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.780823Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.780843Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.780882Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T08:23:43.781803Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { LifetimeSeconds: 86400 LowWatermark: 8388608 SourceIdLifetimeSeconds: 86400 WriteSpeedInBytesPerSecond: 20000000 BurstSize: 20000000 TotalPartitions: 3 SourceIdMaxCounts: 6000000 } PartitionIds: 1 PartitionIds: 2 PartitionIds: 0 TopicName: "rt3.dc1--topic1" Version: 2 LocalDC: true Codecs { Ids: 0 Ids: 1 Ids: 2 Codecs: "raw" Codecs: "gzip" Codecs: "lzop" } TopicPath: "/Root/PQ/rt3.dc1--topic1" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 0 } Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } AllPartitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } AllPartitions { PartitionId: 2 Status: Active CreateVersion: 1 TabletId: 72075186224037892 } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 } Consumers { Name: "debug" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 2 } MonitoringProjectId: ""
2025-10-31T08:23:43.781866Z node 17 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-10-31T08:23:43.782467Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:43.782576Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.782600Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.782617Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.782659Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.782669Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.782676Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.782701Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.782709Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.782718Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.803158Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:43.803292Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:3572: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-10-31T08:23:43.812271Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-10-31T08:23:43.863670Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-10-31T08:23:43.863712Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-10-31T08:23:43.864164Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-10-31T08:23:43.864362Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 1 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37214
2025-10-31T08:23:43.864378Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 1. Disable deduplication for empty producer id
2025-10-31T08:23:43.864398Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="topic server" ip=ipv6:[::1]:37214 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-10-31T08:23:43.864410Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-10-31T08:23:43.867419Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-10-31T08:23:43.867623Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2025-10-31T08:23:43.867643Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-10-31T08:23:43.867663Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2025-10-31T08:23:43.867706Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7567298683897476791:2484] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-10-31T08:23:43.868637Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476792:2484] connected; active server actors: 1
2025-10-31T08:23:43.868813Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7567298683897476791:2484] (SourceId=, PreferedPartition=(NULL)) Received partition 0 from PQRB for SourceId=
2025-10-31T08:23:43.868861Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7567298683897476791:2484] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-10-31T08:23:43.868929Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-10-31T08:23:43.869691Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476792:2484] disconnected; active server actors: 1
2025-10-31T08:23:43.869733Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476792:2484] disconnected no session
2025-10-31T08:23:43.876512Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298683897476799:2484], now have 1 active actors on pipe
2025-10-31T08:23:43.877027Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-10-31T08:23:43.880329Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.880395Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:43.880537Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 generated for partition 0 topic 'rt3.dc1--topic1' owner e5023c20-7459ba00-918bf2ae-8bfd398d
2025-10-31T08:23:43.880586Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.880615Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.880636Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T08:23:43.880690Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.880748Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-10-31T08:23:43.880815Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.884905Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.884966Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:43.885042Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.885067Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.885086Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.885125Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.885136Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.885144Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.885165Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.885175Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.885183Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.885264Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.888328Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 0 MaxSeqNo: 0 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0
2025-10-31T08:23:43.903570Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-10-31T08:23:43.903606Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-10-31T08:23:43.903711Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-10-31T08:23:43.904136Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-10-31T08:23:43.904916Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.904975Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:43.905069Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-10-31T08:23:43.905328Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-10-31T08:23:43.905436Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 2 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37214
2025-10-31T08:23:43.905449Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 2. Disable deduplication for empty producer id
2025-10-31T08:23:43.905465Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:37214 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-10-31T08:23:43.905473Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-10-31T08:23:43.905526Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-10-31T08:23:43.906620Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.906675Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:43.907168Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.907211Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.907231Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 0 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.907303Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:637: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-10-31T08:23:43.907394Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.907449Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.907471Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][0][StateIdle] Batch completed (3)
2025-10-31T08:23:43.907619Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 1 partNo 0
2025-10-31T08:23:43.908533Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-10-31T08:23:43.908605Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 2 partNo 0
2025-10-31T08:23:43.908636Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-10-31T08:23:43.908661Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob processing sourceId '' seqNo 3 partNo 0
2025-10-31T08:23:43.908685Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-10-31T08:23:43.908710Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.909356Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-10-31T08:23:43.909564Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2025-10-31T08:23:43.909594Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-10-31T08:23:43.909614Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2025-10-31T08:23:43.909634Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7567298683897476802:2487] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-10-31T08:23:43.909586Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1675: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 0 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000000_00000000000000000000_00000_0000000003_00000? size 409 WTime 1761899023907
2025-10-31T08:23:43.909853Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:43.910056Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 3 size 409
2025-10-31T08:23:43.910167Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476803:2487] connected; active server actors: 1
2025-10-31T08:23:43.910301Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7567298683897476802:2487] (SourceId=, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=
2025-10-31T08:23:43.910330Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7567298683897476802:2487] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=0
2025-10-31T08:23:43.910385Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 1 expectedGeneration: (NULL)
2025-10-31T08:23:43.910970Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476803:2487] disconnected; active server actors: 1
2025-10-31T08:23:43.911011Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476803:2487] disconnected no session
2025-10-31T08:23:43.913409Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-10-31T08:23:43.916108Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298683897476807:2487], now have 1 active actors on pipe
2025-10-31T08:23:43.916207Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.916247Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:43.916377Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 generated for partition 1 topic 'rt3.dc1--topic1' owner 937c2d56-4ec5845f-11192c79-811369a0
2025-10-31T08:23:43.916417Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.916444Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.916464Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T08:23:43.916516Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.916564Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2025-10-31T08:23:43.916628Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.925063Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 3 size 409 actorID [17:7567298674885248258:2366]
2025-10-31T08:23:43.925144Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:43.925202Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:43.925247Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-10-31T08:23:43.925310Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-10-31T08:23:43.925336Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-10-31T08:23:43.925359Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-10-31T08:23:43.925372Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-10-31T08:23:43.925393Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 0, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-10-31T08:23:43.925743Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:43.925778Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-10-31T08:23:43.925798Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:43.925835Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.925853Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.925871Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.925907Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T08:23:43.925996Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-10-31T08:23:43.926156Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 0 user user offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:43.926507Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:43.926532Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-10-31T08:23:43.926594Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:43.926627Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-10-31T08:23:43.926681Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:43.927029Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:43.927137Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 0 user user readTimeStamp done, result 1761899023906 queuesize 1 startOffset 0
2025-10-31T08:23:43.927174Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-10-31T08:23:43.927210Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:43.927324Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:43.927570Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:43.927588Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2025-10-31T08:23:43.927631Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:43.927655Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-10-31T08:23:43.927691Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:43.928783Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-10-31T08:23:43.928894Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:43.928930Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:43.929437Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:43.929596Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.929637Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:43.929693Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 0 user debug readTimeStamp done, result 1761899023906 queuesize 0 startOffset 0
2025-10-31T08:23:43.929810Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.931037Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::IEventHandle
2025-10-31T08:23:43.931686Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 1 MaxSeqNo: 0 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0
2025-10-31T08:23:43.944923Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-10-31T08:23:43.944969Z node 16 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-10-31T08:23:43.945510Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId:  grpc read done: success: 1 data: init_request { path: "rt3.dc1--topic1" message_group_id: "" }
2025-10-31T08:23:43.945647Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 3 path: "rt3.dc1--topic1" message_group_id: "" from ipv6:[::1]:37214
2025-10-31T08:23:43.945661Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:449: session request cookie: 3. Disable deduplication for empty producer id
2025-10-31T08:23:43.945680Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:37214 proto=topic  topic=rt3.dc1--topic1 durationSec=0
2025-10-31T08:23:43.945692Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-10-31T08:23:43.947941Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-10-31T08:23:43.948142Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2025-10-31T08:23:43.948164Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-10-31T08:23:43.948185Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2025-10-31T08:23:43.948206Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [16:7567298683897476812:2489] (SourceId=, PreferedPartition=(NULL)) RequestPQRB
2025-10-31T08:23:43.952658Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476813:2489] connected; active server actors: 1
2025-10-31T08:23:43.952944Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [16:7567298683897476812:2489] (SourceId=, PreferedPartition=(NULL)) Received partition 2 from PQRB for SourceId=
2025-10-31T08:23:43.952972Z node 16 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [16:7567298683897476812:2489] (SourceId=, PreferedPartition=(NULL)) ReplyResult: Partition=2, SeqNo=0
2025-10-31T08:23:43.953039Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 2 expectedGeneration: (NULL)
2025-10-31T08:23:43.954535Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476813:2489] disconnected; active server actors: 1
2025-10-31T08:23:43.954574Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1689: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298683897476813:2489] disconnected no session
2025-10-31T08:23:43.961224Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298683897476817:2489], now have 1 active actors on pipe
2025-10-31T08:23:43.961296Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=2) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 17, Generation: 1
2025-10-31T08:23:43.961677Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.961735Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:43.961862Z node 17 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 generated for partition 2 topic 'rt3.dc1--topic1' owner d2a8c48d-ba084494-9384ef00-ed1484ba
2025-10-31T08:23:43.961902Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.961928Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.961947Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T08:23:43.961997Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.962043Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:35: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyOwnerOk. Partition: 2
2025-10-31T08:23:43.962120Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.963053Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.963094Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:43.963194Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:43.963683Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 2 MaxSeqNo: 0 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0
2025-10-31T08:23:43.970260Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 grpc read done: success: 0 data: 
2025-10-31T08:23:43.970290Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 grpc read failed
2025-10-31T08:23:43.970347Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 grpc closed
2025-10-31T08:23:43.970383Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: e5023c20-7459ba00-918bf2ae-8bfd398d|9909b4e3-105fbf95-c7f43fe9-77259c63_0 is DEAD
2025-10-31T08:23:43.971442Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-10-31T08:23:43.971575Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-10-31T08:23:43.971797Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-10-31T08:23:43.972561Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298683897476799:2484] destroyed
2025-10-31T08:23:43.972614Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-10-31T08:23:43.972649Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.972668Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.972688Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.974233Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.974283Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:43.974390Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 1
2025-10-31T08:23:43.974729Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-10-31T08:23:43.975293Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:43.975323Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:43.975395Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.975417Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.975436Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 2 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:43.975489Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:637: [72075186224037892][Partition][2][StateIdle] Received TPartition::TEvWrite
2025-10-31T08:23:43.975565Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.975617Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.975634Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][2][StateIdle] Batch completed (3)
2025-10-31T08:23:43.975783Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 1 partNo 0
2025-10-31T08:23:43.976987Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-10-31T08:23:43.977055Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 2 partNo 0
2025-10-31T08:23:43.977089Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-10-31T08:23:43.977113Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob processing sourceId '' seqNo 3 partNo 0
2025-10-31T08:23:43.977136Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-10-31T08:23:43.977164Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.978073Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1675: [72075186224037892][Partition][2][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 2 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000002_00000000000000000000_00000_0000000003_00000? size 409 WTime 1761899023975
2025-10-31T08:23:43.978316Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:43.978525Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 2 offset 0 partNo 0 count 3 size 409
2025-10-31T08:23:43.985202Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 2 offset 0 count 3 size 409 actorID [17:7567298674885248258:2366]
2025-10-31T08:23:43.985279Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:43.985340Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:43.985384Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-10-31T08:23:43.985442Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-10-31T08:23:43.985480Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-10-31T08:23:43.985502Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-10-31T08:23:43.985513Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][2][StateIdle] TPartition::ReplyWrite. Partition: 2
2025-10-31T08:23:43.985551Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][2][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 2, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-10-31T08:23:43.985899Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:43.985930Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-10-31T08:23:43.985949Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:43.985973Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.985992Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.986008Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.986045Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T08:23:43.986138Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 1  requestId: <none> cookie: 1
2025-10-31T08:23:43.986285Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 2 user user offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:43.986639Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][2][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:43.986663Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][2][StateIdle] Reading cookie 3. Send blob request.
2025-10-31T08:23:43.986727Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:43.986762Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-10-31T08:23:43.986818Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:43.987164Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:43.987272Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 2 user user readTimeStamp done, result 1761899023972 queuesize 1 startOffset 0
2025-10-31T08:23:43.987290Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-10-31T08:23:43.987304Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:43.987411Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:43.987653Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][2][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:43.987670Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][2][StateIdle] Reading cookie 4. Send blob request.
2025-10-31T08:23:43.987714Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:43.987736Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-10-31T08:23:43.987771Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:43.988055Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-10-31T08:23:43.988143Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:43.988183Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:43.988677Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::IEventHandle
2025-10-31T08:23:43.995409Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:43.995613Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.995641Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.995659Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:43.995705Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.995720Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.995729Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:43.995761Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:43.995770Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:43.995779Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:43.995821Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 2 user debug readTimeStamp done, result 1761899023972 queuesize 0 startOffset 0
2025-10-31T08:23:44.052074Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-10-31T08:23:44.052415Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-10-31T08:23:44.056321Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.056399Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:44.056511Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2025-10-31T08:23:44.057271Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-10-31T08:23:44.058259Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.058311Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:44.058382Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 1 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:44.058407Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 2 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:44.058429Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2012: [PQ: 72075186224037892] got client message topic: rt3.dc1--topic1 partition: 1 SourceId: '' SeqNo: 3 partNo : 0 messageNo: 1 size 99 offset: -1
2025-10-31T08:23:44.058497Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:637: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2025-10-31T08:23:44.058576Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.058626Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.058643Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][1][StateIdle] Batch completed (3)
2025-10-31T08:23:44.058788Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 1 partNo 0
2025-10-31T08:23:44.059674Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 166 count 1 nextOffset 1 batches 1
2025-10-31T08:23:44.059740Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 2 partNo 0
2025-10-31T08:23:44.059771Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 300 count 2 nextOffset 2 batches 1
2025-10-31T08:23:44.059792Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1319: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob processing sourceId '' seqNo 3 partNo 0
2025-10-31T08:23:44.059815Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1423: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 part blob complete sourceId '' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 434 count 3 nextOffset 3 batches 1
2025-10-31T08:23:44.059838Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.060717Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:1675: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--topic1' partition 1 compactOffset 0,3 HeadOffset 0 endOffset 0 curOffset 3 d0000000001_00000000000000000000_00000_0000000003_00000? size 409 WTime 1761899024057
2025-10-31T08:23:44.060957Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:44.061160Z node 17 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 3 size 409
2025-10-31T08:23:44.073339Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 1 offset 0 count 3 size 409 actorID [17:7567298674885248258:2366]
2025-10-31T08:23:44.073434Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:44.073497Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 297 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:44.073540Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-10-31T08:23:44.073598Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-10-31T08:23:44.073625Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-10-31T08:23:44.073645Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-10-31T08:23:44.073657Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:58: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2025-10-31T08:23:44.073678Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:361: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '', Topic: 'rt3.dc1--topic1', Partition: 1, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-10-31T08:23:44.074018Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-10-31T08:23:44.074051Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-10-31T08:23:44.074072Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:44.074097Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.074117Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.074133Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.074169Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T08:23:44.074260Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 1  requestId: <none> cookie: 1
2025-10-31T08:23:44.074411Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 3 Topic 'rt3.dc1--topic1' partition 1 user user offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:44.074771Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:44.074793Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2025-10-31T08:23:44.074862Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:44.000000Z
2025-10-31T08:23:44.074897Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-10-31T08:23:44.074955Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:44.075282Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:44.075381Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 1 user user readTimeStamp done, result 1761899024057 queuesize 1 startOffset 0
2025-10-31T08:23:44.075398Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:911: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 2
2025-10-31T08:23:44.075413Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:953: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 2
2025-10-31T08:23:44.075517Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 4 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 count 1 size 1024000 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:44.075744Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:44.075760Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2025-10-31T08:23:44.075804Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 0 times before, last time 2025-10-31T08:23:44.000000Z
2025-10-31T08:23:44.075828Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2025-10-31T08:23:44.075864Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:44.076116Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:44.076207Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1003: Topic 'rt3.dc1--topic1' partition 1 user debug readTimeStamp done, result 1761899024057 queuesize 0 startOffset 0
2025-10-31T08:23:44.076386Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63' size 409
2025-10-31T08:23:44.076476Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:44.076515Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:44.077191Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::IEventHandle
2025-10-31T08:23:44.099242Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.099287Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.099306Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.099363Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.099371Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.099380Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.099405Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.099414Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.099422Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.177821Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 grpc read done: success: 0 data: 
2025-10-31T08:23:44.177854Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 grpc read failed
2025-10-31T08:23:44.177889Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 grpc closed
2025-10-31T08:23:44.177912Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: 937c2d56-4ec5845f-11192c79-811369a0|97ab808b-cbea1796-f5ac96ab-aeb3c53c_0 is DEAD
2025-10-31T08:23:44.178686Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2025-10-31T08:23:44.179609Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298683897476807:2487] destroyed
2025-10-31T08:23:44.179669Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2025-10-31T08:23:44.179707Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.179727Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.179746Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.185840Z node 16 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 grpc read done: success: 0 data: 
2025-10-31T08:23:44.185870Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 grpc read failed
2025-10-31T08:23:44.185903Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 grpc closed
2025-10-31T08:23:44.185921Z node 16 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: d2a8c48d-ba084494-9384ef00-ed1484ba|b9bf334f-10934bda-eda1e473-713e2bcd_0 is DEAD
2025-10-31T08:23:44.186694Z node 16 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=2) Received event: NActors::TEvents::TEvPoison
2025-10-31T08:23:44.189119Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298683897476817:2489] destroyed
2025-10-31T08:23:44.189177Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:138: [72075186224037892][Partition][2][StateIdle] TPartition::DropOwner.
2025-10-31T08:23:44.189219Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.189237Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.189256Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
Start reads
2025-10-31T08:23:44.201441Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:109: new grpc connection
2025-10-31T08:23:44.201476Z node 16 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:131: new session created cookie 1
2025-10-31T08:23:44.202327Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer  session  grpc read done: success# 1, data# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-10-31T08:23:44.202691Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:929: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 read init: from# ipv6:[::1]:37214, request# { init_request { topics_read_settings { path: "rt3.dc1--topic1" } consumer: "debug" } }
2025-10-31T08:23:44.203418Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 auth for : debug
2025-10-31T08:23:44.204198Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:131: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Handle describe topics response
2025-10-31T08:23:44.204399Z node 16 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 auth is DEAD
2025-10-31T08:23:44.204530Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1046: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 auth ok: topics# 1, initDone# 0
2025-10-31T08:23:44.204077Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.204115Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.204133Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.204176Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.204186Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.204195Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.204223Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.204234Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.204241Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.206070Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1217: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 register session: topic# rt3.dc1--topic1
2025-10-31T08:23:44.207436Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { read_request { bytes_size: 52428800 } }
2025-10-31T08:23:44.207524Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1839: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 got read request: guid# 54369d7a-835998fb-293e79f5-1e200d0b
2025-10-31T08:23:44.207993Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1653: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298688192444130:2491] connected; active server actors: 1
2025-10-31T08:23:44.208739Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1700: [72075186224037893][rt3.dc1--topic1] consumer "debug" register session for pipe [16:7567298688192444130:2491] session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.208823Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 0
2025-10-31T08:23:44.208933Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=1 (Status=Free, Partitions=[0])
2025-10-31T08:23:44.208952Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 1
2025-10-31T08:23:44.208980Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=2 (Status=Free, Partitions=[1])
2025-10-31T08:23:44.209004Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:637: [72075186224037893][rt3.dc1--topic1] consumer debug register readable partition 2
2025-10-31T08:23:44.209047Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:667: [72075186224037893][rt3.dc1--topic1] consumer debug family created family=3 (Status=Free, Partitions=[2])
2025-10-31T08:23:44.209125Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:869: [72075186224037893][rt3.dc1--topic1] consumer debug register reading session ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=0)
2025-10-31T08:23:44.209179Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1184: [72075186224037893][rt3.dc1--topic1] consumer debug rebalancing was scheduled
2025-10-31T08:23:44.209295Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1256: [72075186224037893][rt3.dc1--topic1] consumer debug balancing. Sessions=1, Families=3, UnradableFamilies=3 [3 (2), 2 (1), 1 (0), ], RequireBalancing=0 []
2025-10-31T08:23:44.209400Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=3 (Status=Free, Partitions=[2]) for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=0)
2025-10-31T08:23:44.209523Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 3 status Active partitions [2] session "shared/debug_16_1_4165521877388972699_v1" sender [16:7567298688192444127:2491] lock partition 2 for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2025-10-31T08:23:44.209610Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=1)
2025-10-31T08:23:44.209654Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 2 status Active partitions [1] session "shared/debug_16_1_4165521877388972699_v1" sender [16:7567298688192444127:2491] lock partition 1 for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2025-10-31T08:23:44.209701Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1303: [72075186224037893][rt3.dc1--topic1] consumer debug balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=2)
2025-10-31T08:23:44.209764Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:547: [72075186224037893][rt3.dc1--topic1] consumer debug family 1 status Active partitions [0] session "shared/debug_16_1_4165521877388972699_v1" sender [16:7567298688192444127:2491] lock partition 0 for ReadingSession "shared/debug_16_1_4165521877388972699_v1" (Sender=[16:7567298688192444127:2491], Pipe=[16:7567298688192444130:2491], Partitions=[], ActiveFamilyCount=3) generation 1 step 3
2025-10-31T08:23:44.209835Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1323: [72075186224037893][rt3.dc1--topic1] consumer debug start rebalancing. familyCount=3, sessionCount=1, desiredFamilyCount=3, allowPlusOne=0
2025-10-31T08:23:44.209895Z node 16 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1400: [72075186224037893][rt3.dc1--topic1] consumer debug balancing duration: 0.000556s
2025-10-31T08:23:44.211306Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 assign: record# { Partition: 2 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 1 Session: "shared/debug_16_1_4165521877388972699_v1" ClientId: "debug" PipeClient { RawX1: 7567298688192444130 RawX2: 4503668346849723 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-10-31T08:23:44.211463Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 2 Session: "shared/debug_16_1_4165521877388972699_v1" ClientId: "debug" PipeClient { RawX1: 7567298688192444130 RawX2: 4503668346849723 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-10-31T08:23:44.211584Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:1335: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 assign: record# { Partition: 0 TabletId: 72075186224037892 Topic: "rt3.dc1--topic1" Generation: 1 Step: 3 Session: "shared/debug_16_1_4165521877388972699_v1" ClientId: "debug" PipeClient { RawX1: 7567298688192444130 RawX2: 4503668346849723 } Path: "/Root/PQ/rt3.dc1--topic1" }
2025-10-31T08:23:44.211692Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)
2025-10-31T08:23:44.211972Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)
2025-10-31T08:23:44.212044Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1143: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INITING TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)
2025-10-31T08:23:44.212764Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298688192444137:2495], now have 1 active actors on pipe
2025-10-31T08:23:44.212817Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298688192444138:2496], now have 1 active actors on pipe
2025-10-31T08:23:44.213143Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7567298688192444137:2495]
2025-10-31T08:23:44.213248Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7567298688192444138:2496]
2025-10-31T08:23:44.213651Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.213698Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:44.213774Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1749: [PQ: 72075186224037892] Created session shared/debug_16_1_4165521877388972699_v1 on pipe: [16:7567298688192444137:2495]
2025-10-31T08:23:44.213859Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.213875Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:44.213894Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1749: [PQ: 72075186224037892] Created session shared/debug_16_1_4165521877388972699_v1 on pipe: [16:7567298688192444138:2496]
2025-10-31T08:23:44.213944Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.213974Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.213993Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2025-10-31T08:23:44.214105Z node 17 :PERSQUEUE DEBUG: partition.cpp:3632: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--topic1' partition 1 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.214134Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.214210Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_4165521877388972699_v1:2 with generation 1
2025-10-31T08:23:44.214253Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_4165521877388972699_v1:3 with generation 1
2025-10-31T08:23:44.214341Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.214358Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.214369Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-10-31T08:23:44.214410Z node 17 :PERSQUEUE DEBUG: partition.cpp:3632: [72075186224037892][Partition][0][StateIdle] Topic 'rt3.dc1--topic1' partition 0 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.214422Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.214525Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:44.214562Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:44.217834Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2723: [PQ: 72075186224037892] server connected, pipe [16:7567298688192444136:2494], now have 1 active actors on pipe
2025-10-31T08:23:44.218203Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:983: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [16:7567298688192444136:2494]
2025-10-31T08:23:44.218485Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.218530Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:44.218588Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:1749: [PQ: 72075186224037892] Created session shared/debug_16_1_4165521877388972699_v1 on pipe: [16:7567298688192444136:2494]
2025-10-31T08:23:44.218663Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.218688Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.218705Z node 17 :PERSQUEUE DEBUG: partition.cpp:2269: [72075186224037892][Partition][2][StateIdle] Batch completed (1)
2025-10-31T08:23:44.218781Z node 17 :PERSQUEUE DEBUG: partition.cpp:3632: [72075186224037892][Partition][2][StateIdle] Topic 'rt3.dc1--topic1' partition 2 user debug session is set to 0 (startOffset 0) session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.218808Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.219009Z node 17 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-10-31T08:23:44.219588Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/debug_16_1_4165521877388972699_v1:1 with generation 1
2025-10-31T08:23:44.229449Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:44.229548Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:44.229579Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.229600Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.229617Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.229656Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2025-10-31T08:23:44.229684Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:44.229739Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:44.229753Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.229763Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.229772Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.229790Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-10-31T08:23:44.229805Z node 17 :PERSQUEUE DEBUG: partition.cpp:2083: [72075186224037892][Partition][2][StateIdle] Received TEvKeyValue::TEvResponse
2025-10-31T08:23:44.229842Z node 17 :PERSQUEUE DEBUG: partition_write.cpp:574: [72075186224037892][Partition][2][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-10-31T08:23:44.229855Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.229865Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.229873Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.229887Z node 17 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][2][StateIdle] No data for blobs compaction
2025-10-31T08:23:44.229930Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T08:23:44.229982Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T08:23:44.230004Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 18446744073709551615
2025-10-31T08:23:44.234547Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1761899023972 CreateTimestampMS: 1761899023967 SizeLag: 409 WriteTimestampEstimateMS: 1761899024203 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T08:23:44.234616Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 readOffset 0 committedOffset 0
2025-10-31T08:23:44.234713Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 sending to client partition status
2025-10-31T08:23:44.235162Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1761899024057 CreateTimestampMS: 1761899023936 SizeLag: 409 WriteTimestampEstimateMS: 1761899024203 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T08:23:44.235199Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 readOffset 0 committedOffset 0
2025-10-31T08:23:44.235289Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 3 WriteTimestampMS: 1761899023906 CreateTimestampMS: 1761899023895 SizeLag: 409 WriteTimestampEstimateMS: 1761899024203 ClientHasAnyCommits: false } Cookie: 18446744073709551615 }
2025-10-31T08:23:44.235313Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:694: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 INIT DONE TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 readOffset 0 committedOffset 0
2025-10-31T08:23:44.235358Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 sending to client partition status
2025-10-31T08:23:44.235396Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1434: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 sending to client partition status
Got start stream event
Got start stream event
Got start stream event
2025-10-31T08:23:44.240078Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2025-10-31T08:23:44.240260Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T08:23:44.248489Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T08:23:44.248558Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) ready for read with readOffset 0 endOffset 3
2025-10-31T08:23:44.248718Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2332: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), readOffset# 0, endOffset# 3, WTime# 1761899023972, sizeLag# 409
2025-10-31T08:23:44.248750Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1TEvPartitionReady. Aval parts: 1
2025-10-31T08:23:44.248832Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2266: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 performing read request: guid# b7b2c910-fe153ce2-6edf319b-4e85199, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-10-31T08:23:44.248954Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid b7b2c910-fe153ce2-6edf319b-4e85199
2025-10-31T08:23:44.250836Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.250897Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 2
2025-10-31T08:23:44.251043Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][2][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 2 user debug offset 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:44.251425Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][2][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:44.251451Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][2][StateIdle] Reading cookie 5. Send blob request.
2025-10-31T08:23:44.251517Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 2 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:44.251565Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-10-31T08:23:44.251636Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:44.252018Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:44.252195Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 2 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:44.252956Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 2 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:44.252110Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2025-10-31T08:23:44.252325Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T08:23:44.252554Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T08:23:44.252593Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) ready for read with readOffset 0 endOffset 3
2025-10-31T08:23:44.252676Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2332: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), readOffset# 0, endOffset# 3, WTime# 1761899024057, sizeLag# 409
2025-10-31T08:23:44.252708Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1TEvPartitionReady. Aval parts: 1
2025-10-31T08:23:44.252776Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2266: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 performing read request: guid# d6bab616-48daa2a1-bb10aff4-a0b5bc6a, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-10-31T08:23:44.252906Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 3 } }
2025-10-31T08:23:44.253057Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:539: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), readOffset# 0, commitOffset# (empty maybe)
2025-10-31T08:23:44.253124Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid d6bab616-48daa2a1-bb10aff4-a0b5bc6a
2025-10-31T08:23:44.253267Z node 16 :PQ_READ_PROXY INFO: partition_actor.cpp:1023: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Start reading TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2025-10-31T08:23:44.253296Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:969: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) ready for read with readOffset 0 endOffset 3
2025-10-31T08:23:44.253649Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1761899023972 CreateTimestampMS: 1761899023967 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1761899023975 CreateTimestampMS: 1761899023967 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1761899023975 CreateTimestampMS: 1761899023967 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-10-31T08:23:44.253924Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) wait data in partition inited, cookie 1 from offset 3
2025-10-31T08:23:44.253986Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1) EndOffset 3 ReadOffset 3 ReadGuid b7b2c910-fe153ce2-6edf319b-4e85199 has messages 1
2025-10-31T08:23:44.254199Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2332: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), readOffset# 0, endOffset# 3, WTime# 1761899023906, sizeLag# 409
2025-10-31T08:23:44.254230Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2343: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1TEvPartitionReady. Aval parts: 1
2025-10-31T08:23:44.254282Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2266: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 performing read request: guid# 5dccb476-b9d9db8a-90c3dad5-de77021d, from# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), count# 3, size# 490, partitionsAsked# 1, maxTimeLag# 0ms
2025-10-31T08:23:44.254382Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1940: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 read done: guid# b7b2c910-fe153ce2-6edf319b-4e85199, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 2(assignId:1), size# 386
2025-10-31T08:23:44.254430Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2102: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 response to read: guid# b7b2c910-fe153ce2-6edf319b-4e85199
2025-10-31T08:23:44.254579Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1395: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 READ FROM TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3)maxCount 3 maxSize 490 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 3 ClientCommitOffset 0 committedOffset 0 Guid 5dccb476-b9d9db8a-90c3dad5-de77021d
2025-10-31T08:23:44.254660Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2145: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Process answer. Aval parts: 0
2025-10-31T08:23:44.255865Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.255932Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 1
2025-10-31T08:23:44.256077Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'rt3.dc1--topic1' requestId: <none>
2025-10-31T08:23:44.256093Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2637: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--topic1' partition 0
2025-10-31T08:23:44.256247Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][1][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 1 user debug offset 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:44.256596Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:44.256619Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2025-10-31T08:23:44.256664Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:868: [72075186224037892][Partition][0][StateIdle] read cookie 5 Topic 'rt3.dc1--topic1' partition 0 user debug offset 0 count 3 size 490 endOffset 3 max time lag 0ms effective offset 0
2025-10-31T08:23:44.256832Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1082: [72075186224037892][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 409 count 3 last offset 0, current partition end offset: 3
2025-10-31T08:23:44.256844Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:1108: [72075186224037892][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2025-10-31T08:23:44.256894Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 1 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-10-31T08:23:44.000000Z
2025-10-31T08:23:44.256942Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-10-31T08:23:44.256996Z node 17 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 3 parts_count 0 source 1 size 409 accessed 2 times before, last time 2025-10-31T08:23:43.000000Z
2025-10-31T08:23:44.257010Z node 17 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2025-10-31T08:23:44.257054Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:44.257388Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:44.257517Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:574: FormAnswer for 1 blobs
2025-10-31T08:23:44.257684Z node 17 :PERSQUEUE DEBUG: partition_read.cpp:491: FormAnswer processing batch offset 0 totakecount 3 count 3 size 389 from pos 0 cbcount 3
2025-10-31T08:23:44.257813Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:44.257897Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'rt3.dc1--topic1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-10-31T08:23:44.258250Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 3 parts 0 suffix '63'
2025-10-31T08:23:44.258294Z node 17 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 3 parts 0 suffix '63'
Got data event with total 3 messages, current total messages: 3
2025-10-31T08:23:44.261632Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1761899024057 CreateTimestampMS: 1761899023936 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1761899024057 CreateTimestampMS: 1761899023936 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1761899024057 CreateTimestampMS: 1761899023936 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-10-31T08:23:44.261812Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) wait data in partition inited, cookie 1 from offset 3
2025-10-31T08:23:44.261850Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2) EndOffset 3 ReadOffset 3 ReadGuid d6bab616-48daa2a1-bb10aff4-a0b5bc6a has messages 1
2025-10-31T08:23:44.262262Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:663: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) initDone 1 event { CmdReadResult { MaxOffset: 3 Result { Offset: 0 Data: "... 99 bytes ..." SourceId: "" SeqNo: 1 WriteTimestampMS: 1761899023906 CreateTimestampMS: 1761899023895 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 99 bytes ..." SourceId: "" SeqNo: 2 WriteTimestampMS: 1761899023907 CreateTimestampMS: 1761899023896 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 99 bytes ..." SourceId: "" SeqNo: 3 WriteTimestampMS: 1761899023907 CreateTimestampMS: 1761899023896 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 7 RealReadOffset: 2 WaitQuotaTimeMs: 0 EndOffset: 3 StartOffset: 0 } Cookie: 0 }
2025-10-31T08:23:44.262379Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1277: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) wait data in partition inited, cookie 1 from offset 3
2025-10-31T08:23:44.262409Z node 16 :PQ_READ_PROXY DEBUG: partition_actor.cpp:901: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 after read state TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3) EndOffset 3 ReadOffset 3 ReadGuid 5dccb476-b9d9db8a-90c3dad5-de77021d has messages 1
2025-10-31T08:23:44.262678Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { read_request { bytes_size: 386 } }
2025-10-31T08:23:44.262745Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1940: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 read done: guid# d6bab616-48daa2a1-bb10aff4-a0b5bc6a, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 1(assignId:2), size# 275
2025-10-31T08:23:44.262768Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2102: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 response to read: guid# d6bab616-48daa2a1-bb10aff4-a0b5bc6a
2025-10-31T08:23:44.262948Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2145: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Process answer. Aval parts: 0
2025-10-31T08:23:44.263021Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1940: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 read done: guid# 5dccb476-b9d9db8a-90c3dad5-de77021d, partition# TopicId: Topic rt3.dc1--topic1 in dc dc1 in database: Root, partition 0(assignId:3), size# 386
2025-10-31T08:23:44.263038Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2102: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 response to read: guid# 5dccb476-b9d9db8a-90c3dad5-de77021d
2025-10-31T08:23:44.263060Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2145: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 Process answer. Aval parts: 0
2025-10-31T08:23:44.263124Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1839: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 got read request: guid# 8cda451a-b07c0a3b-cd00bdae-64b44cc1
Got data event with total 3 messages, current total messages: 6
Got data event with total 3 messages, current total messages: 9
2025-10-31T08:23:44.269992Z node 16 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc read done: success# 1, data# { read_request { bytes_size: 275 } }
2025-10-31T08:23:44.274906Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 grpc closed
2025-10-31T08:23:44.278200Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2259: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.278261Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298688192444138:2496] destroyed
2025-10-31T08:23:44.278290Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2259: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.278311Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298688192444137:2495] destroyed
2025-10-31T08:23:44.278328Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2259: [PQ: 72075186224037892] Destroy direct read session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.278348Z node 17 :PERSQUEUE DEBUG: pq_impl.cpp:2748: [PQ: 72075186224037892] server disconnected, pipe [16:7567298688192444136:2494] destroyed
2025-10-31T08:23:44.278410Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.278430Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.278446Z node 17 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.275178Z node 16 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/debug session shared/debug_16_1_4165521877388972699_v1 is DEAD
2025-10-31T08:23:44.278941Z node 16 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1665: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298688192444130:2491] disconnected; active server actors: 1
2025-10-31T08:23:44.278983Z node 16 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1674: [72075186224037893][rt3.dc1--topic1] pipe [16:7567298688192444130:2491] client debug disconnected session shared/debug_16_1_4165521877388972699_v1
2025-10-31T08:23:44.312156Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.312204Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.312225Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.312276Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.312288Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.312296Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.312324Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.312333Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.312342Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.413739Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.413781Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.413799Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.413849Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.413858Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.413867Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.413892Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.413902Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.413910Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.520383Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.520418Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.520435Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.520484Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.520507Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.520515Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.520542Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.520551Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.520559Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.628060Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.628097Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.628115Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.628164Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.628173Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.628181Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.628206Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.628215Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.628222Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.732156Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.732194Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.732212Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.732260Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.732270Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.732277Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.732302Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.732312Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.732321Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.832216Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.832253Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.832269Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.832316Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.832326Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.832334Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.832357Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.832365Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.832375Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
2025-10-31T08:23:44.932690Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][2][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.932742Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][2][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.932764Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][2][StateIdle] Try persist
2025-10-31T08:23:44.932834Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][0][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.932845Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][0][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.932856Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][0][StateIdle] Try persist
2025-10-31T08:23:44.932889Z node 17 :PERSQUEUE DEBUG: partition.cpp:2256: [72075186224037892][Partition][1][StateIdle] Batching state before ContinueProcessTxsAndUserActs: 0
2025-10-31T08:23:44.932901Z node 17 :PERSQUEUE DEBUG: partition.cpp:2265: [72075186224037892][Partition][1][StateIdle] Batching state after ContinueProcessTxsAndUserActs: 1
2025-10-31T08:23:44.932910Z node 17 :PERSQUEUE DEBUG: partition.cpp:2288: [72075186224037892][Partition][1][StateIdle] Try persist
