2025-11-01T20:09:24.684753Z node 13 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[13:7567851621297024177:2078];send_to=[0:7307199536658146131:7762515];
2025-11-01T20:09:24.684816Z node 13 :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/002be8/r3tmp/tmph1a0Fm/pdisk_1.dat
2025-11-01T20:09:24.758425Z node 13 :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-01T20:09:24.914056Z node 13 :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-01T20:09:24.942925Z node 13 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-01T20:09:24.961715Z node 13 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(13, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-01T20:09:24.961801Z node 13 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(13, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-01T20:09:24.968752Z node 13 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(13, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 27150, node 13
2025-11-01T20:09:25.158651Z node 13 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-11-01T20:09:25.158683Z node 13 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-11-01T20:09:25.158692Z node 13 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-11-01T20:09:25.158896Z node 13 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-11-01T20:09:25.452568Z node 13 :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:19626
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-01T20:09:25.623206Z node 13 :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-01T20:09:25.723482Z node 13 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-01T20:09:25.730548Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# CreateTableRequest, traceId# 01k90gkzz2e3ak0zhs1wqanh62, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:25.734500Z node 13 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715658:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:690)
2025-11-01T20:09:25.743470Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:25.743588Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:25.743606Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:25.743655Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:25.839345Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:25.839541Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:25.839558Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:25.839625Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:25.856095Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# CreateSessionRequest, traceId# 01k90gm02zb04mzdwqnd5pnzh4, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.158708Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# ExecuteDataQueryRequest, traceId# 01k90gm3a6dm2w92wwrh4m95jr, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.164423Z node 13 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [13:7567851642771861850: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-01T20:09:29.164538Z node 13 :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-01T20:09:29.165103Z node 13 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [13:7567851642771861862: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-01T20:09:29.165163Z node 13 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [13:7567851642771861863:2335], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T20:09:29.165297Z node 13 :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-01T20:09:29.169926Z node 13 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976715659: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-01T20:09:29.187547Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:29.187708Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:29.187723Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:29.187782Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:29.215195Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:29.215270Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:532: SchemeBoardUpdate /Root
2025-11-01T20:09:29.215356Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:29.215374Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:561: Can't update SecurityState for /Root - no PublicKeys
2025-11-01T20:09:29.223299Z node 13 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [13:7567851642771861866:2336], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976715659 completed, doublechecking }
2025-11-01T20:09:29.296448Z node 13 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [13:7567851642771861935:2798] txid# 281474976715660, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 6], type: EPathTypeResourcePool, state: EPathStateNoChanges)" severity: 1 }
2025-11-01T20:09:29.506605Z node 13 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976715661. Ctx: { TraceId: 01k90gm3a6dm2w92wwrh4m95jr, Database: , SessionId: ydb://session/3?node_id=13&id=MTdlZDFiZC03MWI1NzM2Ny1lZWRlZDU4OS0xMmMwZjk=, PoolId: default, DatabaseId: /Root}. Database not set, use /Root
2025-11-01T20:09:29.533923Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# ReadTableRequest, traceId# 01k90gm3nxem28qwqzbh11vnr7, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.534401Z node 13 :READ_TABLE_API NOTICE: rpc_read_table.cpp:531: [13:7567851642771861979:2343] Finish grpc stream, status: 400010
2025-11-01T20:09:29.542395Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# ReadTableRequest, traceId# 01k90gm3p66zg2v0k7p10w89zb, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.571013Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:267: [13:7567851642771861980:2344] Adding quota request to queue ShardId: 0, TxId: 281474976715662
2025-11-01T20:09:29.571075Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:629: [13:7567851642771861980:2344] Assign stream quota to Shard 0, Quota 5, TxId 281474976715662 Reserved: 5 of 25, Queued: 0
2025-11-01T20:09:29.576388Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:647: [13:7567851642771861980:2344] got stream part, size: 246, RU required: 128 rate limiter absent
2025-11-01T20:09:29.576763Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:563: [13:7567851642771861980:2344] Starting inactivity timer for 600.000000s with tag 3
2025-11-01T20:09:29.582178Z node 13 :READ_TABLE_API NOTICE: rpc_read_table.cpp:531: [13:7567851642771861980:2344] Finish grpc stream, status: 400000
2025-11-01T20:09:29.591778Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# ReadTableRequest, traceId# 01k90gm3qq1ye3v5d9ywwpheqp, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.655083Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:267: [13:7567851642771862008:2346] Adding quota request to queue ShardId: 0, TxId: 281474976715664
2025-11-01T20:09:29.655153Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:629: [13:7567851642771862008:2346] Assign stream quota to Shard 0, Quota 5, TxId 281474976715664 Reserved: 5 of 25, Queued: 0
2025-11-01T20:09:29.656042Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:647: [13:7567851642771862008:2346] got stream part, size: 84, RU required: 128 rate limiter absent
2025-11-01T20:09:29.656446Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:563: [13:7567851642771862008:2346] Starting inactivity timer for 600.000000s with tag 3
2025-11-01T20:09:29.685266Z node 13 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[13:7567851621297024177:2078];send_to=[0:7307199536658146131:7762515];
2025-11-01T20:09:29.685379Z node 13 :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-01T20:09:29.805753Z node 13 :READ_TABLE_API NOTICE: rpc_read_table.cpp:531: [13:7567851642771862008:2346] Finish grpc stream, status: 400000
2025-11-01T20:09:29.812349Z node 13 :GRPC_SERVER DEBUG: grpc_request_proxy.cpp:638: Got grpc request# ReadTableRequest, traceId# 01k90gm3ym59m62wn2d1bb2rf2, sdkBuildInfo# undef, state# AS_NOT_PERFORMED, database# undef, peer# ipv6:[::1]:46630, grpcInfo# grpc-c++/1.54.3 grpc-c/31.0.0 (linux; chttp2), timeout# undef
2025-11-01T20:09:29.839066Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:267: [13:7567851642771862037:2349] Adding quota request to queue ShardId: 0, TxId: 281474976715666
2025-11-01T20:09:29.839130Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:629: [13:7567851642771862037:2349] Assign stream quota to Shard 0, Quota 5, TxId 281474976715666 Reserved: 5 of 25, Queued: 0
2025-11-01T20:09:29.844623Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:647: [13:7567851642771862037:2349] got stream part, size: 210, RU required: 128 rate limiter absent
2025-11-01T20:09:29.845126Z node 13 :READ_TABLE_API DEBUG: rpc_read_table.cpp:563: [13:7567851642771862037:2349] Starting inactivity timer for 600.000000s with tag 3
2025-11-01T20:09:29.848617Z node 13 :READ_TABLE_API NOTICE: rpc_read_table.cpp:531: [13:7567851642771862037:2349] Finish grpc stream, status: 400000
