2025-10-26 18:41:59,752 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:41:59,752 - INFO - ya.test - pytest_runtest_setup: test_when_write_and_change_tablet_channel_then_can_read_from_tablet
2025-10-26 18:41:59,752 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:41:59,752 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 18:41:59,753 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 18:41:59,753 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 18:41:59,812 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/29909
2025-10-26 18:41:59,812 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/iof4/000477/r3tmp/kikimr__e_hbbx01
2025-10-26 18:41:59,842 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/17658
2025-10-26 18:41:59,842 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/10266
2025-10-26 18:41:59,842 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/21479
2025-10-26 18:41:59,843 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd
2025-10-26 18:41:59,843 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_and_change_tablet_channel_then_can_read_from_tablet/kikimr_configs"
2025-10-26 18:41:59,844 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_and_change_tablet_channel_then_can_read_from_tablet/node_1/logfile_n9nif9p_.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=17658 --mon-port=10266 --ic-port=29909
2025-10-26 18:41:59,844 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17658/1, disk_size 68719476736
2025-10-26 18:41:59,845 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_and_change_tablet_channel_then_can_read_from_tablet/node_1/logfile_n9nif9p_.log', '--yaml-config=/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_and_change_tablet_channel_then_can_read_from_tablet/kikimr_configs/config.yaml', '--grpc-port=17658', '--mon-port=10266', '--ic-port=29909')' with input '/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/stdin' in '/home/runner/.ya/build/build_root/iof4/000477/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk0/testing_out_stuff/test_tablet_channel_migration.py__TestChannelsOps__test_when_write_and_change_tablet_channel_then_can_read_from_tablet/node_1' (no wait)
2025-10-26 18:41:59,847 - DEBUG - ya.test - execute: Command pid: 1664362
2025-10-26 18:41:59,847 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17658/1
2025-10-26 18:42:01,488 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd
2025-10-26 18:42:01,489 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17658', '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: 29909\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 18:42:01,489 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 18:42:01,490 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 18:42:01,490 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17658', '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: 29909\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 18:42:01,491 - DEBUG - ya.test - execute: Command pid: 1664642
2025-10-26 18:42:01,846 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) rc: 0
2025-10-26 18:42:01,847 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) elapsed time (sec): 0.35497546195983887
2025-10-26 18:42:01,847 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) idrss: 0
2025-10-26 18:42:01,847 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) inblock: 0
2025-10-26 18:42:01,847 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) isrss: 0
2025-10-26 18:42:01,848 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) ixrss: 0
2025-10-26 18:42:01,848 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) majflt: 0
2025-10-26 18:42:01,848 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) maxrss: 523192
2025-10-26 18:42:01,848 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) minflt: 19024
2025-10-26 18:42:01,848 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) msgrcv: 0
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) msgsnd: 0
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) nivcsw: 0
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) nsignals: 0
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) nswap: 0
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) nvcsw: 137
2025-10-26 18:42:01,849 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) oublock: 8
2025-10-26 18:42:01,850 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) stime: 0.208069
2025-10-26 18:42:01,850 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) utime: 0.104034
2025-10-26 18:42:01,850 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) wtime: 0.356
2025-10-26 18:42:01,850 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 18:42:01,850 - DEBUG - ya.test - _save_outputs: Command (pid 1664642) errors:

2025-10-26 18:42:01,851 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd
2025-10-26 18:42:01,852 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17658', '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 18:42:01,853 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 18:42:01,853 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 18:42:01,853 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/000477/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17658', '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 18:42:01,854 - DEBUG - ya.test - execute: Command pid: 1664760
2025-10-26 18:42:02,196 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) rc: 0
2025-10-26 18:42:02,196 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) elapsed time (sec): 0.34039902687072754
2025-10-26 18:42:02,196 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) idrss: 0
2025-10-26 18:42:02,197 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) inblock: 0
2025-10-26 18:42:02,197 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) isrss: 0
2025-10-26 18:42:02,197 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) ixrss: 0
2025-10-26 18:42:02,197 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) majflt: 0
2025-10-26 18:42:02,197 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) maxrss: 523204
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) minflt: 19024
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) msgrcv: 0
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) msgsnd: 0
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) nivcsw: 4
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) nsignals: 0
2025-10-26 18:42:02,198 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) nswap: 0
2025-10-26 18:42:02,199 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) nvcsw: 137
2025-10-26 18:42:02,199 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) oublock: 8
2025-10-26 18:42:02,199 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) stime: 0.22497999999999999
2025-10-26 18:42:02,199 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) utime: 0.07231499999999999
2025-10-26 18:42:02,199 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) wtime: 0.342
2025-10-26 18:42:02,200 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 18:42:02,200 - DEBUG - ya.test - _save_outputs: Command (pid 1664760) errors:

2025-10-26 18:42:02,216 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 18:42:02,225 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_tablet_channel_migration.py::TestChannelsOps, test_name: test_when_write_and_change_tablet_channel_then_can_read_from_tablet)
2025-10-26 18:42:02,230 - INFO - root - predicate: Result from callable = 
================================================================================
Status: 1
TabletStateInfo {
  TabletId: 72075186224037892
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037889
  ChangeTime: 1761504122229
  State: Candidate
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037896
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037897
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037893
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037890
  ChangeTime: 1761504122229
  State: BlockBlobStorage
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037894
  ChangeTime: 1761504122229
  State: Candidate
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037888
  ChangeTime: 1761504122229
  State: Candidate
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037895
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037891
  ChangeTime: 1761504122227
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}

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

2025-10-26 18:42:04,232 - INFO - root - predicate: Result from callable = 
================================================================================
Status: 1
TabletStateInfo {
  TabletId: 72075186224037892
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037889
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037896
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037897
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037893
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037890
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037894
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037888
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037895
  ChangeTime: 1761504122235
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037891
  ChangeTime: 1761504122234
  State: Active
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}

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

2025-10-26 18:52:02,948 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:17658/1
2025-10-26 18:52:02,949 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/21479
2025-10-26 18:52:02,949 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/10266
2025-10-26 18:52:02,949 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/17658
2025-10-26 18:52:02,949 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/29909
