2025-10-26 19:25:51,815 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:25:51,816 - INFO - ya.test - pytest_runtest_setup: test_restart_pdisk
2025-10-26 19:25:51,816 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:25:51,816 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 19:25:51,919 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/3476
2025-10-26 19:25:51,921 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:25:51,922 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-26 19:25:51,922 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 19:25:51,923 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 19:25:51,923 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/70hy/0010a9/r3tmp/kikimr__ppyp9kkd
2025-10-26 19:25:51,975 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/10180
2025-10-26 19:25:51,976 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/21061
2025-10-26 19:25:51,980 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/62573
2025-10-26 19:25:51,981 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd
2025-10-26 19:25:51,982 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/testing_out_stuff/test_canonical_records.py__test_restart_pdisk/kikimr_configs"
2025-10-26 19:25:51,982 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/testing_out_stuff/test_canonical_records.py__test_restart_pdisk/node_1/logfile_jwrsr7pd.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=10180 --mon-port=21061 --ic-port=3476
2025-10-26 19:25:51,983 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10180/1, disk_size 68719476736
2025-10-26 19:25:51,983 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:2:64 on node localhost:10180/1, disk_size 68719476736
2025-10-26 19:25:51,984 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/testing_out_stuff/test_canonical_records.py__test_restart_pdisk/node_1/logfile_jwrsr7pd.log', '--yaml-config=/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/testing_out_stuff/test_canonical_records.py__test_restart_pdisk/kikimr_configs/config.yaml', '--grpc-port=10180', '--mon-port=21061', '--ic-port=3476')' with input '/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/stdin' in '/home/runner/.ya/build/build_root/70hy/0010a9/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk7/testing_out_stuff/test_canonical_records.py__test_restart_pdisk/node_1' (no wait)
2025-10-26 19:25:51,987 - DEBUG - ya.test - execute: Command pid: 1869281
2025-10-26 19:25:51,988 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10180/1
2025-10-26 19:25:56,363 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd
2025-10-26 19:25:56,364 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10180', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n    Drive {\n      Path: "SectorMap:2:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 3476\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 19:25:56,365 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 19:25:56,365 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 19:25:56,366 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10180', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n    Drive {\n      Path: "SectorMap:2:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 3476\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 19:25:56,374 - DEBUG - ya.test - execute: Command pid: 1870767
2025-10-26 19:25:57,289 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) rc: 0
2025-10-26 19:25:57,289 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) elapsed time (sec): 0.9141519069671631
2025-10-26 19:25:57,290 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) idrss: 0
2025-10-26 19:25:57,290 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) inblock: 0
2025-10-26 19:25:57,290 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) isrss: 0
2025-10-26 19:25:57,290 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) ixrss: 0
2025-10-26 19:25:57,291 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) majflt: 0
2025-10-26 19:25:57,291 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) maxrss: 582632
2025-10-26 19:25:57,291 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) minflt: 46627
2025-10-26 19:25:57,292 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) msgrcv: 0
2025-10-26 19:25:57,292 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) msgsnd: 0
2025-10-26 19:25:57,293 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) nivcsw: 77
2025-10-26 19:25:57,293 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) nsignals: 0
2025-10-26 19:25:57,294 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) nswap: 0
2025-10-26 19:25:57,294 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) nvcsw: 573
2025-10-26 19:25:57,294 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) oublock: 8
2025-10-26 19:25:57,295 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) stime: 0.226189
2025-10-26 19:25:57,295 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) utime: 0.606661
2025-10-26 19:25:57,295 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) wtime: 0.922
2025-10-26 19:25:57,296 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 19:25:57,296 - DEBUG - ya.test - _save_outputs: Command (pid 1870767) errors:

2025-10-26 19:25:57,298 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd
2025-10-26 19:25:57,299 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10180', '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 19:25:57,299 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 19:25:57,300 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 19:25:57,301 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010a9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10180', '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 19:25:57,302 - DEBUG - ya.test - execute: Command pid: 1870965
2025-10-26 19:25:58,230 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) rc: 0
2025-10-26 19:25:58,231 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) elapsed time (sec): 0.9274699687957764
2025-10-26 19:25:58,231 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) idrss: 0
2025-10-26 19:25:58,231 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) inblock: 0
2025-10-26 19:25:58,231 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) isrss: 0
2025-10-26 19:25:58,231 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) ixrss: 0
2025-10-26 19:25:58,232 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) majflt: 0
2025-10-26 19:25:58,232 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) maxrss: 582812
2025-10-26 19:25:58,232 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) minflt: 46672
2025-10-26 19:25:58,232 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) msgrcv: 0
2025-10-26 19:25:58,233 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) msgsnd: 0
2025-10-26 19:25:58,233 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) nivcsw: 105
2025-10-26 19:25:58,233 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) nsignals: 0
2025-10-26 19:25:58,233 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) nswap: 0
2025-10-26 19:25:58,234 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) nvcsw: 949
2025-10-26 19:25:58,234 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) oublock: 8
2025-10-26 19:25:58,234 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) stime: 0.24562699999999998
2025-10-26 19:25:58,234 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) utime: 0.575785
2025-10-26 19:25:58,236 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) wtime: 0.929
2025-10-26 19:25:58,236 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 19:25:58,237 - DEBUG - ya.test - _save_outputs: Command (pid 1870965) errors:

2025-10-26 19:25:58,339 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 19:25:58,371 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_canonical_records.py, test_name: test_restart_pdisk)
2025-10-26 19:25:58,376 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:21061
2025-10-26 19:25:58,379 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:21061 "GET /actors/pdisks/ HTTP/1.1" 200 288
2025-10-26 19:26:28,420 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:21061
2025-10-26 19:26:28,425 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:21061 "POST /actors/pdisks/pdisk000000001 HTTP/1.1" 403 88
2025-10-26 19:26:28,430 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:21061
2025-10-26 19:26:28,441 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:21061 "POST /actors/pdisks/pdisk000000001 HTTP/1.1" 200 23
2025-10-26 19:26:59,066 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 19:26:59,067 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:27:06,069 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10180/1
2025-10-26 19:27:06,070 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/62573
2025-10-26 19:27:06,070 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/21061
2025-10-26 19:27:06,071 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/10180
2025-10-26 19:27:06,071 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/3476
2025-10-26 19:27:06,639 - DEBUG - ya.test - _verify_test: Verifying [test_canonical_records.test_restart_pdisk in ydb/tests/functional/audit] canonical results
2025-10-26 19:27:06,653 - DEBUG - ya.test - verify: Test test_canonical_records.test_restart_pdisk status: good
