Trying to start YDB, gRPC: 24677, MsgBus: 24540
2024-06-27T18:31:02.201236Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:02.201417Z node 1 :METADATA_PROVIDER WARN: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[1:7385252367164783252:4098];send_to=[0:7307199536658146131:7762515];
2024-06-27T18:31:02.201426Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:02.201515Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:02.201784Z node 1 :METADATA_PROVIDER ERROR: fline=accessor_snapshot_base.cpp:68;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/h6eo/001285/r3tmp/tmpBD4NlF/pdisk_1.dat
2024-06-27T18:31:02.305056Z node 1 :IMPORT WARN: Table profiles were not loaded
TServer::EnableGrpc on GrpcPort 24677, node 1
2024-06-27T18:31:02.333582Z node 1 :NET_CLASSIFIER WARN: distributable config is empty, broken or outdated, will use file: (empty maybe)
2024-06-27T18:31:02.333597Z node 1 :NET_CLASSIFIER WARN: will try to initialize from file: (empty maybe)
2024-06-27T18:31:02.333599Z node 1 :NET_CLASSIFIER WARN: failed to initialize from file: (empty maybe)
2024-06-27T18:31:02.333641Z node 1 :NET_CLASSIFIER ERROR: got bad distributable configuration
2024-06-27T18:31:02.334213Z node 1 :HIVE WARN: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2024-06-27T18:31:02.334232Z node 1 :HIVE WARN: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2024-06-27T18:31:02.347329Z node 1 :HIVE WARN: HIVE#72057594037968897 Node(1, (0,0,0,0)) VolatileState: Connecting -> Connected
TClient is connected to server localhost:24540
TClient is connected to server localhost:24540
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
    }
  }
  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
      PlanResolution: 7
      Coo...
(TRUNCATED)
WaitRootIsUp 'Root' success.
waiting...
2024-06-27T18:31:02.430325Z node 1 :FLAT_TX_SCHEMESHARD WARN: NotifyTxCompletion, unknown transaction, txId: 281474976715657, at schemeshard: 72057594046644480
Status: 53
TxId: 281474976715658
SchemeShardStatus: 1
SchemeShardTabletId: 72057594046644480
PathId: 2
2024-06-27T18:31:02.454205Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:68;event=initialize_shard;step=OnActivateExecutor;
2024-06-27T18:31:02.456272Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:84;event=initialize_shard;step=initialize_tiring_finished;
2024-06-27T18:31:02.456304Z node 1 :TX_COLUMNSHARD DEBUG: TxInitSchema.Execute at tablet 72075186224037888
2024-06-27T18:31:02.457555Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:68;event=initialize_shard;step=OnActivateExecutor;
2024-06-27T18:31:02.457974Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:02.458977Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:84;event=initialize_shard;step=initialize_tiring_finished;
2024-06-27T18:31:02.459004Z node 1 :TX_COLUMNSHARD DEBUG: TxInitSchema.Execute at tablet 72075186224037889
2024-06-27T18:31:02.465942Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:02.474835Z node 1 :TX_COLUMNSHARD DEBUG: TxInitSchema.Complete at tablet 72075186224037889
2024-06-27T18:31:02.474863Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:274;step=TTxUpdateSchema.Execute_Start;details=normalizers_count=6;current_normalizer=CLASS_NAME=Granules;
2024-06-27T18:31:02.474868Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=1;type=Granules;
2024-06-27T18:31:02.474897Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=normalizer.cpp:127;normalizer=TGranulesNormalizer;message=0 chunks found;
2024-06-27T18:31:02.474934Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=2;type=Chunks;
2024-06-27T18:31:02.474941Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=chunks.cpp:132;normalizer=TChunksNormalizer;message=0 chunks found;
2024-06-27T18:31:02.474952Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=3;type=PortionsCleaner;
2024-06-27T18:31:02.475008Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=4;type=TablesCleaner;
2024-06-27T18:31:02.475019Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=normalizer.cpp:123;normalizer=TGranulesNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475028Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=5;type=PortionsMetadata;
2024-06-27T18:31:02.475051Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=6;type=CleanGranuleId;
2024-06-27T18:31:02.475062Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=clean_granule.cpp:133;normalizer=TCleanGranuleIdNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475068Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:294;step=TTxUpdateSchema.Execute_Finish;
2024-06-27T18:31:02.475108Z node 1 :TX_COLUMNSHARD DEBUG: TxInitSchema.Complete at tablet 72075186224037888
2024-06-27T18:31:02.475119Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:274;step=TTxUpdateSchema.Execute_Start;details=normalizers_count=6;current_normalizer=CLASS_NAME=Granules;
2024-06-27T18:31:02.475121Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=1;type=Granules;
2024-06-27T18:31:02.475133Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=normalizer.cpp:127;normalizer=TGranulesNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475145Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=2;type=Chunks;
2024-06-27T18:31:02.475150Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=chunks.cpp:132;normalizer=TChunksNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475157Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=3;type=PortionsCleaner;
2024-06-27T18:31:02.475176Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=4;type=TablesCleaner;
2024-06-27T18:31:02.475185Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=normalizer.cpp:123;normalizer=TGranulesNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475191Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=5;type=PortionsMetadata;
2024-06-27T18:31:02.475209Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;event=initialize_shard;fline=abstract.cpp:137;event=normalization_init;last=0;seq_id=6;type=CleanGranuleId;
2024-06-27T18:31:02.475214Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=clean_granule.cpp:133;normalizer=TCleanGranuleIdNormalizer;message=0 chunks found;
2024-06-27T18:31:02.475219Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:294;step=TTxUpdateSchema.Execute_Finish;
2024-06-27T18:31:02.475266Z node 1 :TX_COLUMNSHARD DEBUG: fline=columnshard.cpp:75;event=tiering_new_event;
2024-06-27T18:31:02.475274Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:1123;event=OnTieringModified;path_id=NO_VALUE_OPTIONAL;
2024-06-27T18:31:02.475279Z node 1 :TX_COLUMNSHARD DEBUG: fline=columnshard.cpp:75;event=tiering_new_event;
2024-06-27T18:31:02.475282Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1123;event=OnTieringModified;path_id=NO_VALUE_OPTIONAL;
2024-06-27T18:31:02.482132Z node 1 :TX_COLUMNSHARD INFO: fline=columnshard__init.cpp:299;step=TTxUpdateSchema.Complete;
2024-06-27T18:31:02.482169Z node 1 :TX_COLUMNSHARD DEBUG: TTxInit.Execute at tablet 72075186224037888
2024-06-27T18:31:02.482234Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:104;step=TInsertTable::Load_Start;
2024-06-27T18:31:02.482394Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:111;step=TInsertTable::Load_Finish;
2024-06-27T18:31:02.482405Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:116;step=TTxController::Load_Start;
2024-06-27T18:31:02.482412Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:123;step=TTxController::Load_Finish;
2024-06-27T18:31:02.482422Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:128;step=TOperationsManager::Load_Start;
2024-06-27T18:31:02.482433Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:135;step=TOperationsManager::Load_Finish;
2024-06-27T18:31:02.482441Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:140;step=TStoragesManager::Load_Start;
2024-06-27T18:31:02.482479Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=0;
2024-06-27T18:31:02.482487Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:146;step=TStoragesManager::Load_Finish;
2024-06-27T18:31:02.482490Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:150;step=TTablesManager::Load_Start;
2024-06-27T18:31:02.482527Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;event=initialize_shard;fline=columnshard__init.cpp:171;step=TTablesManager::Load_Finish;
2024-06-27T18:31:02.482553Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 0 in 0 records at tablet 72075186224037888
2024-06-27T18:31:02.482569Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:47;event=initialize_shard;step=SwitchToWork;
2024-06-27T18:31:02.482576Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:55;event=initialize_shard;step=SignalTabletActive;
2024-06-27T18:31:02.482580Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037888;
2024-06-27T18:31:02.482683Z node 1 :TX_COLUMNSHARD DEBUG: fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.482716Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=0;
2024-06-27T18:31:02.482719Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:02.482721Z node 1 :TX_COLUMNSHARD DEBUG: Disabled periodic stats at tablet 72075186224037888
2024-06-27T18:31:02.482727Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;fline=columnshard_impl.cpp:516;problem=Background activities cannot be started: no index at tablet;
2024-06-27T18:31:02.482746Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:02.482754Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2024-06-27T18:31:02.482755Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:02.482756Z node 1 :TX_COLUMNSHARD DEBUG: Disabled periodic stats at tablet 72075186224037888
2024-06-27T18:31:02.482767Z node 1 :TX_COLUMNSHARD INFO: fline=columnshard__init.cpp:299;step=TTxUpdateSchema.Complete;
2024-06-27T18:31:02.482774Z node 1 :TX_COLUMNSHARD DEBUG: TTxInit.Execute at tablet 72075186224037889
2024-06-27T18:31:02.482816Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:104;step=TInsertTable::Load_Start;
2024-06-27T18:31:02.482916Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:111;step=TInsertTable::Load_Finish;
2024-06-27T18:31:02.482919Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:116;step=TTxController::Load_Start;
2024-06-27T18:31:02.482923Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:123;step=TTxController::Load_Finish;
2024-06-27T18:31:02.482925Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:128;step=TOperationsManager::Load_Start;
2024-06-27T18:31:02.482932Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:135;step=TOperationsManager::Load_Finish;
2024-06-27T18:31:02.482934Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:140;step=TStoragesManager::Load_Start;
2024-06-27T18:31:02.482958Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=0;
2024-06-27T18:31:02.482961Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:146;step=TStoragesManager::Load_Finish;
2024-06-27T18:31:02.482967Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:150;step=TTablesManager::Load_Start;
2024-06-27T18:31:02.482979Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;event=initialize_shard;fline=columnshard__init.cpp:171;step=TTablesManager::Load_Finish;
2024-06-27T18:31:02.482994Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 0 in 0 records at tablet 72075186224037889
2024-06-27T18:31:02.483001Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:47;event=initialize_shard;step=SwitchToWork;
2024-06-27T18:31:02.483003Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:55;event=initialize_shard;step=SignalTabletActive;
2024-06-27T18:31:02.483006Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037889;
2024-06-27T18:31:02.483040Z node 1 :TX_COLUMNSHARD DEBUG: fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.483069Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=0;
2024-06-27T18:31:02.483070Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:02.483072Z node 1 :TX_COLUMNSHARD DEBUG: Disabled periodic stats at tablet 72075186224037889
2024-06-27T18:31:02.483074Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;fline=columnshard_impl.cpp:516;problem=Background activities cannot be started: no index at tablet;
2024-06-27T18:31:02.483083Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:02.483087Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037889;
2024-06-27T18:31:02.483088Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:02.483089Z node 1 :TX_COLUMNSHARD DEBUG: Disabled periodic stats at tablet 72075186224037889
2024-06-27T18:31:02.483532Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037888
2024-06-27T18:31:02.484277Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715658;this=93268339453536;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.484296Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715658;this=93268339453536;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;fline=schema.h:35;event=sync_schema;
2024-06-27T18:31:02.484314Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715658;this=93268339453536;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;fline=tx_controller.cpp:319;event=registered;
2024-06-27T18:31:02.484345Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037889
2024-06-27T18:31:02.484376Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715658;this=93268339453696;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.484383Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715658;this=93268339453696;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;fline=schema.h:35;event=sync_schema;
2024-06-27T18:31:02.484407Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715658;this=93268339453696;method=TTxController::StartProposeOnExecute;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;fline=tx_controller.cpp:319;event=registered;
2024-06-27T18:31:02.488623Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;this=93268339453536;op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;int_op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.488650Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;this=93268339453536;op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;int_op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;method=TTxController::FinishProposeOnComplete;tx_id=281474976715658;fline=tx_controller.cpp:357;event=start;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:1;;
2024-06-27T18:31:02.488676Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;this=93268339453696;op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;int_op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.488684Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;this=93268339453696;op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;int_op_tx=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;method=TTxController::FinishProposeOnComplete;tx_id=281474976715658;fline=tx_controller.cpp:357;event=start;tx_info=281474976715658:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:1;;
2024-06-27T18:31:02.489713Z node 1 :TX_COLUMNSHARD DEBUG: Registered with mediator time cast at tablet 72075186224037888
2024-06-27T18:31:02.489719Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037888
2024-06-27T18:31:02.489723Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062533 at tablet 72075186224037888, mediator 72057594046382081
2024-06-27T18:31:02.489742Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[2] execute at tablet 72075186224037888
2024-06-27T18:31:02.489760Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037888;
2024-06-27T18:31:02.489792Z node 1 :TX_COLUMNSHARD DEBUG: TTxNotifyTxCompletion.Execute at tablet 72075186224037888
2024-06-27T18:31:02.489802Z node 1 :TX_COLUMNSHARD DEBUG: Registered with mediator time cast at tablet 72075186224037889
2024-06-27T18:31:02.489804Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037889
2024-06-27T18:31:02.489807Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062533 at tablet 72075186224037889, mediator 72057594046382081
2024-06-27T18:31:02.489810Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[2] execute at tablet 72075186224037889
2024-06-27T18:31:02.489821Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037889;
2024-06-27T18:31:02.489843Z node 1 :TX_COLUMNSHARD DEBUG: TTxNotifyTxCompletion.Execute at tablet 72075186224037889
2024-06-27T18:31:02.493183Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[2] complete at tablet 72075186224037888
2024-06-27T18:31:02.493221Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:02.493236Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[2] complete at tablet 72075186224037889
2024-06-27T18:31:02.493246Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:02.495139Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715659;this=93268339454176;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.495180Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715659;this=93268339454176;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;fline=schema.h:35;event=sync_schema;
2024-06-27T18:31:02.495197Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715659;this=93268339454176;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;fline=tx_controller.cpp:319;event=registered;
2024-06-27T18:31:02.495231Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715659;this=93268339454496;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.495241Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715659;this=93268339454496;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;fline=schema.h:35;event=sync_schema;
2024-06-27T18:31:02.495248Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715659;this=93268339454496;method=TTxController::StartProposeOnExecute;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;fline=tx_controller.cpp:319;event=registered;
Status: 53
TxId: 281474976715659
SchemeShardStatus: 1
SchemeShardTabletId: 72057594046644480
PathId: 3
2024-06-27T18:31:02.502127Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;this=93268339454496;op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;int_op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.502153Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;this=93268339454496;op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;int_op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;method=TTxController::FinishProposeOnComplete;tx_id=281474976715659;fline=tx_controller.cpp:357;event=start;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=22:2;;
2024-06-27T18:31:02.502186Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;this=93268339454176;op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;int_op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.502190Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;this=93268339454176;op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;int_op_tx=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;method=TTxController::FinishProposeOnComplete;tx_id=281474976715659;fline=tx_controller.cpp:357;event=start;tx_info=281474976715659:TX_KIND_SCHEMA;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164783522:12334];cookie=12:2;;
2024-06-27T18:31:02.503261Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062547 at tablet 72075186224037889, mediator 72057594046382081
2024-06-27T18:31:02.503275Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[4] execute at tablet 72075186224037889
2024-06-27T18:31:02.503306Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037889;
2024-06-27T18:31:02.503336Z node 1 :TX_COLUMNSHARD DEBUG: EnsureTable for pathId: 3 ttl settings: {  } at tablet 72075186224037889
2024-06-27T18:31:02.504167Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=execute;fline=column_engine_logs.cpp:543;event=OnTieringModified;new_count_tierings=0;new_count_ttls=0;
2024-06-27T18:31:02.504178Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=execute;fline=tables_manager.cpp:257;method=RegisterTable;path_id=3;
2024-06-27T18:31:02.504182Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=execute;fline=column_engine.h:282;event=RegisterTable;path_id=3;
2024-06-27T18:31:02.504649Z node 1 :TX_COLUMNSHARD DEBUG: TTxNotifyTxCompletion.Execute at tablet 72075186224037889
2024-06-27T18:31:02.504672Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062547 at tablet 72075186224037888, mediator 72057594046382081
2024-06-27T18:31:02.504678Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[4] execute at tablet 72075186224037888
2024-06-27T18:31:02.504695Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037888;
2024-06-27T18:31:02.504719Z node 1 :TX_COLUMNSHARD DEBUG: EnsureTable for pathId: 3 ttl settings: {  } at tablet 72075186224037888
2024-06-27T18:31:02.505239Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=execute;fline=column_engine_logs.cpp:543;event=OnTieringModified;new_count_tierings=0;new_count_ttls=0;
2024-06-27T18:31:02.505267Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=execute;fline=tables_manager.cpp:257;method=RegisterTable;path_id=3;
2024-06-27T18:31:02.505270Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=execute;fline=column_engine.h:282;event=RegisterTable;path_id=3;
2024-06-27T18:31:02.505347Z node 1 :TX_COLUMNSHARD DEBUG: TTxNotifyTxCompletion.Execute at tablet 72075186224037888
2024-06-27T18:31:02.505848Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[4] complete at tablet 72075186224037888
2024-06-27T18:31:02.505889Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:02.505909Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[4] complete at tablet 72075186224037889
2024-06-27T18:31:02.505916Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
FALLBACK_ACTOR_LOGGING;priority=TRACE;component=2100;fline=native.cpp:67;event=parsing;size=640144;columns=1;
FALLBACK_ACTOR_LOGGING;priority=DEBUG;component=2100;fline=native.cpp:101;event=serialize;size=640144;columns=1;
2024-06-27T18:31:02.516962Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037888
2024-06-27T18:31:02.517085Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.517148Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.517183Z node 1 :TX_COLUMNSHARD DEBUG: Write (blob) 320720 bytes into pathId 3 {object=write_monitor;count=1;size=320720} at tablet 72075186224037888
2024-06-27T18:31:02.529930Z node 1 :TX_COLUMNSHARD DEBUG: Server pipe connected at tablet 72075186224037889
2024-06-27T18:31:02.530093Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.530152Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:02.530186Z node 1 :TX_COLUMNSHARD DEBUG: Write (blob) 319568 bytes into pathId 3 {object=write_monitor;count=1;size=319568} at tablet 72075186224037889
2024-06-27T18:31:02.852357Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 1 at tablet 72075186224037888
2024-06-27T18:31:02.858973Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037888;fline=actor.cpp:24;event=restore_task_finished;reason=;
2024-06-27T18:31:02.859609Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:22;event=flush_writing;size=320720;count=1;
2024-06-27T18:31:02.889008Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 1 at tablet 72075186224037889
2024-06-27T18:31:02.889390Z node 1 :TX_COLUMNSHARD INFO: tablet_id=72075186224037889;fline=actor.cpp:24;event=restore_task_finished;reason=;
2024-06-27T18:31:02.889600Z node 1 :TX_COLUMNSHARD DEBUG: Write (record) into pathId 3 at tablet 72075186224037888
2024-06-27T18:31:02.889761Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 646360 in 1 records, committed: 0 in 0 records at tablet 72075186224037888
2024-06-27T18:31:02.889771Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager on execute at tablet 72075186224037888 Save Batch GenStep: 1:1 Blob count: 1
2024-06-27T18:31:02.889816Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:22;event=flush_writing;size=319568;count=1;
2024-06-27T18:31:02.902471Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037888 Save Batch GenStep: 1:1 Blob count: 1
2024-06-27T18:31:02.902481Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=1;
2024-06-27T18:31:02.942076Z node 1 :TX_COLUMNSHARD DEBUG: Write (record) into pathId 3 at tablet 72075186224037889
2024-06-27T18:31:02.942394Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 644032 in 1 records, committed: 0 in 0 records at tablet 72075186224037889
2024-06-27T18:31:02.942408Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager on execute at tablet 72075186224037889 Save Batch GenStep: 1:1 Blob count: 1
2024-06-27T18:31:02.943074Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037889 Save Batch GenStep: 1:1 Blob count: 1
2024-06-27T18:31:02.943104Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=1;
2024-06-27T18:31:02.943417Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715660;this=93268339438016;method=TTxController::StartProposeOnExecute;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.943413Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715660;this=93268314488096;method=TTxController::StartProposeOnExecute;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=tx_controller.cpp:292;event=start;
2024-06-27T18:31:02.943456Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;tx_id=281474976715660;this=93268314488096;method=TTxController::StartProposeOnExecute;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=tx_controller.cpp:319;event=registered;
2024-06-27T18:31:02.943476Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;tx_id=281474976715660;this=93268339438016;method=TTxController::StartProposeOnExecute;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=tx_controller.cpp:319;event=registered;
2024-06-27T18:31:02.944598Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;this=93268314488096;op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;int_op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.944608Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;request_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;this=93268314488096;op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;int_op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;method=TTxController::FinishProposeOnComplete;tx_id=281474976715660;fline=tx_controller.cpp:357;event=start;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;
2024-06-27T18:31:02.944627Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;this=93268339438016;op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;int_op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;fline=columnshard__propose_transaction.cpp:94;event=actual tx operator;
2024-06-27T18:31:02.944652Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;request_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;this=93268339438016;op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;int_op_tx=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;method=TTxController::FinishProposeOnComplete;tx_id=281474976715660;fline=tx_controller.cpp:357;event=start;tx_info=281474976715660:TX_KIND_COMMIT;min=0;max=18446744073709551615;plan=0;src=[1:7385252367164784079:8348];cookie=0;
2024-06-27T18:31:02.944981Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062988 at tablet 72075186224037889, mediator 72057594046382081
2024-06-27T18:31:02.945013Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[7] execute at tablet 72075186224037889
2024-06-27T18:31:02.945039Z node 1 :TX_COLUMNSHARD DEBUG: PlanStep 1719513062988 at tablet 72075186224037888, mediator 72057594046382081
2024-06-27T18:31:02.945039Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037889;
2024-06-27T18:31:02.945061Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[7] execute at tablet 72075186224037888
2024-06-27T18:31:02.945078Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037888;
2024-06-27T18:31:02.945084Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 644032 in 1 records at tablet 72075186224037889
2024-06-27T18:31:02.945119Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 646360 in 1 records at tablet 72075186224037888
2024-06-27T18:31:02.946200Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[7] complete at tablet 72075186224037888
2024-06-27T18:31:02.946237Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=646360;
2024-06-27T18:31:02.946266Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=manager.cpp:9;event=lock;process_id=CS::INDEXATION::529548e4-613e212c-664de8e0-2dc921d4;
2024-06-27T18:31:02.946270Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;tx_state=complete;fline=ro_controller.cpp:45;event=CS::INDEXATION;tablet_id=72075186224037888;
2024-06-27T18:31:02.946284Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:651;event=indexation;bytes=646360;blobs_count=1;max_limit=251658240;has_more=0;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;
2024-06-27T18:31:02.946365Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:608;event=start_changes;type=CS::INDEXATION;task_id=529548e4-613e212c-664de8e0-2dc921d4;
2024-06-27T18:31:02.946414Z node 1 :TX_COLUMNSHARD DEBUG: TxPlanStep[7] complete at tablet 72075186224037889
2024-06-27T18:31:02.946424Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=644032;
2024-06-27T18:31:02.946433Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=manager.cpp:9;event=lock;process_id=CS::INDEXATION::73ea558f-bb475fc6-29f7825d-2d2cbf4c;
2024-06-27T18:31:02.946436Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;tx_state=complete;fline=ro_controller.cpp:45;event=CS::INDEXATION;tablet_id=72075186224037889;
2024-06-27T18:31:02.946446Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:651;event=indexation;bytes=644032;blobs_count=1;max_limit=251658240;has_more=0;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;
2024-06-27T18:31:02.946451Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:608;event=start_changes;type=CS::INDEXATION;task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;
2024-06-27T18:31:02.946464Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=646162;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;type=CS::INDEXATION;priority=0;;
2024-06-27T18:31:02.946471Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=643840;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;type=CS::INDEXATION;priority=0;;
2024-06-27T18:31:02.946759Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:29;event=result_resources;task_id=1;task=cpu=0;mem=646162;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;type=CS::INDEXATION;priority=0;;
2024-06-27T18:31:02.946770Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=task.cpp:9;event=resource_allocated;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;mem=646162;cpu=0;
2024-06-27T18:31:02.946826Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=task.cpp:40;event=allocate_resources;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;task_id=1;mem=646162;cpu=0;
2024-06-27T18:31:02.946845Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=529548e4-613e212c-664de8e0-2dc921d4;fline=task.cpp:110;event=OnDataReady;task=agents_waiting=0;additional_info=();;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;
2024-06-27T18:31:02.946856Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=529548e4-613e212c-664de8e0-2dc921d4;fline=actor.cpp:48;task=agents_waiting=0;additional_info=();;
2024-06-27T18:31:02.946866Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:29;event=result_resources;task_id=2;task=cpu=0;mem=643840;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;type=CS::INDEXATION;priority=0;;
2024-06-27T18:31:02.946868Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=task.cpp:9;event=resource_allocated;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;mem=643840;cpu=0;
2024-06-27T18:31:02.946871Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=task.cpp:40;event=allocate_resources;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;task_id=2;mem=643840;cpu=0;
2024-06-27T18:31:02.946878Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;fline=task.cpp:110;event=OnDataReady;task=agents_waiting=0;additional_info=();;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;
2024-06-27T18:31:02.946883Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;fline=actor.cpp:48;task=agents_waiting=0;additional_info=();;
2024-06-27T18:31:02.985328Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:02.985407Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:02.985419Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard__write_index.cpp:50;event=TEvWriteIndex;count=1;
2024-06-27T18:31:02.985615Z node 1 :TX_COLUMNSHARD DEBUG: WriteIndex at tablet 72075186224037888
2024-06-27T18:31:02.985670Z node 1 :TX_COLUMNSHARD DEBUG: TxWriteIndex[9] (CS::INDEXATION) apply at tablet 72075186224037888
2024-06-27T18:31:02.985850Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager on execute at tablet 72075186224037888 Save Batch GenStep: 1:2 Blob count: 1
2024-06-27T18:31:02.985878Z node 1 :TX_COLUMNSHARD DEBUG: Index: tables 1 inserted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; compacted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; s-compacted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; inactive portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; evicted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; column records 0 at tablet 72075186224037888
2024-06-27T18:31:02.985883Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037888;
2024-06-27T18:31:02.993808Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard__write_index.cpp:50;event=TEvWriteIndex;count=1;
2024-06-27T18:31:02.995910Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;fline=abstract.cpp:45;event=WriteIndexComplete;type=CS::INDEXATION;success=1;
72075186224037888 SHARING_REMOVE_LOCAL:DS:2181038080:[72075186224037888:1:1:3:0:646360:0] FROM 72075186224037888
72075186224037888 BORROWED_REMOVE_LOCAL:DS:2181038080:[72075186224037888:1:1:3:0:646360:0] FROM 72075186224037888
2024-06-27T18:31:03.000431Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 0 in 0 records at tablet 72075186224037888
2024-06-27T18:31:03.000445Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;fline=manager.cpp:14;event=unlock;process_id=CS::INDEXATION::529548e4-613e212c-664de8e0-2dc921d4;
2024-06-27T18:31:03.000456Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=0;
2024-06-27T18:31:03.000504Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.000507Z node 1 :TX_COLUMNSHARD DEBUG: Create periodic stats pipe to 72057594046644480 at tablet 72075186224037888
2024-06-27T18:31:03.000534Z node 1 :TX_COLUMNSHARD DEBUG: There are stats for 1 tables
2024-06-27T18:31:03.000565Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.000573Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:03.000576Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:03.000578Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037888
2024-06-27T18:31:03.000580Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037888
2024-06-27T18:31:03.000585Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:03.000591Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:03.000598Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.000602Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.000606Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:03.000620Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tablet_id=72075186224037888;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:03.000687Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037888 Delete Blob DS:2181038080:[72075186224037888:1:1:3:0:646360:0]
2024-06-27T18:31:03.000694Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037888 Save Batch GenStep: 1:2 Blob count: 1
2024-06-27T18:31:03.000697Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=1;
2024-06-27T18:31:03.000752Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.000811Z node 1 :TX_COLUMNSHARD DEBUG: fline=task.cpp:21;event=free_resources;task_id=1;external_task_id=529548e4-613e212c-664de8e0-2dc921d4;mem=646162;cpu=0;
2024-06-27T18:31:03.001117Z node 1 :TX_COLUMNSHARD DEBUG: WriteIndex at tablet 72075186224037889
2024-06-27T18:31:03.001164Z node 1 :TX_COLUMNSHARD DEBUG: TxWriteIndex[9] (CS::INDEXATION) apply at tablet 72075186224037889
2024-06-27T18:31:03.001314Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager on execute at tablet 72075186224037889 Save Batch GenStep: 1:2 Blob count: 1
2024-06-27T18:31:03.001333Z node 1 :TX_COLUMNSHARD DEBUG: Index: tables 1 inserted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; compacted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; s-compacted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; inactive portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; evicted portions=0;blobs=0;rows=0;bytes=0;raw_bytes=0; column records 0 at tablet 72075186224037889
2024-06-27T18:31:03.001336Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;fline=columnshard__progress_tx.cpp:77;event=EnqueueProgressTx;tablet_id=72075186224037889;
2024-06-27T18:31:03.001522Z node 1 :TX_COLUMNSHARD DEBUG: Connected to 72057594046644480 at tablet 72075186224037888
72075186224037889 SHARING_REMOVE_LOCAL:DS:2181038080:[72075186224037889:1:1:3:0:644032:0] FROM 72075186224037889
72075186224037889 BORROWED_REMOVE_LOCAL:DS:2181038080:[72075186224037889:1:1:3:0:644032:0] FROM 72075186224037889
2024-06-27T18:31:03.004463Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;fline=abstract.cpp:45;event=WriteIndexComplete;type=CS::INDEXATION;success=1;
2024-06-27T18:31:03.004537Z node 1 :TX_COLUMNSHARD INFO: InsertTable. Prepared: 0 in 0 records, committed: 0 in 0 records at tablet 72075186224037889
2024-06-27T18:31:03.004541Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;fline=manager.cpp:14;event=unlock;process_id=CS::INDEXATION::73ea558f-bb475fc6-29f7825d-2d2cbf4c;
2024-06-27T18:31:03.004549Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=0;
2024-06-27T18:31:03.004554Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.004556Z node 1 :TX_COLUMNSHARD DEBUG: Create periodic stats pipe to 72057594046644480 at tablet 72075186224037889
2024-06-27T18:31:03.004571Z node 1 :TX_COLUMNSHARD DEBUG: There are stats for 1 tables
2024-06-27T18:31:03.004583Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.004589Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:03.004592Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:03.004594Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037889
2024-06-27T18:31:03.004595Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037889
2024-06-27T18:31:03.004599Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:03.004605Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:03.004610Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.004613Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.004615Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:03.004627Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tablet_id=72075186224037889;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:03.004677Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037889 Delete Blob DS:2181038080:[72075186224037889:1:1:3:0:644032:0]
2024-06-27T18:31:03.004682Z node 1 :TX_COLUMNSHARD DEBUG: BlobManager at tablet 72075186224037889 Save Batch GenStep: 1:2 Blob count: 1
2024-06-27T18:31:03.004686Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;fline=blobs_manager.cpp:46;event=OnBlobsKeep;count=1;
2024-06-27T18:31:03.004730Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_state=complete;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.004936Z node 1 :TX_COLUMNSHARD DEBUG: fline=task.cpp:21;event=free_resources;task_id=2;external_task_id=73ea558f-bb475fc6-29f7825d-2d2cbf4c;mem=643840;cpu=0;
2024-06-27T18:31:03.005496Z node 1 :TX_COLUMNSHARD DEBUG: Connected to 72057594046644480 at tablet 72075186224037889
2024-06-27T18:31:03.094106Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:03.094174Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:03.097699Z node 1 :TX_COLUMNSHARD DEBUG: EvScan txId: 281474976715662 scanId: 0 version: {1719513063000:max} readable: {1719513063142:max} at tablet 72075186224037889
2024-06-27T18:31:03.097714Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan prepare txId: 281474976715662 scanId: 0 at tablet 72075186224037889
2024-06-27T18:31:03.097802Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=program.cpp:486;program=Command {
  GroupBy {
    Aggregates {
      Column {
        Id: 3
      }
      Function {
        Id: 2
      }
    }
  }
}
Command {
  Projection {
    Columns {
      Id: 3
    }
  }
}
Version: 5
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/"
;
2024-06-27T18:31:03.102320Z node 1 :TX_COLUMNSHARD DEBUG: EvScan txId: 281474976715662 scanId: 0 version: {1719513063000:max} readable: {1719513063142:max} at tablet 72075186224037888
2024-06-27T18:31:03.102347Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan prepare txId: 281474976715662 scanId: 0 at tablet 72075186224037888
2024-06-27T18:31:03.102428Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=program.cpp:486;program=Command {
  GroupBy {
    Aggregates {
      Column {
        Id: 3
      }
      Function {
        Id: 2
      }
    }
  }
}
Command {
  Projection {
    Columns {
      Id: 3
    }
  }
}
Version: 5
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/"
;
2024-06-27T18:31:03.102972Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan range predicate. From key size: 1 To key size: 0 greater predicate over columns: () less predicate over columns: () at tablet 72075186224037889
2024-06-27T18:31:03.105362Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252371459751430:4350];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=678776;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:03.105399Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252371459751430:4350];fline=actor.cpp:29;event=result_resources;task_id=3;task=cpu=0;mem=678776;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:03.105402Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252371459751430:4350];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=678776;cpu=0;
2024-06-27T18:31:03.105405Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252371459751430:4350];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=3;mem=678776;cpu=0;
2024-06-27T18:31:03.106121Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252371459751430:4350];TabletId=72075186224037889;ScanId=0;TxId=281474976715662;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=3;external_task_id=;mem=678776;cpu=0;
2024-06-27T18:31:03.106328Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 2 at tablet 72075186224037889
2024-06-27T18:31:03.111747Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan range predicate. From key size: 1 To key size: 0 greater predicate over columns: () less predicate over columns: () at tablet 72075186224037888
2024-06-27T18:31:03.117710Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252371459751433:4352];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=681224;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:03.117845Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252371459751433:4352];fline=actor.cpp:29;event=result_resources;task_id=4;task=cpu=0;mem=681224;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:03.117849Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252371459751433:4352];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=681224;cpu=0;
2024-06-27T18:31:03.117854Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252371459751433:4352];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=4;mem=681224;cpu=0;
2024-06-27T18:31:03.118303Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252371459751433:4352];TabletId=72075186224037888;ScanId=0;TxId=281474976715662;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=4;external_task_id=;mem=681224;cpu=0;
2024-06-27T18:31:03.119096Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 2 at tablet 72075186224037888
2024-06-27T18:31:03.119832Z node 1 :KQP_RESOURCE_MANAGER WARN: KqpSnapshotManager: discarding snapshot; our snapshot: [step: 1719513063000, txId: 18446744073709551615] shutting down
2024-06-27T18:31:03.478637Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2024-06-27T18:31:03.478655Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.478789Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513063142 at tablet 72075186224037888
2024-06-27T18:31:03.478797Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:03.478803Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.478812Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.478820Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:03.478823Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:03.478826Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037888
2024-06-27T18:31:03.478827Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037888
2024-06-27T18:31:03.478831Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:03.478837Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:03.478843Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.478847Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.478851Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:03.478864Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:03.483056Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037889;
2024-06-27T18:31:03.483070Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.483169Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513063142 at tablet 72075186224037889
2024-06-27T18:31:03.483177Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:03.483182Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:03.483192Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:03.483200Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:03.483203Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:03.483205Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037889
2024-06-27T18:31:03.483207Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037889
2024-06-27T18:31:03.483210Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:03.483218Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:03.483223Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.483226Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:03.483229Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:03.483239Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:03.485654Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:03.485665Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:03.987928Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:03.987945Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
SHARING: 72075186224037889->72075186224037888
2024-06-27T18:31:04.127086Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:890;process=BlobsSharing;event=TEvProposeFromInitiator;
2024-06-27T18:31:04.132147Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;tx_no=11;tx_info=tx_propose_from_initiator;fline=blobs_sharing_ut.cpp:46;event=sharing_proposed;session_id=693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:04.481970Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2024-06-27T18:31:04.481986Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:04.482151Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513064000 at tablet 72075186224037888
2024-06-27T18:31:04.482156Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:04.482162Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:04.482175Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:04.482202Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:04.482205Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:04.482206Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037888
2024-06-27T18:31:04.482209Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037888
2024-06-27T18:31:04.482213Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:04.482220Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:04.482228Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:04.482231Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:04.482235Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:04.482254Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:04.486058Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037889;
2024-06-27T18:31:04.486067Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:04.486169Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513064000 at tablet 72075186224037889
2024-06-27T18:31:04.486173Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:04.486177Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:04.486184Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:04.486194Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:04.486196Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:04.486197Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037889
2024-06-27T18:31:04.486199Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037889
2024-06-27T18:31:04.486202Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:04.486228Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:04.486233Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:04.486235Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:04.486238Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:04.486252Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:04.494089Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:04.494102Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:04.997956Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:04.997978Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
WAIT_PROPOSING...
2024-06-27T18:31:05.126263Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:913;process=BlobsSharing;event=TEvConfirmFromInitiator;
2024-06-27T18:31:05.130093Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;tx_no=12;tx_info=tx_confirm_from_initiator;fline=blobs_sharing_ut.cpp:50;event=sharing_confirmed;session_id=693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.482793Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2024-06-27T18:31:05.482808Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:05.482941Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513065000 at tablet 72075186224037888
2024-06-27T18:31:05.482948Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:05.482955Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:05.482981Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;sessions=start;tablet_id=72075186224037888;info=dest_proto;fline=manager.cpp:9;event=lock;process_id=sharing_session:693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.482986Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;sessions=start;tablet_id=72075186224037888;info=dest_proto;fline=common.cpp:17;info=Start;
2024-06-27T18:31:05.483030Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:05.483044Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:05.483047Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:05.483048Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037888
2024-06-27T18:31:05.483050Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037888
2024-06-27T18:31:05.483054Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:05.483062Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:05.483067Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:05.483070Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:05.483074Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:05.483089Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:05.483279Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:929;process=BlobsSharing;event=TEvStartToSource;
2024-06-27T18:31:05.484779Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_no=11;tx_info=tx_start_to_source;fline=tx_start_to_source.cpp:15;info=TTxStartToSource::Complete;
2024-06-27T18:31:05.490061Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037889;
2024-06-27T18:31:05.490075Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:05.490193Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513065000 at tablet 72075186224037889
2024-06-27T18:31:05.490200Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:05.490206Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:05.490236Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;sessions=start;tablet_id=72075186224037889;info=source_proto;fline=manager.cpp:9;event=lock;process_id=sharing_session:693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.490242Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;sessions=start;tablet_id=72075186224037889;info=source_proto;fline=common.cpp:17;info=Start;
2024-06-27T18:31:05.490419Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:05.490432Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:05.490435Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:05.490437Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037889
2024-06-27T18:31:05.490439Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037889
2024-06-27T18:31:05.490443Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:05.490449Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:05.490465Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:05.490468Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:05.490472Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:05.490486Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:05.490724Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:944;process=BlobsSharing;event=TEvSendDataFromSource;
2024-06-27T18:31:05.490784Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:964;event=on_received_data;
2024-06-27T18:31:05.491300Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:1038;process=BlobsSharing;event=TEvApplyLinksModification;info=InitiatorTabletId: 72075186224037889
SessionId: "693e15f6-34b3-11ef-b296-34800b83e071"
PackIdx: 1
Task {
  TasksByStorage {
    TabletId: 72075186224037888
    StorageId: "__DEFAULT"
    InitOwner {
      Blobs {
        BlobId: "DS:2181038080:[72075186224037889:1:2:4:0:168096:0]"
        TabletId: 72075186224037889
      }
    }
    AddSharingLinks {
    }
    RemoveSharingLinks {
    }
  }
}
;
2024-06-27T18:31:05.491352Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1038;process=BlobsSharing;event=TEvApplyLinksModification;info=InitiatorTabletId: 72075186224037889
SessionId: "693e15f6-34b3-11ef-b296-34800b83e071"
PackIdx: 1
Task {
  TasksByStorage {
    TabletId: 72075186224037889
    StorageId: "__DEFAULT"
    InitOwner {
    }
    AddSharingLinks {
      Blobs {
        BlobId: "DS:2181038080:[72075186224037889:1:2:4:0:168096:0]"
        TabletIds: 72075186224037889
        TabletIds: 72075186224037888
      }
    }
    RemoveSharingLinks {
    }
  }
}
;
2024-06-27T18:31:05.492000Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:970;process=BlobsSharing;event=TEvAckDataToSource;
2024-06-27T18:31:05.492009Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=cursor.h:50;event=SourceAckData;pack=1;pack_ack=1;links_ready=0;links_waiting=2;
2024-06-27T18:31:05.492012Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:981;event=on_ack_data;
2024-06-27T18:31:05.492050Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1052;process=BlobsSharing;event=TEvApplyLinksModificationFinished;
2024-06-27T18:31:05.492054Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=cursor.h:60;event=SourceAckData;pack=1;pack_ack=1;links_ready=1;links_waiting=2;
2024-06-27T18:31:05.492057Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1064;event=on_change_links_finish;tablet_id=72075186224037888;
2024-06-27T18:31:05.492069Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1052;process=BlobsSharing;event=TEvApplyLinksModificationFinished;
2024-06-27T18:31:05.492071Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=cursor.h:60;event=SourceAckData;pack=1;pack_ack=1;links_ready=2;links_waiting=2;
2024-06-27T18:31:05.492074Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:1064;event=on_change_links_finish;tablet_id=72075186224037889;
2024-06-27T18:31:05.492329Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;tx_no=15;tx_info=ack_to_source_on_ack_links;fline=manager.cpp:14;event=unlock;process_id=sharing_session:693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.492434Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:1004;process=BlobsSharing;event=TEvFinishedFromSource;
2024-06-27T18:31:05.492440Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard_impl.cpp:1015;event=on_finished_data;
2024-06-27T18:31:05.499112Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:05.499140Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
__DEFAULT
    DS:2181038080:[72075186224037889:1:2:4:0:168096:0]
        O: 72075186224037889
        S: 72075186224037888,72075186224037889
2024-06-27T18:31:05.499449Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;tx_no=15;tx_info=;fline=manager.cpp:14;event=unlock;process_id=sharing_session:693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.499459Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037888;tx_no=15;tx_info=;fline=blobs_sharing_ut.cpp:54;event=sharing_finished;session_id=693e15f6-34b3-11ef-b296-34800b83e071;
2024-06-27T18:31:05.499595Z node 1 :TX_COLUMNSHARD NOTICE: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:987;process=BlobsSharing;event=TEvAckFinishToSource;
2024-06-27T18:31:05.499602Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard_impl.cpp:998;event=on_ack_finish;
2024-06-27T18:31:06.002056Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:06.002071Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
WAIT_CONFIRMED...
FALLBACK_ACTOR_LOGGING;priority=WARN;component=332;fline=controller.cpp:117;reason=non_trivial;
2024-06-27T18:31:06.143250Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:06.143322Z node 1 :KQP_EXECUTER ERROR: kqp_predictor.cpp:134 :user pool is undefined for executer tasks construction
2024-06-27T18:31:06.144311Z node 1 :TX_COLUMNSHARD DEBUG: EvScan txId: 281474976715664 scanId: 0 version: {1719513066000:max} readable: {1719513066187:max} at tablet 72075186224037888
2024-06-27T18:31:06.144327Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan prepare txId: 281474976715664 scanId: 0 at tablet 72075186224037888
2024-06-27T18:31:06.144398Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=program.cpp:486;program=Command {
  GroupBy {
    Aggregates {
      Column {
        Id: 3
      }
      Function {
        Id: 2
      }
    }
  }
}
Command {
  Projection {
    Columns {
      Id: 3
    }
  }
}
Version: 5
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/"
;
2024-06-27T18:31:06.144522Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan range predicate. From key size: 1 To key size: 0 greater predicate over columns: () less predicate over columns: () at tablet 72075186224037888
2024-06-27T18:31:06.146355Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=961728;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.146360Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=1920000;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.146364Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=958272;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.146370Z node 1 :TX_COLUMNSHARD DEBUG: EvScan txId: 281474976715664 scanId: 0 version: {1719513066000:max} readable: {1719513066187:max} at tablet 72075186224037889
2024-06-27T18:31:06.146377Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan prepare txId: 281474976715664 scanId: 0 at tablet 72075186224037889
2024-06-27T18:31:06.146415Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=program.cpp:486;program=Command {
  GroupBy {
    Aggregates {
      Column {
        Id: 3
      }
      Function {
        Id: 2
      }
    }
  }
}
Command {
  Projection {
    Columns {
      Id: 3
    }
  }
}
Version: 5
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/"
;
2024-06-27T18:31:06.146474Z node 1 :TX_COLUMNSHARD DEBUG: TTxScan range predicate. From key size: 1 To key size: 0 greater predicate over columns: () less predicate over columns: () at tablet 72075186224037889
2024-06-27T18:31:06.147211Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:29;event=result_resources;task_id=5;task=cpu=0;mem=961728;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.147218Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=961728;cpu=0;
2024-06-27T18:31:06.147231Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=5;mem=961728;cpu=0;
2024-06-27T18:31:06.147246Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:29;event=result_resources;task_id=6;task=cpu=0;mem=1920000;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.147247Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=1920000;cpu=0;
2024-06-27T18:31:06.147250Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=6;mem=1920000;cpu=0;
2024-06-27T18:31:06.147255Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=actor.cpp:29;event=result_resources;task_id=7;task=cpu=0;mem=958272;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.147256Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=958272;cpu=0;
2024-06-27T18:31:06.147258Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252384344653418:4367];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=7;mem=958272;cpu=0;
2024-06-27T18:31:06.147393Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=;fline=actor.cpp:48;task=agents_waiting=1;additional_info=();;
2024-06-27T18:31:06.147399Z node 1 :TX_COLUMNSHARD DEBUG: external_task_id=;fline=actor.cpp:48;task=agents_waiting=1;additional_info=();;
2024-06-27T18:31:06.148002Z node 1 :TX_COLUMNSHARD DEBUG: fline=task.cpp:110;event=OnDataReady;task=agents_waiting=0;additional_info=();;external_task_id=;
2024-06-27T18:31:06.148061Z node 1 :TX_COLUMNSHARD DEBUG: fline=task.cpp:110;event=OnDataReady;task=agents_waiting=0;additional_info=();;external_task_id=;
2024-06-27T18:31:06.163840Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252384344653421:4377];fline=actor.cpp:38;event=ask_resources;task=cpu=0;mem=678776;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.163897Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252384344653418:4367];TabletId=72075186224037888;ScanId=0;TxId=281474976715664;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=5;external_task_id=;mem=961728;cpu=0;
2024-06-27T18:31:06.164335Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252384344653421:4377];fline=actor.cpp:29;event=result_resources;task_id=8;task=cpu=0;mem=678776;external_task_id=;type=CS::SCAN_READ;priority=0;;
2024-06-27T18:31:06.164339Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252384344653421:4377];fline=task.cpp:9;event=resource_allocated;external_task_id=;mem=678776;cpu=0;
2024-06-27T18:31:06.164343Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252384344653421:4377];fline=task.cpp:40;event=allocate_resources;external_task_id=;task_id=8;mem=678776;cpu=0;
2024-06-27T18:31:06.164464Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252384344653421:4377];TabletId=72075186224037889;ScanId=0;TxId=281474976715664;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=8;external_task_id=;mem=678776;cpu=0;
2024-06-27T18:31:06.164959Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 3 at tablet 72075186224037889
2024-06-27T18:31:06.285414Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252384344653418:4367];TabletId=72075186224037888;ScanId=0;TxId=281474976715664;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=6;external_task_id=;mem=1920000;cpu=0;
2024-06-27T18:31:06.285799Z node 1 :TX_COLUMNSHARD DEBUG: SelfId=[1:7385252384344653418:4367];TabletId=72075186224037888;ScanId=0;TxId=281474976715664;ScanGen=1;method=produce result;fline=task.cpp:21;event=free_resources;task_id=7;external_task_id=;mem=958272;cpu=0;
2024-06-27T18:31:06.299350Z node 1 :TX_COLUMNSHARD DEBUG: Finished read cookie: 3 at tablet 72075186224037888
2024-06-27T18:31:06.299988Z node 1 :KQP_RESOURCE_MANAGER WARN: KqpSnapshotManager: discarding snapshot; our snapshot: [step: 1719513066000, txId: 18446744073709551615] shutting down
FALLBACK_ACTOR_LOGGING;priority=TRACE;component=2100;fline=native.cpp:67;event=parsing;size=640144;columns=1;
FALLBACK_ACTOR_LOGGING;priority=DEBUG;component=2100;fline=native.cpp:101;event=serialize;size=640144;columns=1;
2024-06-27T18:31:06.308469Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:06.308524Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:06.308556Z node 1 :TX_COLUMNSHARD DEBUG: Write (blob) 320296 bytes into pathId 3 {object=write_monitor;count=1;size=320296} at tablet 72075186224037888
2024-06-27T18:31:06.327898Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:06.327965Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=column_engine.cpp:22;total=202798018560;kff=0.3;
2024-06-27T18:31:06.328001Z node 1 :TX_COLUMNSHARD DEBUG: Write (blob) 319992 bytes into pathId 3 {object=write_monitor;count=1;size=319992} at tablet 72075186224037889
2024-06-27T18:31:06.966636Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;parent=[1:7385252367164783847:2043];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:06.966700Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037888;
2024-06-27T18:31:06.966710Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:06.966719Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];fline=columnshard.cpp:175;event=TEvPrivate::TEvPeriodicWakeup;tablet_id=72075186224037889;
2024-06-27T18:31:06.966721Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
VERIFY failed (2024-06-27T18:31:07.007693Z): tablet_id=72075186224037889;verification=false;fline=actor.h:66;
  ydb/library/actors/core/log.cpp:744
  ~TVerifyFormattedRecordWriter(): requirement false failed
2024-06-27T18:31:07.006006Z node 1 :TX_COLUMNSHARD_SCAN WARN: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet=72075186224037889;fline=tx_internal_scan.cpp:54;event=TTxScan failed;reason=no metadata;
2024-06-27T18:31:07.007444Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513067000 at tablet 72075186224037889
2024-06-27T18:31:07.007453Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:07.007457Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:07.007467Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:07.007479Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:07.007482Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:07.007484Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037889
2024-06-27T18:31:07.007486Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037889
2024-06-27T18:31:07.007491Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:07.007497Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:07.007502Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:07.007506Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:07.007510Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:07.007525Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037889;self_id=[1:7385252367164783847:2043];tablet_id=72075186224037889;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:07.007590Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;parent=[1:7385252367164783843:2042];fline=actor.cpp:33;event=skip_flush_writing;
2024-06-27T18:31:07.007613Z node 1 :TX_COLUMNSHARD DEBUG: Notified by mediator time cast with PlanStep# 1719513067000 at tablet 72075186224037888
2024-06-27T18:31:07.007617Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:509;event=EnqueueBackgroundActivities;periodic=1;
2024-06-27T18:31:07.007619Z node 1 :TX_COLUMNSHARD DEBUG: Send periodic stats.
2024-06-27T18:31:07.007624Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:685;event=start_indexation_tasks;insert_overload_size=0;
2024-06-27T18:31:07.007630Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=storage.cpp:30;event=no_granules;
2024-06-27T18:31:07.007632Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:298;event=no granules for start compaction;
2024-06-27T18:31:07.007633Z node 1 :TX_COLUMNSHARD DEBUG: Compaction not started: cannot prepare compaction at tablet 72075186224037888
2024-06-27T18:31:07.007634Z node 1 :TX_COLUMNSHARD DEBUG: ActiveCompactions: 0 at tablet 72075186224037888
2024-06-27T18:31:07.007637Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:335;event=StartCleanup;portions_count=0;
2024-06-27T18:31:07.007641Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:406;event=StartCleanup;portions_count=0;portions_prepared=0;drop=0;skip=0;
2024-06-27T18:31:07.007643Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:795;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:07.007645Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:818;background=cleanup;skip_reason=no_changes;
2024-06-27T18:31:07.007647Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=column_engine_logs.cpp:419;event=StartTtl;external=0;
2024-06-27T18:31:07.007656Z node 1 :TX_COLUMNSHARD DEBUG: tablet_id=72075186224037888;self_id=[1:7385252367164783843:2042];tablet_id=72075186224037888;fline=columnshard_impl.cpp:757;background=ttl;skip_reason=no_changes;
2024-06-27T18:31:07.383199Z node 1 :METADATA_PROVIDER ERROR: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[1:7385252367164783252:4098];send_to=[0:7307199536658146131:7762515];
2024-06-27T18:31:07.383229Z node 1 :METADATA_PROVIDER ERROR: fline=accessor_snapshot_base.cpp:68;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2024-06-27T18:31:07.383262Z node 1 :TX_COLUMNSHARD_SCAN WARN: tablet=72075186224037888;fline=tx_internal_scan.cpp:54;event=TTxScan failed;reason=no metadata;
0. /-S/util/system/yassert.cpp:83: NPrivate::InternalPanicImpl(int, char const*, char const*, int, int, int, TBasicStringBuf<char, std::__y1::char_traits<char>>, char const*, unsigned long) @ 0x11BEA33C
1. /-S/util/system/yassert.cpp:55: NPrivate::Panic(NPrivate::TStaticBuf const&, int, char const*, char const*, char const*, ...) @ 0x11BE2086
2. /-S/ydb/library/actors/core/log.cpp:744: NActors::TVerifyFormattedRecordWriter::~TVerifyFormattedRecordWriter() @ 0x12AE3EF3
3. /-S/ydb/core/tx/columnshard/data_reader/actor.h:66: NKikimr::NOlap::NDataReader::TActor::HandleExecute(TAutoPtr<NActors::TEventHandle<NKikimr::NKqp::TEvKqpCompute::TEvScanError>, TDelete>&) @ 0x1CEA1D88
4. /-S/ydb/core/tx/columnshard/data_reader/actor.h:82: NKikimr::NOlap::NDataReader::TActor::StateFunc(TAutoPtr<NActors::IEventHandle, TDelete>&) @ 0x1CEA05F2
5. /-S/ydb/library/actors/core/executor_thread.cpp:251: NActors::TGenericExecutorThread::TProcessingResult NActors::TGenericExecutorThread::Execute<NActors::TMailboxTable::THTSwapMailbox>(NActors::TMailboxTable::THTSwapMailbox*, unsigned int, bool) @ 0x12AC3AB7
6. /-S/ydb/library/actors/core/executor_thread.cpp:439: NActors::TGenericExecutorThread::ProcessExecutorPool(NActors::IExecutorPool*)::$_0::operator()(unsigned int, bool) const @ 0x12ABB211
7. /-S/ydb/library/actors/core/executor_thread.cpp:492: NActors::TGenericExecutorThread::ProcessExecutorPool(NActors::IExecutorPool*) @ 0x12ABAC09
8. /-S/ydb/library/actors/core/executor_thread.cpp:523: NActors::TExecutorThread::ThreadProc() @ 0x12ABBAAF
9. /-S/util/system/thread.cpp:244: (anonymous namespace)::TPosixThread::ThreadProxy(void*) @ 0x11BEFB4E
10. ??:0: ?? @ 0x7F8FDA7A5AC2
11. ??:0: ?? @ 0x7F8FDA83784F