2025-11-01T16:42:04.354716Z node 4 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[4:7567798193662512132:2083];send_to=[0:7307199536658146131:7762515];
2025-11-01T16:42:04.355733Z node 4 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:71;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
test_client.cpp: SetPath # /home/runner/.ya/build/build_root/ewjv/000da5/r3tmp/tmpNfgB5Y/pdisk_1.dat
2025-11-01T16:42:04.420308Z node 4 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2025-11-01T16:42:04.531751Z node 4 :IMPORT WARN: schemeshard_import.cpp:307: Table profiles were not loaded
2025-11-01T16:42:04.549418Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-01T16:42:04.549484Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-01T16:42:04.556245Z node 4 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(4, (0,0,0,0)) VolatileState: Connecting -> Connected
TServer::EnableGrpc on GrpcPort 24620, node 4
2025-11-01T16:42:04.679429Z node 4 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:135: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2025-11-01T16:42:04.748109Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-11-01T16:42:04.748125Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: (empty maybe)
2025-11-01T16:42:04.748132Z node 4 :NET_CLASSIFIER WARN: net_classifier.cpp:204: failed to initialize from file: (empty maybe)
2025-11-01T16:42:04.748228Z node 4 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
TClient is connected to server localhost:18361
WaitRootIsUp 'Root'...
TClient::Ls request: Root
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "Root"
    PathId: 1
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 1
    CreateStep: 0
    ParentPathId: 1
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 1
      SubDomainVersion: 0
      SecurityStateVersion: 0
    }
    ChildrenExist: false
  }
  Children {
    Name: ".sys"
    PathId: 18446744073709551615
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 0
    CreateStep: 0
    ParentPathId: 18446744073709551615
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 0
      Pl...
(TRUNCATED)
WaitRootIsUp 'Root' success.
2025-11-01T16:42:04.933612Z node 4 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976710657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
waiting...
2025-11-01T16:42:05.360022Z node 4 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-01T16:42:07.267564Z node 4 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateColumnTable, opId: 281474976710658:1, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/olap/operations/create_table.cpp:814)
2025-11-01T16:42:07.330835Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];fline=columnshard.cpp:108;event=initialize_shard;step=OnActivateExecutor;
2025-11-01T16:42:07.358967Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];fline=columnshard.cpp:127;event=initialize_shard;step=initialize_tiring_finished;
2025-11-01T16:42:07.359149Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TxInitSchema.Execute at tablet 72075186224037888
2025-11-01T16:42:07.366016Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:90;event=normalization_start;last_saved_id=0;
2025-11-01T16:42:07.366089Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=Granules;
2025-11-01T16:42:07.366383Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=Chunks;
2025-11-01T16:42:07.366515Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=TablesCleaner;
2025-11-01T16:42:07.366628Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=CleanGranuleId;
2025-11-01T16:42:07.366729Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=GCCountersNormalizer;
2025-11-01T16:42:07.366837Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=SyncPortionFromChunks;
2025-11-01T16:42:07.366947Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=RestoreV1Chunks_V2;
2025-11-01T16:42:07.367052Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=RestoreV2Chunks;
2025-11-01T16:42:07.367195Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=CleanDeprecatedSnapshot;
2025-11-01T16:42:07.367334Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=RestoreV0ChunksMeta;
2025-11-01T16:42:07.367446Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=CopyBlobIdsToV2;
2025-11-01T16:42:07.367556Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];tablet_id=72075186224037888;process=TTxInitSchema::Execute;fline=abstract.cpp:11;event=normalizer_register;description=CLASS_NAME=RestoreAppearanceSnapshot;
2025-11-01T16:42:07.372335Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: fline=columnshard.cpp:114;event=tiering_new_event;
2025-11-01T16:42:07.372421Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];process=Enqueue;ev=NKikimr::NColumnShard::TEvPrivate::TEvTieringModified;fline=columnshard.cpp:71;event=skip_switch_to_work;reason=db_reading_not_finished;
2025-11-01T16:42:07.381241Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TxInitSchema.Complete at tablet 72075186224037888
2025-11-01T16:42:07.381394Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=columnshard__init.cpp:131;step=TTxUpdateSchema.Execute_Start;details=normalizers_count=12;current_normalizer=CLASS_NAME=Granules;
2025-11-01T16:42:07.381444Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=1;type=Granules;
2025-11-01T16:42:07.381618Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=normalizer.cpp:127;normalizer=TGranulesNormalizer;message=0 chunks found;
2025-11-01T16:42:07.381737Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=Granules;id=1;
2025-11-01T16:42:07.381786Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=Chunks;id=Chunks;
2025-11-01T16:42:07.381803Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=2;type=Chunks;
2025-11-01T16:42:07.381900Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=chunks.cpp:138;normalizer=TChunksNormalizer;message=0 chunks found;
2025-11-01T16:42:07.381941Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=Chunks;id=2;
2025-11-01T16:42:07.381979Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=TablesCleaner;id=TablesCleaner;
2025-11-01T16:42:07.381994Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=4;type=TablesCleaner;
2025-11-01T16:42:07.382165Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=normalizer.cpp:123;normalizer=TGranulesNormalizer;message=0 chunks found;
2025-11-01T16:42:07.382219Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=TablesCleaner;id=4;
2025-11-01T16:42:07.382250Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=CleanGranuleId;id=CleanGranuleId;
2025-11-01T16:42:07.382268Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=6;type=CleanGranuleId;
2025-11-01T16:42:07.382346Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=clean_granule.cpp:133;normalizer=TCleanGranuleIdNormalizer;message=0 chunks found;
2025-11-01T16:42:07.382385Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=CleanGranuleId;id=6;
2025-11-01T16:42:07.382415Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=GCCountersNormalizer;id=GCCountersNormalizer;
2025-11-01T16:42:07.382431Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=9;type=GCCountersNormalizer;
2025-11-01T16:42:07.382473Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=GCCountersNormalizer;id=9;
2025-11-01T16:42:07.382500Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=SyncPortionFromChunks;id=SyncPortionFromChunks;
2025-11-01T16:42:07.382516Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=11;type=SyncPortionFromChunks;
2025-11-01T16:42:07.382552Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=SyncPortionFromChunks;id=11;
2025-11-01T16:42:07.382584Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=RestoreV1Chunks_V2;id=RestoreV1Chunks_V2;
2025-11-01T16:42:07.382600Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=15;type=RestoreV1Chunks_V2;
2025-11-01T16:42:07.382796Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=RestoreV1Chunks_V2;id=15;
2025-11-01T16:42:07.382829Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=RestoreV2Chunks;id=RestoreV2Chunks;
2025-11-01T16:42:07.382845Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=16;type=RestoreV2Chunks;
2025-11-01T16:42:07.382982Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=RestoreV2Chunks;id=16;
2025-11-01T16:42:07.383010Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=CleanDeprecatedSnapshot;id=CleanDeprecatedSnapshot;
2025-11-01T16:42:07.383025Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=17;type=CleanDeprecatedSnapshot;
2025-11-01T16:42:07.383078Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=CleanDeprecatedSnapshot;id=17;
2025-11-01T16:42:07.383111Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=RestoreV0ChunksMeta;id=RestoreV0ChunksMeta;
2025-11-01T16:42:07.383126Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=18;type=RestoreV0ChunksMeta;
2025-11-01T16:42:07.383168Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=RestoreV0ChunksMeta;id=18;
2025-11-01T16:42:07.383192Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=CopyBlobIdsToV2;id=CopyBlobIdsToV2;
2025-11-01T16:42:07.383209Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=19;type=CopyBlobIdsToV2;
2025-11-01T16:42:07.383347Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=CopyBlobIdsToV2;id=19;
2025-11-01T16:42:07.383384Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:42;event=normalizer_switched;description=CLASS_NAME=RestoreAppearanceSnapshot;id=RestoreAppearanceSnapshot;
2025-11-01T16:42:07.383400Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:156;event=normalizer_init;last=0;seq_id=20;type=RestoreAppearanceSnapshot;
2025-11-01T16:42:07.383495Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:62;event=normalizer_finished;description=CLASS_NAME=RestoreAppearanceSnapshot;id=20;
2025-11-01T16:42:07.383522Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=abstract.cpp:45;event=normalization_finished;
2025-11-01T16:42:07.383549Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Execute;fline=columnshard__init.cpp:151;step=TTxUpdateSchema.Execute_Finish;
2025-11-01T16:42:07.391051Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;process=TTxUpdateSchema::Complete;fline=columnshard__init.cpp:158;step=TTxUpdateSchema.Complete;
2025-11-01T16:42:07.391174Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TTxInit.Execute at tablet 72075186224037888
2025-11-01T16:42:07.391466Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;fline=common_data.cpp:29;PRECHARGE:composite_initLoadingTime=15;
2025-11-01T16:42:07.391642Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:special_valuesLoadingTime=109;
2025-11-01T16:42:07.391938Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:special_valuesLoadingTime=240;
2025-11-01T16:42:07.392120Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:tables_managerLoadingTime=144;
2025-11-01T16:42:07.392293Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;load_stage_name=EXECUTE:composite_init/tables_manager;fline=common_data.cpp:29;TablesLoadingTime=34;
2025-11-01T16:42:07.392339Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;load_stage_name=EXECUTE:composite_init/tables_manager;fline=common_data.cpp:29;SchemaPresetLoadingTime=22;
2025-11-01T16:42:07.392390Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;load_stage_name=EXECUTE:composite_init/tables_manager;fline=common_data.cpp:29;TableVersionssLoadingTime=30;
2025-11-01T16:42:07.392436Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;load_stage_name=EXECUTE:composite_init/tables_manager;fline=common_data.cpp:29;SchemaPresetVersionsLoadingTime=23;
2025-11-01T16:42:07.392474Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:tables_managerLoadingTime=326;
2025-11-01T16:42:07.392532Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:tx_controllerLoadingTime=35;
2025-11-01T16:42:07.392591Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:tx_controllerLoadingTime=41;
2025-11-01T16:42:07.392663Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:operations_managerLoadingTime=53;
2025-11-01T16:42:07.392725Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:operations_managerLoadingTime=44;
2025-11-01T16:42:07.392872Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:storages_managerLoadingTime=124;
2025-11-01T16:42:07.393188Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:storages_managerLoadingTime=295;
2025-11-01T16:42:07.393218Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:db_locksLoadingTime=7;
2025-11-01T16:42:07.393241Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:db_locksLoadingTime=7;
2025-11-01T16:42:07.393264Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:bg_sessionsLoadingTime=6;
2025-11-01T16:42:07.393312Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:bg_sessionsLoadingTime=32;
2025-11-01T16:42:07.393333Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:sharing_sessionsLoadingTime=5;
2025-11-01T16:42:07.393403Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:sharing_sessionsLoadingTime=53;
2025-11-01T16:42:07.393425Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:in_flight_readsLoadingTime=6;
2025-11-01T16:42:07.393473Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:in_flight_readsLoadingTime=32;
2025-11-01T16:42:07.393514Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;PRECHARGE:tiers_managerLoadingTime=23;
2025-11-01T16:42:07.393554Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;load_stage_name=EXECUTE:composite_init;fline=common_data.cpp:29;EXECUTE:tiers_managerLoadingTime=21;
2025-11-01T16:42:07.393579Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;event=initialize_shard;fline=common_data.cpp:29;EXECUTE:composite_initLoadingTime=2071;
2025-11-01T16:42:07.393687Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];process=SwitchToWork;fline=columnshard.cpp:78;event=initialize_shard;step=SwitchToWork;
2025-11-01T16:42:07.393719Z node 4 :TX_COLUMNSHARD INFO: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];process=SwitchToWork;fline=columnshard.cpp:81;event=initialize_shard;step=SignalTabletActive;
2025-11-01T16:42:07.393765Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];process=SwitchToWork;fline=columnshard_impl.cpp:1531;event=OnTieringModified;path_id=NO_VALUE_OPTIONAL;
2025-11-01T16:42:07.393798Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;fline=columnshard_impl.cpp:449;event=EnqueueBackgroundActivities;periodic=0;
2025-11-01T16:42:07.393818Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;fline=columnshard_impl.cpp:455;problem=Background activities cannot be started: no index at tablet;
2025-11-01T16:42:07.393970Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NActors::TEvents::TEvWakeup;fline=columnshard.cpp:264;event=TEvPrivate::TEvPeriodicWakeup::MANUAL;tablet_id=72075186224037888;
2025-11-01T16:42:07.394015Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvPeriodicWakeup;fline=columnshard.cpp:255;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2025-11-01T16:42:07.394045Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;fline=columnshard_impl.cpp:449;event=EnqueueBackgroundActivities;periodic=0;
2025-11-01T16:42:07.394069Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;fline=columnshard_impl.cpp:455;problem=Background activities cannot be started: no index at tablet;
2025-11-01T16:42:07.394156Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvReportExecutorStatistics;fline=columnshard.cpp:490;event=TEvReportExecutorStatistics;ReportExecutorStatisticsPeriodMs=60000;scheduleDuration=60.070000s;
2025-11-01T16:42:07.394180Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvReportExecutorStatistics;fline=columnshard.cpp:442;Disabled periodic stats at tablet=72075186224037888;
2025-11-01T16:42:07.394221Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvReportBaseStatistics;fline=columnshard.cpp:481;event=TEvReportBaseStatistics;ReportBaseStatisticsPeriodMs=60000;scheduleDuration=60.192000s;
2025-11-01T16:42:07.394236Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::TEvPrivate::TEvReportBaseStatistics;fline=columnshard.cpp:442;Disabled periodic stats at tablet=72075186224037888;
2025-11-01T16:42:07.394274Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: Server pipe connected at tablet 72075186224037888
2025-11-01T16:42:07.394793Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NActors::IEventHandle;tablet_id=72075186224037888;tx_id=281474976710658;this=136930180754432;fline=columnshard__propose_transaction.cpp:54;event=propose;subdomain_id=1;
2025-11-01T16:42:07.394820Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NActors::IEventHandle;tablet_id=72075186224037888;tx_id=281474976710658;this=136930180754432;fline=columnshard_subdomain_path_id.cpp:78;started_watching_subdomain=1;
2025-11-01T16:42:07.395436Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NActors::IEventHandle;tablet_id=72075186224037888;tx_id=281474976710658;this=136930180754432;method=TTxController::StartProposeOnExecute;tx_info=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;fline=tables_manager.cpp:57;method=resolve_internal_path_id;ss_local=3;result=not_found;
2025-11-01T16:42:07.395469Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NActors::IEventHandle;tablet_id=72075186224037888;tx_id=281474976710658;this=136930180754432;method=TTxController::StartProposeOnExecute;tx_info=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;fline=schema.h:38;event=sync_schema;
2025-11-01T16:42:07.396218Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated;fline=columnshard_subdomain_path_id.cpp:90;notify_subdomain=[OwnerId: 72057594046644480, LocalPathId: 1];
2025-11-01T16:42:07.400202Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;request_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;this=136930180754432;op_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;int_op_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;int_this=137136325268096;fline=columnshard__propose_transaction.cpp:105;event=actual tx operator;
2025-11-01T16:42:07.400259Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;request_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;this=136930180754432;op_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;int_op_tx=281474976710658:TX_KIND_SCHEMA;min=1762015327394;max=18446744073709551615;plan=0;src=[4:7567798193662512464:2211];cookie=12:1;;int_this=137136325268096;method=TTxController::FinishProposeOnComplete;tx_id=281474976710658;fline=propose_tx.cpp:32;message=;tablet_id=72075186224037888;tx_id=281474976710658;
2025-11-01T16:42:07.400376Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: Registered with mediator time cast at tablet 72075186224037888
2025-11-01T16:42:07.408031Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: Server pipe connected at tablet 72075186224037888
2025-11-01T16:42:07.408083Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: PlanStep 1762015327450 at tablet 72075186224037888, mediator 72057594046382081
2025-11-01T16:42:07.408129Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TxPlanStep[2] execute at tablet 72075186224037888
2025-11-01T16:42:07.408379Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=tables_manager.cpp:57;method=resolve_internal_path_id;ss_local=3;result=not_found;
2025-11-01T16:42:07.408442Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=tables_manager.cpp:57;method=resolve_internal_path_id;ss_local=3;result=not_found;
2025-11-01T16:42:07.408473Z node 4 :TX_COLUMNSHARD WARN: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=tables_manager.cpp:57;method=resolve_internal_path_id;ss_local=3;result=not_found;
2025-11-01T16:42:07.408544Z node 4 :TX_COLUMNSHARD INFO: ctor_logger.h:56: EnsureTable for pathId: {internal: 1000000889, ss: 3} ttl settings: {  } at tablet 72075186224037888
2025-11-01T16:42:07.408642Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=tables_manager.cpp:367;method=RegisterTable;path_id=1000000889;
2025-11-01T16:42:07.417767Z node 4 :TX_COLUMNSHARD NOTICE: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=column_engine_logs.cpp:83;event=new_schema;snapshot=plan_step=1762015327450;tx_id=281474976710658;;switch_optimizer=0;switch_accessors=0;
2025-11-01T16:42:07.417855Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=column_engine.h:145;event=RegisterTable;path_id=1000000889;
2025-11-01T16:42:07.418094Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=tiling.cpp:780;message=creating tiling compaction optimizer;
2025-11-01T16:42:07.418209Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;tx_state=TTxProgressTx::Execute;tx_current=0;tx_id=281474976710658;fline=column_engine_logs.cpp:516;event=OnTieringModified;new_count_tierings=0;
2025-11-01T16:42:07.418737Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated;fline=columnshard_subdomain_path_id.cpp:90;notify_subdomain=[OwnerId: 72057594046644480, LocalPathId: 1];
2025-11-01T16:42:07.418820Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TTxNotifyTxCompletion.Execute at tablet 72075186224037888
2025-11-01T16:42:07.424402Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TxPlanStep[2] complete at tablet 72075186224037888
Table type: 1
FALLBACK_ACTOR_LOGGING;priority=DEBUG;component=2100;fline=native.cpp:110;event=serialize;size=3864;columns=7;
2025-11-01T16:42:07.473511Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: Server pipe connected at tablet 72075186224037888
2025-11-01T16:42:07.483738Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::NPrivateEvents::NWrite::TEvWritePortionResult;tablet_id=72075186224037888;event=TEvWritePortionResult;tablet_id=72075186224037888;local_tx_no=4;method=execute;tx_info=TTxBlobsWritingFinished;tablet_id=72075186224037888;tx_state=execute;fline=constructor_meta.cpp:48;memory_size=60;data_size=12;sum=180;count=5;
2025-11-01T16:42:07.483804Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::NPrivateEvents::NWrite::TEvWritePortionResult;tablet_id=72075186224037888;event=TEvWritePortionResult;tablet_id=72075186224037888;local_tx_no=4;method=execute;tx_info=TTxBlobsWritingFinished;tablet_id=72075186224037888;tx_state=execute;fline=constructor_meta.cpp:65;memory_size=156;data_size=124;sum=468;count=6;size_of_meta=112;
2025-11-01T16:42:07.483850Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::NColumnShard::NPrivateEvents::NWrite::TEvWritePortionResult;tablet_id=72075186224037888;event=TEvWritePortionResult;tablet_id=72075186224037888;local_tx_no=4;method=execute;tx_info=TTxBlobsWritingFinished;tablet_id=72075186224037888;tx_state=execute;fline=constructor_portion.cpp:42;memory_size=236;data_size=204;sum=708;count=3;size_of_portion=192;
2025-11-01T16:42:07.484268Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: BlobManager on execute at tablet 72075186224037888 Save Batch GenStep: 1:1 Blob count: 1
2025-11-01T16:42:07.496872Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: BlobManager at tablet 72075186224037888 Save Batch GenStep: 1:1 Blob count: 1
2025-11-01T16:42:07.497104Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;local_tx_no=4;method=complete;tx_info=TTxBlobsWritingFinished;tablet_id=72075186224037888;tx_state=complete;fline=tiling.cpp:681;message=tiling compaction: returning DoGetUsefulMetric zero;
SUCCESS
Upsert done: 0.047704s
2025-11-01T16:42:07.512079Z node 4 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [4:7567798206547415215:2343], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T16:42:07.512143Z node 4 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T16:42:07.512347Z node 4 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [4:7567798206547415227:2346], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T16:42:07.512365Z node 4 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [4:7567798206547415228:2347], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T16:42:07.512413Z node 4 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:263: [WorkloadService] [Service] Failed to fetch pool default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2025-11-01T16:42:07.514595Z node 4 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976710659:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at /home/runner/actions_runner/_work/ydb/ydb/ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2025-11-01T16:42:07.523374Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated;fline=columnshard_subdomain_path_id.cpp:90;notify_subdomain=[OwnerId: 72057594046644480, LocalPathId: 1];
2025-11-01T16:42:07.534781Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvTxProxySchemeCache::TEvWatchNotifyUpdated;fline=columnshard_subdomain_path_id.cpp:90;notify_subdomain=[OwnerId: 72057594046644480, LocalPathId: 1];
2025-11-01T16:42:07.538061Z node 4 :KQP_WORKLOAD_SERVICE WARN: helpers.h:73: [WorkloadService] [TPoolCreatorActor] ActorId: [4:7567798206547415231:2348], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976710659 completed, doublechecking }
2025-11-01T16:42:07.652853Z node 4 :TX_PROXY ERROR: schemereq.cpp:593: Actor# [4:7567798206547415298:2795] txid# 281474976710660, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 7], type: EPathTypeResourcePool, state: EPathStateNoChanges)" severity: 1 }
2025-11-01T16:42:07.827961Z node 4 :KQP_EXECUTER ERROR: kqp_planner.cpp:123: TxId: 281474976710662. Ctx: { TraceId: 01k904rd8m0hd5e935xeeeavx6, Database: , SessionId: ydb://session/3?node_id=4&id=OTE3MzIyMi1mMjgxNDRjMi1hZDg5ZDBmMC0zMTBjYjBiYg==, PoolId: default, DatabaseId: /Root}. Database not set, use /Root
2025-11-01T16:42:07.891375Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: EvScan txId: 281474976710662 scanId: 2 version: {1762015327576:max} readable: {1762015327870:max} at tablet 72075186224037888
2025-11-01T16:42:07.891494Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: TTxScan prepare txId: 281474976710662 scanId: 2 at tablet 72075186224037888
2025-11-01T16:42:07.891843Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvDataShard::TEvKqpScan;tx_id=281474976710662;scan_id=2;gen=1;table=/Root/OlapStore/OlapTable;snapshot={1762015327576:max};tablet=72075186224037888;timeout=0.000000s;cpu_limits=Disabled;;fline=program.cpp:34;event=parse_program;program=Command {
  Projection {
    Columns {
      Id: 5
    }
    Columns {
      Id: 3
    }
    Columns {
      Id: 4
    }
    Columns {
      Id: 1
    }
    Columns {
      Id: 7
    }
    Columns {
      Id: 2
    }
    Columns {
      Id: 6
    }
  }
}
Kernels: "O\002\020AsScalar\t\211\004\235\213\004\213\000?\000\000\235?\000\000\235?\000\0000BlockAsTuple\000\t\211\002?\006?\000\002\000\013?\000\001\t\211\002?\010?\000\002\000?\016\001\000/"
;
2025-11-01T16:42:07.998835Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvDataShard::TEvKqpScan;tx_id=281474976710662;scan_id=2;gen=1;table=/Root/OlapStore/OlapTable;snapshot={1762015327576:max};tablet=72075186224037888;timeout=0.000000s;cpu_limits=Disabled;;fline=program.cpp:103;parse_proto_program=Command {
  Projection {
    Columns {
      Id: 5
    }
    Columns {
      Id: 3
    }
    Columns {
      Id: 4
    }
    Columns {
      Id: 1
    }
    Columns {
      Id: 7
    }
    Columns {
      Id: 2
    }
    Columns {
      Id: 6
    }
  }
}
Kernels: "O\002\020AsScalar\t\211\004\235\213\004\213\000?\000\000\235?\000\000\235?\000\0000BlockAsTuple\000\t\211\002?\006?\000\002\000\013?\000\001\t\211\002?\010?\000\002\000?\016\001\000/"
;
2025-11-01T16:42:08.002957Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: tablet_id=72075186224037888;self_id=[4:7567798206547415087:2328];ev=NKikimr::TEvDataShard::TEvKqpScan;tx_id=281474976710662;scan_id=2;gen=1;table=/Root/OlapStore/OlapTable;snapshot={1762015327576:max};tablet=72075186224037888;timeout=0.000000s;cpu_limits=Disabled;;fline=program.cpp:52;event=program_parsed;result={"edges":[{"owner_id":0,"inputs":[{"from":8},{"from":12},{"from":4},{"from":6},{"from":2},{"from":14},{"from":10}]},{"owner_id":2,"inputs":[{"from":15}]},{"owner_id":4,"inputs":[{"from":15}]},{"owner_id":6,"inputs":[{"from":15}]},{"owner_id":8,"inputs":[{"from":15}]},{"owner_id":10,"inputs":[{"from":15}]},{"owner_id":12,"inputs":[{"from":15}]},{"owner_id":14,"inputs":[{"from":15}]},{"owner_id":15,"inputs":[{"from":16}]},{"owner_id":16,"inputs":[]}],"nodes":{"15":{"p":{"i":"0","p":{"data":[{"name":"stringToString","id":7},{"name":"id","id":1},{"name":"timestamp","id":2},{"name":"dateTimeS","id":3},{"name":"dateTimeU","id":4},{"name":"date","id":5},{"name":"utf8ToString","id":6}]},"o":"7,1,2,3,4,5,6","t":"FetchOriginalData"},"w":14,"id":15},"2":{"p":{"i":"5","p":{"address":{"name":"date","id":5}},"o":"5","t":"AssembleOriginalData"},"w":19,"id":2},"8":{"p":{"i":"1","p":{"address":{"name":"id","id":1}},"o":"1","t":"AssembleOriginalData"},"w":19,"id":8},"0":{"p":{"i":"5,3,4,1,7,2,6","t":"Projection"},"w":133,"id":0},"4":{"p":{"i":"3","p":{"address":{"name":"dateTimeS","id":3}},"o":"3","t":"AssembleOriginalData"},"w":19,"id":4},"16":{"p":{"p":{"data":[{"name":"stringToString","id":7},{"name":"id","id":1},{"name":"timestamp","id":2},{"name":"dateTimeS","id":3},{"name":"dateTimeU","id":4},{"name":"date","id":5},{"name":"utf8ToString","id":6}]},"o":"0","t":"ReserveMemory"},"w":0,"id":16},"14":{"p":{"i":"6","p":{"address":{"name":"utf8ToString","id":6}},"o":"6","t":"AssembleOriginalData"},"w":19,"id":14},"10":{"p":{"i":"7","p":{"address":{"name":"stringToString","id":7}},"o":"7","t":"AssembleOriginalData"},"w":19,"id":10},"6":{"p":{"i":"4","p":{"address":{"name":"dateTimeU","id":4}},"o":"4","t":"AssembleOriginalData"},"w":19,"id":6},"12":{"p":{"i":"2","p":{"address":{"name":"timestamp","id":2}},"o":"2","t":"AssembleOriginalData"},"w":19,"id":12}}};
2025-11-01T16:42:08.012405Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: external_task_id=;fline=actor.cpp:48;task=agents_waiting=1;additional_info=();;
2025-11-01T16:42:08.013368Z node 4 :TX_COLUMNSHARD DEBUG: log.cpp:841: event_type=NKikimr::NBlobCache::TEvBlobCache::TEvReadBlobRangeResult;fline=task.cpp:110;event=OnDataReady;task=agents_waiting=0;additional_info=();;external_task_id=;
2025-11-01T16:42:08.015861Z node 4 :TX_COLUMNSHARD DEBUG: ctor_logger.h:56: Finished read cookie: 1 at tablet 72075186224037888
2025-11-01T16:42:08.044683Z node 4 :KQP_RESOURCE_MANAGER WARN: kqp_snapshot_manager.cpp:239: KqpSnapshotManager: discarding snapshot; our snapshot: [step: 1762015327576, txId: 18446744073709551615] shutting down
