2025-10-26 15:47:56,287 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:47:56,288 - INFO - ya.test - pytest_runtest_setup: test_when_write_in_new_channel_then_can_read_from_tablet
2025-10-26 15:47:56,288 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:47:56,288 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:47:56,288 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:47:56,288 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:47:56,332 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/15820
2025-10-26 15:47:56,332 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/0006aa/r3tmp/kikimr__q6gks8iw
2025-10-26 15:47:56,355 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/23656
2025-10-26 15:47:56,356 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/12100
2025-10-26 15:47:56,356 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/65140
2025-10-26 15:47:56,357 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd
2025-10-26 15:47:56,357 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_in_new_channel_then_can_read_from_tablet/kikimr_configs"
2025-10-26 15:47:56,357 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_in_new_channel_then_can_read_from_tablet/node_1/logfile_t2zpqg32.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=23656 --mon-port=12100 --ic-port=15820
2025-10-26 15:47:56,357 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:23656/1, disk_size 68719476736
2025-10-26 15:47:56,358 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_in_new_channel_then_can_read_from_tablet/node_1/logfile_t2zpqg32.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_in_new_channel_then_can_read_from_tablet/kikimr_configs/config.yaml', '--grpc-port=23656', '--mon-port=12100', '--ic-port=15820')' with input '/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/stdin' in '/home/runner/.ya/build/build_root/muiu/0006aa/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_in_new_channel_then_can_read_from_tablet/node_1' (no wait)
2025-10-26 15:47:56,359 - DEBUG - ya.test - execute: Command pid: 1537177
2025-10-26 15:47:56,359 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:23656/1
2025-10-26 15:47:57,966 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd
2025-10-26 15:47:57,966 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23656', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 15820\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:47:57,967 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:47:57,967 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:47:57,967 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23656', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 15820\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:47:57,968 - DEBUG - ya.test - execute: Command pid: 1537435
2025-10-26 15:47:58,258 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) rc: 0
2025-10-26 15:47:58,258 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) elapsed time (sec): 0.2896878719329834
2025-10-26 15:47:58,258 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) idrss: 0
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) inblock: 0
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) isrss: 0
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) ixrss: 0
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) majflt: 0
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) maxrss: 523192
2025-10-26 15:47:58,259 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) minflt: 19022
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) msgrcv: 0
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) msgsnd: 0
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) nivcsw: 2
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) nsignals: 0
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) nswap: 0
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) nvcsw: 137
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) oublock: 8
2025-10-26 15:47:58,260 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) stime: 0.15123799999999998
2025-10-26 15:47:58,261 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) utime: 0.095562
2025-10-26 15:47:58,261 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) wtime: 0.291
2025-10-26 15:47:58,261 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:47:58,261 - DEBUG - ya.test - _save_outputs: Command (pid 1537435) errors:

2025-10-26 15:47:58,262 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd
2025-10-26 15:47:58,262 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23656', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-26 15:47:58,263 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:47:58,263 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:47:58,264 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/0006aa/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23656', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:47:58,264 - DEBUG - ya.test - execute: Command pid: 1537485
2025-10-26 15:47:58,565 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) rc: 0
2025-10-26 15:47:58,565 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) elapsed time (sec): 0.29976391792297363
2025-10-26 15:47:58,565 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) idrss: 0
2025-10-26 15:47:58,565 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) inblock: 0
2025-10-26 15:47:58,565 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) isrss: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) ixrss: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) majflt: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) maxrss: 523208
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) minflt: 19023
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) msgrcv: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) msgsnd: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) nivcsw: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) nsignals: 0
2025-10-26 15:47:58,566 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) nswap: 0
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) nvcsw: 129
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) oublock: 8
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) stime: 0.17318999999999998
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) utime: 0.08458099999999999
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) wtime: 0.301
2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:47:58,567 - DEBUG - ya.test - _save_outputs: Command (pid 1537485) errors:

2025-10-26 15:47:58,578 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:47:58,585 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_tablet_channel_migration.py::TestChannelsOps, test_name: test_when_write_in_new_channel_then_can_read_from_tablet)
2025-10-26 15:47:58,589 - INFO - root - predicate: Result from callable = 
================================================================================
Status: 1
TabletStateInfo {
  TabletId: 72075186224037892
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037896
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037889
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037893
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037897
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037890
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037894
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037888
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037895
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037891
  ChangeTime: 1761493678587
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}

================================================================================

2025-10-26 15:48:00,591 - INFO - root - predicate: Result from callable = 
================================================================================
Status: 1
TabletStateInfo {
  TabletId: 72075186224037892
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037896
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037889
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037893
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037897
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037890
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037894
  ChangeTime: 1761493678593
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037888
  ChangeTime: 1761493678595
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037895
  ChangeTime: 1761493678594
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037891
  ChangeTime: 1761493678593
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}

================================================================================

2025-10-26 15:57:59,380 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:23656/1
2025-10-26 15:57:59,380 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/65140
2025-10-26 15:57:59,380 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/12100
2025-10-26 15:57:59,380 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/23656
2025-10-26 15:57:59,381 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/15820
