2025-10-26 19:24:22,825 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:24:22,825 - INFO - ya.test - pytest_runtest_setup: test_dstool_add_group_http
2025-10-26 19:24:22,825 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:24:22,826 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 19:24:22,940 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/16185
2025-10-26 19:24:22,942 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:24:22,943 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-26 19:24:22,944 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 19:24:22,944 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 19:24:22,945 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/70hy/0010b7/r3tmp/kikimr__aqjq7993
2025-10-26 19:24:22,996 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/14141
2025-10-26 19:24:22,997 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/2505
2025-10-26 19:24:22,998 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/16077
2025-10-26 19:24:22,999 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd
2025-10-26 19:24:22,999 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/testing_out_stuff/test_canonical_records.py__test_dstool_add_group_http/kikimr_configs"
2025-10-26 19:24:23,000 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/testing_out_stuff/test_canonical_records.py__test_dstool_add_group_http/node_1/logfile__v2kaokt.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=14141 --mon-port=2505 --ic-port=16185
2025-10-26 19:24:23,000 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:14141/1, disk_size 68719476736
2025-10-26 19:24:23,001 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:2:64 on node localhost:14141/1, disk_size 68719476736
2025-10-26 19:24:23,002 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/testing_out_stuff/test_canonical_records.py__test_dstool_add_group_http/node_1/logfile__v2kaokt.log', '--yaml-config=/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/testing_out_stuff/test_canonical_records.py__test_dstool_add_group_http/kikimr_configs/config.yaml', '--grpc-port=14141', '--mon-port=2505', '--ic-port=16185')' with input '/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/stdin' in '/home/runner/.ya/build/build_root/70hy/0010b7/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk3/testing_out_stuff/test_canonical_records.py__test_dstool_add_group_http/node_1' (no wait)
2025-10-26 19:24:23,005 - DEBUG - ya.test - execute: Command pid: 1851586
2025-10-26 19:24:23,006 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14141/1
2025-10-26 19:24:27,333 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd
2025-10-26 19:24:27,334 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14141', '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: 16185\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 19:24:27,335 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 19:24:27,336 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 19:24:27,336 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14141', '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: 16185\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 19:24:27,350 - DEBUG - ya.test - execute: Command pid: 1852013
2025-10-26 19:24:28,290 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) rc: 0
2025-10-26 19:24:28,290 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) elapsed time (sec): 0.9387722015380859
2025-10-26 19:24:28,290 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) idrss: 0
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) inblock: 0
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) isrss: 0
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) ixrss: 0
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) majflt: 0
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) maxrss: 582624
2025-10-26 19:24:28,291 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) minflt: 46573
2025-10-26 19:24:28,292 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) msgrcv: 0
2025-10-26 19:24:28,292 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) msgsnd: 0
2025-10-26 19:24:28,292 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) nivcsw: 118
2025-10-26 19:24:28,293 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) nsignals: 0
2025-10-26 19:24:28,293 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) nswap: 0
2025-10-26 19:24:28,293 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) nvcsw: 726
2025-10-26 19:24:28,295 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) oublock: 8
2025-10-26 19:24:28,296 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) stime: 0.25426699999999997
2025-10-26 19:24:28,296 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) utime: 0.595708
2025-10-26 19:24:28,296 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) wtime: 0.953
2025-10-26 19:24:28,296 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 19:24:28,297 - DEBUG - ya.test - _save_outputs: Command (pid 1852013) errors:

2025-10-26 19:24:28,298 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd
2025-10-26 19:24:28,299 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14141', '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:24:28,300 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 19:24:28,300 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 19:24:28,301 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14141', '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:24:28,302 - DEBUG - ya.test - execute: Command pid: 1852167
2025-10-26 19:24:29,146 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) rc: 0
2025-10-26 19:24:29,147 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) elapsed time (sec): 0.8434104919433594
2025-10-26 19:24:29,147 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) idrss: 0
2025-10-26 19:24:29,147 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) inblock: 0
2025-10-26 19:24:29,148 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) isrss: 0
2025-10-26 19:24:29,148 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) ixrss: 0
2025-10-26 19:24:29,148 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) majflt: 0
2025-10-26 19:24:29,148 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) maxrss: 582724
2025-10-26 19:24:29,149 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) minflt: 46543
2025-10-26 19:24:29,149 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) msgrcv: 0
2025-10-26 19:24:29,149 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) msgsnd: 0
2025-10-26 19:24:29,150 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) nivcsw: 65
2025-10-26 19:24:29,150 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) nsignals: 0
2025-10-26 19:24:29,150 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) nswap: 0
2025-10-26 19:24:29,151 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) nvcsw: 661
2025-10-26 19:24:29,151 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) oublock: 8
2025-10-26 19:24:29,151 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) stime: 0.273203
2025-10-26 19:24:29,151 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) utime: 0.5186379999999999
2025-10-26 19:24:29,152 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) wtime: 0.845
2025-10-26 19:24:29,152 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 19:24:29,153 - DEBUG - ya.test - _save_outputs: Command (pid 1852167) errors:

2025-10-26 19:24:29,281 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 19:24:29,311 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_canonical_records.py, test_name: test_dstool_add_group_http)
2025-10-26 19:24:29,313 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb-dstool.out
2025-10-26 19:24:29,314 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb-dstool.err
2025-10-26 19:24:29,315 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/dstool/ydb-dstool', '--endpoint', 'http://localhost:2505', '--mon-port', '2505', '--http', 'pool', 'list', '--format', 'json']' in 'None' (waiting)
2025-10-26 19:24:29,322 - DEBUG - ya.test - execute: Command pid: 1852365
2025-10-26 19:24:31,440 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) rc: 0
2025-10-26 19:24:31,441 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) elapsed time (sec): 2.1175220012664795
2025-10-26 19:24:31,442 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) idrss: 0
2025-10-26 19:24:31,442 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) inblock: 8
2025-10-26 19:24:31,442 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) isrss: 0
2025-10-26 19:24:31,443 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) ixrss: 0
2025-10-26 19:24:31,443 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) majflt: 0
2025-10-26 19:24:31,443 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) maxrss: 566728
2025-10-26 19:24:31,444 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) minflt: 78592
2025-10-26 19:24:31,444 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) msgrcv: 0
2025-10-26 19:24:31,444 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) msgsnd: 0
2025-10-26 19:24:31,445 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) nivcsw: 153
2025-10-26 19:24:31,445 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) nsignals: 0
2025-10-26 19:24:31,445 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) nswap: 0
2025-10-26 19:24:31,445 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) nvcsw: 3
2025-10-26 19:24:31,446 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) oublock: 8
2025-10-26 19:24:31,446 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) stime: 0.32181
2025-10-26 19:24:31,446 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) utime: 1.7189999999999999
2025-10-26 19:24:31,447 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) wtime: 2.125
2025-10-26 19:24:31,447 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) output:
[
  {
    "BoxId:PoolId": "[1:1]",
    "PoolName": "dynamic_storage_pool:1",
    "ErasureSpecies": "none",
    "Kind": "hdd",
    "Groups_TOTAL": 2,
    "VDisks_TOTAL": 2
  }
]
2025-10-26 19:24:31,447 - DEBUG - ya.test - _save_outputs: Command (pid 1852365) errors:

2025-10-26 19:25:01,470 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb-dstool.out.0
2025-10-26 19:25:01,471 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb-dstool.err.0
2025-10-26 19:25:01,472 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010b7/ydb/apps/dstool/ydb-dstool', '--endpoint', 'http://localhost:2505', '--mon-port', '2505', '--http', 'group', 'add', '--pool-name', 'dynamic_storage_pool:1', '--groups', '1']' in 'None' (waiting)
2025-10-26 19:25:01,474 - DEBUG - ya.test - execute: Command pid: 1857346
2025-10-26 19:25:03,590 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) rc: 0
2025-10-26 19:25:03,591 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) elapsed time (sec): 2.1155402660369873
2025-10-26 19:25:03,591 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) idrss: 0
2025-10-26 19:25:03,591 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) inblock: 0
2025-10-26 19:25:03,592 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) isrss: 0
2025-10-26 19:25:03,592 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) ixrss: 0
2025-10-26 19:25:03,592 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) majflt: 0
2025-10-26 19:25:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) maxrss: 567496
2025-10-26 19:25:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) minflt: 80077
2025-10-26 19:25:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) msgrcv: 0
2025-10-26 19:25:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) msgsnd: 0
2025-10-26 19:25:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) nivcsw: 76
2025-10-26 19:25:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) nsignals: 0
2025-10-26 19:25:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) nswap: 0
2025-10-26 19:25:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) nvcsw: 3
2025-10-26 19:25:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) oublock: 8
2025-10-26 19:25:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) stime: 0.313538
2025-10-26 19:25:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) utime: 1.7661319999999998
2025-10-26 19:25:03,596 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) wtime: 2.117
2025-10-26 19:25:03,596 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) output:
┌──────────────┬────────────────────────┬─────────────────┬───────────────────────┬────────────────────────┬───────────────────────┬───────────┐
│ BoxId:PoolId │ PoolName               │ Affected PDisks │ Static slots on PDisk │ VSlots on PDisk before │ VSlots on PDisk after │ Result    │
├──────────────┼────────────────────────┼─────────────────┼───────────────────────┼────────────────────────┼───────────────────────┼───────────┤
│ [1:1]        │ dynamic_storage_pool:1 │ 1               │ 1                     │ 2                      │ 2                     │ unchanged │
│ [1:1]        │ dynamic_storage_pool:1 │ 1               │ 0                     │ 1                      │ 2                     │ increased │
└──────────────┴────────────────────────┴─────────────────┴───────────────────────┴────────────────────────┴───────────────────────┴───────────┘

2025-10-26 19:25:03,597 - DEBUG - ya.test - _save_outputs: Command (pid 1857346) errors:

2025-10-26 19:25:34,224 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 19:25:34,225 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:25:46,232 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:14141/1
2025-10-26 19:25:46,233 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/16077
2025-10-26 19:25:46,234 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/2505
2025-10-26 19:25:46,234 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/14141
2025-10-26 19:25:46,235 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/16185
2025-10-26 19:25:46,965 - DEBUG - ya.test - _verify_test: Verifying [test_canonical_records.test_dstool_add_group_http in ydb/tests/functional/audit] canonical results
2025-10-26 19:25:46,969 - DEBUG - ya.test - verify: Test test_canonical_records.test_dstool_add_group_http status: good
