2025-10-26 15:59:19,996 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:59:19,996 - INFO - ya.test - pytest_runtest_setup: test_replication
2025-10-26 15:59:19,996 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:59:19,997 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:59:19,997 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 15:59:19,997 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 15:59:20,045 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/22008
2025-10-26 15:59:20,046 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/muiu/0006ea/r3tmp/kikimr__xntquni1
2025-10-26 15:59:20,072 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/5637
2025-10-26 15:59:20,073 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/23439
2025-10-26 15:59:20,073 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/22706
2025-10-26 15:59:20,074 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd
2025-10-26 15:59:20,074 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/kikimr_configs"
2025-10-26 15:59:20,074 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1/logfile_sgctbkxl.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=5637 --mon-port=23439 --ic-port=22008
2025-10-26 15:59:20,074 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5637/1, disk_size 68719476736
2025-10-26 15:59:20,075 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1/logfile_sgctbkxl.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/kikimr_configs/config.yaml', '--grpc-port=5637', '--mon-port=23439', '--ic-port=22008')' with input '/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/stdin' in '/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1' (no wait)
2025-10-26 15:59:20,076 - DEBUG - ya.test - execute: Command pid: 1637907
2025-10-26 15:59:20,076 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5637/1
2025-10-26 15:59:21,712 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd
2025-10-26 15:59:21,713 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5637', '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: 22008\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:59:21,713 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:59:21,714 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:59:21,714 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5637', '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: 22008\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:59:21,715 - DEBUG - ya.test - execute: Command pid: 1638118
2025-10-26 15:59:22,060 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) rc: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) elapsed time (sec): 0.34476518630981445
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) idrss: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) inblock: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) isrss: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) ixrss: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) majflt: 0
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) maxrss: 523204
2025-10-26 15:59:22,061 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) minflt: 19024
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) msgrcv: 0
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) msgsnd: 0
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) nivcsw: 2
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) nsignals: 0
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) nswap: 0
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) nvcsw: 134
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) oublock: 8
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) stime: 0.215468
2025-10-26 15:59:22,062 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) utime: 0.08537399999999999
2025-10-26 15:59:22,063 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) wtime: 0.346
2025-10-26 15:59:22,063 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:59:22,063 - DEBUG - ya.test - _save_outputs: Command (pid 1638118) errors:

2025-10-26 15:59:22,063 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd
2025-10-26 15:59:22,064 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5637', '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:59:22,064 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:59:22,065 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:59:22,065 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5637', '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:59:22,065 - DEBUG - ya.test - execute: Command pid: 1638168
2025-10-26 15:59:22,461 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) rc: 0
2025-10-26 15:59:22,462 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) elapsed time (sec): 0.3952357769012451
2025-10-26 15:59:22,462 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) idrss: 0
2025-10-26 15:59:22,462 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) inblock: 0
2025-10-26 15:59:22,462 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) isrss: 0
2025-10-26 15:59:22,462 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) ixrss: 0
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) majflt: 0
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) maxrss: 523200
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) minflt: 19021
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) msgrcv: 0
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) msgsnd: 0
2025-10-26 15:59:22,463 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) nivcsw: 1
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) nsignals: 0
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) nswap: 0
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) nvcsw: 126
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) oublock: 8
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) stime: 0.295757
2025-10-26 15:59:22,464 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) utime: 0.065723
2025-10-26 15:59:22,465 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) wtime: 0.396
2025-10-26 15:59:22,465 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 15:59:22,465 - DEBUG - ya.test - _save_outputs: Command (pid 1638168) errors:

2025-10-26 15:59:22,479 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:59:22,490 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_self_heal.py::TestEnableSelfHeal, test_name: test_replication)
2025-10-26 15:59:29,491 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5637/1
2025-10-26 15:59:29,492 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/muiu/0006ea/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1/logfile_sgctbkxl.log', '--yaml-config=/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/kikimr_configs/config.yaml', '--grpc-port=5637', '--mon-port=23439', '--ic-port=22008')' with input '/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/stdin' in '/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1' (no wait)
2025-10-26 15:59:29,494 - DEBUG - ya.test - execute: Command pid: 1639558
2025-10-26 15:59:29,494 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5637/1
2025-10-26 15:59:29,496 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 15:59:30,496 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-zwq4uro7ay'
2025-10-26 15:59:30,497 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-10-26 15:59:30,497 - DEBUG - library.python.cores - recover_core_dump_file: core_pattern = '|/usr/share/apport/apport -p%p -s%s -c%c -d%d -P%P -u%u -g%g -F%F -- %E'
2025-10-26 15:59:30,498 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-10-26 15:59:30,498 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1) permission mask: 0o40755 (expected: 0o41777 (0o40000-dir, 0o1000-sticky bit))
2025-10-26 15:59:30,498 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.1639558' in '/home/runner/.ya/build/build_root/muiu/0006ea/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/testing_out_stuff/test_self_heal.py__TestEnableSelfHeal__test_replication/node_1'
2025-10-26 15:59:30,499 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/1): [] (mismatched samples: logfile_sgctbkxl.log)
2025-10-26 15:59:30,499 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-10-26 15:59:30,499 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-10-26 15:59:30,499 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5637/1
2025-10-26 15:59:30,499 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/22706
2025-10-26 15:59:30,500 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/23439
2025-10-26 15:59:30,500 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/5637
2025-10-26 15:59:30,500 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/22008
