2025-11-05 14:30:04,899 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 14:30:04,900 - INFO - ya.test - pytest_runtest_setup: test_tpch[1]
2025-11-05 14:30:04,900 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 14:30:04,900 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 14:30:04,969 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd
2025-11-05 14:30:04,970 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14958
2025-11-05 14:30:04,971 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/j3ko/001b3d/r3tmp/kikimr_cluster_jp9ta6bb
2025-11-05 14:30:04,998 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd
2025-11-05 14:30:04,999 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6357
2025-11-05 14:30:04,999 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27467
2025-11-05 14:30:04,999 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2543
2025-11-05 14:30:05,000 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4540
2025-11-05 14:30:05,000 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-11-05 14:30:05,001 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_6kxqwvw7.log --grpc-port=6357 --mon-port=27467 --ic-port=14958
2025-11-05 14:30:05,001 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6357/1, disk_size 68719476736
2025-11-05 14:30:05,002 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_6kxqwvw7.log', '--grpc-port=6357', '--mon-port=27467', '--ic-port=14958')' in '/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/node_1' (no wait)
2025-11-05 14:30:05,003 - DEBUG - ya.test - execute: Command pid: 987191
2025-11-05 14:30:05,004 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6357/1
2025-11-05 14:30:07,070 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd
2025-11-05 14:30:07,071 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:6357', '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: 14958\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-05 14:30:07,072 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-05 14:30:07,072 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-05 14:30:07,072 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:6357', '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: 14958\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-05 14:30:07,073 - DEBUG - ya.test - execute: Command pid: 987594
2025-11-05 14:30:07,686 - DEBUG - ya.test - _save_outputs: Command (pid 987594) rc: 0
2025-11-05 14:30:07,687 - DEBUG - ya.test - _save_outputs: Command (pid 987594) elapsed time (sec): 0.6122186183929443
2025-11-05 14:30:07,687 - DEBUG - ya.test - _save_outputs: Command (pid 987594) idrss: 0
2025-11-05 14:30:07,687 - DEBUG - ya.test - _save_outputs: Command (pid 987594) inblock: 0
2025-11-05 14:30:07,688 - DEBUG - ya.test - _save_outputs: Command (pid 987594) isrss: 0
2025-11-05 14:30:07,688 - DEBUG - ya.test - _save_outputs: Command (pid 987594) ixrss: 0
2025-11-05 14:30:07,688 - DEBUG - ya.test - _save_outputs: Command (pid 987594) majflt: 0
2025-11-05 14:30:07,688 - DEBUG - ya.test - _save_outputs: Command (pid 987594) maxrss: 606948
2025-11-05 14:30:07,689 - DEBUG - ya.test - _save_outputs: Command (pid 987594) minflt: 43687
2025-11-05 14:30:07,689 - DEBUG - ya.test - _save_outputs: Command (pid 987594) msgrcv: 0
2025-11-05 14:30:07,689 - DEBUG - ya.test - _save_outputs: Command (pid 987594) msgsnd: 0
2025-11-05 14:30:07,689 - DEBUG - ya.test - _save_outputs: Command (pid 987594) nivcsw: 22
2025-11-05 14:30:07,690 - DEBUG - ya.test - _save_outputs: Command (pid 987594) nsignals: 0
2025-11-05 14:30:07,690 - DEBUG - ya.test - _save_outputs: Command (pid 987594) nswap: 0
2025-11-05 14:30:07,690 - DEBUG - ya.test - _save_outputs: Command (pid 987594) nvcsw: 928
2025-11-05 14:30:07,690 - DEBUG - ya.test - _save_outputs: Command (pid 987594) oublock: 8
2025-11-05 14:30:07,691 - DEBUG - ya.test - _save_outputs: Command (pid 987594) stime: 0.249526
2025-11-05 14:30:07,691 - DEBUG - ya.test - _save_outputs: Command (pid 987594) utime: 0.332765
2025-11-05 14:30:07,691 - DEBUG - ya.test - _save_outputs: Command (pid 987594) wtime: 0.614
2025-11-05 14:30:07,692 - DEBUG - ya.test - _save_outputs: Command (pid 987594) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-05 14:30:07,692 - DEBUG - ya.test - _save_outputs: Command (pid 987594) errors:

2025-11-05 14:30:07,693 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd
2025-11-05 14:30:07,694 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:6357', '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-11-05 14:30:07,695 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-05 14:30:07,696 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-05 14:30:07,696 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:6357', '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-11-05 14:30:07,697 - DEBUG - ya.test - execute: Command pid: 988510
2025-11-05 14:30:08,413 - DEBUG - ya.test - _save_outputs: Command (pid 988510) rc: 0
2025-11-05 14:30:08,413 - DEBUG - ya.test - _save_outputs: Command (pid 988510) elapsed time (sec): 0.7145977020263672
2025-11-05 14:30:08,413 - DEBUG - ya.test - _save_outputs: Command (pid 988510) idrss: 0
2025-11-05 14:30:08,414 - DEBUG - ya.test - _save_outputs: Command (pid 988510) inblock: 0
2025-11-05 14:30:08,414 - DEBUG - ya.test - _save_outputs: Command (pid 988510) isrss: 0
2025-11-05 14:30:08,414 - DEBUG - ya.test - _save_outputs: Command (pid 988510) ixrss: 0
2025-11-05 14:30:08,414 - DEBUG - ya.test - _save_outputs: Command (pid 988510) majflt: 0
2025-11-05 14:30:08,415 - DEBUG - ya.test - _save_outputs: Command (pid 988510) maxrss: 606948
2025-11-05 14:30:08,415 - DEBUG - ya.test - _save_outputs: Command (pid 988510) minflt: 43776
2025-11-05 14:30:08,415 - DEBUG - ya.test - _save_outputs: Command (pid 988510) msgrcv: 0
2025-11-05 14:30:08,415 - DEBUG - ya.test - _save_outputs: Command (pid 988510) msgsnd: 0
2025-11-05 14:30:08,416 - DEBUG - ya.test - _save_outputs: Command (pid 988510) nivcsw: 36
2025-11-05 14:30:08,416 - DEBUG - ya.test - _save_outputs: Command (pid 988510) nsignals: 0
2025-11-05 14:30:08,416 - DEBUG - ya.test - _save_outputs: Command (pid 988510) nswap: 0
2025-11-05 14:30:08,416 - DEBUG - ya.test - _save_outputs: Command (pid 988510) nvcsw: 736
2025-11-05 14:30:08,417 - DEBUG - ya.test - _save_outputs: Command (pid 988510) oublock: 8
2025-11-05 14:30:08,417 - DEBUG - ya.test - _save_outputs: Command (pid 988510) stime: 0.217253
2025-11-05 14:30:08,417 - DEBUG - ya.test - _save_outputs: Command (pid 988510) utime: 0.468588
2025-11-05 14:30:08,417 - DEBUG - ya.test - _save_outputs: Command (pid 988510) wtime: 0.716
2025-11-05 14:30:08,418 - DEBUG - ya.test - _save_outputs: Command (pid 988510) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-05 14:30:08,418 - DEBUG - ya.test - _save_outputs: Command (pid 988510) errors:

2025-11-05 14:30:08,418 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-11-05 14:30:08,475 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-05 14:30:09,000 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd
2025-11-05 14:30:09,000 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29254
2025-11-05 14:30:09,001 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17030
2025-11-05 14:30:09,001 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30638
2025-11-05 14:30:09,002 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19651
2025-11-05 14:30:09,002 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17847
2025-11-05 14:30:09,003 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-11-05 14:30:09,003 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6357 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_4wmj5tiw.log --grpc-port=29254 --mon-port=17030 --ic-port=30638
2025-11-05 14:30:09,005 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:6357', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_4wmj5tiw.log', '--grpc-port=29254', '--mon-port=17030', '--ic-port=30638')' in '/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/cluster/slot_1' (no wait)
2025-11-05 14:30:09,007 - DEBUG - ya.test - execute: Command pid: 989772
2025-11-05 14:30:09,008 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:29254/1
2025-11-05 14:30:11,015 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb
2025-11-05 14:30:11,016 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-05 14:30:11,017 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-05 14:30:11,018 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6357', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'init', '--store=column']' in 'None' (waiting)
2025-11-05 14:30:11,020 - DEBUG - ya.test - execute: Command pid: 991199
2025-11-05 14:30:26,542 - DEBUG - ya.test - _save_outputs: Command (pid 991199) rc: 0
2025-11-05 14:30:26,542 - DEBUG - ya.test - _save_outputs: Command (pid 991199) elapsed time (sec): 15.520787715911865
2025-11-05 14:30:26,543 - DEBUG - ya.test - _save_outputs: Command (pid 991199) idrss: 0
2025-11-05 14:30:26,543 - DEBUG - ya.test - _save_outputs: Command (pid 991199) inblock: 0
2025-11-05 14:30:26,543 - DEBUG - ya.test - _save_outputs: Command (pid 991199) isrss: 0
2025-11-05 14:30:26,543 - DEBUG - ya.test - _save_outputs: Command (pid 991199) ixrss: 0
2025-11-05 14:30:26,543 - DEBUG - ya.test - _save_outputs: Command (pid 991199) majflt: 0
2025-11-05 14:30:26,544 - DEBUG - ya.test - _save_outputs: Command (pid 991199) maxrss: 619320
2025-11-05 14:30:26,544 - DEBUG - ya.test - _save_outputs: Command (pid 991199) minflt: 101255
2025-11-05 14:30:26,544 - DEBUG - ya.test - _save_outputs: Command (pid 991199) msgrcv: 0
2025-11-05 14:30:26,544 - DEBUG - ya.test - _save_outputs: Command (pid 991199) msgsnd: 0
2025-11-05 14:30:26,545 - DEBUG - ya.test - _save_outputs: Command (pid 991199) nivcsw: 72
2025-11-05 14:30:26,545 - DEBUG - ya.test - _save_outputs: Command (pid 991199) nsignals: 0
2025-11-05 14:30:26,545 - DEBUG - ya.test - _save_outputs: Command (pid 991199) nswap: 0
2025-11-05 14:30:26,546 - DEBUG - ya.test - _save_outputs: Command (pid 991199) nvcsw: 3426
2025-11-05 14:30:26,546 - DEBUG - ya.test - _save_outputs: Command (pid 991199) oublock: 16
2025-11-05 14:30:26,546 - DEBUG - ya.test - _save_outputs: Command (pid 991199) stime: 0.26282099999999997
2025-11-05 14:30:26,546 - DEBUG - ya.test - _save_outputs: Command (pid 991199) utime: 0.210634
2025-11-05 14:30:26,546 - DEBUG - ya.test - _save_outputs: Command (pid 991199) wtime: 15.522
2025-11-05 14:30:26,547 - DEBUG - ya.test - _save_outputs: Command (pid 991199) output:
Init tables ...
Init tables ...Ok

2025-11-05 14:30:26,547 - DEBUG - ya.test - _save_outputs: Command (pid 991199) errors:
(!) New version of YDB CLI is available. Run 'ydb update' command for update. You can also disable further version checks with 'ydb version --disable-checks' command

2025-11-05 14:30:26,548 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-11-05 14:30:26,548 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb
2025-11-05 14:30:26,560 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-05 14:30:26,575 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-05 14:30:26,575 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6357', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'import', 'generator', '--scale=1']' in 'None' (waiting)
2025-11-05 14:30:26,577 - DEBUG - ya.test - execute: Command pid: 996873
2025-11-05 14:35:16,172 - DEBUG - ya.test - _save_outputs: Command (pid 996873) rc: 0
2025-11-05 14:35:16,172 - DEBUG - ya.test - _save_outputs: Command (pid 996873) elapsed time (sec): 289.59400272369385
2025-11-05 14:35:16,173 - DEBUG - ya.test - _save_outputs: Command (pid 996873) idrss: 0
2025-11-05 14:35:16,173 - DEBUG - ya.test - _save_outputs: Command (pid 996873) inblock: 0
2025-11-05 14:35:16,173 - DEBUG - ya.test - _save_outputs: Command (pid 996873) isrss: 0
2025-11-05 14:35:16,173 - DEBUG - ya.test - _save_outputs: Command (pid 996873) ixrss: 0
2025-11-05 14:35:16,173 - DEBUG - ya.test - _save_outputs: Command (pid 996873) majflt: 0
2025-11-05 14:35:16,174 - DEBUG - ya.test - _save_outputs: Command (pid 996873) maxrss: 2872512
2025-11-05 14:35:16,174 - DEBUG - ya.test - _save_outputs: Command (pid 996873) minflt: 5516607
2025-11-05 14:35:16,174 - DEBUG - ya.test - _save_outputs: Command (pid 996873) msgrcv: 0
2025-11-05 14:35:16,174 - DEBUG - ya.test - _save_outputs: Command (pid 996873) msgsnd: 0
2025-11-05 14:35:16,174 - DEBUG - ya.test - _save_outputs: Command (pid 996873) nivcsw: 7556
2025-11-05 14:35:16,175 - DEBUG - ya.test - _save_outputs: Command (pid 996873) nsignals: 0
2025-11-05 14:35:16,175 - DEBUG - ya.test - _save_outputs: Command (pid 996873) nswap: 0
2025-11-05 14:35:16,175 - DEBUG - ya.test - _save_outputs: Command (pid 996873) nvcsw: 629140
2025-11-05 14:35:16,175 - DEBUG - ya.test - _save_outputs: Command (pid 996873) oublock: 48
2025-11-05 14:35:16,176 - DEBUG - ya.test - _save_outputs: Command (pid 996873) stime: 34.38145
2025-11-05 14:35:16,176 - DEBUG - ya.test - _save_outputs: Command (pid 996873) utime: 190.81536
2025-11-05 14:35:16,176 - DEBUG - ya.test - _save_outputs: Command (pid 996873) wtime: 289.596
2025-11-05 14:35:16,176 - DEBUG - ya.test - _save_outputs: Command (pid 996873) output:
Fill table supplier...
Fill table supplier OK 10000 / 10000 (11.112529s)
Fill table customer...
Fill table customer OK 150000 / 150000 (3.986489s)
Fill table orders...
Fill table orders OK 1500000 / 1500000 (239.054196s)
Fill table part...
Fill table part OK 200000 / 200000 (33.826637s)
Fill table nation...
Fill table nation OK 25 / 25 (0.294954s)
Fill table region...
Fill table region OK 5 / 5 (0.151092s)

2025-11-05 14:35:16,177 - DEBUG - ya.test - _save_outputs: Command (pid 996873) errors:
(!) New version of YDB CLI is available. Run 'ydb update' command for update. You can also disable further version checks with 'ydb version --disable-checks' command







2025-11-05 14:35:16,177 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-11-05 14:35:16,184 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,188 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:35:16,303 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-05 14:35:16,306 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-05 14:35:16,307 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:6357 to local/test_db ydb_access_token is set False
2025-11-05 14:35:16,307 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-05 14:35:16,321 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 14:35:16,322 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-05 14:35:16,344 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-11-05 14:35:16,345 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cc709376-52b8-4627-ac84-dc8ef55c4653, localhost:6357): creating call state
2025-11-05 14:35:16,346 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cc709376-52b8-4627-ac84-dc8ef55c4653, localhost:6357): request = { database: "local/test_db" }
2025-11-05 14:35:16,355 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cc709376-52b8-4627-ac84-dc8ef55c4653, localhost:6357): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uqiopzyque.auto.internal" port: 29254 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-11-05 14:35:16,356 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uqiopzyque.auto.internal:29254, location , ssl: False>]>
2025-11-05 14:35:16,360 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-05 14:35:16,371 - INFO - ydb.connection - close: Closing channel for endpoint localhost:6357
2025-11-05 14:35:16,372 - INFO - root - _create_ydb_driver: Connected
2025-11-05 14:35:16,373 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a7f56e93-a015-4d8f-9f26-1d6d0b78ddc3, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:16,374 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a7f56e93-a015-4d8f-9f26-1d6d0b78ddc3, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:16,376 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a7f56e93-a015-4d8f-9f26-1d6d0b78ddc3, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:16,635 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,647 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:35:16,679 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-05 14:35:16,682 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-05 14:35:16,682 - INFO - root - _get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:16,682 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:16,683 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, de1a9b10-a2cb-4cb7-bfcd-2d64129fb1f5, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:16,684 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, de1a9b10-a2cb-4cb7-bfcd-2d64129fb1f5, ghrun-uqiopzyque.auto.internal:29254): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-11-05 14:35:16,702 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, de1a9b10-a2cb-4cb7-bfcd-2d64129fb1f5, ghrun-uqiopzyque.auto.internal:29254): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } } } } }
2025-11-05 14:35:16,704 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:16,705 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 492e1b07-2857-4946-a381-a1479b0fd02e, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:16,706 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 492e1b07-2857-4946-a381-a1479b0fd02e, ghrun-uqiopzyque.auto.internal:29254): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-11-05 14:35:16,723 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 492e1b07-2857-4946-a381-a1479b0fd02e, ghrun-uqiopzyque.auto.internal:29254): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353018580 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353018910 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353020600 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353022550 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353024180 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353024440 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353026120 tx_id: 281474976720666 } } } } } }
2025-11-05 14:35:16,730 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,734 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,752 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:16,755 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-11-05 14:35:16,759 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,766 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,806 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:16,812 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-11-05 14:35:16,820 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,824 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,839 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 200 577
2025-11-05 14:35:16,842 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-11-05 14:35:16,847 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,851 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,867 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:16,870 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-11-05 14:35:16,873 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,888 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,905 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:16,908 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-11-05 14:35:16,912 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,916 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,935 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:16,938 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-11-05 14:35:16,942 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,948 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:16,981 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 200 577
2025-11-05 14:35:16,984 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-11-05 14:35:16,992 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:16,997 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:17,016 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 200 578
2025-11-05 14:35:17,019 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-11-05 14:35:17,020 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 1a8ab80f-5ef4-4b46-97da-43b7ace83817, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:17,021 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 1a8ab80f-5ef4-4b46-97da-43b7ace83817, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:17,023 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 1a8ab80f-5ef4-4b46-97da-43b7ace83817, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:17,111 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 0042454d-3017-46e2-9e17-9f051e26d414, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:17,112 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 0042454d-3017-46e2-9e17-9f051e26d414, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/customer`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:17,125 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 0042454d-3017-46e2-9e17-9f051e26d414, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:19,119 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c9b00a15-ef06-4032-8c0e-8e782293d00d, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:19,120 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c9b00a15-ef06-4032-8c0e-8e782293d00d, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/nation`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:19,125 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c9b00a15-ef06-4032-8c0e-8e782293d00d, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:20,109 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 1debd6ea-24cc-4f53-b980-e9a1225f4b11, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:20,110 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 1debd6ea-24cc-4f53-b980-e9a1225f4b11, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/orders`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:20,113 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 1debd6ea-24cc-4f53-b980-e9a1225f4b11, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:27,456 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, e78a2728-49c7-4607-b71c-20bf8c96ef13, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:27,457 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, e78a2728-49c7-4607-b71c-20bf8c96ef13, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/part`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:27,460 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, e78a2728-49c7-4607-b71c-20bf8c96ef13, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:29,340 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 1d8909cf-0f68-4b87-a49e-afaad1f063da, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:29,341 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 1d8909cf-0f68-4b87-a49e-afaad1f063da, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/partsupp`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:29,349 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 1d8909cf-0f68-4b87-a49e-afaad1f063da, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:31,137 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 21be5a7f-6c49-480f-9c51-6354de846e77, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:31,138 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 21be5a7f-6c49-480f-9c51-6354de846e77, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/region`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:31,140 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 21be5a7f-6c49-480f-9c51-6354de846e77, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:32,262 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 625abb43-1c0d-4b35-8b9b-a738580378e5, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:32,263 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 625abb43-1c0d-4b35-8b9b-a738580378e5, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/supplier`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:32,265 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 625abb43-1c0d-4b35-8b9b-a738580378e5, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:33,282 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a3261238-7b74-4b0f-954f-ee1ee11f3be0, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:33,283 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a3261238-7b74-4b0f-954f-ee1ee11f3be0, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/lineitem`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:33,286 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a3261238-7b74-4b0f-954f-ee1ee11f3be0, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:54,982 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:54,997 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes HTTP/1.1" 200 796
2025-11-05 14:35:55,011 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_tpch.py::TestTpchS1, test_name: test_tpch[1])
2025-11-05 14:35:55,024 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,032 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:35:55,059 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-11-05 14:35:55,062 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-05 14:35:55,062 - INFO - root - _get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:55,063 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:55,064 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 6b7b2bea-05e4-430a-8ade-c66e3b4e1787, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:55,065 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 6b7b2bea-05e4-430a-8ade-c66e3b4e1787, ghrun-uqiopzyque.auto.internal:29254): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-11-05 14:35:55,074 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 6b7b2bea-05e4-430a-8ade-c66e3b4e1787, ghrun-uqiopzyque.auto.internal:29254): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } } } } }
2025-11-05 14:35:55,076 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-11-05 14:35:55,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 796f66b7-2768-4076-88f3-b57f1125dab2, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:55,077 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 796f66b7-2768-4076-88f3-b57f1125dab2, ghrun-uqiopzyque.auto.internal:29254): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-11-05 14:35:55,093 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 796f66b7-2768-4076-88f3-b57f1125dab2, ghrun-uqiopzyque.auto.internal:29254): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353016410 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353018580 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353018910 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353020600 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353022550 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353024180 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353024440 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762353026120 tx_id: 281474976720666 } } } } } }
2025-11-05 14:35:55,098 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,107 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,133 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,135 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-11-05 14:35:55,143 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,149 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,164 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,167 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-11-05 14:35:55,173 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,177 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,192 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 200 586
2025-11-05 14:35:55,195 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-11-05 14:35:55,199 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,203 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,216 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,219 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-11-05 14:35:55,223 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,227 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,252 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,255 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-11-05 14:35:55,259 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,263 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,284 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,288 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-11-05 14:35:55,291 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,295 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,315 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 200 586
2025-11-05 14:35:55,318 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-11-05 14:35:55,322 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,325 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 307 0
2025-11-05 14:35:55,338 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 200 587
2025-11-05 14:35:55,341 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-11-05 14:35:55,343 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b7657981-d3a7-4f0b-a460-55bf17198250, ghrun-uqiopzyque.auto.internal:29254): creating call state
2025-11-05 14:35:55,344 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b7657981-d3a7-4f0b-a460-55bf17198250, ghrun-uqiopzyque.auto.internal:29254): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-05 14:35:55,346 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b7657981-d3a7-4f0b-a460-55bf17198250, ghrun-uqiopzyque.auto.internal:29254): response = { <_MultiThreadedRendezvous object> }
2025-11-05 14:35:55,434 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:35:55,441 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:35:55,459 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-11-05 14:35:55,463 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb
2025-11-05 14:35:55,467 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-05 14:35:55,468 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-05 14:35:55,468 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6357', '-d', '/local/test_db', 'workload', 'tpch', '--path', 'olap_yatests/tpch/s1', 'run', '--json', '/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/q1.json', '--output', '/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/q1.out', '--executer', 'generic', '--include', '1', '--iterations', '1', '--plan', '/home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/q1.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical', '--scale', '1']' in 'None' (waiting)
2025-11-05 14:35:55,471 - DEBUG - ya.test - execute: Command pid: 1052075
2025-11-05 14:36:24,193 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) rc: 0
2025-11-05 14:36:24,194 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) elapsed time (sec): 28.721054077148438
2025-11-05 14:36:24,194 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) idrss: 0
2025-11-05 14:36:24,195 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) inblock: 0
2025-11-05 14:36:24,195 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) isrss: 0
2025-11-05 14:36:24,195 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) ixrss: 0
2025-11-05 14:36:24,196 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) majflt: 0
2025-11-05 14:36:24,196 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) maxrss: 634876
2025-11-05 14:36:24,196 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) minflt: 104800
2025-11-05 14:36:24,197 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) msgrcv: 0
2025-11-05 14:36:24,197 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) msgsnd: 0
2025-11-05 14:36:24,198 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) nivcsw: 81
2025-11-05 14:36:24,220 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) nsignals: 0
2025-11-05 14:36:24,221 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) nswap: 0
2025-11-05 14:36:24,221 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) nvcsw: 3018
2025-11-05 14:36:24,221 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) oublock: 448
2025-11-05 14:36:24,221 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) stime: 0.26020499999999996
2025-11-05 14:36:24,222 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) utime: 0.536507
2025-11-05 14:36:24,222 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) wtime: 28.724
2025-11-05 14:36:24,222 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) output:
Query01:
Query text:
$z1_12 = 1;
select
    l_returnflag,
    l_linestatus,
    sum(l_quantity) as sum_qty,
    sum(l_extendedprice) as sum_base_price,
    sum(l_extendedprice * ($z1_12 - l_discount)) as sum_disc_price,
    sum(l_extendedprice * ($z1_12 - l_discount) * ($z1_12 + l_tax)) as sum_charge,
    avg(l_quantity) as avg_qty,
    avg(l_extendedprice) as avg_price,
    avg(l_discount) as avg_disc,
    count(*) as count_order
from
    `olap_yatests/tpch/s1/lineitem`
where
    l_shipdate <= Date('1998-12-01') - Interval("P90D")
group by
    l_returnflag,
    l_linestatus
order by
    l_returnflag,
    l_linestatus;

	iteration 0:	ok	25.302992s seconds

Results for 1 iterations
┌─────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Query01 │  24.932  │  24.932 │  24.932 │  24.932 │  24.932 │  24.932   │   0.000 │   0.371 │   0.371 │   0.370 │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Sum     │  24.932  │  24.932 │  24.932 │  24.932 │  24.932 │  24.932   │   0.000 │   0.371 │   0.371 │   0.370 │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ Avg     │  24.932  │  24.932 │  24.932 │  24.932 │  24.932 │  24.932   │   0.000 │   0.371 │   0.371 │   0.370 │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼──────────────┼────────────┼────────────┤
│ GAvg    │  24.932  │  24.932 │  24.932 │  24.932 │  24.932 │  24.932   │   0.000 │   0.371 │   0.371 │   0.001 │ 1            │            │            │
└─────────┴──────────┴─────────┴─────────┴─────────┴─────────┴───────────┴─────────┴─────────┴─────────┴─────────┴──────────────┴────────────┴────────────┘

Results saved to /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/q1.out
Json report saved to /home/runner/.ya/build/build_root/j3ko/001b3d/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_tpch/testing_out_stuff/test_tpch.py.TestTpchS1.test_tpch.1/q1.json

2025-11-05 14:36:24,223 - DEBUG - ya.test - _save_outputs: Command (pid 1052075) errors:
(!) New version of YDB CLI is available. Run 'ydb update' command for update. You can also disable further version checks with 'ydb version --disable-checks' command

[K|Progress: 0 rows read, 0 B read.
[K
[K|Progress: 0 rows read, 0 B read.
[K/Progress: 186K rows read, 9.45M B read.
[K-Progress: 778K rows read, 39.5M B read.
[K\Progress: 1.67M rows read, 84.9M B read.
[K|Progress: 2.56M rows read, 130M B read.
[K/Progress: 3.64M rows read, 185M B read.
[K-Progress: 4.42M rows read, 225M B read.
[K\Progress: 5.6M rows read, 284M B read.
[K|Progress: 5.92M rows read, 300M B read.
[K
2025-11-05 14:36:24,223 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-11-05 14:36:24,227 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:36:24,233 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:36:24,247 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-05 14:36:24,263 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:36:24,267 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-05 14:36:24,283 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-05 14:36:24,290 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:27467
2025-11-05 14:36:24,321 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:27467 "GET /viewer/json/nodes HTTP/1.1" 200 804
2025-11-05 14:36:25,306 - INFO - ya.test - pytest_runtest_teardown: Test teardown
