2025-10-26 19:27:34,641 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:27:34,642 - INFO - ya.test - pytest_runtest_setup: test_dml_through_http
2025-10-26 19:27:34,642 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 19:27:34,642 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 19:27:34,759 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/10517
2025-10-26 19:27:34,762 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:27:34,763 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-26 19:27:34,764 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: All test params = {}
2025-10-26 19:27:34,764 - INFO - ydb.tests.library.harness.kikimr_cluster - kikimr_cluster_factory: Starting standalone YDB cluster
2025-10-26 19:27:34,765 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/70hy/0010ae/r3tmp/kikimr__1kn040qc
2025-10-26 19:27:34,817 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/20044
2025-10-26 19:27:34,818 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/20712
2025-10-26 19:27:34,819 - DEBUG - library.python.filelock - acquire: Acquiring filelock (blocking=False): /home/runner/.ya/build/port_sync_dir/16023
2025-10-26 19:27:34,820 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd
2025-10-26 19:27:34,821 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/testing_out_stuff/test_canonical_records.py__test_dml_through_http/kikimr_configs"
2025-10-26 19:27:34,821 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd server --suppress-version-check --node=1 --log-file-name=/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/testing_out_stuff/test_canonical_records.py__test_dml_through_http/node_1/logfile_dy3qfq0w.log --yaml-config=$CFG_DIR_PATH/config.yaml --grpc-port=20044 --mon-port=20712 --ic-port=10517
2025-10-26 19:27:34,822 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:20044/1, disk_size 68719476736
2025-10-26 19:27:34,822 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:2:64 on node localhost:20044/1, disk_size 68719476736
2025-10-26 19:27:34,823 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/testing_out_stuff/test_canonical_records.py__test_dml_through_http/node_1/logfile_dy3qfq0w.log', '--yaml-config=/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/testing_out_stuff/test_canonical_records.py__test_dml_through_http/kikimr_configs/config.yaml', '--grpc-port=20044', '--mon-port=20712', '--ic-port=10517')' with input '/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/stdin' in '/home/runner/.ya/build/build_root/70hy/0010ae/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_canonical_records/chunk2/testing_out_stuff/test_canonical_records.py__test_dml_through_http/node_1' (no wait)
2025-10-26 19:27:34,826 - DEBUG - ya.test - execute: Command pid: 1896006
2025-10-26 19:27:34,828 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20044/1
2025-10-26 19:27:39,011 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd
2025-10-26 19:27:39,012 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20044', '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: 10517\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 19:27:39,013 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 19:27:39,013 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 19:27:39,014 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20044', '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: 10517\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 19:27:39,016 - DEBUG - ya.test - execute: Command pid: 1897172
2025-10-26 19:27:39,946 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) rc: 0
2025-10-26 19:27:39,947 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) elapsed time (sec): 0.929039716720581
2025-10-26 19:27:39,947 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) idrss: 0
2025-10-26 19:27:39,947 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) inblock: 0
2025-10-26 19:27:39,948 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) isrss: 0
2025-10-26 19:27:39,948 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) ixrss: 0
2025-10-26 19:27:39,948 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) majflt: 0
2025-10-26 19:27:39,948 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) maxrss: 582556
2025-10-26 19:27:39,949 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) minflt: 46569
2025-10-26 19:27:39,949 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) msgrcv: 0
2025-10-26 19:27:39,950 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) msgsnd: 0
2025-10-26 19:27:39,951 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) nivcsw: 79
2025-10-26 19:27:39,951 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) nsignals: 0
2025-10-26 19:27:39,951 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) nswap: 0
2025-10-26 19:27:39,952 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) nvcsw: 724
2025-10-26 19:27:39,952 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) oublock: 8
2025-10-26 19:27:39,952 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) stime: 0.270875
2025-10-26 19:27:39,952 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) utime: 0.604595
2025-10-26 19:27:39,953 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) wtime: 0.932
2025-10-26 19:27:39,953 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 19:27:39,953 - DEBUG - ya.test - _save_outputs: Command (pid 1897172) errors:

2025-10-26 19:27:39,955 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd
2025-10-26 19:27:39,956 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20044', '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:27:39,957 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 19:27:39,958 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 19:27:39,958 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70hy/0010ae/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20044', '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:27:39,959 - DEBUG - ya.test - execute: Command pid: 1897456
2025-10-26 19:27:40,864 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) rc: 0
2025-10-26 19:27:40,865 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) elapsed time (sec): 0.9042108058929443
2025-10-26 19:27:40,865 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) idrss: 0
2025-10-26 19:27:40,866 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) inblock: 0
2025-10-26 19:27:40,866 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) isrss: 0
2025-10-26 19:27:40,866 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) ixrss: 0
2025-10-26 19:27:40,866 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) majflt: 0
2025-10-26 19:27:40,867 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) maxrss: 582792
2025-10-26 19:27:40,867 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) minflt: 46614
2025-10-26 19:27:40,867 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) msgrcv: 0
2025-10-26 19:27:40,867 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) msgsnd: 0
2025-10-26 19:27:40,868 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) nivcsw: 51
2025-10-26 19:27:40,868 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) nsignals: 0
2025-10-26 19:27:40,868 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) nswap: 0
2025-10-26 19:27:40,868 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) nvcsw: 735
2025-10-26 19:27:40,869 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) oublock: 8
2025-10-26 19:27:40,869 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) stime: 0.297542
2025-10-26 19:27:40,869 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) utime: 0.556146
2025-10-26 19:27:40,870 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) wtime: 0.906
2025-10-26 19:27:40,870 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-26 19:27:40,870 - DEBUG - ya.test - _save_outputs: Command (pid 1897456) errors:

2025-10-26 19:27:40,972 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 19:27:41,005 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_canonical_records.py, test_name: test_dml_through_http)
2025-10-26 19:28:11,036 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:20712
2025-10-26 19:28:11,041 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:20712 "POST /viewer/json/query?schema=multi&base64=false HTTP/1.1" 403 88
2025-10-26 19:28:11,047 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:20712
2025-10-26 19:28:11,196 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:20712 "POST /viewer/json/query?schema=multi&base64=false HTTP/1.1" 200 1470
2025-10-26 19:28:41,876 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-26 19:28:41,877 - INFO - ydb.tests.library.harness.ydb_fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_canonical_records
2025-10-26 19:28:48,879 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:20044/1
2025-10-26 19:28:48,880 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/16023
2025-10-26 19:28:48,881 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/20712
2025-10-26 19:28:48,881 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/20044
2025-10-26 19:28:48,882 - DEBUG - library.python.filelock - release: Ensuring filelock released: /home/runner/.ya/build/port_sync_dir/10517
2025-10-26 19:28:49,677 - DEBUG - ya.test - _verify_test: Verifying [test_canonical_records.test_dml_through_http in ydb/tests/functional/audit] canonical results
2025-10-26 19:28:49,681 - DEBUG - ya.test - verify: Test test_canonical_records.test_dml_through_http status: good
