2025-11-01T19:44:02.843999Z node 22 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[22:7567845087863120770:2080];send_to=[0:7307199536658146131:7762515];
2025-11-01T19:44:02.844065Z node 22 :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/4xuc/001483/r3tmp/tmpo4jHc0/pdisk_1.dat
2025-11-01T19:44:02.887970Z node 22 :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-01T19:44:03.044543Z node 22 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-01T19:44:03.080218Z node 22 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(22, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-01T19:44:03.080396Z node 22 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(22, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-01T19:44:03.093771Z node 22 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(22, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 26413, node 22
2025-11-01T19:44:03.154753Z node 22 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-11-01T19:44:03.198672Z node 22 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-11-01T19:44:03.198703Z node 22 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-11-01T19:44:03.198720Z node 22 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-11-01T19:44:03.198984Z node 22 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
TClient is connected to server localhost:6803
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-01T19:44:03.620301Z node 22 :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-11-01T19:44:03.857278Z node 22 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-01T19:44:07.846906Z node 22 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[22:7567845087863120770:2080];send_to=[0:7307199536658146131:7762515];
2025-11-01T19:44:07.847017Z node 22 :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-01T19:44:07.965409Z node 22 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [22:7567845109337958324:2331], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T19:44:07.965424Z node 22 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [22:7567845109337958332:2334], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T19:44:07.965610Z node 22 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T19:44:07.966179Z node 22 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [22:7567845109337958339:2336], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T19:44:07.966395Z node 22 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T19:44:07.972114Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976715658:3, 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_resource_pool.cpp:179)
2025-11-01T19:44:08.000979Z node 22 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [22:7567845109337958338:2335], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976715658 completed, doublechecking }
2025-11-01T19:44:08.064808Z node 22 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [22:7567845113632925707:2698] txid# 281474976715659, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 5], type: EPathTypeResourcePool, state: EPathStateNoChanges)" severity: 1 }
2025-11-01T19:44:08.116673Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715660:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
Backup "/Root" to "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/"Create temporary directory "/Root/~backup_20251101T194408" in databaseProcess "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a"Create directory "/Root/~backup_20251101T194408/a" in databaseProcess "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b"Create directory "/Root/~backup_20251101T194408/a/b" in databaseProcess "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c"Create directory "/Root/~backup_20251101T194408/a/b/c" in databaseProcess "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table"Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view"Backup view "/Root/a/b/c/view" to "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view"Write view into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view/create_view.sql"Write ACL into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view/permissions.pb"Copy tables: { src: "/Root/a/b/c/table", dst: "/Root/~backup_20251101T194408/a/b/c/table" }Describe table "/Root/a/b/c/table"Describe table "/Root/~backup_20251101T194408/a/b/c/table"Backup table "/Root/~backup_20251101T194408/a/b/c/table" to "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table"Write scheme into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/scheme.pb"Write ACL into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/permissions.pb"Read table "/Root/~backup_20251101T194408/a/b/c/table"Write data into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/data_00.csv"Drop table "/Root/~backup_20251101T194408/a/b/c/table"2025-11-01T19:44:09.691092Z node 22 :HIVE WARN: hive_impl.cpp:516: HIVE#72057594037968897 Handle TEvLocal::TEvTabletStatus from node 22, TabletId: 72075186224037889 not found
Write ACL into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/permissions.pb"Remove directory "/Root/~backup_20251101T194408/a/b/c"2025-11-01T19:44:09.710164Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpRmDir, opId: 281474976715673: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_rmdir.cpp:66)
Write ACL into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/permissions.pb"Remove directory "/Root/~backup_20251101T194408/a/b"2025-11-01T19:44:09.756572Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpRmDir, opId: 281474976715674: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_rmdir.cpp:66)
Write ACL into "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/permissions.pb"Remove directory "/Root/~backup_20251101T194408/a"2025-11-01T19:44:09.794533Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpRmDir, opId: 281474976715675: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_rmdir.cpp:66)
Remove temporary directory "/Root/~backup_20251101T194408" in database2025-11-01T19:44:09.831114Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpRmDir, opId: 281474976715676: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_rmdir.cpp:66)
Backup completed successfullyRestore "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/" to "/Root/restore/point"2025-11-01T19:44:09.995113Z node 22 :HIVE WARN: hive_impl.cpp:516: HIVE#72057594037968897 Handle TEvLocal::TEvTabletStatus from node 22, TabletId: 72075186224037888 not found
Resolved db base path: "/Root"List of entries in the backup: [{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/","dbPath":"/Root/restore/point","type":"Directory"},{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a","dbPath":"/Root/restore/point/a","type":"Directory"},{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b","dbPath":"/Root/restore/point/a/b","type":"Directory"},{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c","dbPath":"/Root/restore/point/a/b/c","type":"Directory"},{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table","dbPath":"/Root/restore/point/a/b/c/table","type":"Table"},{"fsPath":"/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view","dbPath":"/Root/restore/point/a/b/c/view","type":"View"}]Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/"Restore directory "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/" to "/Root/restore/point"Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a"Restore directory "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a" to "/Root/restore/point/a"Restore ACL "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a" to "/Root/restore/point/a"Read ACL from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/permissions.pb"2025-11-01T19:44:10.162718Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976715681: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_modify_acl.cpp:101)
Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b"Restore directory "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b" to "/Root/restore/point/a/b"Restore ACL "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b" to "/Root/restore/point/a/b"Read ACL from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/permissions.pb"2025-11-01T19:44:10.209778Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976715683: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_modify_acl.cpp:101)
Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c"Restore directory "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c" to "/Root/restore/point/a/b/c"Restore ACL "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c" to "/Root/restore/point/a/b/c"Read ACL from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/permissions.pb"2025-11-01T19:44:10.255020Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976715685: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_modify_acl.cpp:101)
Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table"Read scheme from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/scheme.pb"Restore table "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table" to "/Root/restore/point/a/b/c/table"2025-11-01T19:44:10.295401Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715686:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
Created "/Root/restore/point/a/b/c/table"Read data from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/data_00.csv"Restore ACL "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table" to "/Root/restore/point/a/b/c/table"Read ACL from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/table/permissions.pb"2025-11-01T19:44:10.692009Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976715688: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_modify_acl.cpp:101)
Process "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view"Restore view "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view" to "/Root/restore/point/a/b/c/view"Read view from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view/create_view.sql"Created "/Root/restore/point/a/b/c/view"Restore ACL "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view" to "/Root/restore/point/a/b/c/view"Read ACL from "/home/runner/.ya/build/build_root/4xuc/001483/r3tmp/tmpi0R938/a/b/c/view/permissions.pb"2025-11-01T19:44:10.835897Z node 22 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpModifyACL, opId: 281474976715690: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_modify_acl.cpp:101)
Restore completed successfully
