2025-11-10T09:35:31.322358Z :TABLET_SAUSAGECACHE NOTICE: shared_sausagecache.cpp:1477: Bootstrap with config 
2025-11-10T09:35:31.322378Z :BLOB_CACHE NOTICE: ctor_logger.h:56: MaxCacheDataSize: 1048576000 InFlightDataSize: 0
2025-11-10T09:35:31.323308Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-10T09:35:31.323350Z :GRPC_SERVER NOTICE: grpc_request_proxy.cpp:369: Grpc request proxy started, nodeid# 1, serve as static node
2025-11-10T09:35:31.324600Z :GENERAL_CACHE NOTICE: log.cpp:841: fline=manager.h:383;event=general_cache_manager;owner_actor_id=[1:7571028037247419199:2097];config={MemoryLimit=(NULL);DirectInflightSourceLimit=2000;DirectInflightGlobalLimit=20000;};
2025-11-10T09:35:31.324615Z :GENERAL_CACHE NOTICE: log.cpp:841: fline=manager.h:383;event=general_cache_manager;owner_actor_id=[1:7571028037247419200:2098];config={MemoryLimit=(NULL);DirectInflightSourceLimit=2000;DirectInflightGlobalLimit=20000;};
2025-11-10T09:35:31.324977Z :TX_CONVEYOR NOTICE: log.cpp:841: fline=service.cpp:26;name=COMPOSITE_CONVEYOR;action=conveyor_registered;config={{Categories:[{category=insert;queue_limit=262144;pools=2;};{category=compaction;queue_limit=262144;pools=1;};{category=normalizer;queue_limit=262144;pools=0;};{category=scan;queue_limit=262144;pools=3;};{category=deduplication;queue_limit=262144;pools=4;};]};{WorkerPools:[{id=0;threads={f=0.33;};links=[{c=normalizer;w=1;};];};{id=1;threads={f=0.33;};links=[{c=compaction;w=1;};];};{id=2;threads={f=0.2;};links=[{c=insert;w=1;};];};{id=3;threads={f=0.4;};links=[{c=scan;w=1;};];};{id=4;threads={f=0.3;};links=[{c=deduplication;w=1;};];};]};Enabled=1;};actor_id=[1:7571028037247419198:2096];manager={0,0,0,0,0,;};
2025-11-10T09:35:31.325049Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-11-10T09:35:31.325411Z :FQ_LOG_UPDATER DEBUG: log.cpp:71: Subscribed for config changes.
2025-11-10T09:35:31.332816Z :MEMORY_PROFILER NOTICE: monitor.cpp:332: Bootstrapped
2025-11-10T09:35:31.334055Z :TABLET_SAUSAGECACHE NOTICE: shared_sausagecache.cpp:238: Register memory consumer
2025-11-10T09:35:31.334718Z :BS_NODE WARN: {NW89@node_warden_pdisk.cpp:124} Can't write new MockDevicesConfig to file Path# /Berkanavt/kikimr/testing/mock_devices.txt
2025-11-10T09:35:31.338053Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419230:2121][/local] Set up state: owner# [1:7571028037247419163:2072], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.338068Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419231:2122][/local] Set up state: owner# [1:7571028037247419164:2073], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.338079Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /local Strong=0
2025-11-10T09:35:31.338082Z :GRPC_SERVER WARN: grpc_request_proxy.cpp:572: SchemeBoardDelete /local Strong=0
2025-11-10T09:35:31.419924Z :BS_PDISK NOTICE: {BPD38@blobstorage_pdisk_impl.cpp:2875}  OnDriveStartup  Path# "SectorMap:1:64" PDiskId# 1
2025-11-10T09:35:31.420475Z :BS_PROXY NOTICE: dsproxy_state.cpp:319: EnsureMonitoring Group# 0 IsLimitedKeyless# 0 fullIfPossible# 0 Marker# DSP58
2025-11-10T09:35:31.421071Z :BS_PROXY NOTICE: dsproxy_state.cpp:259: Group# 4294967295 HasInvalidGroupId# 1 Bootstrap -> StateEjected Marker# DSP42
2025-11-10T09:35:31.427096Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037968897, type: Hive, boot
2025-11-10T09:35:31.427119Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037932033, type: BSController, boot
2025-11-10T09:35:31.427126Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594046678944, type: SchemeShard, boot
2025-11-10T09:35:31.427132Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594046316545, type: Coordinator, boot
2025-11-10T09:35:31.427137Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594046382081, type: Mediator, boot
2025-11-10T09:35:31.427142Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594046447617, type: TxAllocator, boot
2025-11-10T09:35:31.427147Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037936128, type: Cms, boot
2025-11-10T09:35:31.427152Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037936129, type: NodeBroker, boot
2025-11-10T09:35:31.427157Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037936130, type: TenantSlotBroker, boot
2025-11-10T09:35:31.427161Z :BOOTSTRAPPER NOTICE: bootstrapper.cpp:698: tablet: 72057594037936131, type: Console, boot
2025-11-10T09:35:31.427220Z :TENANT_POOL NOTICE: tenant_pool.cpp:526: TDomainTenantPool(local) started tenant /local
2025-11-10T09:35:31.427323Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_impl.cpp:316} Shutdown OwnerInfo# { PDisk system/log ChunkIds: {} Free ChunkIds: {} PDiskId# 1
2025-11-10T09:35:31.432667Z :BS_PDISK WARN: {BSP01@blobstorage_pdisk_actor.cpp:416} Magic sector is present on disk, now going to format device PDiskId# 1
2025-11-10T09:35:31.432833Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:707: PDiskId# 1 VDISK[0:_:0:0:0]: (0) LocalRecovery START
2025-11-10T09:35:31.459757Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419425:2197][/local] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.459920Z :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: local/.metadata/script_executions
2025-11-10T09:35:31.459943Z :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//local/.metadata/cs_index/external;error=incorrect path status: LookupError;
2025-11-10T09:35:31.459952Z :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//local/.metadata/initialization/migrations;error=incorrect path status: LookupError;
2025-11-10T09:35:31.640966Z :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: local/.metadata/script_executions
2025-11-10T09:35:31.775273Z :BS_PDISK NOTICE: {BPD38@blobstorage_pdisk_impl.cpp:2875}  OnDriveStartup  Path# "SectorMap:1:64" PDiskId# 1
2025-11-10T09:35:31.775316Z :BS_PDISK WARN: {BSP01@blobstorage_pdisk_actor.cpp:354} Device formatting done PDiskId# 1
2025-11-10T09:35:31.776906Z :BS_PDISK NOTICE: {BSP01@blobstorage_pdisk_actor.cpp:561} Successfully read format record Format# {TDiskFormat Version: 3 DiskSize: 68719476736 bytes (68 GB) Guid: 1 MagicNextLogChunkReference: 11700595829887332438 MagicLogChunk: 1545539174400920329 MagicDataChunk: 9850659434034960304 MagicSysLogChunk: 4270302453319120705 MagicFormatChunk: 17332287817462050952 ChunkSize: 35651584 bytes (35 MB) SectorSize: 4096 SysLogSectorCount: 112 SystemChunkCount: 1 FormatText: "" DiskFormatSize: 1168 (current sizeof: 1168) TimestampUs: 1762767331616356 (2025-11-10T09:35:31.616356Z) FormatFlags: {ErasureEncodeSysLog | ErasureEncodeFormat | ErasureEncodeNextChunkReference | EncryptFormat | EncryptData}} PDiskId# 1
2025-11-10T09:35:31.781535Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_impl_log.cpp:282} SysLogRecord is read Record# {TSysLogRecord Version# 0 NonceSet# {TNonceSet Version# 0 NonceSysLog# 106 NonceLog# 1 NonceData# 1} LogHeadChunkIdx# 1 LogHeadChunkPreviousNonce# 0} PDiskId# 1
2025-11-10T09:35:31.784884Z :BS_PDISK NOTICE: {LR018@blobstorage_pdisk_logreader.cpp:809} PDiskId# 1 LogReader IsInitial# 1 ChunkIdx# 1 SectorIdx# 0 OffsetInSector# 0 In ProcessSectorSet got !restorator.GoodSectorFlags LastGoodToWriteLogPosition# { ChunkIdx# 1 OffsetInChunk# 0} PDiskId# 1
2025-11-10T09:35:31.785120Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 0 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 1 OffsetInChunk# 0} isEndOfLog# true StatusFlags# IsValid | DiskSpaceCyan | DiskSpaceLightYellowMove | DiskSpaceYellowStop | DiskSpaceLightOrange | DiskSpacePreOrange | DiskSpaceOrange | DiskSpaceRed | DiskSpaceBlack Results.size# 0} PDiskId# 1
2025-11-10T09:35:31.785736Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_impl_log.cpp:1759} PDisk have successfully started PDiskId# 1
2025-11-10T09:35:31.787007Z :BS_PDISK NOTICE: {BPD02@blobstorage_pdisk_impl.cpp:2073} New owner is created ownerId# 3 vDiskId# [0:_:0:0:0] FirstNonceToKeep# 1094579 CutLogId# [1:7571028037247419348:2115] ownerRound# 3 PDiskId# 1
2025-11-10T09:35:31.787883Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:190: PDiskId# 1 VDISK[0:_:0:0:0]: (0) MAX LSNS: LogoBlobs# [ExplicitlySet# true Derived# false Lsn# NotSet] Blocks# [ExplicitlySet# true Derived# false Lsn# NotSet] Barriers# [ExplicitlySet# true Derived# false Lsn# NotSet] SyncLog# 0
2025-11-10T09:35:31.787952Z :BS_LOCALRECOVERY NOTICE: localrecovery_logreplay.cpp:83: PDiskId# 1 VDISK[0:_:0:0:0]: (0) TRecoveryLogReplayer: START
2025-11-10T09:35:31.788058Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 3 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 4294967295 OffsetInChunk# 4294967295} isEndOfLog# true StatusFlags# IsValid Results.size# 0} PDiskId# 1
2025-11-10T09:35:31.788138Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:143: PDiskId# 1 VDISK[0:_:0:0:0]: (0) LocalRecovery FINISHED: {RecoveryDuration# 0.361301s RecoveredLogStartLsn# 0 SuccessfulRecovery# true EmptyLogoBlobsDb# true EmptyBlocksDb# true EmptyBarriersDb# true EmptySyncLog# true EmptySyncer# true EmptyHuge# true LogRecLogoBlob# 0 LogRecBlock# 0 LogRecGC# 0 LogRecSyncLogIdx# 0 LogRecLogoBlobsDB# 0 LogRecBlocksDB# 0 LogRecBarriersDB# 0 LogRecCutLog# 0 LogRecLocalSyncData# 0 LogRecSyncerState# 0 LogRecHandoffDel# 0 LogRecHugeBlobAllocChunk# 0 LogRecHugeBlobFreeChunk# 0 LogRecHugeBlobEntryPoint# 0 LogRecHugeLogoBlob# 0 LogRecLogoBlobOpt# 0 LogRecPhantomBlob# 0 LogRecAnubisOsirisPut# 0 LogRecAddBulkSst# 0 LogoBlobFreshApply# 0 LogoBlobFreshSkip# 0 LogoBlobsBatchFreshApply# 0 LogoBlobsBatchFreshSkip#0 LogoBlobSyncLogApply# 0 LogoBlobSyncLogSkip# 0 HugeLogoBlobFreshApply# 0 HugeLogoBlobFreshSkip# 0 HugeLogoBlobSyncLogApply# 0 HugeLogoBlobSyncLogSkip# 0 BlockFreshApply# 0 BlockFreshSkip# 0 BlocksBatchFreshApply# 0 BlocksBatchFreshSkip# 0 BlockSyncLogApply# 0 BlockSyncLogSkip# 0 BarrierFreshApply# 0 BarrierFreshSkip# 0 BarriersBatchFreshApply# 0 BarriersBatchFreshSkip# 0 BarrierSyncLogApply# 0 BarrierSyncLogSkip# 0 GCBarrierFreshApply# 0 GCBarrierFreshSkip# 0 GCLogoBlobFreshApply# 0 GCLogoBlobFreshSkip# 0 GCSyncLogApply# 0 GCSyncLogSkip# 0 TryPutLogoBlobSyncData# 0 TryPutBlockSyncData# 0 TryPutBarrierSyncData# 0 HandoffDelFreshApply# 0 HandoffDelFreshSkip# 0 HugeBlobAllocChunkApply# 0 HugeBlobAllocChunkSkip# 0 HugeBlobFreeChunkApply# 0 HugeBlobFreeChunkSkip# 0 HugeLogoBlobToHeapApply# 0 HugeLogoBlobToHeapSkip# 0 HugeSlotsDelGenericApply# 0 HugeSlotsDelGenericSkip# 0 TryPutLogoBlobPhantom# 0 RecoveryLogDiapason# [18446744073709551615 0] StartingPoints# {} ReadLogReplies# {{RecsN# 0 Lsns# [0 0]}}}
2025-11-10T09:35:31.790979Z :BS_PROXY NOTICE: dsproxy_state.cpp:315: EnsureMonitoring Group# 0 IsLimitedKeyless# 0 Marker# DSP57 initialize full monitoring
2025-11-10T09:35:31.791575Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [8e386b656b675a85] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791588Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [224dcd682cb9c1e8] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791595Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [c5edde40fa8e43ed] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791603Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [5cc1c1ba1ab7c3fa] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791609Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [ee984bf24b6d96b7] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791616Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [6033bdf5052f3899] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791623Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [c84ef305ca5acda4] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791631Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [f348a90201ab9078] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791637Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [44fb8f1d1778d790] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.791645Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [874f523a873d82aa] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 0 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806594Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [822ca890bb5b543f] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806614Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [1cd3b32244166c86] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806626Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [0922e3f4138a288f] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806638Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [10359c2a35ea88c0] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806650Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [5ac8cb5ef3c49f23] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806662Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [708d29a2a9a4b6b3] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806674Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [b725b956b622a0bb] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806687Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [8a9f50602410f523] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.806699Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [d37494abf9d98646] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.818003Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7927: BackgroundCompactionQueue configured: Timeout# 600.000000s, compact single parted# no, Rate# 1, WakeupInterval# 60.000000s, RoundInterval# 172800.000000s, InflightLimit# 1, MinCompactionRepeatDelaySeconds# 600.000000s, MaxRate# 1
2025-11-10T09:35:31.818010Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7955: BorrowedCompactionQueue configured: Timeout# 15.000000s, Rate# 0, WakeupInterval# 1.000000s, InflightLimit# 10
2025-11-10T09:35:31.818013Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7841: StatsBatching config: StatsBatchTimeout# 0.100000s, StatsMaxBatchSize# 100, StatsMaxExecuteTime# 0.010000s
2025-11-10T09:35:31.818019Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7857: OperationsProcessing config: using default configuration
2025-11-10T09:35:31.818037Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7863: OperationsProcessing config: type TxSplitTablePartition, limit 10000
2025-11-10T09:35:31.818039Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7863: OperationsProcessing config: type TxMergeTablePartition, limit 10000
2025-11-10T09:35:31.818045Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:7987: BackgroundCleaningQueue configured: Timeout# 15.000000s, Rate# 0, WakeupInterval# 1.000000s, InflightLimit# 10
2025-11-10T09:35:31.818054Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__root_shred_manager.cpp:39: [RootShredManager] Created: Timeout# 600, Rate# 0, InflightLimit# 10, ShredInterval# 604800.000000s, ShredBSCInterval# 600.000000s, CurrentWakeupInterval# 604800.000000s, IsManualStartup# false
2025-11-10T09:35:31.818176Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:8058: ExternalSources configured: HostnamePatterns# , AvailableExternalDataSources# 
2025-11-10T09:35:31.820764Z :HIVE NOTICE: tx__load_everything.cpp:16: HIVE#72057594037968897 THive::TTxLoadEverything::Execute
2025-11-10T09:35:31.820920Z :HIVE NOTICE: tx__load_everything.cpp:252: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 sequences
2025-11-10T09:35:31.820925Z :HIVE NOTICE: tx__load_everything.cpp:296: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 subdomains
2025-11-10T09:35:31.820929Z :HIVE NOTICE: tx__load_everything.cpp:310: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 blocked owners
2025-11-10T09:35:31.820933Z :HIVE NOTICE: tx__load_everything.cpp:330: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 bridge piles
2025-11-10T09:35:31.820938Z :HIVE NOTICE: tx__load_everything.cpp:385: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 nodes
2025-11-10T09:35:31.820942Z :HIVE NOTICE: tx__load_everything.cpp:402: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet categories
2025-11-10T09:35:31.820953Z :HIVE NOTICE: tx__load_everything.cpp:524: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablets
2025-11-10T09:35:31.820959Z :HIVE NOTICE: tx__load_everything.cpp:564: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet/channel pairs (0 for missing tablets)
2025-11-10T09:35:31.820964Z :HIVE NOTICE: tx__load_everything.cpp:602: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet/channel history items (0 for missing tablets)
2025-11-10T09:35:31.820967Z :HIVE NOTICE: tx__load_everything.cpp:608: HIVE#72057594037968897 THive::TTxLoadEverything initialized allocation units for 0 tablets
2025-11-10T09:35:31.820970Z :HIVE NOTICE: tx__load_everything.cpp:655: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet follower groups (0 for missing tablets)
2025-11-10T09:35:31.820982Z :HIVE NOTICE: tx__load_everything.cpp:698: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet followers (0 for missing tablets)
2025-11-10T09:35:31.820987Z :HIVE NOTICE: tx__load_everything.cpp:783: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 metrics (0 for missing tablets)
2025-11-10T09:35:31.820993Z :HIVE NOTICE: tx__load_everything.cpp:809: HIVE#72057594037968897 THive::TTxLoadEverything loaded 0 tablet availability restrictions (0 for missing nodes)
2025-11-10T09:35:31.820995Z :HIVE NOTICE: tx__load_everything.cpp:832: HIVE#72057594037968897 THive::TTxLoadEverything deleted 0 unnecessary nodes << (and 0 restrictions for them)
2025-11-10T09:35:31.821534Z :BS_PROXY_DISCOVER WARN: dsproxy_discover.cpp:309: [1222cf2da4da426c] Result# TEvDiscoverResult {Status# NODATA BlockedGeneration# 1 Id# [0:0:0:0:0:0:0] Size# 0 MinGeneration# 0} Marker# BSD01
2025-11-10T09:35:31.822267Z :HIVE NOTICE: tx__load_everything.cpp:893: HIVE#72057594037968897 THive::TTxLoadEverything::Complete 
2025-11-10T09:35:31.822598Z :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-10T09:35:31.822612Z :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-10T09:35:31.823851Z :TX_COORDINATOR NOTICE: coordinator_impl.cpp:412: tablet# 72057594046316545 HANDLE EvMediatorQueueRestart MediatorId# 72057594046382081
2025-11-10T09:35:31.824002Z :NODE_BROKER NOTICE: node_broker.cpp:1350: [DB] Update main nodes table to: Nodes
2025-11-10T09:35:31.829315Z :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-11-10T09:35:31.832202Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:8083: Subscription to Console has been set up, schemeshardId: 72057594046678944
2025-11-10T09:35:31.845528Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__upgrade_schema.cpp:44: UpgradeInitState as Uninitialized, schemeshardId: 72057594046678944
2025-11-10T09:35:31.856883Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__init.cpp:1382: TTxInit, SS hasn't been configured yet, state: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:31.856989Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__init_root.cpp:33: TTxInitRoot DoExecute, path: local, pathId: [OwnerId: 72057594046678944, LocalPathId: 1], at schemeshard: 72057594046678944
2025-11-10T09:35:31.861168Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__init_root.cpp:158: TTxInitRoot DoComplete, at schemeshard: 72057594046678944
2025-11-10T09:35:31.861242Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__root_shred_manager.cpp:84: [RootShredManager] Stop
2025-11-10T09:35:31.861557Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:778: [1:7571028037247419122:2060] Successful handshake: owner# 72057594046678944, generation# 2
2025-11-10T09:35:31.861606Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:255: [1:7571028037247419758:2227] Successful handshake: replica# [1:7571028037247419122:2060]
2025-11-10T09:35:31.861609Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:259: [1:7571028037247419758:2227] Start full sync: replica# [1:7571028037247419122:2060]
2025-11-10T09:35:31.861645Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:31.861683Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:994: [1:7571028037247419122:2060] Commit generation: owner# 72057594046678944, generation# 2
2025-11-10T09:35:31.861712Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Update to strong state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.861759Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Update to strong state: owner# [1:7571028037247419163:2072], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.861791Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Update to strong state: owner# [1:7571028037247419164:2073], state# { Deleted: 1 Strong: 0 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.880749Z :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-10T09:35:31.880920Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:8058: ExternalSources configured: HostnamePatterns# , AvailableExternalDataSources# 
2025-11-10T09:35:31.880934Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__root_shred_manager.cpp:84: [RootShredManager] Stop
2025-11-10T09:35:31.880961Z :FQ_LOG_UPDATER DEBUG: log.cpp:77: Updated table service config.
2025-11-10T09:35:31.880970Z :FQ_LOG_UPDATER DEBUG: log.cpp:93: Updated YQL logs priority: 8
2025-11-10T09:35:31.881694Z :TABLET_SAUSAGECACHE NOTICE: shared_sausagecache.cpp:1378: Update config 
2025-11-10T09:35:31.889914Z :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/6t6o/002527/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_bindings_0/testing_out_stuff/test_bindings_0.py.TestBindings.test_name_uniqueness_constraint.v1-client0/netData.tsv
2025-11-10T09:35:31.889919Z :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/6t6o/002527/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_bindings_0/testing_out_stuff/test_bindings_0.py.TestBindings.test_name_uniqueness_constraint.v1-client0/netData.tsv
2025-11-10T09:35:31.913524Z :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/6t6o/002527/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_bindings_0/testing_out_stuff/test_bindings_0.py.TestBindings.test_name_uniqueness_constraint.v1-client0/netData.tsv
2025-11-10T09:35:31.913652Z :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2025-11-10T09:35:31.957873Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419775:2231][/local/.metadata/script_executions] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.957906Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419777:2233][/local/.metadata/result_sets] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.957926Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028037247419776:2232][/local/.metadata/script_execution_leases] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:31.957997Z :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: local/.metadata/script_executions
2025-11-10T09:35:31.962572Z :CMS NOTICE: audit_log.cpp:12: [AuditLog] [CMS tablet] Reply: request# NKikimr::NCms::TEvCms::TEvClusterStateRequest {  }, response# NKikimr::NCms::TEvCms::TEvClusterStateResponse { Status { Code: OK } State { Hosts { Name: "localhost" State: UP Services { Name: "storage" State: UP Version: ".2d3bc32" Timestamp: 1762767331961336 } Devices { Name: "vdisk-0-1-0-0-0" State: UP Timestamp: 1762767331961336 } Devices { Name: "pdisk-1-1" State: DOWN Timestamp: 1762767331961336 } Timestamp: 1762767331961336 NodeId: 1 InterconnectPort: 26459 Location { DataCenter: "1" Rack: "1" Unit: "1" } StartTimeSeconds: 1762767331 } Timestamp: 1762767331961336 } }
2025-11-10T09:35:32.116451Z :KQP_COMPUTE INFO: spilling_file.cpp:230: Init DQ local file spilling service at /home/runner/.ya/build/build_root/6t6o/002527/r3tmp/spilling-tmp-runner/node_1_107867e3-a9ef1912-ed294c49-b0bd0149, actor: [1:7571028041542387090:2240]
2025-11-10T09:35:32.118509Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028041542387089:2239][/local/.metadata/workload_manager/classifiers/resource_pool_classifiers] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:32.119240Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028041542387107:2253][/local/.metadata/workload_manager/delayed_requests] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:32.119262Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028041542387108:2254][/local/.metadata/workload_manager/running_requests] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:32.119794Z :KQP_COMPUTE INFO: spilling_file.cpp:781: [RemoveOldTmp] removing at root: /home/runner/.ya/build/build_root/6t6o/002527/r3tmp/spilling-tmp-runner
2025-11-10T09:35:32.158193Z :KQP_COMPUTE DEBUG: spilling_file.cpp:805: [RemoveOldTmp] found old temporary at /home/runner/.ya/build/build_root/6t6o/002527/r3tmp/spilling-tmp-runner/node_1_47a33466-a8243a21-d4a12934-5f75e49
2025-11-10T09:35:32.321568Z :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-10T09:35:32.333516Z :TX_COLUMNSHARD NOTICE: log.cpp:841: fline=manager.h:494;event=update_max_cache_size;id=portions_metadata;new_value=3380540518;old_value=1073741824;
2025-11-10T09:35:32.333531Z :TX_COLUMNSHARD NOTICE: log.cpp:841: fline=manager.h:494;event=update_max_cache_size;id=column_data;new_value=3380540518;old_value=1073741824;
2025-11-10T09:35:32.460317Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028041542387121:2258][/local/.metadata/initialization/migrations] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:32.460334Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028041542387122:2259][/local/.metadata/cs_index/external] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:34.181467Z :BS_CONTROLLER NOTICE: {BSCFP02@config_fit_pdisks.cpp:356} Create new pdisk PDiskId# 1:1 Path# SectorMap:1:64
2025-11-10T09:35:34.726456Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:707: PDiskId# 1 VDISK[82000000:_:0:0:0]: (2181038080) LocalRecovery START
2025-11-10T09:35:34.726472Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:707: PDiskId# 1 VDISK[82000001:_:0:0:0]: (2181038081) LocalRecovery START
2025-11-10T09:35:34.726582Z :BS_PDISK NOTICE: {BPD02@blobstorage_pdisk_impl.cpp:2073} New owner is created ownerId# 4 vDiskId# [82000000:_:0:0:0] FirstNonceToKeep# 1094622 CutLogId# [1:7571028050132321751:2426] ownerRound# 9 PDiskId# 1
2025-11-10T09:35:34.726725Z :BS_PDISK NOTICE: {BPD02@blobstorage_pdisk_impl.cpp:2073} New owner is created ownerId# 5 vDiskId# [82000001:_:0:0:0] FirstNonceToKeep# 1094622 CutLogId# [1:7571028050132321753:2427] ownerRound# 10 PDiskId# 1
2025-11-10T09:35:34.727219Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:190: PDiskId# 1 VDISK[82000000:_:0:0:0]: (2181038080) MAX LSNS: LogoBlobs# [ExplicitlySet# true Derived# false Lsn# NotSet] Blocks# [ExplicitlySet# true Derived# false Lsn# NotSet] Barriers# [ExplicitlySet# true Derived# false Lsn# NotSet] SyncLog# 0
2025-11-10T09:35:34.727260Z :BS_LOCALRECOVERY NOTICE: localrecovery_logreplay.cpp:83: PDiskId# 1 VDISK[82000000:_:0:0:0]: (2181038080) TRecoveryLogReplayer: START
2025-11-10T09:35:34.727350Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 4 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 4294967295 OffsetInChunk# 4294967295} isEndOfLog# true StatusFlags# IsValid Results.size# 0} PDiskId# 1
2025-11-10T09:35:34.727360Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:190: PDiskId# 1 VDISK[82000001:_:0:0:0]: (2181038081) MAX LSNS: LogoBlobs# [ExplicitlySet# true Derived# false Lsn# NotSet] Blocks# [ExplicitlySet# true Derived# false Lsn# NotSet] Barriers# [ExplicitlySet# true Derived# false Lsn# NotSet] SyncLog# 0
2025-11-10T09:35:34.727413Z :BS_LOCALRECOVERY NOTICE: localrecovery_logreplay.cpp:83: PDiskId# 1 VDISK[82000001:_:0:0:0]: (2181038081) TRecoveryLogReplayer: START
2025-11-10T09:35:34.727448Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:143: PDiskId# 1 VDISK[82000000:_:0:0:0]: (2181038080) LocalRecovery FINISHED: {RecoveryDuration# 0.000999s RecoveredLogStartLsn# 0 SuccessfulRecovery# true EmptyLogoBlobsDb# true EmptyBlocksDb# true EmptyBarriersDb# true EmptySyncLog# true EmptySyncer# true EmptyHuge# true LogRecLogoBlob# 0 LogRecBlock# 0 LogRecGC# 0 LogRecSyncLogIdx# 0 LogRecLogoBlobsDB# 0 LogRecBlocksDB# 0 LogRecBarriersDB# 0 LogRecCutLog# 0 LogRecLocalSyncData# 0 LogRecSyncerState# 0 LogRecHandoffDel# 0 LogRecHugeBlobAllocChunk# 0 LogRecHugeBlobFreeChunk# 0 LogRecHugeBlobEntryPoint# 0 LogRecHugeLogoBlob# 0 LogRecLogoBlobOpt# 0 LogRecPhantomBlob# 0 LogRecAnubisOsirisPut# 0 LogRecAddBulkSst# 0 LogoBlobFreshApply# 0 LogoBlobFreshSkip# 0 LogoBlobsBatchFreshApply# 0 LogoBlobsBatchFreshSkip#0 LogoBlobSyncLogApply# 0 LogoBlobSyncLogSkip# 0 HugeLogoBlobFreshApply# 0 HugeLogoBlobFreshSkip# 0 HugeLogoBlobSyncLogApply# 0 HugeLogoBlobSyncLogSkip# 0 BlockFreshApply# 0 BlockFreshSkip# 0 BlocksBatchFreshApply# 0 BlocksBatchFreshSkip# 0 BlockSyncLogApply# 0 BlockSyncLogSkip# 0 BarrierFreshApply# 0 BarrierFreshSkip# 0 BarriersBatchFreshApply# 0 BarriersBatchFreshSkip# 0 BarrierSyncLogApply# 0 BarrierSyncLogSkip# 0 GCBarrierFreshApply# 0 GCBarrierFreshSkip# 0 GCLogoBlobFreshApply# 0 GCLogoBlobFreshSkip# 0 GCSyncLogApply# 0 GCSyncLogSkip# 0 TryPutLogoBlobSyncData# 0 TryPutBlockSyncData# 0 TryPutBarrierSyncData# 0 HandoffDelFreshApply# 0 HandoffDelFreshSkip# 0 HugeBlobAllocChunkApply# 0 HugeBlobAllocChunkSkip# 0 HugeBlobFreeChunkApply# 0 HugeBlobFreeChunkSkip# 0 HugeLogoBlobToHeapApply# 0 HugeLogoBlobToHeapSkip# 0 HugeSlotsDelGenericApply# 0 HugeSlotsDelGenericSkip# 0 TryPutLogoBlobPhantom# 0 RecoveryLogDiapason# [18446744073709551615 0] StartingPoints# {} ReadLogReplies# {{RecsN# 0 Lsns# [0 0]}}}
2025-11-10T09:35:34.727500Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 5 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 4294967295 OffsetInChunk# 4294967295} isEndOfLog# true StatusFlags# IsValid Results.size# 0} PDiskId# 1
2025-11-10T09:35:34.727564Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:143: PDiskId# 1 VDISK[82000001:_:0:0:0]: (2181038081) LocalRecovery FINISHED: {RecoveryDuration# 0.001114s RecoveredLogStartLsn# 0 SuccessfulRecovery# true EmptyLogoBlobsDb# true EmptyBlocksDb# true EmptyBarriersDb# true EmptySyncLog# true EmptySyncer# true EmptyHuge# true LogRecLogoBlob# 0 LogRecBlock# 0 LogRecGC# 0 LogRecSyncLogIdx# 0 LogRecLogoBlobsDB# 0 LogRecBlocksDB# 0 LogRecBarriersDB# 0 LogRecCutLog# 0 LogRecLocalSyncData# 0 LogRecSyncerState# 0 LogRecHandoffDel# 0 LogRecHugeBlobAllocChunk# 0 LogRecHugeBlobFreeChunk# 0 LogRecHugeBlobEntryPoint# 0 LogRecHugeLogoBlob# 0 LogRecLogoBlobOpt# 0 LogRecPhantomBlob# 0 LogRecAnubisOsirisPut# 0 LogRecAddBulkSst# 0 LogoBlobFreshApply# 0 LogoBlobFreshSkip# 0 LogoBlobsBatchFreshApply# 0 LogoBlobsBatchFreshSkip#0 LogoBlobSyncLogApply# 0 LogoBlobSyncLogSkip# 0 HugeLogoBlobFreshApply# 0 HugeLogoBlobFreshSkip# 0 HugeLogoBlobSyncLogApply# 0 HugeLogoBlobSyncLogSkip# 0 BlockFreshApply# 0 BlockFreshSkip# 0 BlocksBatchFreshApply# 0 BlocksBatchFreshSkip# 0 BlockSyncLogApply# 0 BlockSyncLogSkip# 0 BarrierFreshApply# 0 BarrierFreshSkip# 0 BarriersBatchFreshApply# 0 BarriersBatchFreshSkip# 0 BarrierSyncLogApply# 0 BarrierSyncLogSkip# 0 GCBarrierFreshApply# 0 GCBarrierFreshSkip# 0 GCLogoBlobFreshApply# 0 GCLogoBlobFreshSkip# 0 GCSyncLogApply# 0 GCSyncLogSkip# 0 TryPutLogoBlobSyncData# 0 TryPutBlockSyncData# 0 TryPutBarrierSyncData# 0 HandoffDelFreshApply# 0 HandoffDelFreshSkip# 0 HugeBlobAllocChunkApply# 0 HugeBlobAllocChunkSkip# 0 HugeBlobFreeChunkApply# 0 HugeBlobFreeChunkSkip# 0 HugeLogoBlobToHeapApply# 0 HugeLogoBlobToHeapSkip# 0 HugeSlotsDelGenericApply# 0 HugeSlotsDelGenericSkip# 0 TryPutLogoBlobPhantom# 0 RecoveryLogDiapason# [18446744073709551615 0] StartingPoints# {} ReadLogReplies# {{RecsN# 0 Lsns# [0 0]}}}
2025-11-10T09:35:35.456018Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:707: PDiskId# 1 VDISK[82000002:_:0:0:0]: (2181038082) LocalRecovery START
2025-11-10T09:35:35.456044Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:707: PDiskId# 1 VDISK[82000003:_:0:0:0]: (2181038083) LocalRecovery START
2025-11-10T09:35:35.456186Z :BS_PDISK NOTICE: {BPD02@blobstorage_pdisk_impl.cpp:2073} New owner is created ownerId# 7 vDiskId# [82000003:_:0:0:0] FirstNonceToKeep# 1094633 CutLogId# [1:7571028054427289148:2495] ownerRound# 14 PDiskId# 1
2025-11-10T09:35:35.456330Z :BS_PDISK NOTICE: {BPD02@blobstorage_pdisk_impl.cpp:2073} New owner is created ownerId# 6 vDiskId# [82000002:_:0:0:0] FirstNonceToKeep# 1094633 CutLogId# [1:7571028054427289146:2494] ownerRound# 13 PDiskId# 1
2025-11-10T09:35:35.457003Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:190: PDiskId# 1 VDISK[82000003:_:0:0:0]: (2181038083) MAX LSNS: LogoBlobs# [ExplicitlySet# true Derived# false Lsn# NotSet] Blocks# [ExplicitlySet# true Derived# false Lsn# NotSet] Barriers# [ExplicitlySet# true Derived# false Lsn# NotSet] SyncLog# 0
2025-11-10T09:35:35.460814Z :BS_LOCALRECOVERY NOTICE: localrecovery_logreplay.cpp:83: PDiskId# 1 VDISK[82000003:_:0:0:0]: (2181038083) TRecoveryLogReplayer: START
2025-11-10T09:35:35.466035Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 7 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 4294967295 OffsetInChunk# 4294967295} isEndOfLog# true StatusFlags# IsValid Results.size# 0} PDiskId# 1
2025-11-10T09:35:35.466244Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:143: PDiskId# 1 VDISK[82000003:_:0:0:0]: (2181038083) LocalRecovery FINISHED: {RecoveryDuration# 0.010206s RecoveredLogStartLsn# 0 SuccessfulRecovery# true EmptyLogoBlobsDb# true EmptyBlocksDb# true EmptyBarriersDb# true EmptySyncLog# true EmptySyncer# true EmptyHuge# true LogRecLogoBlob# 0 LogRecBlock# 0 LogRecGC# 0 LogRecSyncLogIdx# 0 LogRecLogoBlobsDB# 0 LogRecBlocksDB# 0 LogRecBarriersDB# 0 LogRecCutLog# 0 LogRecLocalSyncData# 0 LogRecSyncerState# 0 LogRecHandoffDel# 0 LogRecHugeBlobAllocChunk# 0 LogRecHugeBlobFreeChunk# 0 LogRecHugeBlobEntryPoint# 0 LogRecHugeLogoBlob# 0 LogRecLogoBlobOpt# 0 LogRecPhantomBlob# 0 LogRecAnubisOsirisPut# 0 LogRecAddBulkSst# 0 LogoBlobFreshApply# 0 LogoBlobFreshSkip# 0 LogoBlobsBatchFreshApply# 0 LogoBlobsBatchFreshSkip#0 LogoBlobSyncLogApply# 0 LogoBlobSyncLogSkip# 0 HugeLogoBlobFreshApply# 0 HugeLogoBlobFreshSkip# 0 HugeLogoBlobSyncLogApply# 0 HugeLogoBlobSyncLogSkip# 0 BlockFreshApply# 0 BlockFreshSkip# 0 BlocksBatchFreshApply# 0 BlocksBatchFreshSkip# 0 BlockSyncLogApply# 0 BlockSyncLogSkip# 0 BarrierFreshApply# 0 BarrierFreshSkip# 0 BarriersBatchFreshApply# 0 BarriersBatchFreshSkip# 0 BarrierSyncLogApply# 0 BarrierSyncLogSkip# 0 GCBarrierFreshApply# 0 GCBarrierFreshSkip# 0 GCLogoBlobFreshApply# 0 GCLogoBlobFreshSkip# 0 GCSyncLogApply# 0 GCSyncLogSkip# 0 TryPutLogoBlobSyncData# 0 TryPutBlockSyncData# 0 TryPutBarrierSyncData# 0 HandoffDelFreshApply# 0 HandoffDelFreshSkip# 0 HugeBlobAllocChunkApply# 0 HugeBlobAllocChunkSkip# 0 HugeBlobFreeChunkApply# 0 HugeBlobFreeChunkSkip# 0 HugeLogoBlobToHeapApply# 0 HugeLogoBlobToHeapSkip# 0 HugeSlotsDelGenericApply# 0 HugeSlotsDelGenericSkip# 0 TryPutLogoBlobPhantom# 0 RecoveryLogDiapason# [18446744073709551615 0] StartingPoints# {} ReadLogReplies# {{RecsN# 0 Lsns# [0 0]}}}
2025-11-10T09:35:35.480029Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:190: PDiskId# 1 VDISK[82000002:_:0:0:0]: (2181038082) MAX LSNS: LogoBlobs# [ExplicitlySet# true Derived# false Lsn# NotSet] Blocks# [ExplicitlySet# true Derived# false Lsn# NotSet] Barriers# [ExplicitlySet# true Derived# false Lsn# NotSet] SyncLog# 0
2025-11-10T09:35:35.480178Z :BS_LOCALRECOVERY NOTICE: localrecovery_logreplay.cpp:83: PDiskId# 1 VDISK[82000002:_:0:0:0]: (2181038082) TRecoveryLogReplayer: START
2025-11-10T09:35:35.480510Z :BS_PDISK NOTICE: {BPD01@blobstorage_pdisk_logreader.cpp:1176} Reply to owner OwnerId# 6 Result# {EvReadLogResult Status# OK ErrorReason# "" position# { ChunkIdx# 0 OffsetInChunk# 0} nextPosition# { ChunkIdx# 4294967295 OffsetInChunk# 4294967295} isEndOfLog# true StatusFlags# IsValid Results.size# 0} PDiskId# 1
2025-11-10T09:35:35.481032Z :BS_LOCALRECOVERY NOTICE: localrecovery_public.cpp:143: PDiskId# 1 VDISK[82000002:_:0:0:0]: (2181038082) LocalRecovery FINISHED: {RecoveryDuration# 0.025085s RecoveredLogStartLsn# 0 SuccessfulRecovery# true EmptyLogoBlobsDb# true EmptyBlocksDb# true EmptyBarriersDb# true EmptySyncLog# true EmptySyncer# true EmptyHuge# true LogRecLogoBlob# 0 LogRecBlock# 0 LogRecGC# 0 LogRecSyncLogIdx# 0 LogRecLogoBlobsDB# 0 LogRecBlocksDB# 0 LogRecBarriersDB# 0 LogRecCutLog# 0 LogRecLocalSyncData# 0 LogRecSyncerState# 0 LogRecHandoffDel# 0 LogRecHugeBlobAllocChunk# 0 LogRecHugeBlobFreeChunk# 0 LogRecHugeBlobEntryPoint# 0 LogRecHugeLogoBlob# 0 LogRecLogoBlobOpt# 0 LogRecPhantomBlob# 0 LogRecAnubisOsirisPut# 0 LogRecAddBulkSst# 0 LogoBlobFreshApply# 0 LogoBlobFreshSkip# 0 LogoBlobsBatchFreshApply# 0 LogoBlobsBatchFreshSkip#0 LogoBlobSyncLogApply# 0 LogoBlobSyncLogSkip# 0 HugeLogoBlobFreshApply# 0 HugeLogoBlobFreshSkip# 0 HugeLogoBlobSyncLogApply# 0 HugeLogoBlobSyncLogSkip# 0 BlockFreshApply# 0 BlockFreshSkip# 0 BlocksBatchFreshApply# 0 BlocksBatchFreshSkip# 0 BlockSyncLogApply# 0 BlockSyncLogSkip# 0 BarrierFreshApply# 0 BarrierFreshSkip# 0 BarriersBatchFreshApply# 0 BarriersBatchFreshSkip# 0 BarrierSyncLogApply# 0 BarrierSyncLogSkip# 0 GCBarrierFreshApply# 0 GCBarrierFreshSkip# 0 GCLogoBlobFreshApply# 0 GCLogoBlobFreshSkip# 0 GCSyncLogApply# 0 GCSyncLogSkip# 0 TryPutLogoBlobSyncData# 0 TryPutBlockSyncData# 0 TryPutBarrierSyncData# 0 HandoffDelFreshApply# 0 HandoffDelFreshSkip# 0 HugeBlobAllocChunkApply# 0 HugeBlobAllocChunkSkip# 0 HugeBlobFreeChunkApply# 0 HugeBlobFreeChunkSkip# 0 HugeLogoBlobToHeapApply# 0 HugeLogoBlobToHeapSkip# 0 HugeSlotsDelGenericApply# 0 HugeSlotsDelGenericSkip# 0 TryPutLogoBlobPhantom# 0 RecoveryLogDiapason# [18446744073709551615 0] StartingPoints# {} ReadLogReplies# {{RecsN# 0 Lsns# [0 0]}}}
2025-11-10T09:35:35.765626Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_alter_subdomain.cpp:89: TAlterSubDomain Propose, path: //local, opId: 281474976710657:0, at schemeshard: 72057594046678944
2025-11-10T09:35:35.767373Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:127: IgniteOperation, opId: 281474976710657:1, propose status:StatusAccepted, reason: , at schemeshard: 72057594046678944
2025-11-10T09:35:35.767396Z :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:  72057594046678944, 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)
2025-11-10T09:35:35.769191Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_audit_log.cpp:222: AUDIT: txId: 281474976710657, subject: , status: StatusAccepted, operation: ALTER DATABASE, path: //local
2025-11-10T09:35:35.774647Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:685: TTxOperationPlanStep Execute, stepId: 1762767335820, transactions count in step: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:35.775550Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:993: Operation and all the parts is done, operation id: 281474976710657:0
2025-11-10T09:35:35.775576Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:1002: Publication still in progress, tx: 281474976710657, publications: 1, subscribers: 1
2025-11-10T09:35:35.775647Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710657, is deletion# false, version: 3
2025-11-10T09:35:35.775867Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:35.776235Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:35.776317Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:35.776356Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 2) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:35.776520Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710657, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 3
2025-11-10T09:35:35.776577Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__publish_to_scheme_board.cpp:155: Publication complete, notify & remove, at schemeshard: 72057594046678944, txId: 281474976710657, subscribers: 1
2025-11-10T09:35:35.817964Z :IMPORT NOTICE: schemeshard_import.cpp:304: Load table profiles
2025-11-10T09:35:35.818117Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_impl.cpp:8058: ExternalSources configured: HostnamePatterns# , AvailableExternalDataSources# 
2025-11-10T09:35:35.818142Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__root_shred_manager.cpp:84: [RootShredManager] Stop
2025-11-10T09:35:38.211688Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:849: [main][1:7571028067312191231:2290][/local/.metadata/workload_manager/pools/default] Set up state: owner# [1:7571028037247419224:2116], state# { Deleted: 1 Strong: 1 Version: <Invalid> DomainId: <Invalid> AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.211872Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028067312191229:2289], DatabaseId: /local, 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-10T09:35:38.211891Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:38.211938Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028067312191235:2292], DatabaseId: /local, 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-10T09:35:38.211943Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:38.255182Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.255 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core dq] kqp_host.cpp:1342: Good place to weld in
2025-11-10T09:35:38.255326Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.255 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core dq] kqp_host.cpp:1347: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
)

2025-11-10T09:35:38.255374Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.255 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [KQP] kqp_host.cpp:1353: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
)

2025-11-10T09:35:38.255481Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.255 TRACE ydbd(pid=245059, tid=0x00007F63F5073640) [KQP] kqp_transform.cpp:33: YqlTransformer:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(let $3 (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
(return (Commit! $3 (DataSink '"kikimr" '"db") '('('"mode" '"flush"))))
)

2025-11-10T09:35:38.255523Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.255 DEBUG ydbd(pid=245059, tid=0x00007F63F5073640) [perf] yql_expr_optimize.cpp:671: Execution of [ExpandApply] took 25us
2025-11-10T09:35:38.256476Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.256 DEBUG ydbd(pid=245059, tid=0x00007F63F5073640) [perf] type_ann_expr.cpp:48: Execution of [TypeAnnotationTransformer::DoTransform] took 719us
2025-11-10T09:35:38.256536Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.256 DEBUG ydbd(pid=245059, tid=0x00007F63F5073640) [perf] yql_expr_constraint.cpp:3312: Execution of [ConstraintTransformer::DoTransform] took 35us
2025-11-10T09:35:38.256555Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.256 DEBUG ydbd(pid=245059, tid=0x00007F63F5073640) [perf] yql_expr_csee.cpp:638: Execution of [UpdateCompletness] took 8us
2025-11-10T09:35:38.256605Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.256 DEBUG ydbd(pid=245059, tid=0x00007F63F5073640) [perf] yql_expr_csee.cpp:651: Execution of [EliminateCommonSubExpressions] took 40us
2025-11-10T09:35:38.257062Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core] yql_linear_checker.cpp:210: Scanned 0 static linear types
2025-11-10T09:35:38.257131Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [KQP] kqp_transform.cpp:33: Optimized expr:
(
(let $1 (DataSink '"kikimr" '"db"))
(let $2 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $3 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(let $4 (KiCreateObject! world $1 $2 '"EXTERNAL_DATA_SOURCE" $3 '0 '0 world))
(return (Commit! $4 $1 '('('"mode" '"flush"))))
)

2025-11-10T09:35:38.257142Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:61: Begin, root #48
2025-11-10T09:35:38.257151Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #48, status: Ok
2025-11-10T09:35:38.257161Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 TRACE ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:411: {0}, callable #48 <Commit!>
2025-11-10T09:35:38.257169Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 TRACE ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:411: {1}, callable #47 <KiCreateObject!>
2025-11-10T09:35:38.257193Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 TRACE ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:411: {1}, callable #47 <KiCreateObject!>
2025-11-10T09:35:38.257355Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:519: Register async execution for node #47
2025-11-10T09:35:38.257368Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.257 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:89: Finish, output #48, status: Async
2025-11-10T09:35:38.258020Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_create_external_data_source.cpp:233: [72057594046678944] TCreateExternalDataSource Propose: opId# 281474976710658:0, path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection_name
2025-11-10T09:35:38.258185Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:127: IgniteOperation, opId: 281474976710658:1, propose status:StatusAccepted, reason: , at schemeshard: 72057594046678944
2025-11-10T09:35:38.258196Z :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateExternalDataSource, opId: 281474976710658:0, at schemeshard:  72057594046678944, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_external_data_source.cpp:268)
2025-11-10T09:35:38.259377Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_audit_log.cpp:222: AUDIT: txId: 281474976710658, database: /local, subject: , status: StatusAccepted, operation: CREATE EXTERNAL DATA SOURCE, path: /local/h7024720695778179612_test_name_uniqueness_constraintconnection_name
2025-11-10T09:35:38.259698Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710658, is deletion# false, version: 4
2025-11-10T09:35:38.259762Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], cookie# 281474976710658, is deletion# false, version: 1
2025-11-10T09:35:38.259789Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:38.259848Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection_name, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], deletion# false
2025-11-10T09:35:38.259884Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.259942Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.260011Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 3) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.260179Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710658, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 4
2025-11-10T09:35:38.260184Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710658, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], version# 1
2025-11-10T09:35:38.261491Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:685: TTxOperationPlanStep Execute, stepId: 1762767338310, transactions count in step: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:38.264203Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:993: Operation and all the parts is done, operation id: 281474976710658:0
2025-11-10T09:35:38.264227Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:1002: Publication still in progress, tx: 281474976710658, publications: 2, subscribers: 1
2025-11-10T09:35:38.264299Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710658, is deletion# false, version: 5
2025-11-10T09:35:38.264355Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], cookie# 281474976710658, is deletion# false, version: 2
2025-11-10T09:35:38.264377Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], cookie# 281474976710658, is deletion# false, version: 2
2025-11-10T09:35:38.264422Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:38.264472Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection_name, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], deletion# false
2025-11-10T09:35:38.264524Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.264590Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.264627Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 4) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.264769Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710658, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 5
2025-11-10T09:35:38.264774Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710658, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], version# 2
2025-11-10T09:35:38.264869Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__publish_to_scheme_board.cpp:155: Publication complete, notify & remove, at schemeshard: 72057594046678944, txId: 281474976710658, subscribers: 1
2025-11-10T09:35:38.265524Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:135: Completed async execution for node #47
2025-11-10T09:35:38.265563Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:155: State is ExecutionComplete after apply async changes for node #47
2025-11-10T09:35:38.265580Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:61: Begin, root #48
2025-11-10T09:35:38.265589Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #48, status: Ok
2025-11-10T09:35:38.265602Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {0}, callable #48 <Commit!>
2025-11-10T09:35:38.265622Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:630: Node #48<Commit!> finished execution
2025-11-10T09:35:38.265647Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:647: Node #48<Commit!> created 0 trackable nodes: 
2025-11-10T09:35:38.265655Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:89: Finish, output #48, status: Ok
2025-11-10T09:35:38.265666Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxyq18jzjqtexzt7bgzak, SessionId: ydb://session/3?node_id=1&id=Y2JhMzFiZmQtZDRhYjRkZTktY2FjZTUyYTEtYWQ3OGQ0NzA= 2025-11-10 09:35:38.265 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:95: Creating finalizing transformer, output #48
2025-11-10T09:35:38.750032Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028067312191296:2304], DatabaseId: /local, 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-10T09:35:38.750077Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:38.750112Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028067312191298:2306], DatabaseId: /local, 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-10T09:35:38.750118Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:38.755503Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.755 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1342: Good place to weld in
2025-11-10T09:35:38.755756Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.755 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1347: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding_name")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
)

2025-11-10T09:35:38.755841Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.755 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_host.cpp:1353: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding_name")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
)

2025-11-10T09:35:38.756012Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.755 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: YqlTransformer:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding_name")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(let $7 (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
(return (Commit! $7 (DataSink '"kikimr" '"db") '('('"mode" '"flush"))))
)

2025-11-10T09:35:38.756064Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.756 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_optimize.cpp:671: Execution of [ExpandApply] took 36us
2025-11-10T09:35:38.756485Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.756 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] type_ann_expr.cpp:48: Execution of [TypeAnnotationTransformer::DoTransform] took 131us
2025-11-10T09:35:38.756572Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.756 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_constraint.cpp:3312: Execution of [ConstraintTransformer::DoTransform] took 55us
2025-11-10T09:35:38.756593Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.756 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:638: Execution of [UpdateCompletness] took 11us
2025-11-10T09:35:38.756683Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.756 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:651: Execution of [EliminateCommonSubExpressions] took 80us
2025-11-10T09:35:38.757210Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core] yql_linear_checker.cpp:210: Scanned 0 static linear types
2025-11-10T09:35:38.757308Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: Optimized expr:
(
(let $1 (DataSink '"kikimr" '"db"))
(let $2 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding_name")
(let $3 '('columnConstrains '('('not_null))))
(let $4 '('"a" (DataType 'Int32) $3 '()))
(let $5 '('"b" (DataType 'Utf8) $3 '()))
(let $6 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection_name")
(let $7 '('('data_source_path (String $6)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $8 (KiCreateTable! world $1 $2 '($4 $5) '() '() '() '() '() $7 '() 'externalTable '"false" '0 '0))
(return (Commit! $8 $1 '('('"mode" '"flush"))))
)

2025-11-10T09:35:38.757318Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:61: Begin, root #91
2025-11-10T09:35:38.757327Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #91, status: Ok
2025-11-10T09:35:38.757337Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {0}, callable #91 <Commit!>
2025-11-10T09:35:38.757345Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #90 <KiCreateTable!>
2025-11-10T09:35:38.757361Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #90 <KiCreateTable!>
2025-11-10T09:35:38.757445Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:519: Register async execution for node #90
2025-11-10T09:35:38.757459Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.757 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:89: Finish, output #91, status: Async
2025-11-10T09:35:38.761237Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_create_external_table.cpp:301: [72057594046678944] TCreateExternalTable Propose: opId# 281474976710659:0, path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding_name
2025-11-10T09:35:38.761463Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:127: IgniteOperation, opId: 281474976710659:1, propose status:StatusAccepted, reason: , at schemeshard: 72057594046678944
2025-11-10T09:35:38.761473Z :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateExternalTable, opId: 281474976710659:0, at schemeshard:  72057594046678944, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_external_table.cpp:352)
2025-11-10T09:35:38.764214Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_audit_log.cpp:222: AUDIT: txId: 281474976710659, database: /local, subject: , status: StatusAccepted, operation: CREATE EXTERNAL TABLE, path: /local/h7024720695778179612_test_name_uniqueness_constraintbinding_name
2025-11-10T09:35:38.764583Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710659, is deletion# false, version: 6
2025-11-10T09:35:38.764638Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], cookie# 281474976710659, is deletion# false, version: 1
2025-11-10T09:35:38.764804Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:38.764867Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding_name, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], deletion# false
2025-11-10T09:35:38.765341Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.765406Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.765452Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 5) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.765651Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710659, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 6
2025-11-10T09:35:38.765655Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710659, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], version# 1
2025-11-10T09:35:38.768014Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:685: TTxOperationPlanStep Execute, stepId: 1762767338810, transactions count in step: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:38.773031Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:993: Operation and all the parts is done, operation id: 281474976710659:0
2025-11-10T09:35:38.773068Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:1002: Publication still in progress, tx: 281474976710659, publications: 3, subscribers: 1
2025-11-10T09:35:38.773176Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710659, is deletion# false, version: 7
2025-11-10T09:35:38.773245Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], cookie# 281474976710659, is deletion# false, version: 2
2025-11-10T09:35:38.773264Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], cookie# 281474976710659, is deletion# false, version: 2
2025-11-10T09:35:38.773274Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], cookie# 281474976710659, is deletion# false, version: 2
2025-11-10T09:35:38.773382Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:38.773422Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding_name, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], deletion# false
2025-11-10T09:35:38.773429Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection_name, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], deletion# false
2025-11-10T09:35:38.774108Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.774176Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.774216Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 6) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:38.774424Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710659, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 7
2025-11-10T09:35:38.774429Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710659, pathId# [OwnerId: 72057594046678944, LocalPathId: 3], version# 2
2025-11-10T09:35:38.774433Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710659, pathId# [OwnerId: 72057594046678944, LocalPathId: 2], version# 2
2025-11-10T09:35:38.774540Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__publish_to_scheme_board.cpp:155: Publication complete, notify & remove, at schemeshard: 72057594046678944, txId: 281474976710659, subscribers: 1
2025-11-10T09:35:38.777448Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:135: Completed async execution for node #90
2025-11-10T09:35:38.777478Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:155: State is ExecutionComplete after apply async changes for node #90
2025-11-10T09:35:38.777497Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:61: Begin, root #91
2025-11-10T09:35:38.777507Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #91, status: Ok
2025-11-10T09:35:38.777520Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 TRACE ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:411: {0}, callable #91 <Commit!>
2025-11-10T09:35:38.777542Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:630: Node #91<Commit!> finished execution
2025-11-10T09:35:38.777571Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:647: Node #91<Commit!> created 0 trackable nodes: 
2025-11-10T09:35:38.777581Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:89: Finish, output #91, status: Ok
2025-11-10T09:35:38.777590Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxz7x7a3x6308ghyd9y1t, SessionId: ydb://session/3?node_id=1&id=NTc1MDhlOGItMTllM2I5ZWUtNDJiNjRiM2YtNDhiOGZmYTY= 2025-11-10 09:35:38.777 INFO  ydbd(pid=245059, tid=0x00007F63F5073640) [core exec] yql_execution.cpp:95: Creating finalizing transformer, output #91
2025-11-10T09:35:39.276291Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.276 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1342: Good place to weld in
2025-11-10T09:35:39.276455Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.276 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1347: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
)

2025-11-10T09:35:39.276511Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.276 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_host.cpp:1353: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
)

2025-11-10T09:35:39.276628Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.276 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: YqlTransformer:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $2 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(let $3 (Write! world (DataSink '"kikimr" '"db") (Key '('objectId (String $1)) '('typeId (String '"EXTERNAL_DATA_SOURCE"))) (Void) '('('mode 'createObject) '('features $2))))
(return (Commit! $3 (DataSink '"kikimr" '"db") '('('"mode" '"flush"))))
)

2025-11-10T09:35:39.276680Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.276 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_optimize.cpp:671: Execution of [ExpandApply] took 28us
2025-11-10T09:35:39.276808Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028071607158647:2320], DatabaseId: /local, 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-10T09:35:39.276832Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:39.276875Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028071607158650:2322], DatabaseId: /local, 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-10T09:35:39.276880Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:39.277163Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] type_ann_expr.cpp:48: Execution of [TypeAnnotationTransformer::DoTransform] took 62us
2025-11-10T09:35:39.277236Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_constraint.cpp:3312: Execution of [ConstraintTransformer::DoTransform] took 33us
2025-11-10T09:35:39.277261Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:638: Execution of [UpdateCompletness] took 7us
2025-11-10T09:35:39.277310Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:651: Execution of [EliminateCommonSubExpressions] took 40us
2025-11-10T09:35:39.277568Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core] yql_linear_checker.cpp:210: Scanned 0 static linear types
2025-11-10T09:35:39.277642Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: Optimized expr:
(
(let $1 (DataSink '"kikimr" '"db"))
(let $2 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $3 '('('"auth_method" '"NONE") '('"location" '"http://localhost:19363/bindbucket/") '('"source_type" '"ObjectStorage")))
(let $4 (KiCreateObject! world $1 $2 '"EXTERNAL_DATA_SOURCE" $3 '0 '0 world))
(return (Commit! $4 $1 '('('"mode" '"flush"))))
)

2025-11-10T09:35:39.277654Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:61: Begin, root #48
2025-11-10T09:35:39.277662Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #48, status: Ok
2025-11-10T09:35:39.277673Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {0}, callable #48 <Commit!>
2025-11-10T09:35:39.277683Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #47 <KiCreateObject!>
2025-11-10T09:35:39.277706Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #47 <KiCreateObject!>
2025-11-10T09:35:39.277877Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:519: Register async execution for node #47
2025-11-10T09:35:39.277892Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.277 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:89: Finish, output #48, status: Async
2025-11-10T09:35:39.278480Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_create_external_data_source.cpp:233: [72057594046678944] TCreateExternalDataSource Propose: opId# 281474976710660:0, path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection
2025-11-10T09:35:39.278647Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:127: IgniteOperation, opId: 281474976710660:1, propose status:StatusAccepted, reason: , at schemeshard: 72057594046678944
2025-11-10T09:35:39.278664Z :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateExternalDataSource, opId: 281474976710660:0, at schemeshard:  72057594046678944, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_external_data_source.cpp:268)
2025-11-10T09:35:39.285251Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_audit_log.cpp:222: AUDIT: txId: 281474976710660, database: /local, subject: , status: StatusAccepted, operation: CREATE EXTERNAL DATA SOURCE, path: /local/h7024720695778179612_test_name_uniqueness_constraintconnection
2025-11-10T09:35:39.285533Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710660, is deletion# false, version: 8
2025-11-10T09:35:39.285612Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], cookie# 281474976710660, is deletion# false, version: 1
2025-11-10T09:35:39.285677Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:39.285747Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], deletion# false
2025-11-10T09:35:39.285806Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.285883Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.285931Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 7) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.286149Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710660, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 8
2025-11-10T09:35:39.286154Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710660, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], version# 1
2025-11-10T09:35:39.287129Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:685: TTxOperationPlanStep Execute, stepId: 1762767339330, transactions count in step: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:39.288025Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710660, is deletion# false, version: 9
2025-11-10T09:35:39.288032Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:993: Operation and all the parts is done, operation id: 281474976710660:0
2025-11-10T09:35:39.288050Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:1002: Publication still in progress, tx: 281474976710660, publications: 2, subscribers: 1
2025-11-10T09:35:39.288094Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], cookie# 281474976710660, is deletion# false, version: 2
2025-11-10T09:35:39.288120Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], cookie# 281474976710660, is deletion# false, version: 2
2025-11-10T09:35:39.288148Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:39.288194Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], deletion# false
2025-11-10T09:35:39.288236Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.288243Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.288290Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 8) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.288354Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710660, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 9
2025-11-10T09:35:39.288358Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710660, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], version# 2
2025-11-10T09:35:39.288441Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__publish_to_scheme_board.cpp:155: Publication complete, notify & remove, at schemeshard: 72057594046678944, txId: 281474976710660, subscribers: 1
2025-11-10T09:35:39.289149Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:135: Completed async execution for node #47
2025-11-10T09:35:39.289176Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:155: State is ExecutionComplete after apply async changes for node #47
2025-11-10T09:35:39.289194Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:61: Begin, root #48
2025-11-10T09:35:39.289203Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #48, status: Ok
2025-11-10T09:35:39.289214Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {0}, callable #48 <Commit!>
2025-11-10T09:35:39.289247Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:630: Node #48<Commit!> finished execution
2025-11-10T09:35:39.289272Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:647: Node #48<Commit!> created 0 trackable nodes: 
2025-11-10T09:35:39.289280Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:89: Finish, output #48, status: Ok
2025-11-10T09:35:39.289300Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxzr945dhvxxq106c11p2, SessionId: ydb://session/3?node_id=1&id=NTU4ODc1NTYtZGY2YWVjMGEtY2RkYjRiZGEtYzQ0N2RhZmI= 2025-11-10 09:35:39.289 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:95: Creating finalizing transformer, output #48
2025-11-10T09:35:39.344365Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.344 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1342: Good place to weld in
2025-11-10T09:35:39.344560Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.344 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core dq] kqp_host.cpp:1347: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
)

2025-11-10T09:35:39.344633Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.344 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_host.cpp:1353: Compiled query:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(return (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
)

2025-11-10T09:35:39.344813Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.344 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: YqlTransformer:
(
(let $1 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding")
(let $2 '('"a" (DataType 'Int32) '('columnConstrains '('('not_null))) '()))
(let $3 '('"b" (DataType 'Utf8) '('columnConstrains '('('not_null))) '()))
(let $4 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $5 '('('data_source_path (String $4)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $6 '('('mode 'create) '('columns '($2 $3)) '('tableSettings $5) '('tableType 'externalTable)))
(let $7 (Write! world (DataSink '"kikimr" '"db") (Key '('tablescheme (String $1))) (Void) $6))
(return (Commit! $7 (DataSink '"kikimr" '"db") '('('"mode" '"flush"))))
)

2025-11-10T09:35:39.344860Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.344 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_optimize.cpp:671: Execution of [ExpandApply] took 34us
2025-11-10T09:35:39.345141Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] type_ann_expr.cpp:48: Execution of [TypeAnnotationTransformer::DoTransform] took 86us
2025-11-10T09:35:39.345217Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_constraint.cpp:3312: Execution of [ConstraintTransformer::DoTransform] took 48us
2025-11-10T09:35:39.345232Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:638: Execution of [UpdateCompletness] took 8us
2025-11-10T09:35:39.345290Z :KQP_YQL DEBUG: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 DEBUG ydbd(pid=245059, tid=0x00007F63F6075640) [perf] yql_expr_csee.cpp:651: Execution of [EliminateCommonSubExpressions] took 49us
2025-11-10T09:35:39.345582Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core] yql_linear_checker.cpp:210: Scanned 0 static linear types
2025-11-10T09:35:39.345641Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [KQP] kqp_transform.cpp:33: Optimized expr:
(
(let $1 (DataSink '"kikimr" '"db"))
(let $2 '"/local/h7024720695778179612_test_name_uniqueness_constraintbinding")
(let $3 '('columnConstrains '('('not_null))))
(let $4 '('"a" (DataType 'Int32) $3 '()))
(let $5 '('"b" (DataType 'Utf8) $3 '()))
(let $6 '"/local/h7024720695778179612_test_name_uniqueness_constraintconnection")
(let $7 '('('data_source_path (String $6)) '('location (String '"abc.json")) '('FORMAT (String '"json_each_row"))))
(let $8 (KiCreateTable! world $1 $2 '($4 $5) '() '() '() '() '() $7 '() 'externalTable '"false" '0 '0))
(return (Commit! $8 $1 '('('"mode" '"flush"))))
)

2025-11-10T09:35:39.345649Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:61: Begin, root #91
2025-11-10T09:35:39.345658Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #91, status: Ok
2025-11-10T09:35:39.345668Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {0}, callable #91 <Commit!>
2025-11-10T09:35:39.345677Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #90 <KiCreateTable!>
2025-11-10T09:35:39.345694Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 TRACE ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:411: {1}, callable #90 <KiCreateTable!>
2025-11-10T09:35:39.345757Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:519: Register async execution for node #90
2025-11-10T09:35:39.345767Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.345 INFO  ydbd(pid=245059, tid=0x00007F63F6075640) [core exec] yql_execution.cpp:89: Finish, output #91, status: Async
2025-11-10T09:35:39.345876Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028071607158695:2334], DatabaseId: /local, 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-10T09:35:39.345892Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:39.345989Z :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [1:7571028071607158700:2338], DatabaseId: /local, 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-10T09:35:39.345996Z :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /local, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-10T09:35:39.346337Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_create_external_table.cpp:301: [72057594046678944] TCreateExternalTable Propose: opId# 281474976710661:0, path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding
2025-11-10T09:35:39.346509Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:127: IgniteOperation, opId: 281474976710661:1, propose status:StatusAccepted, reason: , at schemeshard: 72057594046678944
2025-11-10T09:35:39.346516Z :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateExternalTable, opId: 281474976710661:0, at schemeshard:  72057594046678944, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_external_table.cpp:352)
2025-11-10T09:35:39.347672Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard_audit_log.cpp:222: AUDIT: txId: 281474976710661, database: /local, subject: , status: StatusAccepted, operation: CREATE EXTERNAL TABLE, path: /local/h7024720695778179612_test_name_uniqueness_constraintbinding
2025-11-10T09:35:39.347934Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710661, is deletion# false, version: 10
2025-11-10T09:35:39.347974Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], cookie# 281474976710661, is deletion# false, version: 1
2025-11-10T09:35:39.348045Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:39.348102Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], deletion# false
2025-11-10T09:35:39.348138Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.348200Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.348240Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 9) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.348432Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710661, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 10
2025-11-10T09:35:39.348437Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710661, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], version# 1
2025-11-10T09:35:39.350306Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation.cpp:685: TTxOperationPlanStep Execute, stepId: 1762767339390, transactions count in step: 1, at schemeshard: 72057594046678944
2025-11-10T09:35:39.351021Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:993: Operation and all the parts is done, operation id: 281474976710661:0
2025-11-10T09:35:39.351038Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__operation_side_effects.cpp:1002: Publication still in progress, tx: 281474976710661, publications: 3, subscribers: 1
2025-11-10T09:35:39.351093Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], cookie# 281474976710661, is deletion# false, version: 11
2025-11-10T09:35:39.351152Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], cookie# 281474976710661, is deletion# false, version: 2
2025-11-10T09:35:39.351168Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], cookie# 281474976710661, is deletion# false, version: 2
2025-11-10T09:35:39.351178Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:693: [1:7571028037247419757:2226] Update description: owner# 72057594046678944, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], cookie# 281474976710661, is deletion# false, version: 2
2025-11-10T09:35:39.351253Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], deletion# false
2025-11-10T09:35:39.351290Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintbinding, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], deletion# false
2025-11-10T09:35:39.351299Z :SCHEME_BOARD_REPLICA NOTICE: replica.cpp:831: [1:7571028037247419122:2060] Update description: path# /local/h7024720695778179612_test_name_uniqueness_constraintconnection, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], deletion# false
2025-11-10T09:35:39.351341Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419425:2197][/local] Path was updated to new version: owner# [1:7571028037247419224:2116], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 11) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.351398Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419230:2121][/local] Path was updated to new version: owner# [1:7571028037247419163:2072], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 11) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.351418Z :SCHEME_BOARD_SUBSCRIBER NOTICE: subscriber.cpp:855: [main][1:7571028037247419231:2122][/local] Path was updated to new version: owner# [1:7571028037247419164:2073], state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 10) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }, new state# { Deleted: 0 Strong: 1 Version: (PathId: [OwnerId: 72057594046678944, LocalPathId: 1], Version: 11) DomainId: [OwnerId: 72057594046678944, LocalPathId: 1] AbandonedSchemeShards: there are 0 elements }
2025-11-10T09:35:39.351518Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710661, pathId# [OwnerId: 72057594046678944, LocalPathId: 1], version# 11
2025-11-10T09:35:39.351524Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710661, pathId# [OwnerId: 72057594046678944, LocalPathId: 5], version# 2
2025-11-10T09:35:39.351528Z :SCHEME_BOARD_POPULATOR NOTICE: populator.cpp:786: [1:7571028037247419757:2226] Ack update: ack to# [1:7571028037247419504:2211], cookie# 281474976710661, pathId# [OwnerId: 72057594046678944, LocalPathId: 4], version# 2
2025-11-10T09:35:39.351635Z :FLAT_TX_SCHEMESHARD NOTICE: schemeshard__publish_to_scheme_board.cpp:155: Publication complete, notify & remove, at schemeshard: 72057594046678944, txId: 281474976710661, subscribers: 1
2025-11-10T09:35:39.352156Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:135: Completed async execution for node #90
2025-11-10T09:35:39.352175Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:155: State is ExecutionComplete after apply async changes for node #90
2025-11-10T09:35:39.352188Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:61: Begin, root #91
2025-11-10T09:35:39.352193Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:74: Collect unused nodes for root #91, status: Ok
2025-11-10T09:35:39.352201Z :KQP_YQL TRACE: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 TRACE ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:411: {0}, callable #91 <Commit!>
2025-11-10T09:35:39.352215Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:630: Node #91<Commit!> finished execution
2025-11-10T09:35:39.352233Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:647: Node #91<Commit!> created 0 trackable nodes: 
2025-11-10T09:35:39.352238Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:89: Finish, output #91, status: Ok
2025-11-10T09:35:39.352244Z :KQP_YQL INFO: log.cpp:67: TraceId: 01k9phxztd326j0mggqnkv3zx3, SessionId: ydb://session/3?node_id=1&id=M2QxMzhkODMtMzA4NzQyYTYtYWExZmM4OTktN2U2NDMzZmY= 2025-11-10 09:35:39.352 INFO  ydbd(pid=245059, tid=0x00007F63F4071640) [core exec] yql_execution.cpp:95: Creating finalizing transformer, output #91
2025-11-10T09:35:46.830329Z :FLAT_TX_SCHEMESHARD WARN: schemeshard_impl.cpp:7737: Cannot get console configs
2025-11-10T09:35:46.830346Z :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-10T09:35:46.948734Z :KQP_PROXY NOTICE: kqp_proxy_service.cpp:498: KQP proxy shutdown requested.
2025-11-10T09:35:46.948742Z :GRPC_SERVER NOTICE: grpc_endpoint_publish_actor.cpp:69: Stop publish endpoints for database: /local
