2025-10-14T14:22:14.714671Z node 4 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[4:7561082624204923623:2107];send_to=[0:7307199536658146131:7762515];
2025-10-14T14:22:14.719258Z node 4 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:123: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, got undelivered to scheme cache: ActorUnknown
2025-10-14T14:22:14.719905Z 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/8izf/0012f9/r3tmp/tmpYiFUi3/pdisk_1.dat
2025-10-14T14:22:14.828718Z 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-10-14T14:22:14.847455Z node 4 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-10-14T14:22:14.863575Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-10-14T14:22:14.863662Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-10-14T14:22:14.875817Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 10596, node 4
2025-10-14T14:22:14.957576Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-10-14T14:22:14.957597Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-10-14T14:22:14.957603Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-10-14T14:22:14.957703Z node 4 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-10-14T14:22:15.026641Z 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
TClient is connected to server localhost:14442
WaitRootIsUp 'Root'...
TClient::Ls request: Root
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "Root"
    PathId: 1
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 1
    CreateStep: 0
    ParentPathId: 1
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 1
      SubDomainVersion: 0
      SecurityStateVersion: 0
    }
    ChildrenExist: false
  }
  Children {
    Name: ".sys"
    PathId: 18446744073709551615
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 0
    CreateStep: 0
    ParentPathId: 18446744073709551615
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 0
      Pl...
(TRUNCATED)
WaitRootIsUp 'Root' success.
2025-10-14T14:22:15.149849Z 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-10-14T14:22:15.197931Z node 4 :GRPC_SERVER INFO: grpc_request_proxy.cpp:592: Got grpc request# ListEndpointsRequest, traceId# 01k7hhkbmx857vcne4as5348ns, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46962, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# 9.998526s
2025-10-14T14:22:15.210862Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:595: Got grpc request# CreateSessionRequest, traceId# 01k7hhkbnafggm4tcf930txyhb, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46974, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-10-14T14:22:15.700535Z node 4 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-10-14T14:22:17.617743Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:595: Got grpc request# CreateTableRequest, traceId# 01k7hhke0hfcggjq8fmbf9y2nv, sdkBuildInfo# ydb-cpp-sdk/dev, state# AS_NOT_PERFORMED, database# undef, peer# ipv4:127.0.0.1:37976, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-10-14T14:22:17.618429Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:314: actor# [4:7561082624204923736:2142] Handle TEvProposeTransaction
2025-10-14T14:22:17.618456Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:237: actor# [4:7561082624204923736:2142] TxId# 281474976715658 ProcessProposeTransaction
2025-10-14T14:22:17.618498Z node 4 :TX_PROXY DEBUG: proxy_impl.cpp:256: actor# [4:7561082624204923736:2142] Cookie# 0 userReqId# "" txid# 281474976715658 SEND to# [4:7561082637089826469:2626]
2025-10-14T14:22:17.621073Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1673: Actor# [4:7561082637089826469:2626] 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: "ipv4:127.0.0.1:37976" 
2025-10-14T14:22:17.621114Z node 4 :TX_PROXY DEBUG: schemereq.cpp:613: Actor# [4:7561082637089826469:2626] txid# 281474976715658 Bootstrap, UserSID: <empty> CheckAdministrator: 0 CheckDatabaseAdministrator: 0
2025-10-14T14:22:17.621463Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1738: Actor# [4:7561082637089826469:2626] txid# 281474976715658 HANDLE EvNavigateKeySetResult TFlatSchemeReq marker# P6
2025-10-14T14:22:17.621517Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1728: Actor# [4:7561082637089826469:2626] txid# 281474976715658 TEvNavigateKeySet requested from SchemeCache
2025-10-14T14:22:17.621667Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1561: Actor# [4:7561082637089826469:2626] txid# 281474976715658 HANDLE EvNavigateKeySetResult TFlatSchemeReq marker# P5 ErrorCount# 0
2025-10-14T14:22:17.621759Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1608: Actor# [4:7561082637089826469:2626] 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-10-14T14:22:17.621793Z node 4 :TX_PROXY DEBUG: schemereq.cpp:103: Actor# [4:7561082637089826469:2626] txid# 281474976715658 SEND to# 72057594046644480 shardToRequest {TEvModifySchemeTransaction txid# 281474976715658 TabletId# 72057594046644480}
2025-10-14T14:22:17.621920Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1463: Actor# [4:7561082637089826469:2626] txid# 281474976715658 HANDLE EvClientConnected
2025-10-14T14:22:17.623705Z 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:684)
2025-10-14T14:22:17.628205Z node 4 :TX_PROXY DEBUG: schemereq.cpp:1485: Actor# [4:7561082637089826469:2626] txid# 281474976715658 Status StatusAccepted HANDLE {TEvModifySchemeTransactionResult Status# StatusAccepted txid# 281474976715658}
2025-10-14T14:22:17.628258Z node 4 :TX_PROXY DEBUG: schemereq.cpp:593: Actor# [4:7561082637089826469:2626] txid# 281474976715658 SEND to# [4:7561082637089826468:2318] Source {TEvProposeTransactionStatus txid# 281474976715658 Status# 53}
2025-10-14T14:22:17.629019Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:489: SchemeBoardUpdate /Root
2025-10-14T14:22:17.629148Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:518: Can't update SecurityState for /Root - no PublicKeys
2025-10-14T14:22:17.629160Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:489: SchemeBoardUpdate /Root
2025-10-14T14:22:17.629204Z node 4 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:518: Can't update SecurityState for /Root - no PublicKeys
2025-10-14T14:22:17.661247Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826542:2692], Recipient [4:7561082637089826637:2321]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.662183Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826532:2684], Recipient [4:7561082637089826667:2334]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.662921Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826537:2689], Recipient [4:7561082637089826664:2332]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.662933Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826534:2686], Recipient [4:7561082637089826640:2324]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.663680Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826535:2687], Recipient [4:7561082637089826644:2328]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.664041Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826531:2683], Recipient [4:7561082637089826638:2322]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.665000Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826538:2690], Recipient [4:7561082637089826642:2326]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.665575Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826545:2695], Recipient [4:7561082637089826668:2335]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.666132Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826536:2688], Recipient [4:7561082637089826655:2330]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.667342Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826533:2685], Recipient [4:7561082637089826643:2327]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.667341Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826529:2681], Recipient [4:7561082637089826673:2336]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.671729Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826529:2681], Recipient [4:7561082637089826673:2336]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.672128Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037889 actor [4:7561082637089826673:2336]
2025-10-14T14:22:17.672305Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.674222Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826533:2685], Recipient [4:7561082637089826643:2327]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.674524Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037902 actor [4:7561082637089826643:2327]
2025-10-14T14:22:17.674669Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.681384Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826542:2692], Recipient [4:7561082637089826637:2321]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.681778Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037894 actor [4:7561082637089826637:2321]
2025-10-14T14:22:17.681926Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.682406Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826531:2683], Recipient [4:7561082637089826638:2322]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.682673Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037895 actor [4:7561082637089826638:2322]
2025-10-14T14:22:17.682793Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.691757Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826533:2685], Recipient [4:7561082637089826643:2327]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.694033Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.694083Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.695397Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037902
2025-10-14T14:22:17.695428Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037902
2025-10-14T14:22:17.695459Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037902
2025-10-14T14:22:17.695706Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.695744Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.695763Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037902 persisting started state actor id [4:7561082637089826952:2327] in generation 1
2025-10-14T14:22:17.695900Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826540:2691], Recipient [4:7561082637089826661:2331]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.696684Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826540:2691], Recipient [4:7561082637089826661:2331]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.697200Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037896 actor [4:7561082637089826661:2331]
2025-10-14T14:22:17.697356Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.697916Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826537:2689], Recipient [4:7561082637089826664:2332]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.698218Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037898 actor [4:7561082637089826664:2332]
2025-10-14T14:22:17.698375Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.705041Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826540:2691], Recipient [4:7561082637089826661:2331]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.705162Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826531:2683], Recipient [4:7561082637089826638:2322]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.705235Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.705283Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.705292Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826537:2689], Recipient [4:7561082637089826664:2332]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.705363Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826528:2680], Recipient [4:7561082637089826666:2333]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.706049Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826528:2680], Recipient [4:7561082637089826666:2333]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.706331Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037888 actor [4:7561082637089826666:2333]
2025-10-14T14:22:17.706495Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.706644Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037895
2025-10-14T14:22:17.706682Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037895
2025-10-14T14:22:17.706708Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037895
2025-10-14T14:22:17.706957Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.706985Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.707003Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037895 persisting started state actor id [4:7561082637089826966:2322] in generation 1
2025-10-14T14:22:17.707113Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826535:2687], Recipient [4:7561082637089826644:2328]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.707386Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037900 actor [4:7561082637089826644:2328]
2025-10-14T14:22:17.707556Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.713813Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826528:2680], Recipient [4:7561082637089826666:2333]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.713891Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826529:2681], Recipient [4:7561082637089826673:2336]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.713990Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.714030Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.715201Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037889
2025-10-14T14:22:17.715232Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037889
2025-10-14T14:22:17.715260Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037889
2025-10-14T14:22:17.715486Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.715513Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.715533Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037889 persisting started state actor id [4:7561082637089826976:2336] in generation 1
2025-10-14T14:22:17.715646Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826543:2693], Recipient [4:7561082637089826641:2325]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.716375Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826543:2693], Recipient [4:7561082637089826641:2325]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.717208Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826545:2695], Recipient [4:7561082637089826668:2335]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.717538Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037890 actor [4:7561082637089826668:2335]
2025-10-14T14:22:17.717709Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.720221Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037893 actor [4:7561082637089826641:2325]
2025-10-14T14:22:17.720411Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.725549Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826545:2695], Recipient [4:7561082637089826668:2335]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.725638Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826530:2682], Recipient [4:7561082637089826653:2329]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.726489Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826530:2682], Recipient [4:7561082637089826653:2329]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.726812Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037891 actor [4:7561082637089826653:2329]
2025-10-14T14:22:17.727024Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.728068Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826542:2692], Recipient [4:7561082637089826637:2321]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.728152Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.728196Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.730091Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037894
2025-10-14T14:22:17.730121Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037894
2025-10-14T14:22:17.730147Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037894
2025-10-14T14:22:17.730380Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.730410Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.730428Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037894 persisting started state actor id [4:7561082637089827006:2321] in generation 1
2025-10-14T14:22:17.730542Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826536:2688], Recipient [4:7561082637089826655:2330]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.730943Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037899 actor [4:7561082637089826655:2330]
2025-10-14T14:22:17.731124Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.734747Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826530:2682], Recipient [4:7561082637089826653:2329]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.735403Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828672, Sender [4:7561082637089826544:2694], Recipient [4:7561082637089826639:2323]: NKikimr::TEvTablet::TEvBoot
2025-10-14T14:22:17.736217Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826544:2694], Recipient [4:7561082637089826639:2323]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.736546Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037892 actor [4:7561082637089826639:2323]
2025-10-14T14:22:17.736716Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.739693Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826536:2688], Recipient [4:7561082637089826655:2330]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.739777Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826538:2690], Recipient [4:7561082637089826642:2326]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.740178Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037897 actor [4:7561082637089826642:2326]
2025-10-14T14:22:17.740374Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.745599Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826532:2684], Recipient [4:7561082637089826667:2334]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.746040Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037903 actor [4:7561082637089826667:2334]
2025-10-14T14:22:17.746224Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.751447Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.751517Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.752749Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037899
2025-10-14T14:22:17.752781Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037899
2025-10-14T14:22:17.752806Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037899
2025-10-14T14:22:17.753088Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.753117Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.753137Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037899 persisting started state actor id [4:7561082637089827037:2330] in generation 1
2025-10-14T14:22:17.753284Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3109: StateInit, received event# 268828673, Sender [4:7561082637089826534:2686], Recipient [4:7561082637089826640:2324]: NKikimr::TEvTablet::TEvRestored
2025-10-14T14:22:17.753775Z node 4 :TX_DATASHARD INFO: datashard.cpp:373: TDataShard::OnActivateExecutor: tablet 72075186224037901 actor [4:7561082637089826640:2324]
2025-10-14T14:22:17.754007Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:661: TxInitSchema.Execute
2025-10-14T14:22:17.756004Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.756043Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.757744Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037888
2025-10-14T14:22:17.757784Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037888
2025-10-14T14:22:17.757814Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037888
2025-10-14T14:22:17.758094Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.758122Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.758140Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037888 persisting started state actor id [4:7561082637089827055:2333] in generation 1
2025-10-14T14:22:17.758270Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.758307Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.759244Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037898
2025-10-14T14:22:17.759281Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037898
2025-10-14T14:22:17.759319Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037898
2025-10-14T14:22:17.759534Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.759558Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.759572Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037898 persisting started state actor id [4:7561082637089827057:2332] in generation 1
2025-10-14T14:22:17.759693Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.759728Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.761466Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037896
2025-10-14T14:22:17.761497Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037896
2025-10-14T14:22:17.761524Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037896
2025-10-14T14:22:17.761747Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.761779Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.761794Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037896 persisting started state actor id [4:7561082637089827059:2331] in generation 1
2025-10-14T14:22:17.762408Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826535:2687], Recipient [4:7561082637089826644:2328]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.762494Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.762524Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.763088Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826534:2686], Recipient [4:7561082637089826640:2324]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.763174Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.763195Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037899
2025-10-14T14:22:17.763244Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037899 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.763271Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037899, actorId: [4:7561082637089827067:2338]
2025-10-14T14:22:17.763281Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037899
2025-10-14T14:22:17.763289Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037899, state: WaitScheme
2025-10-14T14:22:17.763297Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037899
2025-10-14T14:22:17.763392Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826655:2330], Recipient [4:7561082637089826655:2330]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.763410Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.763466Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037899
2025-10-14T14:22:17.763513Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037899
2025-10-14T14:22:17.763529Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037899
2025-10-14T14:22:17.763540Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037899 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.763556Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037899
2025-10-14T14:22:17.763567Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037899 has no attached operations
2025-10-14T14:22:17.763576Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037899
2025-10-14T14:22:17.763584Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037899 TxInFly 0
2025-10-14T14:22:17.763598Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037899
2025-10-14T14:22:17.763673Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037900
2025-10-14T14:22:17.763689Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.763700Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037902
2025-10-14T14:22:17.763708Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037900
2025-10-14T14:22:17.763738Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037902 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.763743Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037900
2025-10-14T14:22:17.763766Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037902, actorId: [4:7561082637089827069:2339]
2025-10-14T14:22:17.763773Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037902
2025-10-14T14:22:17.763784Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037902, state: WaitScheme
2025-10-14T14:22:17.763794Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037902
2025-10-14T14:22:17.763855Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826643:2327], Recipient [4:7561082637089826643:2327]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.763867Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.763903Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037902
2025-10-14T14:22:17.763933Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037902
2025-10-14T14:22:17.763946Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037902
2025-10-14T14:22:17.763953Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037902 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.763960Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037902
2025-10-14T14:22:17.763967Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037902 has no attached operations
2025-10-14T14:22:17.763973Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037902
2025-10-14T14:22:17.763979Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037902 TxInFly 0
2025-10-14T14:22:17.763991Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037902
2025-10-14T14:22:17.764005Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.764032Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826763:2817], Recipient [4:7561082637089826643:2327]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.764034Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.764045Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.764049Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037900 persisting started state actor id [4:7561082637089827071:2328] in generation 1
2025-10-14T14:22:17.764061Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037902, clientId# [4:7561082637089826733:2801], serverId# [4:7561082637089826763:2817], sessionId# [0:0:0]
2025-10-14T14:22:17.764090Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826763:2817]
2025-10-14T14:22:17.764109Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.764169Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.764173Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037902
2025-10-14T14:22:17.764200Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.764316Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit CheckSchemeTx
2025-10-14T14:22:17.764333Z 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-10-14T14:22:17.764387Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037902
2025-10-14T14:22:17.764407Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.764420Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit CheckSchemeTx
2025-10-14T14:22:17.764692Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit StoreSchemeTx
2025-10-14T14:22:17.764704Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit StoreSchemeTx
2025-10-14T14:22:17.764937Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.764951Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit StoreSchemeTx
2025-10-14T14:22:17.764960Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit FinishPropose
2025-10-14T14:22:17.764970Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit FinishPropose
2025-10-14T14:22:17.764991Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037902 is DelayComplete
2025-10-14T14:22:17.765004Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037902 executing on unit FinishPropose
2025-10-14T14:22:17.765011Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037902 to execution unit WaitForPlan
2025-10-14T14:22:17.765019Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037902 on unit WaitForPlan
2025-10-14T14:22:17.765027Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037902 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.765148Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.765162Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037895
2025-10-14T14:22:17.765219Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037895 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.765255Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037895, actorId: [4:7561082637089827082:2340]
2025-10-14T14:22:17.765263Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037895
2025-10-14T14:22:17.765294Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037895, state: WaitScheme
2025-10-14T14:22:17.765302Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037895
2025-10-14T14:22:17.765370Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826638:2322], Recipient [4:7561082637089826638:2322]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.765384Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.765428Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037895
2025-10-14T14:22:17.765463Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037895
2025-10-14T14:22:17.765476Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037895
2025-10-14T14:22:17.765486Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037895 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.765492Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037895
2025-10-14T14:22:17.765500Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037895 has no attached operations
2025-10-14T14:22:17.765513Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037895
2025-10-14T14:22:17.765520Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037895 TxInFly 0
2025-10-14T14:22:17.765533Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037895
2025-10-14T14:22:17.765557Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.765569Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037889
2025-10-14T14:22:17.765596Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037889 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.765613Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037889, actorId: [4:7561082637089827084:2341]
2025-10-14T14:22:17.765618Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037889
2025-10-14T14:22:17.765625Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037889, state: WaitScheme
2025-10-14T14:22:17.765630Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037889
2025-10-14T14:22:17.765648Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037891
2025-10-14T14:22:17.765688Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037891
2025-10-14T14:22:17.765696Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826673:2336], Recipient [4:7561082637089826673:2336]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.765707Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.765712Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037891
2025-10-14T14:22:17.765743Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037889
2025-10-14T14:22:17.765779Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037889
2025-10-14T14:22:17.765795Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037889
2025-10-14T14:22:17.765802Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037889 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.765808Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037889
2025-10-14T14:22:17.765814Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037889 has no attached operations
2025-10-14T14:22:17.765820Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037889
2025-10-14T14:22:17.765824Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037889 TxInFly 0
2025-10-14T14:22:17.765834Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037889
2025-10-14T14:22:17.765947Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.765979Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.765981Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826756:2810], Recipient [4:7561082637089826655:2330]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.765993Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.765993Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037891 persisting started state actor id [4:7561082637089827086:2329] in generation 1
2025-10-14T14:22:17.766007Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037899, clientId# [4:7561082637089826730:2798], serverId# [4:7561082637089826756:2810], sessionId# [0:0:0]
2025-10-14T14:22:17.766034Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826756:2810]
2025-10-14T14:22:17.766044Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.766086Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037899
2025-10-14T14:22:17.766141Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826532:2684], Recipient [4:7561082637089826667:2334]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.766205Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit CheckSchemeTx
2025-10-14T14:22:17.766216Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.766221Z 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-10-14T14:22:17.766244Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.766286Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037899
2025-10-14T14:22:17.766302Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.766312Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit CheckSchemeTx
2025-10-14T14:22:17.766320Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit StoreSchemeTx
2025-10-14T14:22:17.766328Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit StoreSchemeTx
2025-10-14T14:22:17.766517Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.766529Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit StoreSchemeTx
2025-10-14T14:22:17.766537Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit FinishPropose
2025-10-14T14:22:17.766546Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit FinishPropose
2025-10-14T14:22:17.766562Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037899 is DelayComplete
2025-10-14T14:22:17.766571Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037899 executing on unit FinishPropose
2025-10-14T14:22:17.766577Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037899 to execution unit WaitForPlan
2025-10-14T14:22:17.766604Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037899 on unit WaitForPlan
2025-10-14T14:22:17.766612Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037899 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.766726Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826655:2330]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.766747Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037899
2025-10-14T14:22:17.766794Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.766806Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037894
2025-10-14T14:22:17.766832Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037894 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.766851Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037894, actorId: [4:7561082637089827092:2342]
2025-10-14T14:22:17.766857Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037894
2025-10-14T14:22:17.766863Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037894, state: WaitScheme
2025-10-14T14:22:17.766870Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037894
2025-10-14T14:22:17.766912Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826637:2321], Recipient [4:7561082637089826637:2321]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.766922Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.766956Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037894
2025-10-14T14:22:17.766987Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037894
2025-10-14T14:22:17.767001Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037894
2025-10-14T14:22:17.767009Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037894 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.767015Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037894
2025-10-14T14:22:17.767022Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037894 has no attached operations
2025-10-14T14:22:17.767028Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037894
2025-10-14T14:22:17.767033Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037894 TxInFly 0
2025-10-14T14:22:17.767044Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037894
2025-10-14T14:22:17.767094Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826759:2813], Recipient [4:7561082637089826637:2321]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.767105Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.767120Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037894, clientId# [4:7561082637089826723:2793], serverId# [4:7561082637089826759:2813], sessionId# [0:0:0]
2025-10-14T14:22:17.767227Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826543:2693], Recipient [4:7561082637089826641:2325]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.767257Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037903
2025-10-14T14:22:17.767294Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037903
2025-10-14T14:22:17.767326Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037903
2025-10-14T14:22:17.767333Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.767364Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.767529Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.767558Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.767572Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037903 persisting started state actor id [4:7561082637089827096:2334] in generation 1
2025-10-14T14:22:17.767916Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826759:2813]
2025-10-14T14:22:17.767933Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.767990Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037894
2025-10-14T14:22:17.768127Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037894 on unit CheckSchemeTx
2025-10-14T14:22:17.768143Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:133: Propose scheme transaction at tablet 72075186224037894 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-10-14T14:22:17.768186Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037894
2025-10-14T14:22:17.768204Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037894 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.768216Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037894 executing on unit CheckSchemeTx
2025-10-14T14:22:17.768225Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037894 to execution unit StoreSchemeTx
2025-10-14T14:22:17.768234Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037894 on unit StoreSchemeTx
2025-10-14T14:22:17.768583Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037893
2025-10-14T14:22:17.768614Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037893
2025-10-14T14:22:17.768638Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037894 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.768642Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037893
2025-10-14T14:22:17.768657Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037894 executing on unit StoreSchemeTx
2025-10-14T14:22:17.768666Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037894 to execution unit FinishPropose
2025-10-14T14:22:17.768676Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037894 on unit FinishPropose
2025-10-14T14:22:17.768697Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037894 is DelayComplete
2025-10-14T14:22:17.768708Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037894 executing on unit FinishPropose
2025-10-14T14:22:17.768716Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037894 to execution unit WaitForPlan
2025-10-14T14:22:17.768724Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037894 on unit WaitForPlan
2025-10-14T14:22:17.768731Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037894 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.768842Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826637:2321]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.768859Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.768864Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037894
2025-10-14T14:22:17.768889Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.768905Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037893 persisting started state actor id [4:7561082637089827099:2325] in generation 1
2025-10-14T14:22:17.769007Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826538:2690], Recipient [4:7561082637089826642:2326]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.769035Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.769071Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037896
2025-10-14T14:22:17.769097Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.769115Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037896 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.769134Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.769142Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037896, actorId: [4:7561082637089827103:2343]
2025-10-14T14:22:17.769154Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037896
2025-10-14T14:22:17.769165Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037896, state: WaitScheme
2025-10-14T14:22:17.769172Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037896
2025-10-14T14:22:17.769251Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826661:2331], Recipient [4:7561082637089826661:2331]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.769268Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.769323Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037896
2025-10-14T14:22:17.769361Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037896
2025-10-14T14:22:17.769399Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826754:2808], Recipient [4:7561082637089826661:2331]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.769410Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.769432Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037896, clientId# [4:7561082637089826727:2795], serverId# [4:7561082637089826754:2808], sessionId# [0:0:0]
2025-10-14T14:22:17.769452Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037896
2025-10-14T14:22:17.769462Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037896 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.769470Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037896
2025-10-14T14:22:17.769479Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037896 has no attached operations
2025-10-14T14:22:17.769486Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037896
2025-10-14T14:22:17.769492Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037896 TxInFly 0
2025-10-14T14:22:17.769506Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037896
2025-10-14T14:22:17.769535Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826754:2808]
2025-10-14T14:22:17.769546Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.769599Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037896
2025-10-14T14:22:17.769688Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit CheckSchemeTx
2025-10-14T14:22:17.769700Z 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-10-14T14:22:17.769736Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037896
2025-10-14T14:22:17.769748Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.769758Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit CheckSchemeTx
2025-10-14T14:22:17.769767Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit StoreSchemeTx
2025-10-14T14:22:17.769775Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit StoreSchemeTx
2025-10-14T14:22:17.769951Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.769962Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit StoreSchemeTx
2025-10-14T14:22:17.769969Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit FinishPropose
2025-10-14T14:22:17.769977Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit FinishPropose
2025-10-14T14:22:17.769992Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037896 is DelayComplete
2025-10-14T14:22:17.770002Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037896 executing on unit FinishPropose
2025-10-14T14:22:17.770009Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037896 to execution unit WaitForPlan
2025-10-14T14:22:17.770016Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037896 on unit WaitForPlan
2025-10-14T14:22:17.770023Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037896 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.770134Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826661:2331]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.770151Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037896
2025-10-14T14:22:17.770195Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.770207Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037898
2025-10-14T14:22:17.770246Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037898 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.770257Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037897
2025-10-14T14:22:17.770264Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037898, actorId: [4:7561082637089827105:2344]
2025-10-14T14:22:17.770270Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037898
2025-10-14T14:22:17.770277Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037898, state: WaitScheme
2025-10-14T14:22:17.770283Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037898
2025-10-14T14:22:17.770285Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037897
2025-10-14T14:22:17.770310Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037897
2025-10-14T14:22:17.770357Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826664:2332], Recipient [4:7561082637089826664:2332]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.770368Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.770410Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037898
2025-10-14T14:22:17.770443Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037898
2025-10-14T14:22:17.770481Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826755:2809], Recipient [4:7561082637089826664:2332]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.770490Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.770502Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037898, clientId# [4:7561082637089826729:2797], serverId# [4:7561082637089826755:2809], sessionId# [0:0:0]
2025-10-14T14:22:17.770526Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.770527Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826755:2809]
2025-10-14T14:22:17.770537Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.770553Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.770567Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037897 persisting started state actor id [4:7561082637089827106:2326] in generation 1
2025-10-14T14:22:17.770570Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037898
2025-10-14T14:22:17.770580Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037898 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.770590Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037898
2025-10-14T14:22:17.770603Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037898 has no attached operations
2025-10-14T14:22:17.770610Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037898
2025-10-14T14:22:17.770615Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037898 TxInFly 0
2025-10-14T14:22:17.770629Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037898
2025-10-14T14:22:17.770644Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037898
2025-10-14T14:22:17.770694Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.770723Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.770731Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit CheckSchemeTx
2025-10-14T14:22:17.770744Z 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-10-14T14:22:17.770782Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037898
2025-10-14T14:22:17.770797Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.770809Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit CheckSchemeTx
2025-10-14T14:22:17.770816Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit StoreSchemeTx
2025-10-14T14:22:17.770824Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit StoreSchemeTx
2025-10-14T14:22:17.771039Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.771051Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit StoreSchemeTx
2025-10-14T14:22:17.771060Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit FinishPropose
2025-10-14T14:22:17.771068Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit FinishPropose
2025-10-14T14:22:17.771082Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037898 is DelayComplete
2025-10-14T14:22:17.771095Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037898 executing on unit FinishPropose
2025-10-14T14:22:17.771107Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037898 to execution unit WaitForPlan
2025-10-14T14:22:17.771118Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037898 on unit WaitForPlan
2025-10-14T14:22:17.771127Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037898 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.771219Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826664:2332]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.771234Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037898
2025-10-14T14:22:17.771357Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.771369Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037900
2025-10-14T14:22:17.771404Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037900 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.771426Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037900, actorId: [4:7561082637089827109:2345]
2025-10-14T14:22:17.771434Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037900
2025-10-14T14:22:17.771440Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037900, state: WaitScheme
2025-10-14T14:22:17.771447Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037900
2025-10-14T14:22:17.771531Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826644:2328], Recipient [4:7561082637089826644:2328]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.771543Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.771590Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037900
2025-10-14T14:22:17.771628Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037900
2025-10-14T14:22:17.771661Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826761:2815], Recipient [4:7561082637089826644:2328]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.771671Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.771683Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037900, clientId# [4:7561082637089826731:2799], serverId# [4:7561082637089826761:2815], sessionId# [0:0:0]
2025-10-14T14:22:17.771696Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037900
2025-10-14T14:22:17.771703Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037900 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.771710Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037900
2025-10-14T14:22:17.771718Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037900 has no attached operations
2025-10-14T14:22:17.771725Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037900
2025-10-14T14:22:17.771730Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037900 TxInFly 0
2025-10-14T14:22:17.771739Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037900
2025-10-14T14:22:17.771764Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826761:2815]
2025-10-14T14:22:17.771773Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.771783Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037890
2025-10-14T14:22:17.771803Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037900
2025-10-14T14:22:17.771809Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037890
2025-10-14T14:22:17.771835Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037890
2025-10-14T14:22:17.771877Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit CheckSchemeTx
2025-10-14T14:22:17.771889Z 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-10-14T14:22:17.771953Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037900
2025-10-14T14:22:17.771967Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.771977Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit CheckSchemeTx
2025-10-14T14:22:17.771986Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit StoreSchemeTx
2025-10-14T14:22:17.771994Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit StoreSchemeTx
2025-10-14T14:22:17.772043Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.772077Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.772090Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037890 persisting started state actor id [4:7561082637089827111:2335] in generation 1
2025-10-14T14:22:17.772173Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.772184Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit StoreSchemeTx
2025-10-14T14:22:17.772192Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit FinishPropose
2025-10-14T14:22:17.772199Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3122: StateInactive, received event# 268828684, Sender [4:7561082637089826544:2694], Recipient [4:7561082637089826639:2323]: NKikimr::TEvTablet::TEvFollowerSyncComplete
2025-10-14T14:22:17.772201Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit FinishPropose
2025-10-14T14:22:17.772216Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037900 is DelayComplete
2025-10-14T14:22:17.772226Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037900 executing on unit FinishPropose
2025-10-14T14:22:17.772233Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037900 to execution unit WaitForPlan
2025-10-14T14:22:17.772240Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037900 on unit WaitForPlan
2025-10-14T14:22:17.772247Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037900 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.772270Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.772307Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.772341Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826644:2328]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.772357Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037900
2025-10-14T14:22:17.772466Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:152: TTxProposeTransactionBase::Complete at 72075186224037899
2025-10-14T14:22:17.772483Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037899 on unit StoreSchemeTx
2025-10-14T14:22:17.772490Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1949: Complete execution for [0:281474976715658] at 72075186224037899 on unit FinishPropose
2025-10-14T14:22:17.772507Z 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: 6 ms, status: PREPARED
2025-10-14T14:22:17.772541Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:467: 72075186224037899 not sending time cast registration request in state WaitScheme
2025-10-14T14:22:17.772579Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.772590Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037903
2025-10-14T14:22:17.772622Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037903 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.772641Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037903, actorId: [4:7561082637089827113:2346]
2025-10-14T14:22:17.772649Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037903
2025-10-14T14:22:17.772655Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037903, state: WaitScheme
2025-10-14T14:22:17.772662Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037903
2025-10-14T14:22:17.772715Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826667:2334], Recipient [4:7561082637089826667:2334]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.772731Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.772777Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037903
2025-10-14T14:22:17.772812Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037903
2025-10-14T14:22:17.772823Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037903
2025-10-14T14:22:17.772832Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037903 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.772838Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037903
2025-10-14T14:22:17.772845Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037903 has no attached operations
2025-10-14T14:22:17.772851Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037903
2025-10-14T14:22:17.772857Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037903 TxInFly 0
2025-10-14T14:22:17.772869Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037903
2025-10-14T14:22:17.772963Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826753:2807], Recipient [4:7561082637089826638:2322]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.772975Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.772989Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037895, clientId# [4:7561082637089826726:2794], serverId# [4:7561082637089826753:2807], sessionId# [0:0:0]
2025-10-14T14:22:17.773020Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826753:2807]
2025-10-14T14:22:17.773036Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.773089Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037895
2025-10-14T14:22:17.773162Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit CheckSchemeTx
2025-10-14T14:22:17.773174Z 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-10-14T14:22:17.773212Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037895
2025-10-14T14:22:17.773224Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.773233Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit CheckSchemeTx
2025-10-14T14:22:17.773241Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit StoreSchemeTx
2025-10-14T14:22:17.773250Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit StoreSchemeTx
2025-10-14T14:22:17.773375Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037892
2025-10-14T14:22:17.773405Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037892
2025-10-14T14:22:17.773410Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.773422Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit StoreSchemeTx
2025-10-14T14:22:17.773428Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037892
2025-10-14T14:22:17.773430Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit FinishPropose
2025-10-14T14:22:17.773438Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit FinishPropose
2025-10-14T14:22:17.773455Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037895 is DelayComplete
2025-10-14T14:22:17.773464Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037895 executing on unit FinishPropose
2025-10-14T14:22:17.773471Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037895 to execution unit WaitForPlan
2025-10-14T14:22:17.773479Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037895 on unit WaitForPlan
2025-10-14T14:22:17.773486Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037895 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.773620Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826638:2322]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.773643Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.773647Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037895
2025-10-14T14:22:17.773674Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.773688Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037892 persisting started state actor id [4:7561082637089827117:2323] in generation 1
2025-10-14T14:22:17.773789Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:151: TDataShard::TTxInitRestored::Complete
2025-10-14T14:22:17.773806Z node 4 :TX_DATASHARD INFO: datashard.cpp:417: Switched to work state WaitScheme tabletId 72075186224037888
2025-10-14T14:22:17.773829Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269746185, Sender [4:7561082637089827074:3052], Recipient [4:7561082637089826643:2327]: NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated
2025-10-14T14:22:17.773843Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:457: 72075186224037888 not sending time cast registration request in state WaitScheme: missing processing params
2025-10-14T14:22:17.773843Z node 4 :TX_DATASHARD DEBUG: datashard_subdomain_path_id.cpp:129: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037902
2025-10-14T14:22:17.773865Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1258: Change sender created: at tablet: 72075186224037888, actorId: [4:7561082637089827120:2347]
2025-10-14T14:22:17.773873Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1263: Trying to activate change sender: at tablet: 72075186224037888
2025-10-14T14:22:17.773880Z node 4 :TX_DATASHARD INFO: datashard.cpp:1280: Cannot activate change sender: at tablet: 72075186224037888, state: WaitScheme
2025-10-14T14:22:17.773888Z node 4 :TX_DATASHARD DEBUG: cdc_stream_heartbeat.cpp:87: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-10-14T14:22:17.773918Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:727: TxInitSchema.Complete
2025-10-14T14:22:17.773954Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 2146435072, Sender [4:7561082637089826666:2333], Recipient [4:7561082637089826666:2333]: NKikimr::NDataShard::TDataShard::TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.773958Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:50: TDataShard::TTxInit::Execute
2025-10-14T14:22:17.773970Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3168: StateWork, processing event TEvPrivate::TEvProgressTransaction
2025-10-14T14:22:17.774010Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:96: TTxCheckInReadSets::Execute at 72075186224037888
2025-10-14T14:22:17.774045Z node 4 :TX_DATASHARD DEBUG: datashard__cleanup_in_rs.cpp:139: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037888
2025-10-14T14:22:17.774058Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:16: TTxProgressTransaction::Execute at 72075186224037888
2025-10-14T14:22:17.774070Z node 4 :TX_DATASHARD DEBUG: datashard_pipeline.cpp:272: GetNextActiveOp at 72075186224037888 active 0 active planned 0 immediate 0 planned 0
2025-10-14T14:22:17.774079Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:385: Check unit PlanQueue at 72075186224037888
2025-10-14T14:22:17.774087Z node 4 :TX_DATASHARD TRACE: plan_queue_unit.cpp:59: TPlanQueueUnit at 72075186224037888 has no attached operations
2025-10-14T14:22:17.774094Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:403: Unit PlanQueue has no ready operations at 72075186224037888
2025-10-14T14:22:17.774101Z node 4 :TX_DATASHARD INFO: datashard__progress_tx.cpp:48: No tx to execute at 72075186224037888 TxInFly 0
2025-10-14T14:22:17.774113Z node 4 :TX_DATASHARD DEBUG: datashard__progress_tx.cpp:119: TTxProgressTransaction::Complete at 72075186224037888
2025-10-14T14:22:17.774153Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826757:2811], Recipient [4:7561082637089826666:2333]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.774164Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.774179Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037888, clientId# [4:7561082637089826717:2787], serverId# [4:7561082637089826757:2811], sessionId# [0:0:0]
2025-10-14T14:22:17.774743Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826764:2818], Recipient [4:7561082637089826667:2334]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.774755Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.774769Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037903, clientId# [4:7561082637089826736:2802], serverId# [4:7561082637089826764:2818], sessionId# [0:0:0]
2025-10-14T14:22:17.774797Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269549568, Sender [4:7561082624204923921:2212], Recipient [4:7561082637089826764:2818]
2025-10-14T14:22:17.774809Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3149: StateWork, processing event TEvDataShard::TEvProposeTransaction
2025-10-14T14:22:17.774861Z node 4 :TX_DATASHARD DEBUG: datashard__propose_tx_base.cpp:33: TTxProposeTransactionBase::Execute at 72075186224037903
2025-10-14T14:22:17.774970Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit CheckSchemeTx
2025-10-14T14:22:17.774986Z 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-10-14T14:22:17.775029Z node 4 :TX_DATASHARD DEBUG: check_scheme_tx_unit.cpp:221: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037903
2025-10-14T14:22:17.775045Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is ExecutedNoMoreRestarts
2025-10-14T14:22:17.775056Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit CheckSchemeTx
2025-10-14T14:22:17.775065Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit StoreSchemeTx
2025-10-14T14:22:17.775073Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit StoreSchemeTx
2025-10-14T14:22:17.775134Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1323: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037901
2025-10-14T14:22:17.775164Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1379: LoadLockChangeRecords at tablet: 72075186224037901
2025-10-14T14:22:17.775198Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:1428: LoadChangeRecordCommits at tablet: 72075186224037901
2025-10-14T14:22:17.775261Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is DelayCompleteNoMoreRestarts
2025-10-14T14:22:17.775275Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit StoreSchemeTx
2025-10-14T14:22:17.775283Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit FinishPropose
2025-10-14T14:22:17.775292Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit FinishPropose
2025-10-14T14:22:17.775309Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1878: Execution status for [0:281474976715658] at 72075186224037903 is DelayComplete
2025-10-14T14:22:17.775319Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1926: Advance execution plan for [0:281474976715658] at 72075186224037903 executing on unit FinishPropose
2025-10-14T14:22:17.775326Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1932: Add [0:281474976715658] at 72075186224037903 to execution unit WaitForPlan
2025-10-14T14:22:17.775334Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1843: Trying to execute [0:281474976715658] at 72075186224037903 on unit WaitForPlan
2025-10-14T14:22:17.775342Z node 4 :TX_DATASHARD TRACE: datashard_pipeline.cpp:1848: Operation [0:281474976715658] at 72075186224037903 is not ready to execute on unit WaitForPlan
2025-10-14T14:22:17.775423Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:94: TDataShard::TTxInit::Complete
2025-10-14T14:22:17.775453Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:102: TDataShard::TTxInitRestored::Execute
2025-10-14T14:22:17.775469Z node 4 :TX_DATASHARD DEBUG: datashard__init.cpp:142: DataShard 72075186224037901 persisting started state actor id [4:7561082637089827132:2324] in generation 1
2025-10-14T14:22:17.775640Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3143: StateWork, received event# 269877761, Sender [4:7561082637089826744:2803], Recipient [4:7561082637089826673:2336]: NKikimr::TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.775657Z node 4 :TX_DATASHARD TRACE: datashard_impl.h:3179: StateWork, processing event TEvTabletPipe::TEvServerConnected
2025-10-14T14:22:17.775671Z node 4 :TX_DATASHARD DEBUG: datashard.cpp:3721: Server connected at leader tablet# 72075186224037889, clientId# [4:7561082637089826718:2788], serverId# [4:7561082637089826744:2803], sessionId# [0:0:0]
