2025-12-09T04:01:00.376304Z node 4 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[4:7581703306943287452:2197];send_to=[0:7307199536658146131:7762515];
2025-12-09T04:01:00.376347Z node 4 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/av6y/000e22/r3tmp/tmpszOmyG/pdisk_1.dat
2025-12-09T04:01:00.390581Z node 4 :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-09T04:01:00.439699Z node 4 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
TServer::EnableGrpc on GrpcPort 6631, node 4
2025-12-09T04:01:00.461780Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-12-09T04:01:00.461793Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-12-09T04:01:00.461795Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-12-09T04:01:00.461813Z node 4 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-12-09T04:01:00.478715Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-12-09T04:01:00.478739Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-12-09T04:01:00.480869Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Connecting -> Connected
TClient is connected to server localhost:8064
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-09T04:01:00.499204Z node 4 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976715657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
2025-12-09T04:01:00.543284Z node 4 :GRPC_SERVER INFO: grpc_request_proxy.cpp:646: Got grpc request# ListEndpointsRequest, traceId# 01kc0ma2kzah480vbftv3xfeeb, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:37426, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# 9.999714s
2025-12-09T04:01:00.544815Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:649: Got grpc request# CreateSessionRequest, traceId# 01kc0ma2m01sgh5sh6b5skbmfj, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv4:127.0.0.1:35534, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-12-09T04:01:00.622575Z node 4 :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-09T04:01:00.825019Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:649: Got grpc request# CreateTableRequest, traceId# 01kc0ma2wr9z28hsq2k40xv81w, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:37426, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-12-09T04:01:00.825148Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:314: actor# [4:7581703306943287542:2145] Handle TEvProposeTransaction
2025-12-09T04:01:00.825161Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:237: actor# [4:7581703306943287542:2145] TxId# 281474976715658 ProcessProposeTransaction
2025-12-09T04:01:00.825177Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:256: actor# [4:7581703306943287542:2145] Cookie# 0 userReqId# "" txid# 281474976715658 SEND to# [4:7581703306943288261:2609]
2025-12-09T04:01:00.825857Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1673: Actor# [4:7581703306943288261:2609] txid# 281474976715658 Bootstrap EvSchemeRequest record: Transaction { ModifyScheme { WorkingDir: "/Root" OperationType: ESchemeOpCreateTable CreateTable { Name: "Test" Columns { Name: "Key" Type: "Uint32" NotNull: false } Columns { Name: "Fk" Type: "Uint64" NotNull: false } Columns { Name: "Value" Type: "String" NotNull: false } KeyColumnNames: "Key" KeyColumnNames: "Fk" UniformPartitionsCount: 16 PartitionConfig { } Temporary: false } CreateIndexedTable { } } } DatabaseName: "" RequestType: "" PeerName: "ipv6:[::1]:37426" 
2025-12-09T04:01:00.825867Z node 4 :TX_PROXY DEBUG: schemereq.cpp:613: Actor# [4:7581703306943288261:2609] txid# 281474976715658 Bootstrap, UserSID: <empty> CheckAdministrator: 0 CheckDatabaseAdministrator: 0
2025-12-09T04:01:00.825983Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1738: Actor# [4:7581703306943288261:2609] txid# 281474976715658 HANDLE EvNavigateKeySetResult TFlatSchemeReq marker# P6
2025-12-09T04:01:00.826011Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1728: Actor# [4:7581703306943288261:2609] txid# 281474976715658 TEvNavigateKeySet requested from SchemeCache
2025-12-09T04:01:00.826065Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1561: Actor# [4:7581703306943288261:2609] txid# 281474976715658 HANDLE EvNavigateKeySetResult TFlatSchemeReq marker# P5 ErrorCount# 0
2025-12-09T04:01:00.826093Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1608: Actor# [4:7581703306943288261:2609] HANDLE EvNavigateKeySetResult, txid# 281474976715658 shardToRequest# 72057594046644480 DomainKey# [OwnerId: 72057594046644480, LocalPathId: 1] DomainInfo.Params# Version: 1 PlanResolution: 7 Coordinators: 72057594046316545 TimeCastBucketsPerMediator: 2 Mediators: 72057594046382081 RedirectRequired# true
2025-12-09T04:01:00.826103Z node 4 :TX_PROXY DEBUG: schemereq.cpp:103: Actor# [4:7581703306943288261:2609] txid# 281474976715658 SEND to# 72057594046644480 shardToRequest {TEvModifySchemeTransaction txid# 281474976715658 TabletId# 72057594046644480}
2025-12-09T04:01:00.826140Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1463: Actor# [4:7581703306943288261:2609] txid# 281474976715658 HANDLE EvClientConnected
2025-12-09T04:01:00.826714Z node 4 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715658:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-12-09T04:01:00.827498Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1485: Actor# [4:7581703306943288261:2609] txid# 281474976715658 Status StatusAccepted HANDLE {TEvModifySchemeTransactionResult Status# StatusAccepted txid# 281474976715658}
2025-12-09T04:01:00.827511Z node 4 :TX_PROXY DEBUG: schemereq.cpp:593: Actor# [4:7581703306943288261:2609] txid# 281474976715658 SEND to# [4:7581703306943288260:2316] Source {TEvProposeTransactionStatus txid# 281474976715658 Status# 53}
2025-12-09T04:01:00.827744Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:543: SchemeBoardUpdate /Root
2025-12-09T04:01:00.827746Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:543: SchemeBoardUpdate /Root
2025-12-09T04:01:00.827768Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:572: Can't update SecurityState for /Root - no PublicKeys
2025-12-09T04:01:00.827774Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:572: Can't update SecurityState for /Root - no PublicKeys
2025-12-09T04:01:00.835221Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288313:2659], Recipient [4:7581703306943288466:2322]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835239Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288319:2665], Recipient [4:7581703306943288474:2324]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835491Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288316:2662], Recipient [4:7581703306943288494:2331]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835499Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288321:2667], Recipient [4:7581703306943288482:2328]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835670Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288305:2651], Recipient [4:7581703306943288490:2330]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835749Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288320:2666], Recipient [4:7581703306943288462:2319]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835785Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288317:2663], Recipient [4:7581703306943288499:2332]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835897Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288308:2654], Recipient [4:7581703306943288467:2323]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835935Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288322:2668], Recipient [4:7581703306943288508:2334]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.835994Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288312:2658], Recipient [4:7581703306943288478:2327]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836139Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288315:2661], Recipient [4:7581703306943288465:2321]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836152Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288310:2656], Recipient [4:7581703306943288476:2325]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836272Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288318:2664], Recipient [4:7581703306943288486:2329]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836288Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288311:2657], Recipient [4:7581703306943288507:2333]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836427Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288314:2660], Recipient [4:7581703306943288477:2326]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836514Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828672, Sender [4:7581703306943288307:2653], Recipient [4:7581703306943288464:2320]: NKikimr::TEvTablet::TEvBoot
2025-12-09T04:01:00.836750Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288313:2659], Recipient [4:7581703306943288466:2322]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.836770Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288315:2661], Recipient [4:7581703306943288465:2321]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.836871Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037901 actor [4:7581703306943288466:2322]
2025-12-09T04:01:00.836871Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037899 actor [4:7581703306943288465:2321]
2025-12-09T04:01:00.836967Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.836974Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.838175Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288313:2659], Recipient [4:7581703306943288466:2322]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.838177Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288315:2661], Recipient [4:7581703306943288465:2321]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.838194Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288307:2653], Recipient [4:7581703306943288464:2320]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.838195Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288317:2663], Recipient [4:7581703306943288499:2332]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.838272Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037889 actor [4:7581703306943288464:2320]
2025-12-09T04:01:00.838272Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037897 actor [4:7581703306943288499:2332]
2025-12-09T04:01:00.838310Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.838310Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.839239Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288317:2663], Recipient [4:7581703306943288499:2332]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.839254Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288320:2666], Recipient [4:7581703306943288462:2319]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.839304Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037893 actor [4:7581703306943288462:2319]
2025-12-09T04:01:00.839329Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.839362Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288307:2653], Recipient [4:7581703306943288464:2320]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.839376Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288321:2667], Recipient [4:7581703306943288482:2328]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.839428Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037892 actor [4:7581703306943288482:2328]
2025-12-09T04:01:00.839460Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.840126Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288320:2666], Recipient [4:7581703306943288462:2319]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.840136Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288312:2658], Recipient [4:7581703306943288478:2327]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.840188Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037902 actor [4:7581703306943288478:2327]
2025-12-09T04:01:00.840226Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.840447Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288321:2667], Recipient [4:7581703306943288482:2328]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.840460Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288316:2662], Recipient [4:7581703306943288494:2331]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.840513Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037898 actor [4:7581703306943288494:2331]
2025-12-09T04:01:00.840553Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.841071Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288312:2658], Recipient [4:7581703306943288478:2327]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.841084Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288310:2656], Recipient [4:7581703306943288476:2325]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.841136Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037895 actor [4:7581703306943288476:2325]
2025-12-09T04:01:00.841167Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.841527Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288316:2662], Recipient [4:7581703306943288494:2331]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.841552Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288318:2664], Recipient [4:7581703306943288486:2329]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.841606Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037896 actor [4:7581703306943288486:2329]
2025-12-09T04:01:00.841639Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.842187Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288310:2656], Recipient [4:7581703306943288476:2325]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.842212Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288314:2660], Recipient [4:7581703306943288477:2326]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.842264Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037900 actor [4:7581703306943288477:2326]
2025-12-09T04:01:00.842303Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.842639Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288318:2664], Recipient [4:7581703306943288486:2329]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.842662Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288311:2657], Recipient [4:7581703306943288507:2333]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.842721Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037903 actor [4:7581703306943288507:2333]
2025-12-09T04:01:00.842765Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.843082Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288314:2660], Recipient [4:7581703306943288477:2326]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.843091Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288322:2668], Recipient [4:7581703306943288508:2334]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.843132Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037890 actor [4:7581703306943288508:2334]
2025-12-09T04:01:00.843164Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.843723Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288311:2657], Recipient [4:7581703306943288507:2333]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.843742Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.843755Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.843937Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037899
2025-12-09T04:01:00.843943Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037899
2025-12-09T04:01:00.843949Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037899
2025-12-09T04:01:00.843986Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288322:2668], Recipient [4:7581703306943288508:2334]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.844001Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.844004Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.844008Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.844014Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.844015Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037899 persisting started state actor id [4:7581703306943288809:2321] in generation 1
2025-12-09T04:01:00.844042Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.844051Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.844143Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037903
2025-12-09T04:01:00.844148Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037903
2025-12-09T04:01:00.844152Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037903
2025-12-09T04:01:00.844181Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037901
2025-12-09T04:01:00.844186Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037901
2025-12-09T04:01:00.844187Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.844191Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037901
2025-12-09T04:01:00.844192Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.844196Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037903 persisting started state actor id [4:7581703306943288814:2333] in generation 1
2025-12-09T04:01:00.844217Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288305:2651], Recipient [4:7581703306943288490:2330]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.844230Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.844234Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.844237Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037901 persisting started state actor id [4:7581703306943288815:2322] in generation 1
2025-12-09T04:01:00.844259Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.844267Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.844268Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037888 actor [4:7581703306943288490:2330]
2025-12-09T04:01:00.844303Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.844388Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037897
2025-12-09T04:01:00.844392Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037897
2025-12-09T04:01:00.844395Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037897
2025-12-09T04:01:00.844422Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.844425Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.844428Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037897 persisting started state actor id [4:7581703306943288819:2332] in generation 1
2025-12-09T04:01:00.844447Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288319:2665], Recipient [4:7581703306943288474:2324]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.844505Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037894 actor [4:7581703306943288474:2324]
2025-12-09T04:01:00.844548Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.845163Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288305:2651], Recipient [4:7581703306943288490:2330]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.845183Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.845189Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.845282Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037890
2025-12-09T04:01:00.845285Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037890
2025-12-09T04:01:00.845289Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037890
2025-12-09T04:01:00.845321Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.845325Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.845328Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037890 persisting started state actor id [4:7581703306943288831:2334] in generation 1
2025-12-09T04:01:00.845365Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.845371Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.845466Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037889
2025-12-09T04:01:00.845469Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037889
2025-12-09T04:01:00.845472Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037889
2025-12-09T04:01:00.845488Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288319:2665], Recipient [4:7581703306943288474:2324]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.845490Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.845493Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.845495Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037889 persisting started state actor id [4:7581703306943288838:2320] in generation 1
2025-12-09T04:01:00.845501Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3110: StateInit, received event# 268828673, Sender [4:7581703306943288308:2654], Recipient [4:7581703306943288467:2323]: NKikimr::TEvTablet::TEvRestored
2025-12-09T04:01:00.845516Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.845521Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.845553Z node 4 :TX_DATASHARD INFO: datashard.cpp:372: TDataShard::OnActivateExecutor: tablet 72075186224037891 actor [4:7581703306943288467:2323]
2025-12-09T04:01:00.845584Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-12-09T04:01:00.845598Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037893
2025-12-09T04:01:00.845600Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037893
2025-12-09T04:01:00.845602Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037893
2025-12-09T04:01:00.845623Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.845626Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.845628Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037893 persisting started state actor id [4:7581703306943288842:2319] in generation 1
2025-12-09T04:01:00.845648Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.845653Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.845744Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037892
2025-12-09T04:01:00.845747Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037892
2025-12-09T04:01:00.845750Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037892
2025-12-09T04:01:00.845769Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.845771Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.845773Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037892 persisting started state actor id [4:7581703306943288845:2328] in generation 1
2025-12-09T04:01:00.845788Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.845793Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.845900Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037902
2025-12-09T04:01:00.845904Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037902
2025-12-09T04:01:00.845907Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037902
2025-12-09T04:01:00.845938Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.845942Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.845953Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037902 persisting started state actor id [4:7581703306943288849:2327] in generation 1
2025-12-09T04:01:00.845970Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.845978Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037897
2025-12-09T04:01:00.846010Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037897 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.846019Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037897, actorId: [4:7581703306943288850:2335]
2025-12-09T04:01:00.846022Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037897
2025-12-09T04:01:00.846023Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037897, state: WaitScheme
2025-12-09T04:01:00.846026Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037897
2025-12-09T04:01:00.846039Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288499:2332], Recipient [4:7581703306943288499:2332]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846042Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846053Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037897
2025-12-09T04:01:00.846060Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037897
2025-12-09T04:01:00.846062Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037897
2025-12-09T04:01:00.846065Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037897 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.846070Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037897
2025-12-09T04:01:00.846073Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037897 has no attached operations
2025-12-09T04:01:00.846075Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037897
2025-12-09T04:01:00.846079Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037897 TxInFly 0
2025-12-09T04:01:00.846081Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037897
2025-12-09T04:01:00.846085Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288460:2756], Recipient [4:7581703306943288499:2332]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846086Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846089Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037897, clientId# [4:7581703306943288415:2727], serverId# [4:7581703306943288460:2756], sessionId# [0:0:0]
2025-12-09T04:01:00.846093Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.846094Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037899
2025-12-09T04:01:00.846098Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037899 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.846108Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037899, actorId: [4:7581703306943288851:2336]
2025-12-09T04:01:00.846109Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037899
2025-12-09T04:01:00.846110Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037899, state: WaitScheme
2025-12-09T04:01:00.846111Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037899
2025-12-09T04:01:00.846115Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288465:2321], Recipient [4:7581703306943288465:2321]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846116Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846121Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037899
2025-12-09T04:01:00.846125Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037899
2025-12-09T04:01:00.846127Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037899
2025-12-09T04:01:00.846127Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037899 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.846128Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037899
2025-12-09T04:01:00.846131Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037899 has no attached operations
2025-12-09T04:01:00.846131Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037899
2025-12-09T04:01:00.846132Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037899 TxInFly 0
2025-12-09T04:01:00.846133Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037899
2025-12-09T04:01:00.846136Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288483:2763], Recipient [4:7581703306943288465:2321]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846137Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846138Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037899, clientId# [4:7581703306943288417:2729], serverId# [4:7581703306943288483:2763], sessionId# [0:0:0]
2025-12-09T04:01:00.846140Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288483:2763]
2025-12-09T04:01:00.846142Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.846157Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037899
2025-12-09T04:01:00.846212Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit CheckSchemeTx
2025-12-09T04:01:00.846220Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037899 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.846233Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037899
2025-12-09T04:01:00.846240Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.846242Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit CheckSchemeTx
2025-12-09T04:01:00.846246Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit StoreSchemeTx
2025-12-09T04:01:00.846247Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit StoreSchemeTx
2025-12-09T04:01:00.846301Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.846303Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit StoreSchemeTx
2025-12-09T04:01:00.846305Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit FinishPropose
2025-12-09T04:01:00.846308Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit FinishPropose
2025-12-09T04:01:00.846314Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is DelayComplete
2025-12-09T04:01:00.846316Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit FinishPropose
2025-12-09T04:01:00.846321Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit WaitForPlan
2025-12-09T04:01:00.846322Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit WaitForPlan
2025-12-09T04:01:00.846326Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037899 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.846348Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.846356Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.846483Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037898
2025-12-09T04:01:00.846487Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037898
2025-12-09T04:01:00.846491Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037898
2025-12-09T04:01:00.846523Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.846527Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.846530Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037898 persisting started state actor id [4:7581703306943288861:2331] in generation 1
2025-12-09T04:01:00.846539Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3123: StateInactive, received event# 268828684, Sender [4:7581703306943288308:2654], Recipient [4:7581703306943288467:2323]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-12-09T04:01:00.846550Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.846551Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.846557Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.846560Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.846675Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037895
2025-12-09T04:01:00.846679Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037895
2025-12-09T04:01:00.846682Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037895
2025-12-09T04:01:00.846688Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037888
2025-12-09T04:01:00.846692Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037888
2025-12-09T04:01:00.846696Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037888
2025-12-09T04:01:00.846710Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.846713Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.846716Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037895 persisting started state actor id [4:7581703306943288867:2325] in generation 1
2025-12-09T04:01:00.846728Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.846731Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.846735Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037888 persisting started state actor id [4:7581703306943288868:2330] in generation 1
2025-12-09T04:01:00.846736Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.846742Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.846754Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.846759Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037901
2025-12-09T04:01:00.846768Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037901 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.846774Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037901, actorId: [4:7581703306943288870:2337]
2025-12-09T04:01:00.846777Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037901
2025-12-09T04:01:00.846779Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037901, state: WaitScheme
2025-12-09T04:01:00.846781Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037901
2025-12-09T04:01:00.846792Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288466:2322], Recipient [4:7581703306943288466:2322]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846794Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.846804Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037901
2025-12-09T04:01:00.846812Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037901
2025-12-09T04:01:00.846815Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037901
2025-12-09T04:01:00.846818Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037901 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.846827Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037901
2025-12-09T04:01:00.846829Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037901 has no attached operations
2025-12-09T04:01:00.846831Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037901
2025-12-09T04:01:00.846833Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037901 TxInFly 0
2025-12-09T04:01:00.846835Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037901
2025-12-09T04:01:00.846842Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288468:2759], Recipient [4:7581703306943288466:2322]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846844Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.846847Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037901, clientId# [4:7581703306943288419:2731], serverId# [4:7581703306943288468:2759], sessionId# [0:0:0]
2025-12-09T04:01:00.846859Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037896
2025-12-09T04:01:00.846881Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037896
2025-12-09T04:01:00.846881Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288460:2756]
2025-12-09T04:01:00.846883Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.846886Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037896
2025-12-09T04:01:00.846898Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037897
2025-12-09T04:01:00.846913Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.846917Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.846919Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037896 persisting started state actor id [4:7581703306943288872:2329] in generation 1
2025-12-09T04:01:00.846934Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037897 on unit CheckSchemeTx
2025-12-09T04:01:00.846938Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.846939Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037897 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.846944Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.846951Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037897
2025-12-09T04:01:00.846954Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037897 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.846972Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037897 executing on unit CheckSchemeTx
2025-12-09T04:01:00.846974Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037897 to execution unit StoreSchemeTx
2025-12-09T04:01:00.846976Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037897 on unit StoreSchemeTx
2025-12-09T04:01:00.847022Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037897 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.847024Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037897 executing on unit StoreSchemeTx
2025-12-09T04:01:00.847026Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037897 to execution unit FinishPropose
2025-12-09T04:01:00.847027Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037897 on unit FinishPropose
2025-12-09T04:01:00.847032Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037897 is DelayComplete
2025-12-09T04:01:00.847034Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037897 executing on unit FinishPropose
2025-12-09T04:01:00.847035Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037897 to execution unit WaitForPlan
2025-12-09T04:01:00.847037Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037897 on unit WaitForPlan
2025-12-09T04:01:00.847039Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037897 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.847062Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.847070Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.847077Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037900
2025-12-09T04:01:00.847081Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037900
2025-12-09T04:01:00.847084Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037900
2025-12-09T04:01:00.847112Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.847116Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.847118Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037900 persisting started state actor id [4:7581703306943288879:2326] in generation 1
2025-12-09T04:01:00.847156Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288465:2321]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.847161Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037899
2025-12-09T04:01:00.847184Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288468:2759]
2025-12-09T04:01:00.847186Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.847193Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037894
2025-12-09T04:01:00.847197Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037901
2025-12-09T04:01:00.847204Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037894
2025-12-09T04:01:00.847208Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037894
2025-12-09T04:01:00.847223Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037901 on unit CheckSchemeTx
2025-12-09T04:01:00.847228Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037901 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.847237Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037901
2025-12-09T04:01:00.847238Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.847241Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037901 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.847242Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.847243Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037901 executing on unit CheckSchemeTx
2025-12-09T04:01:00.847244Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037901 to execution unit StoreSchemeTx
2025-12-09T04:01:00.847245Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037894 persisting started state actor id [4:7581703306943288884:2324] in generation 1
2025-12-09T04:01:00.847246Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037901 on unit StoreSchemeTx
2025-12-09T04:01:00.847275Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288499:2332]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.847278Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037897
2025-12-09T04:01:00.847280Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037901 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.847282Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037901 executing on unit StoreSchemeTx
2025-12-09T04:01:00.847284Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037901 to execution unit FinishPropose
2025-12-09T04:01:00.847286Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037901 on unit FinishPropose
2025-12-09T04:01:00.847314Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037901 is DelayComplete
2025-12-09T04:01:00.847316Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037901 executing on unit FinishPropose
2025-12-09T04:01:00.847318Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037901 to execution unit WaitForPlan
2025-12-09T04:01:00.847319Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037901 on unit WaitForPlan
2025-12-09T04:01:00.847321Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037901 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.847336Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288466:2322]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.847339Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037901
2025-12-09T04:01:00.847351Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-12-09T04:01:00.847357Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-12-09T04:01:00.847461Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1322: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037891
2025-12-09T04:01:00.847464Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1378: LoadLockChangeRecords at tablet: 72075186224037891
2025-12-09T04:01:00.847468Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1427: LoadChangeRecordCommits at tablet: 72075186224037891
2025-12-09T04:01:00.847492Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-12-09T04:01:00.847496Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-12-09T04:01:00.847500Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037891 persisting started state actor id [4:7581703306943288892:2323] in generation 1
2025-12-09T04:01:00.847899Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.847904Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037890
2025-12-09T04:01:00.847916Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037890 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.847921Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037890, actorId: [4:7581703306943288900:2338]
2025-12-09T04:01:00.847922Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037890
2025-12-09T04:01:00.847921Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:152: TTxProposeTransactionBase::Complete at 72075186224037899
2025-12-09T04:01:00.847924Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037890, state: WaitScheme
2025-12-09T04:01:00.847927Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037890
2025-12-09T04:01:00.847928Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037899 on unit StoreSchemeTx
2025-12-09T04:01:00.847931Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037899 on unit FinishPropose
2025-12-09T04:01:00.847938Z node 4 :TX_DATASHARD TRACE: finish_propose_unit.cpp:167: Propose transaction complete txid 281474976715658 at tablet 72075186224037899 send to client, exec latency: 0 ms, propose latency: 1 ms, status: PREPARED
2025-12-09T04:01:00.847940Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288508:2334], Recipient [4:7581703306943288508:2334]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.847942Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.847949Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:466: 72075186224037899 not sending time cast registration request in state WaitScheme
2025-12-09T04:01:00.847955Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037890
2025-12-09T04:01:00.847965Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037890
2025-12-09T04:01:00.847970Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288454:2750], Recipient [4:7581703306943288508:2334]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.847972Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.847974Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037890, clientId# [4:7581703306943288408:2720], serverId# [4:7581703306943288454:2750], sessionId# [0:0:0]
2025-12-09T04:01:00.847978Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288454:2750]
2025-12-09T04:01:00.847980Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.847982Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.847986Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037892
2025-12-09T04:01:00.847991Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037890
2025-12-09T04:01:00.847993Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037890 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.847995Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037890
2025-12-09T04:01:00.847997Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037890 has no attached operations
2025-12-09T04:01:00.847998Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037892 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.847999Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037890
2025-12-09T04:01:00.848001Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037890 TxInFly 0
2025-12-09T04:01:00.848003Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037890
2025-12-09T04:01:00.848005Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037890
2025-12-09T04:01:00.848011Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037892, actorId: [4:7581703306943288901:2339]
2025-12-09T04:01:00.848013Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037892
2025-12-09T04:01:00.848015Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037892, state: WaitScheme
2025-12-09T04:01:00.848017Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037892
2025-12-09T04:01:00.848031Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288482:2328], Recipient [4:7581703306943288482:2328]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848032Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037890 on unit CheckSchemeTx
2025-12-09T04:01:00.848033Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848036Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037890 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848046Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037892
2025-12-09T04:01:00.848057Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037890
2025-12-09T04:01:00.848060Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037890 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848062Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037892
2025-12-09T04:01:00.848062Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037890 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848064Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037890 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848066Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037890 on unit StoreSchemeTx
2025-12-09T04:01:00.848077Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037892
2025-12-09T04:01:00.848079Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037892 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848082Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037892
2025-12-09T04:01:00.848084Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037892 has no attached operations
2025-12-09T04:01:00.848085Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037892
2025-12-09T04:01:00.848087Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037892 TxInFly 0
2025-12-09T04:01:00.848090Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037892
2025-12-09T04:01:00.848097Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288456:2752], Recipient [4:7581703306943288482:2328]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848099Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848102Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037892, clientId# [4:7581703306943288410:2722], serverId# [4:7581703306943288456:2752], sessionId# [0:0:0]
2025-12-09T04:01:00.848105Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288456:2752]
2025-12-09T04:01:00.848106Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848109Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037890 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848110Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037890 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848111Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037890 to execution unit FinishPropose
2025-12-09T04:01:00.848112Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037890 on unit FinishPropose
2025-12-09T04:01:00.848116Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037890 is DelayComplete
2025-12-09T04:01:00.848117Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037890 executing on unit FinishPropose
2025-12-09T04:01:00.848118Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037890 to execution unit WaitForPlan
2025-12-09T04:01:00.848119Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037892
2025-12-09T04:01:00.848119Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037890 on unit WaitForPlan
2025-12-09T04:01:00.848121Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037890 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848139Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288508:2334]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848142Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037890
2025-12-09T04:01:00.848146Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037892 on unit CheckSchemeTx
2025-12-09T04:01:00.848150Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848150Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037892 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848152Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037888
2025-12-09T04:01:00.848157Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037888 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848161Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037888, actorId: [4:7581703306943288904:2340]
2025-12-09T04:01:00.848162Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037888
2025-12-09T04:01:00.848164Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037892
2025-12-09T04:01:00.848164Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037888, state: WaitScheme
2025-12-09T04:01:00.848166Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-12-09T04:01:00.848167Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037892 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848172Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288490:2330], Recipient [4:7581703306943288490:2330]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848173Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848181Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037888
2025-12-09T04:01:00.848185Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037892 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848187Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037892 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848189Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037888
2025-12-09T04:01:00.848189Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037892 on unit StoreSchemeTx
2025-12-09T04:01:00.848191Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037888
2025-12-09T04:01:00.848192Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037888 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848194Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037888
2025-12-09T04:01:00.848196Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037888 has no attached operations
2025-12-09T04:01:00.848196Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037888
2025-12-09T04:01:00.848202Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037888 TxInFly 0
2025-12-09T04:01:00.848204Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037888
2025-12-09T04:01:00.848210Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288481:2762], Recipient [4:7581703306943288490:2330]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848212Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848214Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037888, clientId# [4:7581703306943288406:2718], serverId# [4:7581703306943288481:2762], sessionId# [0:0:0]
2025-12-09T04:01:00.848219Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848221Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037898
2025-12-09T04:01:00.848226Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037898 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848229Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037898, actorId: [4:7581703306943288906:2341]
2025-12-09T04:01:00.848231Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037898
2025-12-09T04:01:00.848234Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037898, state: WaitScheme
2025-12-09T04:01:00.848235Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037898
2025-12-09T04:01:00.848238Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037892 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848240Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037892 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848242Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037892 to execution unit FinishPropose
2025-12-09T04:01:00.848242Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288494:2331], Recipient [4:7581703306943288494:2331]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848243Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037892 on unit FinishPropose
2025-12-09T04:01:00.848244Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848250Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037898
2025-12-09T04:01:00.848255Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037898
2025-12-09T04:01:00.848258Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037892 is DelayComplete
2025-12-09T04:01:00.848259Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288461:2757], Recipient [4:7581703306943288494:2331]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848261Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848263Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037898, clientId# [4:7581703306943288416:2728], serverId# [4:7581703306943288461:2757], sessionId# [0:0:0]
2025-12-09T04:01:00.848263Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037892 executing on unit FinishPropose
2025-12-09T04:01:00.848264Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037898
2025-12-09T04:01:00.848264Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037892 to execution unit WaitForPlan
2025-12-09T04:01:00.848266Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037898 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848266Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037892 on unit WaitForPlan
2025-12-09T04:01:00.848267Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037898
2025-12-09T04:01:00.848268Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037898 has no attached operations
2025-12-09T04:01:00.848268Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037892 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848269Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037898
2025-12-09T04:01:00.848269Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037898 TxInFly 0
2025-12-09T04:01:00.848271Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037898
2025-12-09T04:01:00.848274Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288461:2757]
2025-12-09T04:01:00.848275Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848281Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037898
2025-12-09T04:01:00.848294Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288482:2328]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848297Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037892
2025-12-09T04:01:00.848298Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit CheckSchemeTx
2025-12-09T04:01:00.848300Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037898 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848307Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037898
2025-12-09T04:01:00.848307Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848311Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037893
2025-12-09T04:01:00.848321Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848323Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848324Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848326Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit StoreSchemeTx
2025-12-09T04:01:00.848340Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037893 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848345Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037893, actorId: [4:7581703306943288907:2342]
2025-12-09T04:01:00.848346Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037893
2025-12-09T04:01:00.848348Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037893, state: WaitScheme
2025-12-09T04:01:00.848349Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037893
2025-12-09T04:01:00.848356Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848357Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848359Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit FinishPropose
2025-12-09T04:01:00.848360Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288462:2319], Recipient [4:7581703306943288462:2319]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848360Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit FinishPropose
2025-12-09T04:01:00.848362Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848363Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is DelayComplete
2025-12-09T04:01:00.848364Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit FinishPropose
2025-12-09T04:01:00.848366Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit WaitForPlan
2025-12-09T04:01:00.848367Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit WaitForPlan
2025-12-09T04:01:00.848368Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037898 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848372Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037893
2025-12-09T04:01:00.848382Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288494:2331]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848385Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037898
2025-12-09T04:01:00.848392Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848392Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037893
2025-12-09T04:01:00.848395Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037895
2025-12-09T04:01:00.848398Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288463:2758], Recipient [4:7581703306943288462:2319]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848399Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848400Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037895 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848402Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037893, clientId# [4:7581703306943288411:2723], serverId# [4:7581703306943288463:2758], sessionId# [0:0:0]
2025-12-09T04:01:00.848404Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037893
2025-12-09T04:01:00.848404Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037895, actorId: [4:7581703306943288908:2343]
2025-12-09T04:01:00.848406Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037895
2025-12-09T04:01:00.848407Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037893 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848408Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037895, state: WaitScheme
2025-12-09T04:01:00.848408Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037893
2025-12-09T04:01:00.848409Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037895
2025-12-09T04:01:00.848410Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037893 has no attached operations
2025-12-09T04:01:00.848411Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037893
2025-12-09T04:01:00.848413Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037893 TxInFly 0
2025-12-09T04:01:00.848415Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037893
2025-12-09T04:01:00.848415Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288476:2325], Recipient [4:7581703306943288476:2325]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848417Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848418Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288463:2758]
2025-12-09T04:01:00.848419Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848433Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037893
2025-12-09T04:01:00.848437Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037895
2025-12-09T04:01:00.848444Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037895
2025-12-09T04:01:00.848449Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288458:2754], Recipient [4:7581703306943288476:2325]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848450Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848453Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037895, clientId# [4:7581703306943288413:2725], serverId# [4:7581703306943288458:2754], sessionId# [0:0:0]
2025-12-09T04:01:00.848455Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288458:2754]
2025-12-09T04:01:00.848456Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848457Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037893 on unit CheckSchemeTx
2025-12-09T04:01:00.848461Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037893 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848462Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037895
2025-12-09T04:01:00.848463Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037895 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848464Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037895
2025-12-09T04:01:00.848465Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037895 has no attached operations
2025-12-09T04:01:00.848466Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037895
2025-12-09T04:01:00.848467Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037895 TxInFly 0
2025-12-09T04:01:00.848468Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037895
2025-12-09T04:01:00.848471Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037895
2025-12-09T04:01:00.848472Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037893
2025-12-09T04:01:00.848475Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037893 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848477Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037893 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848478Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037893 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848480Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037893 on unit StoreSchemeTx
2025-12-09T04:01:00.848487Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit CheckSchemeTx
2025-12-09T04:01:00.848489Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037895 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848496Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037895
2025-12-09T04:01:00.848498Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848500Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848502Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848503Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit StoreSchemeTx
2025-12-09T04:01:00.848517Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037893 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848519Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037893 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848520Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037893 to execution unit FinishPropose
2025-12-09T04:01:00.848522Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037893 on unit FinishPropose
2025-12-09T04:01:00.848525Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037893 is DelayComplete
2025-12-09T04:01:00.848527Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037893 executing on unit FinishPropose
2025-12-09T04:01:00.848528Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037893 to execution unit WaitForPlan
2025-12-09T04:01:00.848529Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037893 on unit WaitForPlan
2025-12-09T04:01:00.848531Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037893 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848545Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288462:2319]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848548Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037893
2025-12-09T04:01:00.848556Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848558Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848559Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit FinishPropose
2025-12-09T04:01:00.848561Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit FinishPropose
2025-12-09T04:01:00.848564Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is DelayComplete
2025-12-09T04:01:00.848565Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit FinishPropose
2025-12-09T04:01:00.848566Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit WaitForPlan
2025-12-09T04:01:00.848568Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit WaitForPlan
2025-12-09T04:01:00.848570Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037895 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848591Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288476:2325]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848593Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037895
2025-12-09T04:01:00.848601Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848603Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037896
2025-12-09T04:01:00.848609Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037896 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848612Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037896, actorId: [4:7581703306943288915:2344]
2025-12-09T04:01:00.848613Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037896
2025-12-09T04:01:00.848614Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288481:2762]
2025-12-09T04:01:00.848615Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037896, state: WaitScheme
2025-12-09T04:01:00.848616Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037896
2025-12-09T04:01:00.848616Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848622Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037888
2025-12-09T04:01:00.848623Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288486:2329], Recipient [4:7581703306943288486:2329]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848624Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848633Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037896
2025-12-09T04:01:00.848636Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037888 on unit CheckSchemeTx
2025-12-09T04:01:00.848638Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037888 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848639Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037896
2025-12-09T04:01:00.848643Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288459:2755], Recipient [4:7581703306943288486:2329]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848645Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848645Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037888
2025-12-09T04:01:00.848647Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037896, clientId# [4:7581703306943288414:2726], serverId# [4:7581703306943288459:2755], sessionId# [0:0:0]
2025-12-09T04:01:00.848647Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037888 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848649Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037896
2025-12-09T04:01:00.848649Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037888 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848650Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037896 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848651Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037888 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848651Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037896
2025-12-09T04:01:00.848653Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037896 has no attached operations
2025-12-09T04:01:00.848654Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037896
2025-12-09T04:01:00.848655Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037896 TxInFly 0
2025-12-09T04:01:00.848657Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037896
2025-12-09T04:01:00.848661Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288459:2755]
2025-12-09T04:01:00.848662Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848652Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037888 on unit StoreSchemeTx
2025-12-09T04:01:00.848668Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037896
2025-12-09T04:01:00.848683Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit CheckSchemeTx
2025-12-09T04:01:00.848685Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037896 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848692Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037896
2025-12-09T04:01:00.848694Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848696Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848700Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848702Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit StoreSchemeTx
2025-12-09T04:01:00.848723Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037888 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848725Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037888 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848727Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037888 to execution unit FinishPropose
2025-12-09T04:01:00.848728Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037888 on unit FinishPropose
2025-12-09T04:01:00.848731Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848731Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037888 is DelayComplete
2025-12-09T04:01:00.848732Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037888 executing on unit FinishPropose
2025-12-09T04:01:00.848734Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037888 to execution unit WaitForPlan
2025-12-09T04:01:00.848736Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037888 on unit WaitForPlan
2025-12-09T04:01:00.848737Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037888 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848743Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848745Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit FinishPropose
2025-12-09T04:01:00.848747Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit FinishPropose
2025-12-09T04:01:00.848749Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is DelayComplete
2025-12-09T04:01:00.848751Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit FinishPropose
2025-12-09T04:01:00.848752Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit WaitForPlan
2025-12-09T04:01:00.848754Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit WaitForPlan
2025-12-09T04:01:00.848755Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288490:2330]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848755Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037896 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848757Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037888
2025-12-09T04:01:00.848766Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848770Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037902
2025-12-09T04:01:00.848769Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288486:2329]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848772Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037896
2025-12-09T04:01:00.848778Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037902 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848780Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848782Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037902, actorId: [4:7581703306943288922:2345]
2025-12-09T04:01:00.848783Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037903
2025-12-09T04:01:00.848783Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037902
2025-12-09T04:01:00.848784Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037902, state: WaitScheme
2025-12-09T04:01:00.848786Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037902
2025-12-09T04:01:00.848789Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037903 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848793Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037903, actorId: [4:7581703306943288923:2346]
2025-12-09T04:01:00.848793Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288478:2327], Recipient [4:7581703306943288478:2327]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848794Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037903
2025-12-09T04:01:00.848795Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848795Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037903, state: WaitScheme
2025-12-09T04:01:00.848796Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037903
2025-12-09T04:01:00.848803Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288507:2333], Recipient [4:7581703306943288507:2333]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848803Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037902
2025-12-09T04:01:00.848804Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848811Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037903
2025-12-09T04:01:00.848813Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037902
2025-12-09T04:01:00.848818Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288479:2760], Recipient [4:7581703306943288478:2327]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848818Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037903
2025-12-09T04:01:00.848821Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037903
2025-12-09T04:01:00.848823Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037903 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848828Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037903
2025-12-09T04:01:00.848830Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037903 has no attached operations
2025-12-09T04:01:00.848831Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037903
2025-12-09T04:01:00.848831Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848832Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037903 TxInFly 0
2025-12-09T04:01:00.848834Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037903
2025-12-09T04:01:00.848835Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037902, clientId# [4:7581703306943288420:2732], serverId# [4:7581703306943288479:2760], sessionId# [0:0:0]
2025-12-09T04:01:00.848837Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037902
2025-12-09T04:01:00.848839Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288480:2761], Recipient [4:7581703306943288507:2333]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848839Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037902 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.848841Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.848844Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037903, clientId# [4:7581703306943288421:2733], serverId# [4:7581703306943288480:2761], sessionId# [0:0:0]
2025-12-09T04:01:00.848846Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037902
2025-12-09T04:01:00.848847Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288480:2761]
2025-12-09T04:01:00.848848Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848849Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037902 has no attached operations
2025-12-09T04:01:00.848851Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037902
2025-12-09T04:01:00.848852Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037902 TxInFly 0
2025-12-09T04:01:00.848854Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037903
2025-12-09T04:01:00.848855Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037902
2025-12-09T04:01:00.848859Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288479:2760]
2025-12-09T04:01:00.848860Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.848866Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037902
2025-12-09T04:01:00.848870Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit CheckSchemeTx
2025-12-09T04:01:00.848872Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037903 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848878Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037903
2025-12-09T04:01:00.848882Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848883Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit CheckSchemeTx
2025-12-09T04:01:00.848884Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848885Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848886Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037902 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.848887Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit StoreSchemeTx
2025-12-09T04:01:00.848894Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037902
2025-12-09T04:01:00.848896Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.848898Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit CheckSchemeTx
2025-12-09T04:01:00.848899Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit StoreSchemeTx
2025-12-09T04:01:00.848901Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit StoreSchemeTx
2025-12-09T04:01:00.848917Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848919Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848920Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit FinishPropose
2025-12-09T04:01:00.848922Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit FinishPropose
2025-12-09T04:01:00.848924Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is DelayComplete
2025-12-09T04:01:00.848926Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit FinishPropose
2025-12-09T04:01:00.848927Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit WaitForPlan
2025-12-09T04:01:00.848929Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit WaitForPlan
2025-12-09T04:01:00.848931Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037903 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848943Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.848944Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit StoreSchemeTx
2025-12-09T04:01:00.848944Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288507:2333]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848946Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit FinishPropose
2025-12-09T04:01:00.848948Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit FinishPropose
2025-12-09T04:01:00.848951Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is DelayComplete
2025-12-09T04:01:00.848954Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037903
2025-12-09T04:01:00.848954Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit FinishPropose
2025-12-09T04:01:00.848956Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit WaitForPlan
2025-12-09T04:01:00.848957Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit WaitForPlan
2025-12-09T04:01:00.848959Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037902 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.848962Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.848965Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037889
2025-12-09T04:01:00.848971Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037889 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.848974Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037889, actorId: [4:7581703306943288924:2347]
2025-12-09T04:01:00.848975Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037889
2025-12-09T04:01:00.848976Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037889, state: WaitScheme
2025-12-09T04:01:00.848978Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037889
2025-12-09T04:01:00.848983Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269746185, Sender [4:7581703306943288852:3025], Recipient [4:7581703306943288478:2327]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-12-09T04:01:00.848985Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288464:2320], Recipient [4:7581703306943288464:2320]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848985Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:180: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037902
2025-12-09T04:01:00.848986Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.848994Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037889
2025-12-09T04:01:00.849002Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037889
2025-12-09T04:01:00.849004Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037889
2025-12-09T04:01:00.849006Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037889 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.849007Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037889
2025-12-09T04:01:00.849008Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037889 has no attached operations
2025-12-09T04:01:00.849009Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037889
2025-12-09T04:01:00.849011Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037889 TxInFly 0
2025-12-09T04:01:00.849015Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037889
2025-12-09T04:01:00.849023Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:152: TTxProposeTransactionBase::Complete at 72075186224037897
2025-12-09T04:01:00.849025Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037897 on unit StoreSchemeTx
2025-12-09T04:01:00.849027Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037897 on unit FinishPropose
2025-12-09T04:01:00.849031Z node 4 :TX_DATASHARD TRACE: finish_propose_unit.cpp:167: Propose transaction complete txid 281474976715658 at tablet 72075186224037897 send to client, exec latency: 0 ms, propose latency: 2 ms, status: PREPARED
2025-12-09T04:01:00.849038Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:466: 72075186224037897 not sending time cast registration request in state WaitScheme
2025-12-09T04:01:00.849048Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288485:2765], Recipient [4:7581703306943288464:2320]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.849050Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.849052Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037889, clientId# [4:7581703306943288407:2719], serverId# [4:7581703306943288485:2765], sessionId# [0:0:0]
2025-12-09T04:01:00.849057Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-12-09T04:01:00.849059Z node 4 :TX_DATASHARD INFO: datashard.cpp:416: Switched to work state WaitScheme tabletId 72075186224037900
2025-12-09T04:01:00.849069Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:456: 72075186224037900 not sending time cast registration request in state WaitScheme: missing processing params
2025-12-09T04:01:00.849072Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1257: Change sender created: at tablet: 72075186224037900, actorId: [4:7581703306943288925:2348]
2025-12-09T04:01:00.849073Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1262: Trying to activate change sender: at tablet: 72075186224037900
2025-12-09T04:01:00.849074Z node 4 :TX_DATASHARD INFO: datashard.cpp:1279: Cannot activate change sender: at tablet: 72075186224037900, state: WaitScheme
2025-12-09T04:01:00.849076Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037900
2025-12-09T04:01:00.849081Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288485:2765]
2025-12-09T04:01:00.849082Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 2146435072, Sender [4:7581703306943288477:2326], Recipient [4:7581703306943288477:2326]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.849083Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.849083Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3169: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-12-09T04:01:00.849090Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037900
2025-12-09T04:01:00.849097Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037900
2025-12-09T04:01:00.849097Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037889
2025-12-09T04:01:00.849100Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037900
2025-12-09T04:01:00.849102Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037900 active 0 active planned 0 immediate 0 planned 0
2025-12-09T04:01:00.849103Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037900
2025-12-09T04:01:00.849104Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037900 has no attached operations
2025-12-09T04:01:00.849105Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037900
2025-12-09T04:01:00.849106Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037900 TxInFly 0
2025-12-09T04:01:00.849109Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037900
2025-12-09T04:01:00.849113Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269877761, Sender [4:7581703306943288484:2764], Recipient [4:7581703306943288477:2326]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.849114Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3180: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-12-09T04:01:00.849114Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037889 on unit CheckSchemeTx
2025-12-09T04:01:00.849116Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3720: Server connected at leader tablet# 72075186224037900, clientId# [4:7581703306943288418:2730], serverId# [4:7581703306943288484:2764], sessionId# [0:0:0]
2025-12-09T04:01:00.849116Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037889 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.849119Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3144: StateWork, received event# 269549568, Sender [4:7581703306943287648:2203], Recipient [4:7581703306943288484:2764]
2025-12-09T04:01:00.849121Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3150: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-12-09T04:01:00.849126Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037889
2025-12-09T04:01:00.849127Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037900
2025-12-09T04:01:00.849129Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037889 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.849131Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037889 executing on unit CheckSchemeTx
2025-12-09T04:01:00.849132Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037889 to execution unit StoreSchemeTx
2025-12-09T04:01:00.849134Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037889 on unit StoreSchemeTx
2025-12-09T04:01:00.849143Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit CheckSchemeTx
2025-12-09T04:01:00.849145Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037900 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-12-09T04:01:00.849154Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037900
2025-12-09T04:01:00.849156Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is ExecutedNoMoreRestarts
2025-12-09T04:01:00.849158Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit CheckSchemeTx
2025-12-09T04:01:00.849159Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit StoreSchemeTx
2025-12-09T04:01:00.849162Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit StoreSchemeTx
2025-12-09T04:01:00.849167Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037889 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.849179Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037889 executing on unit StoreSchemeTx
2025-12-09T04:01:00.849180Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037889 to execution unit FinishPropose
2025-12-09T04:01:00.849182Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037889 on unit FinishPropose
2025-12-09T04:01:00.849186Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037889 is DelayComplete
2025-12-09T04:01:00.849188Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037889 executing on unit FinishPropose
2025-12-09T04:01:00.849189Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is DelayCompleteNoMoreRestarts
2025-12-09T04:01:00.849189Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037889 to execution unit WaitForPlan
2025-12-09T04:01:00.849190Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit StoreSchemeTx
2025-12-09T04:01:00.849190Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037889 on unit WaitForPlan
2025-12-09T04:01:00.849191Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit FinishPropose
2025-12-09T04:01:00.849191Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037889 is not ready to execute on unit WaitForPlan
2025-12-09T04:01:00.849193Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit FinishPropose
2025-12-09T04:01:00.849204Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is DelayComplete
2025-12-09T04:01:00.849205Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit FinishPropose
2025-12-09T04:01:00.849207Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit WaitForPlan
2025-12-09T04:01:00.849208Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit WaitForPlan
2025-12-09T04:01:00.849210Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037900 is not ready to execute on unit WaitForPlan
