2025-10-26 18:45:13,261 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:45:13,261 - INFO - ya.test - pytest_runtest_setup: test_when_write_in_new_channel_then_can_read_from_tablet
2025-10-26 18:45:13,261 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:45:13,261 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 18:45:13,262 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 18:45:13,262 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 18:45:13,307 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/14803
2025-10-26 18:45:13,307 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/iof4/00046a/r3tmp/kikimr__plffg3jh
2025-10-26 18:45:13,332 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/11138
2025-10-26 18:45:13,333 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/4505
2025-10-26 18:45:13,333 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/11782
2025-10-26 18:45:13,334 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd
2025-10-26 18:45:13,334 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/iof4/00046a/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 18:45:13,334 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/iof4/00046a/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_ke2c73hp.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=11138 --mon-port=4505 --ic-port=14803
2025-10-26 18:45:13,334 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:11138/1, disk_size 68719476736
2025-10-26 18:45:13,335 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/iof4/00046a/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_ke2c73hp.log', '--yaml-config=/home/runner/.ya/build/build_root/iof4/00046a/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=11138', '--mon-port=4505', '--ic-port=14803')' with input '/home/runner/.ya/build/build_root/iof4/00046a/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_tablet_channel_migration/chunk1/stdin' in '/home/runner/.ya/build/build_root/iof4/00046a/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 18:45:13,336 - DEBUG - ya.test - execute: Command pid: 1696193
2025-10-26 18:45:13,336 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11138/1
2025-10-26 18:45:15,009 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd
2025-10-26 18:45:15,009 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11138', '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: 14803\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 18:45:15,010 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 18:45:15,010 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 18:45:15,010 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11138', '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: 14803\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 18:45:15,011 - DEBUG - ya.test - execute: Command pid: 1696467
2025-10-26 18:45:15,331 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) rc: 0
2025-10-26 18:45:15,331 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) elapsed time (sec): 0.3189377784729004
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) idrss: 0
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) inblock: 0
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) isrss: 0
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) ixrss: 0
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) majflt: 0
2025-10-26 18:45:15,332 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) maxrss: 523192
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) minflt: 19024
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) msgrcv: 0
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) msgsnd: 0
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) nivcsw: 1
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) nsignals: 0
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) nswap: 0
2025-10-26 18:45:15,333 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) nvcsw: 134
2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) oublock: 8
2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) stime: 0.18839999999999998
2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) utime: 0.088187
2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) wtime: 0.32
2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 18:45:15,334 - DEBUG - ya.test - _save_outputs: Command (pid 1696467) errors:

2025-10-26 18:45:15,335 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd
2025-10-26 18:45:15,336 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11138', '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:45:15,337 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 18:45:15,337 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 18:45:15,337 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/00046a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:11138', '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:45:15,338 - DEBUG - ya.test - execute: Command pid: 1696531
2025-10-26 18:45:15,694 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) rc: 0
2025-10-26 18:45:15,695 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) elapsed time (sec): 0.35564422607421875
2025-10-26 18:45:15,695 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) idrss: 0
2025-10-26 18:45:15,695 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) inblock: 0
2025-10-26 18:45:15,695 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) isrss: 0
2025-10-26 18:45:15,695 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) ixrss: 0
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) majflt: 0
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) maxrss: 523196
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) minflt: 19024
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) msgrcv: 0
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) msgsnd: 0
2025-10-26 18:45:15,696 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) nivcsw: 5
2025-10-26 18:45:15,697 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) nsignals: 0
2025-10-26 18:45:15,697 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) nswap: 0
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) nvcsw: 126
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) oublock: 8
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) stime: 0.22577899999999998
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) utime: 0.08466699999999999
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) wtime: 0.357
2025-10-26 18:45:15,698 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 18:45:15,699 - DEBUG - ya.test - _save_outputs: Command (pid 1696531) errors:

2025-10-26 18:45:15,714 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 18:45:15,722 - 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 18:45:15,727 - INFO - root - predicate: Result from callable = 
================================================================================
Status: 1
TabletStateInfo {
  TabletId: 72075186224037892
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037896
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037889
  ChangeTime: 1761504315727
  State: Candidate
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037893
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037897
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037890
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037894
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037888
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037891
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}
TabletStateInfo {
  TabletId: 72075186224037895
  ChangeTime: 1761504315724
  State: Discover
  Generation: 1
  Type: KeyValue
  Host: "localhost"
  Leader: true
  FollowerId: 0
  TenantId {
    SchemeShard: 72075186232723360
    PathId: 1
  }
  HiveId: 72057594037968897
}

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

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

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

2025-10-26 18:55:16,217 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11138/1
2025-10-26 18:55:16,218 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/11782
2025-10-26 18:55:16,218 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/4505
2025-10-26 18:55:16,218 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/11138
2025-10-26 18:55:16,218 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/14803
