2025-12-04T20:33:28.333300Z node 11 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[11:7580103637409680413:2081];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:33:28.333732Z node 11 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2025-12-04T20:33:28.335058Z node 12 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[12:7580103636781550405:2079];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:33:28.335087Z node 12 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2025-12-04T20:33:28.335564Z node 11 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-04T20:33:28.335881Z node 11 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/6mdl/003d39/r3tmp/tmp4cdDFn/pdisk_1.dat
2025-12-04T20:33:28.337565Z node 12 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2025-12-04T20:33:28.337610Z node 12 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-12-04T20:33:28.350307Z node 11 :IMPORT WARN: schemeshard_import.cpp:423: Table profiles were not loaded
2025-12-04T20:33:28.354385Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(12, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-04T20:33:28.354404Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(12, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-04T20:33:28.355464Z node 11 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 12 Cookie 12
2025-12-04T20:33:28.355684Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(12, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 21362, node 11
2025-12-04T20:33:28.361918Z node 11 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/6mdl/003d39/r3tmp/yandexQWVhDG.tmp
2025-12-04T20:33:28.361938Z node 11 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/6mdl/003d39/r3tmp/yandexQWVhDG.tmp
2025-12-04T20:33:28.361996Z node 11 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/6mdl/003d39/r3tmp/yandexQWVhDG.tmp
2025-12-04T20:33:28.362024Z node 11 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-04T20:33:28.364835Z INFO: TTestServer started on Port 11490 GrpcPort 21362
TClient is connected to server localhost:11490
PQClient connected to localhost:21362
WaitRootIsUp 'Root'...
TClient::Ls request: Root
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "Root"
    PathId: 1
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 1
    CreateStep: 0
    ParentPathId: 1
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 1
      SubDomainVersion: 0
      SecurityStateVersion: 0
    }
    ChildrenExist: false
  }
  Children {
    Name: ".sys"
    PathId: 18446744073709551615
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 0
    CreateStep: 0
    ParentPathId: 18446744073709551615
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 0
      Pl...
(TRUNCATED)
WaitRootIsUp 'Root' success.
2025-12-04T20:33:28.435261Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(11, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-04T20:33:28.435295Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(11, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-04T20:33:28.436891Z node 11 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(11, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-12-04T20:33:28.437487Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976720657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
waiting...
2025-12-04T20:33:28.446891Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
waiting...
waiting...
waiting...
2025-12-04T20:33:28.530284Z node 11 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-04T20:33:28.569395Z node 12 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-12-04T20:33:28.736019Z node 11 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [11:7580103637409681481:2333], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:33:28.736046Z node 11 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [11:7580103637409681470:2330], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:33:28.736070Z node 11 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:33:28.736176Z node 11 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [11:7580103637409681485:2335], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:33:28.736189Z node 11 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-12-04T20:33:28.736781Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976720662:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2025-12-04T20:33:28.741670Z node 11 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [11:7580103637409681484:2334], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976720662 completed, doublechecking }
2025-12-04T20:33:28.742440Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:33:28.756416Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:33:28.777779Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720665:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp: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-12-04T20:33:28.799917Z node 11 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [11:7580103637409681913:3038] txid# 281474976720666, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 9], type: EPathTypeResourcePool, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
=== CheckClustersList. Subcribe to ClusterTracker from [11:7580103637409681957:3059] 
2025-12-04T20:33:29.335252Z node 11 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-04T20:33:29.337462Z node 12 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-12-04T20:33:33.333415Z node 11 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[11:7580103637409680413:2081];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:33:33.333453Z node 11 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2025-12-04T20:33:33.335455Z node 12 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[12:7580103636781550405:2079];send_to=[0:7307199536658146131:7762515];
2025-12-04T20:33:33.335493Z node 12 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
=== CheckClustersList. Ok
CreateTopicNoLegacy: /Root/topic-f1
Create topic: /Root/topic-f1
AddTopic: /Root/topic-f1
===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 = /Root/topic-f1, dc = unknown
2025-12-04T20:33:34.161878Z node 11 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:196: new Create topic request
2025-12-04T20:33:34.173298Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2959: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2025-12-04T20:33:34.174637Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2991: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2025-12-04T20:33:34.174700Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:787: [PQ: 72075186224037892] no config, start with empty partitions and default config
2025-12-04T20:33:34.174709Z node 11 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-04T20:33:34.174717Z node 11 :PERSQUEUE INFO: pq_impl.cpp:612: [PQ: 72075186224037892] doesn't have tx writes info
2025-12-04T20:33:34.175004Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103663179486044:3232], now have 1 active actors on pipe
2025-12-04T20:33:34.175430Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:33:34.175448Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [12:7580103662551354796:2324], now have 1 active actors on pipe
2025-12-04T20:33:34.176312Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:33:34.173358Z node 12 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1656: [72075186224037893][] pipe [11:7580103663179486045:3233] connected; active server actors: 1
2025-12-04T20:33:34.173450Z node 12 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1520: [72075186224037893][topic-f1] updating configuration. Deleted partitions []. Added partitions [0]
2025-12-04T20:33:34.173641Z node 12 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:942: [72075186224037893][topic-f1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-12-04T20:33:34.174587Z node 12 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:140: [72075186224037893][topic-f1] BALANCER INIT DONE for topic-f1: (0, 72075186224037892) 
2025-12-04T20:33:34.175431Z node 12 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:404: [72075186224037893][topic-f1] TEvClientConnected TabletId 72075186224037892, NodeId 11, Generation 1
2025-12-04T20:33:34.179126Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103663179486131:3302], now have 1 active actors on pipe
2025-12-04T20:33:34.179305Z node 11 :PERSQUEUE DEBUG: partition_init.cpp:86: [topic-f1:0:Initializer] Start initializing step TInitConfigStep
2025-12-04T20:33:34.179323Z node 11 :PERSQUEUE DEBUG: partition.cpp:1315: [72075186224037892][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2025-12-04T20:33:34.179441Z node 11 :PERSQUEUE DEBUG: partition_init.cpp:86: [topic-f1:0:Initializer] Start initializing step TInitInternalFieldsStep
2025-12-04T20:33:34.179514Z node 11 :PERSQUEUE INFO: partition_init.cpp:1150: [72075186224037892][Partition][0][StateInit] bootstrapping 0 [11:7580103663179486141:2441]
2025-12-04T20:33:34.179716Z node 11 :PERSQUEUE DEBUG: partition_init.cpp:86: [topic-f1:0:Initializer] Start initializing step TInitFieldsStep
2025-12-04T20:33:34.179724Z node 11 :PERSQUEUE DEBUG: partition_init.cpp:66: [topic-f1:0:Initializer] Initializing completed.
2025-12-04T20:33:34.179727Z node 11 :PERSQUEUE INFO: partition.cpp:707: [72075186224037892][Partition][0][StateInit] init complete for topic 'topic-f1' partition 0 generation 1 [11:7580103663179486141:2441]
2025-12-04T20:33:34.179733Z node 11 :PERSQUEUE DEBUG: partition.cpp:727: [72075186224037892][Partition][0][StateInit] SYNC INIT topic topic-f1 partitition 0 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2025-12-04T20:33:34.179739Z node 11 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:33:34.179803Z node 11 :PERSQUEUE DEBUG: partition.cpp:4404: [72075186224037892][Partition][0][StateIdle] Process pending events. Count 1
2025-12-04T20:33:34.179812Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.179817Z node 11 :PERSQUEUE DEBUG: partition.cpp:2392: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2025-12-04T20:33:34.179833Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.179839Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.179841Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.179843Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.179851Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.179853Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.179854Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.179855Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.179857Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.179863Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:930: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-04T20:33:34.179868Z node 11 :PERSQUEUE DEBUG: partition_mlp.cpp:70: [72075186224037892][Partition][0][StateIdle] Process MLP pending events. Count 0
2025-12-04T20:33:34.179947Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:33:34.179968Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.180926Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:33:34.180969Z node 11 :PERSQUEUE DEBUG: partition.cpp:1420: [72075186224037892][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1764880414227, TxId 281474976720674
2025-12-04T20:33:34.180973Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.180976Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.180978Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.180985Z node 11 :PERSQUEUE DEBUG: partition.cpp:2456: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2025-12-04T20:33:34.181007Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.181014Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.181017Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.181058Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.182283Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.182333Z node 11 :PERSQUEUE DEBUG: partition_mlp.cpp:107: [72075186224037892][Partition][0][StateIdle] Initializing MLP Consumers: 0
2025-12-04T20:33:34.182375Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.182388Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.182391Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.182393Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.182395Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.182397Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.182402Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.182540Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:398: [PQ: 72075186224037892] Apply new config PartitionConfig { MaxCountInPartition: 2147483647 MaxSizeInPartition: 9223372036854775807 LifetimeSeconds: 64800 SourceIdLifetimeSeconds: 1382400 WriteSpeedInBytesPerSecond: 2097152 BurstSize: 2097152 TotalPartitions: 1 ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } SourceIdMaxCounts: 6000000 } PartitionIds: 0 TopicName: "topic-f1" Version: 0 RequireAuthWrite: true RequireAuthRead: true FormatVersion: 0 Codecs { } TopicPath: "/Root/topic-f1" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 } MonitoringProjectId: ""
2025-12-04T20:33:34.182560Z node 11 :PERSQUEUE NOTICE: pq_impl.cpp:913: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2025-12-04T20:33:34.182618Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
===Query complete
2025-12-04T20:33:34.184232Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2025-12-04T20:33:34.185249Z node 12 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:942: [72075186224037893][topic-f1] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037893
2025-12-04T20:33:34.184283Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:3563: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2025-12-04T20:33:34.185674Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:1045: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
Create topic result: 1 
Created writer (initially).
2025-12-04T20:33:34.189414Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:33:34.189429Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2025-12-04T20:33:34.189623Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId:  grpc read done: success: 1 data: init_request { topic: "/Root/topic-f1" message_group_id: "123" }
2025-12-04T20:33:34.189645Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 1 topic: "/Root/topic-f1" message_group_id: "123" from ipv6:[::1]:38162
2025-12-04T20:33:34.189650Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=1 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:38162 proto=v1  topic=/Root/topic-f1 durationSec=0
2025-12-04T20:33:34.189653Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:33:34.190359Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 1 sessionId:  describe result for acl check
2025-12-04T20:33:34.190406Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint64; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `//Root/.metadata/TopicPartitionsMapping` WHERE Hash == $Hash AND Topic == $Topic AND ProducerId == $SourceId;
2025-12-04T20:33:34.190418Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64; DECLARE $SeqNo AS Uint64;
UPSERT INTO `//Root/.metadata/TopicPartitionsMapping` (Hash, Topic, ProducerId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-12-04T20:33:34.190420Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `//Root/.metadata/TopicPartitionsMapping` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND ProducerId = $SourceId AND Partition = $Partition;
2025-12-04T20:33:34.191562Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720676:1, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:33:34.280946Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.280968Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.280971Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.280974Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.280976Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.306341Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976720679:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_modify_acl.cpp:101)
2025-12-04T20:33:34.379764Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720686:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-04T20:33:34.380943Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.380955Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.380956Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.380958Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.380960Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.481542Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.481563Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.481566Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.481569Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.481571Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.486697Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterTable, opId: 281474976720689:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_table.cpp:188)
2025-12-04T20:33:34.581822Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.581842Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.581845Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.581849Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.581851Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.583792Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterTable, opId: 281474976720692:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_table.cpp:188)
2025-12-04T20:33:34.684959Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.684983Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.684986Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.684990Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.684993Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.753475Z node 11 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976720695:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_modify_acl.cpp:101)
2025-12-04T20:33:34.784966Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.784988Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.784991Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.784995Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.784997Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.822779Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) StartKqpSession
2025-12-04T20:33:34.823529Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) Select from the table
2025-12-04T20:33:34.864752Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) Update the table
2025-12-04T20:33:34.888938Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.888959Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.888962Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.888967Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.888970Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.905714Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2025-12-04T20:33:34.905732Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=(NULL)
2025-12-04T20:33:34.905735Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [11:7580103663179486208:2450] (SourceId=123, PreferedPartition=(NULL)) Start idle
2025-12-04T20:33:34.905746Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 1 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-04T20:33:34.906617Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 11, Generation: 1
2025-12-04T20:33:34.906636Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103663179486981:2450], now have 1 active actors on pipe
2025-12-04T20:33:34.906718Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.906727Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.906758Z node 11 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 123|5762fe5e-20ccd982-8983f599-2c416580_0 generated for partition 0 topic 'topic-f1' owner 123
2025-12-04T20:33:34.906771Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.906775Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.906781Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.906783Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.906788Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.906797Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.906799Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.906803Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.906817Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-04T20:33:34.906835Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:34.907000Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.907005Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.907021Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:34.907045Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 1 partition: 0 MaxSeqNo: 0 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0
Writes done
2025-12-04T20:33:34.909243Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.909364Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.909589Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.909603Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.909642Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:33:34.909655Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.909662Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.909712Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.909836Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.909842Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.909858Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 1 partNo : 0 messageNo: 1 size 83 offset: -1
2025-12-04T20:33:34.909864Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.909866Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.909873Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.909892Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.909896Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.909907Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.909909Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.909914Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.909953Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 1 partNo 0
2025-12-04T20:33:34.910012Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 154 count 1 nextOffset 1 batches 1
2025-12-04T20:33:34.910019Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.910022Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.910025Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.910083Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 0,1 HeadOffset 0 endOffset 0 curOffset 1 d0000000000_00000000000000000000_00000_0000000001_00000? size 140 WTime 1764880414909
2025-12-04T20:33:34.910121Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.910133Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 1 size 140
2025-12-04T20:33:34.910145Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 2  requestId: <none> cookie: 2
2025-12-04T20:33:34.910322Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.910330Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.910396Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.910995Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.911005Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.911016Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 2 partNo : 0 messageNo: 3 size 83 offset: -1
2025-12-04T20:33:34.911024Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.911027Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.911034Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.911042Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.911050Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 4  requestId: <none> cookie: 3
2025-12-04T20:33:34.911057Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.911064Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.911127Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.911401Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.911448Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.911463Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.911466Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.911475Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 3 partNo : 0 messageNo: 5 size 83 offset: -1
2025-12-04T20:33:34.911492Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.911495Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.911546Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.911553Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.911565Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 0 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.911573Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 6  requestId: <none> cookie: 4
2025-12-04T20:33:34.911578Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.911601Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.911609Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.911616Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2025-12-04T20:33:34.911646Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:930: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2025-12-04T20:33:34.911652Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:972: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2025-12-04T20:33:34.911655Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.911657Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.911662Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.911665Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-04T20:33:34.911667Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.911671Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.911689Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 2 partNo 0
2025-12-04T20:33:34.911715Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 1 PartNo 0 PackedSize 154 count 1 nextOffset 2 batches 1
2025-12-04T20:33:34.911720Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.911726Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 3 partNo 0
2025-12-04T20:33:34.911731Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 1 PartNo 0 PackedSize 276 count 2 nextOffset 3 batches 1
2025-12-04T20:33:34.911734Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-04T20:33:34.911736Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (2)
2025-12-04T20:33:34.911739Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.911773Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 1,2 HeadOffset 1 endOffset 1 curOffset 3 d0000000000_00000000000000000001_00000_0000000002_00000? size 273 WTime 1764880414911
2025-12-04T20:33:34.911796Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.911804Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:33:34.911816Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.911824Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 1 partNo 0 count 2 size 273
2025-12-04T20:33:34.911870Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:887: [72075186224037892][Partition][0][StateIdle] read cookie 3 Topic 'topic-f1' partition 0 user user offset 0 partno 0 count 1 size 1024000 endOffset 1 max time lag 0ms effective offset 0
2025-12-04T20:33:34.911883Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:1112: [72075186224037892][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 140 count 1 last offset 0, current partition end offset: 1
2025-12-04T20:33:34.911887Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:1128: [72075186224037892][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2025-12-04T20:33:34.911903Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:497: Got data from cache. Partition 0 offset 0 partno 0 count 1 parts_count 0 source 1 size 140 accessed 0 times before, last time 2025-12-04T20:33:34.000000Z
2025-12-04T20:33:34.911906Z node 11 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2025-12-04T20:33:34.912002Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:576: FormAnswer for 1 blobs
2025-12-04T20:33:34.912016Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:496: FormAnswer processing batch offset 0 totakecount 1 count 1 size 122 from pos 0 cbcount 1
2025-12-04T20:33:34.912031Z node 11 :PERSQUEUE DEBUG: partition_read.cpp:1022: Topic 'topic-f1' partition 0 user user readTimeStamp done, result 1764880414909 queuesize 0 startOffset 0
2025-12-04T20:33:34.912046Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.912052Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.912455Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.912467Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 0 offset 0 partno 0 count 1 parts 0 suffix '63'
2025-12-04T20:33:34.912890Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.912952Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.912968Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.912972Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.912980Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.912981Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.912989Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 4 partNo : 0 messageNo: 8 size 83 offset: -1
2025-12-04T20:33:34.913028Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.913034Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.913041Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 1 count 2 size 273 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.913057Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 7  requestId: <none> cookie: 5
2025-12-04T20:33:34.913062Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.913074Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 174 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.913079Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.913084Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2025-12-04T20:33:34.913087Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.913091Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2025-12-04T20:33:34.913108Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.913110Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.913114Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.913116Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.913119Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.913132Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 4 partNo 0
2025-12-04T20:33:34.913151Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 4 partNo 0 FormedBlobsCount 0 NewHead: Offset 3 PartNo 0 PackedSize 154 count 1 nextOffset 4 batches 1
2025-12-04T20:33:34.913155Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.913156Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.913159Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.913189Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 3,1 HeadOffset 3 endOffset 3 curOffset 4 d0000000000_00000000000000000003_00000_0000000001_00000? size 140 WTime 1764880414912
2025-12-04T20:33:34.913207Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.913213Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 3  requestId: <none> cookie: 2
2025-12-04T20:33:34.913218Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 5  requestId: <none> cookie: 3
2025-12-04T20:33:34.913222Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.913241Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 3 partNo 0 count 1 size 140
2025-12-04T20:33:34.913326Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.913332Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.913335Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.913663Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 1 partno 0 count 2 parts 0 suffix '63' size 273
2025-12-04T20:33:34.914040Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.914091Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.914103Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.914106Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.914112Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.914115Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.914123Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 5 partNo : 0 messageNo: 10 size 83 offset: -1
2025-12-04T20:33:34.914158Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.914162Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.914168Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 3 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.914174Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 9  requestId: <none> cookie: 6
2025-12-04T20:33:34.914178Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.914188Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.914192Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.914197Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 4, partNo: 0, Offset: 3 is stored on disk
2025-12-04T20:33:34.914212Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.914214Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.914219Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.914221Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.914224Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.914236Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 5 partNo 0
2025-12-04T20:33:34.914253Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 5 partNo 0 FormedBlobsCount 0 NewHead: Offset 4 PartNo 0 PackedSize 154 count 1 nextOffset 5 batches 1
2025-12-04T20:33:34.914257Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.914259Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.914261Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.914290Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 4,1 HeadOffset 4 endOffset 4 curOffset 5 d0000000000_00000000000000000004_00000_0000000001_00000? size 140 WTime 1764880414914
2025-12-04T20:33:34.914306Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.914311Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 8  requestId: <none> cookie: 4
2025-12-04T20:33:34.914316Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.914323Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 4 partNo 0 count 1 size 140
2025-12-04T20:33:34.914400Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.914405Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.914660Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 3 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.914993Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.915039Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.915053Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.915056Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.915061Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.915064Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.915071Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 6 partNo : 0 messageNo: 12 size 83 offset: -1
2025-12-04T20:33:34.915114Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.915119Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.915124Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 4 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.915129Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 11  requestId: <none> cookie: 7
2025-12-04T20:33:34.915134Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.915143Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.915147Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.915152Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 5, partNo: 0, Offset: 4 is stored on disk
2025-12-04T20:33:34.915165Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.915168Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.915171Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.915173Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.915175Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.915187Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 6 partNo 0
2025-12-04T20:33:34.915203Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 6 partNo 0 FormedBlobsCount 0 NewHead: Offset 5 PartNo 0 PackedSize 154 count 1 nextOffset 6 batches 1
2025-12-04T20:33:34.915206Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.915208Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.915210Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.915238Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 5,1 HeadOffset 5 endOffset 5 curOffset 6 d0000000000_00000000000000000005_00000_0000000001_00000? size 140 WTime 1764880414914
2025-12-04T20:33:34.915253Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.915258Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 10  requestId: <none> cookie: 5
2025-12-04T20:33:34.915263Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.915270Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 5 partNo 0 count 1 size 140
2025-12-04T20:33:34.915342Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.915347Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.915578Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 4 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.915930Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.915979Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.915991Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.915995Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.916001Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.916004Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.916011Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 7 partNo : 0 messageNo: 14 size 83 offset: -1
2025-12-04T20:33:34.916040Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.916045Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.916051Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 5 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.916056Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 13  requestId: <none> cookie: 8
2025-12-04T20:33:34.916061Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.916069Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.916073Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.916079Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 6, partNo: 0, Offset: 5 is stored on disk
2025-12-04T20:33:34.916091Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.916094Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.916098Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.916099Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.916102Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.916113Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 7 partNo 0
2025-12-04T20:33:34.916129Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 7 partNo 0 FormedBlobsCount 0 NewHead: Offset 6 PartNo 0 PackedSize 154 count 1 nextOffset 7 batches 1
2025-12-04T20:33:34.916132Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.916134Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.916136Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.916163Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 6,1 HeadOffset 6 endOffset 6 curOffset 7 d0000000000_00000000000000000006_00000_0000000001_00000? size 140 WTime 1764880414915
2025-12-04T20:33:34.916179Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.916183Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 12  requestId: <none> cookie: 6
2025-12-04T20:33:34.916187Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.916194Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 6 partNo 0 count 1 size 140
2025-12-04T20:33:34.916261Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.916266Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.916488Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 5 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.916805Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:34.916840Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:34.916851Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.916856Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.916861Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.916863Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.916883Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 8 partNo : 0 messageNo: 16 size 83 offset: -1
2025-12-04T20:33:34.916918Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.916924Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.916929Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 6 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.916935Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.916936Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.916940Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 15  requestId: <none> cookie: 9
2025-12-04T20:33:34.916944Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.916952Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.916956Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.916960Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 7, partNo: 0, Offset: 6 is stored on disk
2025-12-04T20:33:34.916965Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 6 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.916974Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.916977Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.916982Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:34.916984Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.916987Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.916998Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 8 partNo 0
2025-12-04T20:33:34.917015Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 8 partNo 0 FormedBlobsCount 0 NewHead: Offset 7 PartNo 0 PackedSize 154 count 1 nextOffset 8 batches 1
2025-12-04T20:33:34.917018Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:34.917020Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:34.917021Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.917048Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 7,1 HeadOffset 7 endOffset 7 curOffset 8 d0000000000_00000000000000000007_00000_0000000001_00000? size 140 WTime 1764880414917
2025-12-04T20:33:34.917062Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.917068Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 14  requestId: <none> cookie: 7
2025-12-04T20:33:34.917073Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.917080Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 7 partNo 0 count 1 size 140
2025-12-04T20:33:34.917083Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 17  requestId: <none> cookie: 10
2025-12-04T20:33:34.917161Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.917166Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.917169Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.917689Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.917696Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.917704Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 9 partNo : 0 messageNo: 18 size 83 offset: -1
2025-12-04T20:33:34.917708Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:34.917710Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:34.917713Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 10 partNo : 0 messageNo: 19 size 83 offset: -1
2025-12-04T20:33:34.917738Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.917744Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.917746Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:34.917749Z node 11 :PERSQUEUE DEBUG: partition.cpp:2281: [72075186224037892][Partition][0][StateIdle] Writing. Can't process user action and tx events
2025-12-04T20:33:34.917754Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 7 count 1 size 140 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.917761Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.917768Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 87 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.917772Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.917776Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 8, partNo: 0, Offset: 7 is stored on disk
2025-12-04T20:33:34.917791Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.917793Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.917809Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:34.917815Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 2, PendingWrites: 0
2025-12-04T20:33:34.917817Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.917820Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.917833Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 9 partNo 0
2025-12-04T20:33:34.917848Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 9 partNo 0 FormedBlobsCount 0 NewHead: Offset 8 PartNo 0 PackedSize 154 count 1 nextOffset 9 batches 1
2025-12-04T20:33:34.917852Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:34.917858Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 10 partNo 0
2025-12-04T20:33:34.917862Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 10 partNo 0 FormedBlobsCount 0 NewHead: Offset 8 PartNo 0 PackedSize 276 count 2 nextOffset 10 batches 1
2025-12-04T20:33:34.917864Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 2
2025-12-04T20:33:34.917866Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (2)
2025-12-04T20:33:34.917869Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.917895Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 8,2 HeadOffset 8 endOffset 8 curOffset 10 d0000000000_00000000000000000008_00000_0000000002_00000? size 273 WTime 1764880414917
2025-12-04T20:33:34.917912Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.917918Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 16  requestId: <none> cookie: 8
2025-12-04T20:33:34.917924Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:34.917931Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 8 partNo 0 count 2 size 273
2025-12-04T20:33:34.918002Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.918593Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 7 partno 0 count 1 parts 0 suffix '63' size 140
2025-12-04T20:33:34.920201Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 8 count 2 size 273 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:34.920223Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:34.920236Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 174 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:34.920241Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.920247Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 9, partNo: 0, Offset: 8 is stored on disk
2025-12-04T20:33:34.920250Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:34.920256Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 10, partNo: 0, Offset: 9 is stored on disk
2025-12-04T20:33:34.920273Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.920275Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.920277Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.920288Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.920290Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:34.920296Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:34.920303Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 18  requestId: <none> cookie: 9
2025-12-04T20:33:34.920309Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 19  requestId: <none> cookie: 10
2025-12-04T20:33:34.920318Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.920322Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:34.921450Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 8 partno 0 count 2 parts 0 suffix '63' size 273
2025-12-04T20:33:34.992896Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:34.992917Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.992920Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:34.992924Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:34.992928Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
Writer closed
2025-12-04T20:33:35.017070Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read done: success: 0 data: 
2025-12-04T20:33:35.017086Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc read failed
2025-12-04T20:33:35.017096Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 grpc closed
2025-12-04T20:33:35.017101Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: 123|5762fe5e-20ccd982-8983f599-2c416580_0 is DEAD
2025-12-04T20:33:35.017391Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:33:35.017622Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [11:7580103663179486981:2450] destroyed
2025-12-04T20:33:35.017636Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-04T20:33:35.017643Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.017645Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.017647Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.017650Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.017653Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.052527Z node 11 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:207: new Alter topic request
2025-12-04T20:33:35.052999Z node 11 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [11:7580103667474454342:3816] txid# 281474976720701, issues: { message: "Path does not exist" issue_code: 200200 severity: 1 }
Alter topic (/Root/PQ//Root/topic-f1) response: 0 <main>: Error: Path does not exist, code: 200200
2025-12-04T20:33:35.054768Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:33:35.054779Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2025-12-04T20:33:35.055195Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId:  grpc read done: success: 1 data: init_request { topic: "/Root/topic-f1" message_group_id: "123" }
2025-12-04T20:33:35.055219Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 2 topic: "/Root/topic-f1" message_group_id: "123" from ipv6:[::1]:38162
2025-12-04T20:33:35.055225Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=2 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:38162 proto=v1  topic=/Root/topic-f1 durationSec=0
2025-12-04T20:33:35.055230Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:33:35.055604Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 2 sessionId:  describe result for acl check
2025-12-04T20:33:35.055643Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint64; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `//Root/.metadata/TopicPartitionsMapping` WHERE Hash == $Hash AND Topic == $Topic AND ProducerId == $SourceId;
2025-12-04T20:33:35.055644Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64; DECLARE $SeqNo AS Uint64;
UPSERT INTO `//Root/.metadata/TopicPartitionsMapping` (Hash, Topic, ProducerId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-12-04T20:33:35.055646Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `//Root/.metadata/TopicPartitionsMapping` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND ProducerId = $SourceId AND Partition = $Partition;
2025-12-04T20:33:35.055676Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) StartKqpSession
2025-12-04T20:33:35.056248Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) Select from the table
2025-12-04T20:33:35.060299Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) Update the table
2025-12-04T20:33:35.064736Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2025-12-04T20:33:35.064754Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-04T20:33:35.064759Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [11:7580103667474454359:2639] (SourceId=123, PreferedPartition=(NULL)) Start idle
2025-12-04T20:33:35.064772Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 2 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-04T20:33:35.065093Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 11, Generation: 1
2025-12-04T20:33:35.065111Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103667474454388:2639], now have 1 active actors on pipe
2025-12-04T20:33:35.065120Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.065127Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.065163Z node 11 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 generated for partition 0 topic 'topic-f1' owner 123
2025-12-04T20:33:35.065176Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.065180Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.065186Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.065189Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.065194Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.065207Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.065210Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.065214Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.065234Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-04T20:33:35.065254Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.065340Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.065348Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.065363Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.065386Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 2 partition: 0 MaxSeqNo: 10 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0
2025-12-04T20:33:35.066078Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:35.066159Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:35.066271Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.066279Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.066299Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:33:35.066313Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.066399Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.066405Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.066419Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 11 partNo : 0 messageNo: 1 size 80 offset: -1
2025-12-04T20:33:35.066427Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:35.066439Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.066443Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.066452Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.066454Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.066458Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.066492Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 11 partNo 0
2025-12-04T20:33:35.066527Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 11 partNo 0 FormedBlobsCount 0 NewHead: Offset 10 PartNo 0 PackedSize 151 count 1 nextOffset 11 batches 1
2025-12-04T20:33:35.066533Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.066535Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.066538Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.066590Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 10,1 HeadOffset 10 endOffset 10 curOffset 11 d0000000000_00000000000000000010_00000_0000000001_00000? size 137 WTime 1764880415066
2025-12-04T20:33:35.066622Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:35.066633Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 10 partNo 0 count 1 size 137
2025-12-04T20:33:35.067700Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 10 count 1 size 137 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:35.067717Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:35.067739Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 84 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:35.067748Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:35.067757Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 11, partNo: 0, Offset: 10 is stored on disk
2025-12-04T20:33:35.067782Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.067784Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.067786Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.067789Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.067791Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.067797Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:35.067808Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:33:35.067825Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.068339Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 10 partno 0 count 1 parts 0 suffix '63' size 137
2025-12-04T20:33:35.091231Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.091248Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.091251Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.091255Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.091258Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.168023Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 grpc read done: success: 0 data: 
2025-12-04T20:33:35.168036Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 grpc read failed
2025-12-04T20:33:35.168046Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 grpc closed
2025-12-04T20:33:35.168050Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: 123|461380ad-4c27f0a8-be2879e3-1caa32d7_0 is DEAD
2025-12-04T20:33:35.168371Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:33:35.171023Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [11:7580103667474454388:2639] destroyed
2025-12-04T20:33:35.171044Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-04T20:33:35.171053Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.171057Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.171059Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.171062Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.171065Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.225950Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.225979Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.225981Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.225983Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.225985Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.256218Z node 11 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:207: new Alter topic request
2025-12-04T20:33:35.256563Z node 11 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [11:7580103667474454449:3858] txid# 281474976720706, issues: { message: "Path does not exist" issue_code: 200200 severity: 1 }
Alter topic (/Root/PQ//Root/topic-f1) response: 0 <main>: Error: Path does not exist, code: 200200
2025-12-04T20:33:35.261957Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:33:35.261980Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2025-12-04T20:33:35.266453Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId:  grpc read done: success: 1 data: init_request { topic: "/Root/topic-f1" message_group_id: "123" }
2025-12-04T20:33:35.266511Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 3 topic: "/Root/topic-f1" message_group_id: "123" from ipv6:[::1]:38162
2025-12-04T20:33:35.266519Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=3 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:38162 proto=v1  topic=/Root/topic-f1 durationSec=0
2025-12-04T20:33:35.266526Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:33:35.267059Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 3 sessionId:  describe result for acl check
2025-12-04T20:33:35.267128Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint64; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `//Root/.metadata/TopicPartitionsMapping` WHERE Hash == $Hash AND Topic == $Topic AND ProducerId == $SourceId;
2025-12-04T20:33:35.267130Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64; DECLARE $SeqNo AS Uint64;
UPSERT INTO `//Root/.metadata/TopicPartitionsMapping` (Hash, Topic, ProducerId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-12-04T20:33:35.267132Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `//Root/.metadata/TopicPartitionsMapping` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND ProducerId = $SourceId AND Partition = $Partition;
2025-12-04T20:33:35.267166Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) StartKqpSession
2025-12-04T20:33:35.267827Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) Select from the table
2025-12-04T20:33:35.271812Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) Update the table
2025-12-04T20:33:35.290290Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2025-12-04T20:33:35.290312Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-04T20:33:35.290317Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [11:7580103667474454459:2667] (SourceId=123, PreferedPartition=(NULL)) Start idle
2025-12-04T20:33:35.290328Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 3 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-04T20:33:35.290826Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 11, Generation: 1
2025-12-04T20:33:35.290839Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103667474454487:2667], now have 1 active actors on pipe
2025-12-04T20:33:35.290853Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.290863Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.290905Z node 11 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 generated for partition 0 topic 'topic-f1' owner 123
2025-12-04T20:33:35.290921Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.290927Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.290933Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.290936Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.290942Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.290957Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.290963Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.290967Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.290991Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-04T20:33:35.291022Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.292016Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.292031Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.292061Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.292096Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 3 partition: 0 MaxSeqNo: 11 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0
2025-12-04T20:33:35.296023Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:35.296144Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:35.296895Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.296908Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.296946Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:33:35.296968Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.297094Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.297099Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.297117Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 12 partNo : 0 messageNo: 1 size 80 offset: -1
2025-12-04T20:33:35.297127Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:35.297156Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.297160Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.297171Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.297174Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.297180Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.297228Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 12 partNo 0
2025-12-04T20:33:35.297279Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 12 partNo 0 FormedBlobsCount 0 NewHead: Offset 11 PartNo 0 PackedSize 151 count 1 nextOffset 12 batches 1
2025-12-04T20:33:35.297288Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.297290Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.297294Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.297368Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 11,1 HeadOffset 11 endOffset 11 curOffset 12 d0000000000_00000000000000000011_00000_0000000001_00000? size 137 WTime 1764880415296
2025-12-04T20:33:35.297409Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:35.297422Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 11 partNo 0 count 1 size 137
2025-12-04T20:33:35.298686Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 11 count 1 size 137 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:35.298718Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:35.298743Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 84 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:35.298753Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:35.298765Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 12, partNo: 0, Offset: 11 is stored on disk
2025-12-04T20:33:35.298795Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.298797Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.298800Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.298802Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.298805Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.298812Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:35.298822Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:33:35.298854Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.299125Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 11 partno 0 count 1 parts 0 suffix '63' size 137
2025-12-04T20:33:35.326265Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.326284Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.326287Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.326291Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.326294Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.401028Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 grpc read done: success: 0 data: 
2025-12-04T20:33:35.401045Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 grpc read failed
2025-12-04T20:33:35.401056Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 grpc closed
2025-12-04T20:33:35.401061Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: 123|ec8b0d38-508867a8-50effa1e-6d32ce5e_0 is DEAD
2025-12-04T20:33:35.401656Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:33:35.401708Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [11:7580103667474454487:2667] destroyed
2025-12-04T20:33:35.401722Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-04T20:33:35.401731Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.401734Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.401737Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.401739Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.401751Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.426515Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.426533Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.426535Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.426537Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.426539Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.441488Z node 11 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:207: new Alter topic request
2025-12-04T20:33:35.441805Z node 11 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [11:7580103667474454564:3898] txid# 281474976720712, issues: { message: "Path does not exist" issue_code: 200200 severity: 1 }
Alter topic (/Root/PQ//Root/topic-f1) response: 0 <main>: Error: Path does not exist, code: 200200
2025-12-04T20:33:35.445253Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2025-12-04T20:33:35.445279Z node 11 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 4
2025-12-04T20:33:35.445382Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 4 sessionId:  grpc read done: success: 1 data: init_request { topic: "/Root/topic-f1" message_group_id: "123" }
2025-12-04T20:33:35.445401Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:447: session request cookie: 4 topic: "/Root/topic-f1" message_group_id: "123" from ipv6:[::1]:38162
2025-12-04T20:33:35.445406Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1538: write session:  cookie=4 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:38162 proto=v1  topic=/Root/topic-f1 durationSec=0
2025-12-04T20:33:35.445410Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:571: init check schema
2025-12-04T20:33:35.445761Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:632: session v1 cookie: 4 sessionId:  describe result for acl check
2025-12-04T20:33:35.445813Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint64; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `//Root/.metadata/TopicPartitionsMapping` WHERE Hash == $Hash AND Topic == $Topic AND ProducerId == $SourceId;
2025-12-04T20:33:35.445819Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64; DECLARE $SeqNo AS Uint64;
UPSERT INTO `//Root/.metadata/TopicPartitionsMapping` (Hash, Topic, ProducerId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2025-12-04T20:33:35.445821Z node 11 :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 Uint64; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `//Root/.metadata/TopicPartitionsMapping` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND ProducerId = $SourceId AND Partition = $Partition;
2025-12-04T20:33:35.445856Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) StartKqpSession
2025-12-04T20:33:35.446444Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) Select from the table
2025-12-04T20:33:35.449001Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) Update the table
2025-12-04T20:33:35.453251Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2025-12-04T20:33:35.453264Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) ReplyResult: Partition=0, SeqNo=0
2025-12-04T20:33:35.453266Z node 11 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [11:7580103667474454574:2698] (SourceId=123, PreferedPartition=(NULL)) Start idle
2025-12-04T20:33:35.453275Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:694: ProceedPartition. session cookie: 4 sessionId:  partition: 0 expectedGeneration: (NULL)
2025-12-04T20:33:35.453519Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:822: TPartitionWriter 72075186224037892 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 11, Generation: 1
2025-12-04T20:33:35.453541Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2720: [PQ: 72075186224037892] server connected, pipe [11:7580103667474454603:2698], now have 1 active actors on pipe
2025-12-04T20:33:35.453548Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.453555Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.453592Z node 11 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie 123|4516a10d-43cc30e-1962442f-154cc574_0 generated for partition 0 topic 'topic-f1' owner 123
2025-12-04T20:33:35.453605Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.453609Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.453614Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.453620Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.453624Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.453634Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.453640Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.453642Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.453658Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2025-12-04T20:33:35.453676Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.453715Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.453723Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.453743Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2025-12-04T20:33:35.453764Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:871: session inited cookie: 4 partition: 0 MaxSeqNo: 12 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0
2025-12-04T20:33:35.455138Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 4 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0 grpc read done: success: 1 data:  write_request[data omitted]
2025-12-04T20:33:35.455246Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2025-12-04T20:33:35.455379Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.455388Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.455411Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2025-12-04T20:33:35.455425Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.455509Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:146: Handle TEvRequest topic: 'topic-f1' requestId: <none>
2025-12-04T20:33:35.455513Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2634: [PQ: 72075186224037892] got client message batch for topic 'topic-f1' partition 0
2025-12-04T20:33:35.455526Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2010: [PQ: 72075186224037892] got client message topic: topic-f1 partition: 0 SourceId: '\000123' SeqNo: 13 partNo : 0 messageNo: 1 size 80 offset: -1
2025-12-04T20:33:35.455533Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][0][StateIdle] Received TPartition::TEvWrite
2025-12-04T20:33:35.455545Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.455547Z node 11 :PERSQUEUE DEBUG: partition.cpp:2399: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2025-12-04T20:33:35.455555Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2025-12-04T20:33:35.455557Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.455561Z node 11 :PERSQUEUE DEBUG: partition.cpp:2463: [72075186224037892][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2025-12-04T20:33:35.455591Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1342: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob processing sourceId '\000123' seqNo 13 partNo 0
2025-12-04T20:33:35.455642Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1446: [72075186224037892][Partition][0][StateIdle] Topic 'topic-f1' partition 0 part blob complete sourceId '\000123' seqNo 13 partNo 0 FormedBlobsCount 0 NewHead: Offset 12 PartNo 0 PackedSize 151 count 1 nextOffset 13 batches 1
2025-12-04T20:33:35.455649Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2025-12-04T20:33:35.455651Z node 11 :PERSQUEUE DEBUG: partition.cpp:2318: [72075186224037892][Partition][0][StateIdle] Batch completed (1)
2025-12-04T20:33:35.455654Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.455700Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:1698: [72075186224037892][Partition][0][StateIdle] Add new write blob: topic 'topic-f1' partition 0 compactOffset 12,1 HeadOffset 12 endOffset 12 curOffset 13 d0000000000_00000000000000000012_00000_0000000001_00000? size 137 WTime 1764880415455
2025-12-04T20:33:35.455727Z node 11 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2025-12-04T20:33:35.455738Z node 11 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 12 partNo 0 count 1 size 137
2025-12-04T20:33:35.456894Z node 11 :PERSQUEUE DEBUG: cache_eviction.h:321: Caching head blob in L1. Partition 0 offset 12 count 1 size 137 actorID [11:7580103663179486089:2441]
2025-12-04T20:33:35.456909Z node 11 :PERSQUEUE DEBUG: partition.cpp:2129: [72075186224037892][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2025-12-04T20:33:35.456932Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 84 WriteNewSizeFromSupportivePartitions# 0
2025-12-04T20:33:35.456941Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2025-12-04T20:33:35.456949Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][0][StateIdle] Answering for message sourceid: '\000123', Topic: 'topic-f1', Partition: 0, SeqNo: 13, partNo: 0, Offset: 12 is stored on disk
2025-12-04T20:33:35.456974Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.456977Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.456979Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.456981Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.456983Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.456989Z node 11 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][0][StateIdle] No data for blobs compaction
2025-12-04T20:33:35.456998Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:181: Answer ok topic: 'topic-f1' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2025-12-04T20:33:35.457022Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NKikimr::TEvPersQueue::TEvResponse
2025-12-04T20:33:35.457072Z node 11 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 0 offset 12 partno 0 count 1 parts 0 suffix '63' size 137
2025-12-04T20:33:35.526880Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.526900Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.526903Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.526906Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.526909Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.555154Z node 11 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 4 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0 grpc read done: success: 0 data: 
2025-12-04T20:33:35.555172Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 4 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0 grpc read failed
2025-12-04T20:33:35.555182Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 4 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0 grpc closed
2025-12-04T20:33:35.555188Z node 11 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 4 sessionId: 123|4516a10d-43cc30e-1962442f-154cc574_0 is DEAD
2025-12-04T20:33:35.555506Z node 11 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=0) Received event: NActors::TEvents::TEvPoison
2025-12-04T20:33:35.556060Z node 11 :PERSQUEUE DEBUG: pq_impl.cpp:2745: [PQ: 72075186224037892] server disconnected, pipe [11:7580103667474454603:2698] destroyed
2025-12-04T20:33:35.556090Z node 11 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][0][StateIdle] TPartition::DropOwner.
2025-12-04T20:33:35.556100Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.556103Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.556106Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.556109Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.556111Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.627236Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.627258Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.627262Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.627266Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.627269Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.727618Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.727643Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.727646Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.727650Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.727653Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.832772Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.832797Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.832800Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.832805Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.832808Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
2025-12-04T20:33:35.936936Z node 11 :PERSQUEUE DEBUG: partition.cpp:2305: [72075186224037892][Partition][0][StateIdle] Process user action and tx events
2025-12-04T20:33:35.936965Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.936969Z node 11 :PERSQUEUE DEBUG: partition.cpp:2313: [72075186224037892][Partition][0][StateIdle] Process user action and tx pending commits
2025-12-04T20:33:35.936972Z node 11 :PERSQUEUE DEBUG: partition.cpp:2364: [72075186224037892][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2025-12-04T20:33:35.936975Z node 11 :PERSQUEUE DEBUG: partition.cpp:2323: [72075186224037892][Partition][0][StateIdle] Try persist
