2025-11-14T10:36:32.107382Z node 10 :METADATA_PROVIDER WARN: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[10:7572528105843404072:2156];send_to=[0:7307199536658146131:7762515];
2025-11-14T10:36:32.111238Z node 10 :METADATA_PROVIDER ERROR: fline=accessor_snapshot_base.cpp:69;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/haex/0022b5/r3tmp/tmpkq1T6v/pdisk_1.dat
2025-11-14T10:36:32.332232Z node 10 :IMPORT WARN: Table profiles were not loaded
TServer::EnableGrpc on GrpcPort 10348, node 10
2025-11-14T10:36:32.491126Z node 10 :HIVE WARN: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2025-11-14T10:36:32.491297Z node 10 :HIVE WARN: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2025-11-14T10:36:32.563856Z node 10 :HIVE WARN: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Connecting -> Connected
2025-11-14T10:36:32.625510Z node 10 :NET_CLASSIFIER WARN: distributable config is empty, broken or outdated, will use file: (empty maybe)
2025-11-14T10:36:32.625544Z node 10 :NET_CLASSIFIER WARN: will try to initialize from file: (empty maybe)
2025-11-14T10:36:32.625552Z node 10 :NET_CLASSIFIER WARN: failed to initialize from file: (empty maybe)
2025-11-14T10:36:32.625719Z node 10 :NET_CLASSIFIER ERROR: got bad distributable configuration
TClient is connected to server localhost:12825
WaitRootIsUp 'Root'...
TClient::Ls request: Root
TClient::Ls response: Status: 1
StatusCode: SUCCESS
SchemeStatus: 0
PathDescription {
  Self {
    Name: "Root"
    PathId: 1
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 1
    CreateStep: 0
    ParentPathId: 1
    PathState: EPathStateNoChanges
    Owner: "root@builtin"
    ACL: ""
    EffectiveACL: ""
    PathVersion: 2
    PathSubType: EPathSubTypeEmpty
    Version {
      GeneralVersion: 2
      ACLVersion: 0
      EffectiveACLVersion: 0
      UserAttrsVersion: 1
      ChildrenVersion: 1
      SubDomainVersion: 0
      SecurityStateVersion: 0
    }
    ChildrenExist: false
  }
  Children {
    Name: ".sys"
    PathId: 18446744073709551615
    SchemeshardId: 72057594046644480
    PathType: EPathTypeDir
    CreateFinished: true
    CreateTxId: 0
    CreateStep: 0
    ParentPathId: 18446744073709551615
  }
  DomainDescription {
    SchemeShardId_Depricated: 72057594046644480
    PathId_Depricated: 1
    ProcessingParams {
      Version: 0
      Pl...
(TRUNCATED)
WaitRootIsUp 'Root' success.
2025-11-14T10:36:33.040952Z node 10 :FLAT_TX_SCHEMESHARD WARN: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976715657:0, at schemeshard:  72057594046644480
waiting...
2025-11-14T10:36:33.110712Z node 10 :TX_CONVEYOR ERROR: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2025-11-14T10:36:35.810388Z node 10 :FLAT_TX_SCHEMESHARD WARN: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976715658:0, at schemeshard:  72057594046644480
2025-11-14T10:36:35.845974Z node 10 :TX_DATASHARD INFO: TDataShard::OnActivateExecutor: tablet 72075186224037888 actor [10:7572528118728306903:2319]
2025-11-14T10:36:35.846243Z node 10 :TX_DATASHARD DEBUG: TxInitSchema.Execute
2025-11-14T10:36:35.880770Z node 10 :TX_DATASHARD DEBUG: TxInitSchema.Complete
2025-11-14T10:36:35.880874Z node 10 :TX_DATASHARD DEBUG: TDataShard::TTxInit::Execute
2025-11-14T10:36:35.882811Z node 10 :TX_DATASHARD DEBUG: LoadChangeRecords: QueueSize: 0, at tablet: 72075186224037888
2025-11-14T10:36:35.882870Z node 10 :TX_DATASHARD DEBUG: LoadLockChangeRecords at tablet: 72075186224037888
2025-11-14T10:36:35.882924Z node 10 :TX_DATASHARD DEBUG: LoadChangeRecordCommits at tablet: 72075186224037888
2025-11-14T10:36:35.883283Z node 10 :TX_DATASHARD DEBUG: TDataShard::TTxInit::Complete
2025-11-14T10:36:35.883345Z node 10 :TX_DATASHARD DEBUG: TDataShard::TTxInitRestored::Execute
2025-11-14T10:36:35.883391Z node 10 :TX_DATASHARD DEBUG: DataShard 72075186224037888 persisting started state actor id [10:7572528118728306926:2319] in generation 1
2025-11-14T10:36:35.893219Z node 10 :TX_DATASHARD DEBUG: TDataShard::TTxInitRestored::Complete
2025-11-14T10:36:35.893278Z node 10 :TX_DATASHARD INFO: Switched to work state WaitScheme tabletId 72075186224037888
2025-11-14T10:36:35.893361Z node 10 :TX_DATASHARD DEBUG: 72075186224037888 not sending time cast registration request in state WaitScheme: missing processing params
2025-11-14T10:36:35.893397Z node 10 :TX_DATASHARD DEBUG: Change sender created: at tablet: 72075186224037888, actorId: [10:7572528118728306932:2320]
2025-11-14T10:36:35.893414Z node 10 :TX_DATASHARD DEBUG: Trying to activate change sender: at tablet: 72075186224037888
2025-11-14T10:36:35.893429Z node 10 :TX_DATASHARD INFO: Cannot activate change sender: at tablet: 72075186224037888, state: WaitScheme
2025-11-14T10:36:35.893448Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-11-14T10:36:35.893574Z node 10 :TX_DATASHARD DEBUG: TTxCheckInReadSets::Execute at 72075186224037888
2025-11-14T10:36:35.893644Z node 10 :TX_DATASHARD DEBUG: TTxCheckInReadSets::Complete found 0 read sets to remove in 72075186224037888
2025-11-14T10:36:35.893673Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Execute at 72075186224037888
2025-11-14T10:36:35.893692Z node 10 :TX_DATASHARD DEBUG: GetNextActiveOp at 72075186224037888 active 0 active planned 0 immediate 0 planned 0
2025-11-14T10:36:35.893713Z node 10 :TX_DATASHARD INFO: No tx to execute at 72075186224037888 TxInFly 0
2025-11-14T10:36:35.893736Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Complete at 72075186224037888
2025-11-14T10:36:35.894810Z node 10 :TX_DATASHARD DEBUG: Server connected at leader tablet# 72075186224037888, clientId# [10:7572528118728306898:2660], serverId# [10:7572528118728306929:2680], sessionId# [0:0:0]
2025-11-14T10:36:35.894915Z node 10 :TX_DATASHARD DEBUG: TTxProposeTransactionBase::Execute at 72075186224037888
2025-11-14T10:36:35.895163Z node 10 :TX_DATASHARD DEBUG: Propose scheme transaction at tablet 72075186224037888 txId 281474976715658 ssId 72057594046644480 seqNo 2:1
2025-11-14T10:36:35.895229Z node 10 :TX_DATASHARD DEBUG: Prepared scheme transaction txId 281474976715658 at tablet 72075186224037888
2025-11-14T10:36:35.897849Z node 10 :TX_DATASHARD DEBUG: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037888
2025-11-14T10:36:35.904711Z node 10 :TX_DATASHARD DEBUG: TTxProposeTransactionBase::Complete at 72075186224037888
2025-11-14T10:36:35.904780Z node 10 :TX_DATASHARD DEBUG: 72075186224037888 not sending time cast registration request in state WaitScheme
2025-11-14T10:36:35.917943Z node 10 :TX_DATASHARD DEBUG: Server connected at leader tablet# 72075186224037888, clientId# [10:7572528118728306954:2698], serverId# [10:7572528118728306957:2701], sessionId# [0:0:0]
2025-11-14T10:36:35.939958Z node 10 :TX_DATASHARD DEBUG: Planned transaction txId 281474976715658 at step 1763116595961 at tablet 72075186224037888 { Transactions { TxId: 281474976715658 AckTo { RawX1: 0 RawX2: 0 } } Step: 1763116595961 MediatorID: 72057594046382081 TabletID: 72075186224037888 }
2025-11-14T10:36:35.940004Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-11-14T10:36:35.940175Z node 10 :TX_DATASHARD DEBUG: Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at datashard 72075186224037888
2025-11-14T10:36:35.940256Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Execute at 72075186224037888
2025-11-14T10:36:35.940276Z node 10 :TX_DATASHARD DEBUG: GetNextActiveOp at 72075186224037888 active 0 active planned 0 immediate 0 planned 1
2025-11-14T10:36:35.940316Z node 10 :TX_DATASHARD DEBUG: Found ready operation [1763116595961:281474976715658] in PlanQueue unit at 72075186224037888
2025-11-14T10:36:35.940589Z node 10 :TX_DATASHARD DEBUG: LoadTxDetails at 72075186224037888 loaded tx from db 1763116595961:281474976715658 keys extracted: 0
2025-11-14T10:36:35.941080Z node 10 :TX_DATASHARD DEBUG: GetNextActiveOp at 72075186224037888 (dry run) active 1 active planned 1 immediate 0 planned 1
2025-11-14T10:36:35.941348Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Execute at 72075186224037888
2025-11-14T10:36:35.941401Z node 10 :TX_DATASHARD INFO: Trying to CREATE TABLE at 72075186224037888 tableId# [OwnerId: 72057594046644480, LocalPathId: 2] schema version# 1
2025-11-14T10:36:35.941995Z node 10 :TX_DATASHARD INFO: Send registration request to time cast Ready tabletId 72075186224037888 mediators count is 1 coordinators count is 1 buckets per mediator 2
2025-11-14T10:36:35.942497Z node 10 :TX_DATASHARD DEBUG: GetNextActiveOp at 72075186224037888 (dry run) active 0 active planned 0 immediate 0 planned 0
2025-11-14T10:36:35.943968Z node 10 :TX_DATASHARD DEBUG: Got TEvMediatorTimecast::TEvRegisterTabletResult at 72075186224037888 time 1763116595960
2025-11-14T10:36:35.944001Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-11-14T10:36:35.945287Z node 10 :TX_DATASHARD DEBUG: Sending '{TEvPlanStepAccepted TabletId# 72075186224037888 step# 1763116595961}
2025-11-14T10:36:35.945355Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Complete at 72075186224037888
2025-11-14T10:36:35.945805Z node 10 :TX_DATASHARD DEBUG: Got TEvMediatorTimecast::TEvSubscribeReadStepResult at 72075186224037888 coordinator 72057594046316545 last step 0 next step 1763116595968
2025-11-14T10:36:35.946830Z node 10 :TX_DATASHARD DEBUG: TTxProgressTransaction::Complete at 72075186224037888
2025-11-14T10:36:35.946877Z node 10 :TX_DATASHARD DEBUG: Trying to activate change sender: at tablet: 72075186224037888
2025-11-14T10:36:35.946915Z node 10 :TX_DATASHARD INFO: Change sender activated: at tablet: 72075186224037888
2025-11-14T10:36:35.946976Z node 10 :TX_DATASHARD DEBUG: Complete [1763116595961 : 281474976715658] from 72075186224037888 at tablet 72075186224037888 send result to client [10:7572528105843404293:2200], exec latency: 1 ms, propose latency: 5 ms
2025-11-14T10:36:35.947029Z node 10 :TX_DATASHARD INFO: 72075186224037888 Sending notify to schemeshard 72057594046644480 txId 281474976715658 state Ready TxInFly 0
2025-11-14T10:36:35.947076Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
2025-11-14T10:36:35.953444Z node 10 :TX_DATASHARD DEBUG: Handle TEvSchemaChangedResult 281474976715658  datashard 72075186224037888 state Ready
2025-11-14T10:36:35.953530Z node 10 :TX_DATASHARD DEBUG: 72075186224037888 Got TEvSchemaChangedResult from SS at 72075186224037888
2025-11-14T10:36:35.996729Z node 10 :TX_DATASHARD DEBUG: Server connected at leader tablet# 72075186224037888, clientId# [10:7572528118728307008:2742], serverId# [10:7572528118728307009:2743], sessionId# [0:0:0]
2025-11-14T10:36:35.996853Z node 10 :TX_DATASHARD INFO: TTxDirectBase(36) Execute: at tablet# 72075186224037888
2025-11-14T10:36:36.005657Z node 10 :TX_DATASHARD INFO: TTxDirectBase(36) Complete: at tablet# 72075186224037888
2025-11-14T10:36:36.005718Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
SUCCESS
Upsert done: 0.041152s
2025-11-14T10:36:36.432014Z node 10 :KQP_EXECUTER ERROR: TxId: 281474976715659. Ctx: { TraceId: 01ka0z0ese0pppsj7rw8zjhdtc, Database: , DatabaseId: /Root, SessionId: ydb://session/3?node_id=10&id=ZDczYmQ3NGEtYmNhM2QyM2UtYzY1NzU4NjMtOWZhMzBhYzM=, CurrentExecutionId: , CustomerSuppliedId: , PoolId: }. Database not set, use /Root
SUCCESS
count returned 100 rows
Negative (format is not CSV): SCHEME_ERROR
Negative (format is not CSV): BAD_REQUEST
Negative (less columns): BAD_REQUEST
Negative (reordered columns): BAD_REQUEST
2025-11-14T10:36:36.505249Z node 10 :TX_DATASHARD INFO: TTxDirectBase(36) Execute: at tablet# 72075186224037888
2025-11-14T10:36:36.510731Z node 10 :TX_DATASHARD INFO: TTxDirectBase(36) Complete: at tablet# 72075186224037888
Reordered columns (with header): SUCCESS
2025-11-14T10:36:36.510766Z node 10 :TX_DATASHARD DEBUG: [CdcStreamHeartbeat] Emit heartbeats: at tablet# 72075186224037888
