2025-11-19T21:02:03.003419Z node 7 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[7:7574544726027310863:2084];send_to=[0:7307199536658146131:7762515];
2025-11-19T21:02:03.004116Z node 7 :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/jm6f/00273d/r3tmp/tmpCW4A4w/pdisk_1.dat
2025-11-19T21:02:03.071176Z node 7 :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-11-19T21:02:03.194561Z node 7 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-19T21:02:03.233342Z node 7 :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-11-19T21:02:03.240627Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(7, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-19T21:02:03.240724Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(7, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-19T21:02:03.250960Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(7, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 3853, node 7
2025-11-19T21:02:03.308845Z node 8 :BS_NODE WARN: {NWDC01@distconf.cpp:386} StateFunc too long Type# 268639239 Duration# 0.009309s
2025-11-19T21:02:03.304260Z node 9 :BS_NODE WARN: {NWDC01@distconf.cpp:386} StateFunc too long Type# 268639238 Duration# 0.007953s
2025-11-19T21:02:03.481922Z node 7 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-11-19T21:02:03.481950Z node 7 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-11-19T21:02:03.481960Z node 7 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-11-19T21:02:03.482110Z node 7 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-11-19T21:02:03.599048Z node 7 :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:20792
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-11-19T21:02:03.865445Z node 7 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976710657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
2025-11-19T21:02:04.012393Z node 7 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-19T21:02:04.048303Z node 7 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateExtSubDomain, opId: 281474976710658:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_extsubdomain.cpp:194)
2025-11-19T21:02:04.106021Z node 9 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[9:7574544729847645392:2198];send_to=[0:7307199536658146131:7762515];
2025-11-19T21:02:04.106087Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/tenant/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2025-11-19T21:02:04.120482Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-19T21:02:04.120560Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-19T21:02:04.133612Z node 9 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/tenant/.metadata/script_executions
2025-11-19T21:02:04.138592Z node 7 :HIVE WARN: hive_impl.cpp:811: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 9 Cookie 9
2025-11-19T21:02:04.140034Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-11-19T21:02:04.235344Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235483Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235545Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235602Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235650Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235704Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235756Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235811Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.235865Z node 9 :HIVE WARN: tx__create_tablet.cpp:348: HIVE#72075186224037888 THive::TTxCreateTablet::Execute CreateTablet Postponed
2025-11-19T21:02:04.248735Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72075186224037888 Node(9, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-19T21:02:04.248847Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72075186224037888 Node(9, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-19T21:02:04.261386Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72075186224037888 Node(9, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-11-19T21:02:04.488532Z node 9 :STATISTICS WARN: tx_init.cpp:295: [72075186224037894] TTxInit::Complete. EnableColumnStatistics=false
2025-11-19T21:02:04.489117Z node 9 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-19T21:02:05.113253Z node 9 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-19T21:02:07.490943Z node 7 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [7:7574544743207181210:2326], DatabaseId: /Root/tenant, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-19T21:02:07.491079Z node 7 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root/tenant, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-19T21:02:07.492538Z node 7 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [7:7574544743207181225:2329], DatabaseId: /Root/tenant, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-19T21:02:07.492637Z node 7 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [7:7574544743207181226:2330], DatabaseId: /Root/tenant, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-19T21:02:07.492819Z node 7 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root/tenant, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-19T21:02:07.495001Z node 7 :TX_PROXY_SCHEME_CACHE WARN: cache.cpp:307: Path does not belong to the specified domain: self# [7:7574544743207181252:2789], domain# [OwnerId: 72057594046644480, LocalPathId: 2], path's domain# [OwnerId: 72057594046644480, LocalPathId: 1]
2025-11-19T21:02:07.502040Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976710660:3, at schemeshard:  72075186224037897, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2025-11-19T21:02:07.575425Z node 7 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [7:7574544743207181229:2331], DatabaseId: /Root/tenant, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976710660 completed, doublechecking }
2025-11-19T21:02:07.669397Z node 7 :TX_PROXY_SCHEME_CACHE WARN: cache.cpp:307: Path does not belong to the specified domain: self# [7:7574544743207181267:2802], domain# [OwnerId: 72057594046644480, LocalPathId: 2], path's domain# [OwnerId: 72057594046644480, LocalPathId: 1]
2025-11-19T21:02:07.675336Z node 7 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [7:7574544743207181266:2801] txid# 281474976710661, issues: { message: "Check failed: path: \'/Root/tenant/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 5], type: EPathTypeResourcePool, state: EPathStateNoChanges)" severity: 1 }
2025-11-19T21:02:07.857540Z node 7 :TX_PROXY_SCHEME_CACHE WARN: cache.cpp:307: Path does not belong to the specified domain: self# [7:7574544743207181286:2810], domain# [OwnerId: 72057594046644480, LocalPathId: 2], path's domain# [OwnerId: 72057594046644480, LocalPathId: 1]
Backup "/Root/tenant" to "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/"Create temporary directory "/Root/tenant/~backup_20251119T210208" in database2025-11-19T21:02:08.007069Z node 7 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[7:7574544726027310863:2084];send_to=[0:7307199536658146131:7762515];
2025-11-19T21:02:08.007451Z node 7 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2025-11-19T21:02:08.012926Z node 7 :TX_PROXY_SCHEME_CACHE WARN: cache.cpp:307: Path does not belong to the specified domain: self# [7:7574544747502148614:2830], domain# [OwnerId: 72057594046644480, LocalPathId: 2], path's domain# [OwnerId: 72057594046644480, LocalPathId: 1]
Process "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view"Backup view "/Root/tenant/view" to "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view"Write view into "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view/create_view.sql"Write ACL into "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view/permissions.pb"Remove temporary directory "/Root/tenant/~backup_20251119T210208" in database2025-11-19T21:02:08.447024Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpRmDir, opId: 281474976710665:0, at schemeshard:  72075186224037897, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_rmdir.cpp:66)
Backup completed successfullyRestore "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/" to "/Root/tenant"Resolved db base path: "/Root/tenant"List of entries in the backup: [{"fsPath":"/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/","dbPath":"/Root/tenant","type":"Directory"},{"fsPath":"/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view","dbPath":"/Root/tenant/view","type":"View"}]Process "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/"Restore directory "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/" to "/Root/tenant"Process "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view"Restore view "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view" to "/Root/tenant/view"Read view from "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view/create_view.sql"2025-11-19T21:02:08.801575Z node 7 :TX_PROXY_SCHEME_CACHE WARN: cache.cpp:307: Path does not belong to the specified domain: self# [7:7574544747502148772:2923], domain# [OwnerId: 72057594046644480, LocalPathId: 2], path's domain# [OwnerId: 72057594046644480, LocalPathId: 1]
Created "/Root/tenant/view"Restore ACL "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view" to "/Root/tenant/view"Read ACL from "/home/runner/.ya/build/build_root/jm6f/00273d/r3tmp/tmpsLeoUn/view/permissions.pb"2025-11-19T21:02:08.868058Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976710668:0, at schemeshard:  72075186224037897, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_modify_acl.cpp:101)
Restore completed successfully2025-11-19T21:02:08.924782Z node 7 :HIVE WARN: tx__status.cpp:58: HIVE#72057594037968897 THive::TTxStatus(status=2 node=Connected) - killing node 9
2025-11-19T21:02:08.925232Z node 7 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Connected -> Disconnected
2025-11-19T21:02:09.108246Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[9:7574544729847645392:2198];send_to=[0:7307199536658146131:7762515];
2025-11-19T21:02:09.108314Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/tenant/.metadata/initialization/migrations;error=timeout;
2025-11-19T21:02:09.148668Z node 9 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TCleanupTablesActor] ActorId: [9:7574544751322482654:2339], Scheduled retry for error: { <main>: Error: Retry LookupError for table .metadata/workload_manager/delayed_requests }
2025-11-19T21:02:09.149003Z node 9 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:125: [WorkloadService] [Service] Failed to discover tenant nodes
2025-11-19T21:02:09.150304Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/tenant/.metadata/workload_manager/classifiers/resource_pool_classifiers;error=incorrect path status: LookupError;
2025-11-19T21:02:09.228123Z node 9 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TCleanupTablesActor] ActorId: [9:7574544751322482654:2339], Scheduled retry for error: { <main>: Error: Retry LookupError for table .metadata/workload_manager/delayed_requests }
2025-11-19T21:02:09.412567Z node 9 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TCleanupTablesActor] ActorId: [9:7574544751322482654:2339], Scheduled retry for error: { <main>: Error: Retry LookupError for table .metadata/workload_manager/delayed_requests }
