2025-10-26 18:43:30,896 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:43:30,896 - INFO - ya.test - pytest_runtest_setup: test_replication
2025-10-26 18:43:30,896 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 18:43:30,896 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 18:43:30,897 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 18:43:30,897 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 18:43:30,944 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/24046
2025-10-26 18:43:30,945 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/iof4/000471/r3tmp/kikimr__nzk4u3dj
2025-10-26 18:43:30,971 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/23131
2025-10-26 18:43:30,972 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/10301
2025-10-26 18:43:30,972 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/13748
2025-10-26 18:43:30,973 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd
2025-10-26 18:43:30,973 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/iof4/000471/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 18:43:30,973 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/iof4/000471/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_twdnxw9l.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=23131 --mon-port=10301 --ic-port=24046
2025-10-26 18:43:30,974 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:23131/1, disk_size 68719476736
2025-10-26 18:43:30,974 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/iof4/000471/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_twdnxw9l.log', '--yaml-config=/home/runner/.ya/build/build_root/iof4/000471/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=23131', '--mon-port=10301', '--ic-port=24046')' with input '/home/runner/.ya/build/build_root/iof4/000471/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/stdin' in '/home/runner/.ya/build/build_root/iof4/000471/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 18:43:30,975 - DEBUG - ya.test - execute: Command pid: 1675690
2025-10-26 18:43:30,976 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:23131/1
2025-10-26 18:43:32,641 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd
2025-10-26 18:43:32,642 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23131', '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: 24046\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 18:43:32,642 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 18:43:32,643 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 18:43:32,643 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23131', '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: 24046\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 18:43:32,644 - DEBUG - ya.test - execute: Command pid: 1676235
2025-10-26 18:43:32,938 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) rc: 0
2025-10-26 18:43:32,938 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) elapsed time (sec): 0.29309701919555664
2025-10-26 18:43:32,938 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) idrss: 0
2025-10-26 18:43:32,938 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) inblock: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) isrss: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) ixrss: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) majflt: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) maxrss: 523212
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) minflt: 19022
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) msgrcv: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) msgsnd: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) nivcsw: 0
2025-10-26 18:43:32,939 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) nsignals: 0
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) nswap: 0
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) nvcsw: 128
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) oublock: 8
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) stime: 0.158485
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) utime: 0.091129
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) wtime: 0.294
2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-26 18:43:32,940 - DEBUG - ya.test - _save_outputs: Command (pid 1676235) errors:

2025-10-26 18:43:32,941 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd
2025-10-26 18:43:32,941 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23131', '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:43:32,942 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 18:43:32,943 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 18:43:32,943 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:23131', '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:43:32,944 - DEBUG - ya.test - execute: Command pid: 1676300
2025-10-26 18:43:33,311 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) rc: 0
2025-10-26 18:43:33,311 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) elapsed time (sec): 0.3669583797454834
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) idrss: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) inblock: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) isrss: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) ixrss: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) majflt: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) maxrss: 523196
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) minflt: 19024
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) msgrcv: 0
2025-10-26 18:43:33,312 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) msgsnd: 0
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) nivcsw: 1
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) nsignals: 0
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) nswap: 0
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) nvcsw: 139
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) oublock: 8
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) stime: 0.235815
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) utime: 0.089447
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) wtime: 0.368
2025-10-26 18:43:33,313 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 18:43:33,314 - DEBUG - ya.test - _save_outputs: Command (pid 1676300) errors:

2025-10-26 18:43:33,326 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 18:43:33,337 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_self_heal.py::TestEnableSelfHeal, test_name: test_replication)
2025-10-26 18:43:40,339 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:23131/1
2025-10-26 18:43:40,339 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/iof4/000471/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/iof4/000471/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_twdnxw9l.log', '--yaml-config=/home/runner/.ya/build/build_root/iof4/000471/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=23131', '--mon-port=10301', '--ic-port=24046')' with input '/home/runner/.ya/build/build_root/iof4/000471/ydb/tests/functional/blobstorage/test-results/py3test/testing_out_stuff/test_self_heal/chunk0/stdin' in '/home/runner/.ya/build/build_root/iof4/000471/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 18:43:40,341 - DEBUG - ya.test - execute: Command pid: 1677204
2025-10-26 18:43:40,341 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:23131/1
2025-10-26 18:43:40,342 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 18:43:41,343 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-ml4ixexc2y'
2025-10-26 18:43:41,344 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-10-26 18:43:41,344 - 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 18:43:41,344 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-10-26 18:43:41,345 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/iof4/000471/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 18:43:41,345 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.1677204' in '/home/runner/.ya/build/build_root/iof4/000471/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 18:43:41,346 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/1): [] (mismatched samples: logfile_twdnxw9l.log)
2025-10-26 18:43:41,346 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-10-26 18:43:41,346 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-10-26 18:43:41,346 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:23131/1
2025-10-26 18:43:41,347 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/13748
2025-10-26 18:43:41,347 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/10301
2025-10-26 18:43:41,347 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/23131
2025-10-26 18:43:41,347 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/24046
