2025-10-26 15:41:40,706 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:41:40,706 - INFO - ya.test - pytest_runtest_setup: test_tpch[1]
2025-10-26 15:41:40,706 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-26 15:41:40,707 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-26 15:41:40,782 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd
2025-10-26 15:41:40,783 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22230
2025-10-26 15:41:40,784 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/7ljz/001d5d/r3tmp/kikimr_cluster_nu5h31v5
2025-10-26 15:41:40,810 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd
2025-10-26 15:41:40,811 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17840
2025-10-26 15:41:40,812 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17841
2025-10-26 15:41:40,814 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2321
2025-10-26 15:41:40,814 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14214
2025-10-26 15:41:40,815 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8724
2025-10-26 15:41:40,816 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7ljz/001d5d/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-10-26 15:41:40,816 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/7ljz/001d5d/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/7ljz/001d5d/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_bpdrv22i.log --grpc-port=17841 --mon-port=2321 --ic-port=22230
2025-10-26 15:41:40,817 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17841/1, disk_size 68719476736
2025-10-26 15:41:40,818 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/7ljz/001d5d/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/7ljz/001d5d/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_bpdrv22i.log', '--grpc-port=17841', '--mon-port=2321', '--ic-port=22230')' in '/home/runner/.ya/build/build_root/7ljz/001d5d/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-10-26 15:41:40,820 - DEBUG - ya.test - execute: Command pid: 936205
2025-10-26 15:41:40,821 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17841/1
2025-10-26 15:41:42,958 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd
2025-10-26 15:41:42,959 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17841', '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: 22230\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-26 15:41:42,959 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-26 15:41:42,960 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-26 15:41:42,960 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17841', '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: 22230\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-26 15:41:42,962 - DEBUG - ya.test - execute: Command pid: 936387
2025-10-26 15:41:43,544 - DEBUG - ya.test - _save_outputs: Command (pid 936387) rc: 0
2025-10-26 15:41:43,544 - DEBUG - ya.test - _save_outputs: Command (pid 936387) elapsed time (sec): 0.5814671516418457
2025-10-26 15:41:43,545 - DEBUG - ya.test - _save_outputs: Command (pid 936387) idrss: 0
2025-10-26 15:41:43,545 - DEBUG - ya.test - _save_outputs: Command (pid 936387) inblock: 0
2025-10-26 15:41:43,545 - DEBUG - ya.test - _save_outputs: Command (pid 936387) isrss: 0
2025-10-26 15:41:43,545 - DEBUG - ya.test - _save_outputs: Command (pid 936387) ixrss: 0
2025-10-26 15:41:43,546 - DEBUG - ya.test - _save_outputs: Command (pid 936387) majflt: 0
2025-10-26 15:41:43,546 - DEBUG - ya.test - _save_outputs: Command (pid 936387) maxrss: 592764
2025-10-26 15:41:43,546 - DEBUG - ya.test - _save_outputs: Command (pid 936387) minflt: 43948
2025-10-26 15:41:43,546 - DEBUG - ya.test - _save_outputs: Command (pid 936387) msgrcv: 0
2025-10-26 15:41:43,547 - DEBUG - ya.test - _save_outputs: Command (pid 936387) msgsnd: 0
2025-10-26 15:41:43,547 - DEBUG - ya.test - _save_outputs: Command (pid 936387) nivcsw: 47
2025-10-26 15:41:43,547 - DEBUG - ya.test - _save_outputs: Command (pid 936387) nsignals: 0
2025-10-26 15:41:43,548 - DEBUG - ya.test - _save_outputs: Command (pid 936387) nswap: 0
2025-10-26 15:41:43,548 - DEBUG - ya.test - _save_outputs: Command (pid 936387) nvcsw: 697
2025-10-26 15:41:43,548 - DEBUG - ya.test - _save_outputs: Command (pid 936387) oublock: 8
2025-10-26 15:41:43,548 - DEBUG - ya.test - _save_outputs: Command (pid 936387) stime: 0.22666499999999998
2025-10-26 15:41:43,549 - DEBUG - ya.test - _save_outputs: Command (pid 936387) utime: 0.33191699999999996
2025-10-26 15:41:43,549 - DEBUG - ya.test - _save_outputs: Command (pid 936387) wtime: 0.583
2025-10-26 15:41:43,549 - DEBUG - ya.test - _save_outputs: Command (pid 936387) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-26 15:41:43,550 - DEBUG - ya.test - _save_outputs: Command (pid 936387) errors:

2025-10-26 15:41:43,551 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd
2025-10-26 15:41:43,551 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17841', '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 15:41:43,553 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-26 15:41:43,553 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-26 15:41:43,554 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17841', '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 15:41:43,555 - DEBUG - ya.test - execute: Command pid: 936440
2025-10-26 15:41:44,111 - DEBUG - ya.test - _save_outputs: Command (pid 936440) rc: 0
2025-10-26 15:41:44,111 - DEBUG - ya.test - _save_outputs: Command (pid 936440) elapsed time (sec): 0.5469875335693359
2025-10-26 15:41:44,112 - DEBUG - ya.test - _save_outputs: Command (pid 936440) idrss: 0
2025-10-26 15:41:44,112 - DEBUG - ya.test - _save_outputs: Command (pid 936440) inblock: 0
2025-10-26 15:41:44,112 - DEBUG - ya.test - _save_outputs: Command (pid 936440) isrss: 0
2025-10-26 15:41:44,112 - DEBUG - ya.test - _save_outputs: Command (pid 936440) ixrss: 0
2025-10-26 15:41:44,113 - DEBUG - ya.test - _save_outputs: Command (pid 936440) majflt: 0
2025-10-26 15:41:44,113 - DEBUG - ya.test - _save_outputs: Command (pid 936440) maxrss: 592764
2025-10-26 15:41:44,113 - DEBUG - ya.test - _save_outputs: Command (pid 936440) minflt: 44038
2025-10-26 15:41:44,113 - DEBUG - ya.test - _save_outputs: Command (pid 936440) msgrcv: 0
2025-10-26 15:41:44,114 - DEBUG - ya.test - _save_outputs: Command (pid 936440) msgsnd: 0
2025-10-26 15:41:44,114 - DEBUG - ya.test - _save_outputs: Command (pid 936440) nivcsw: 20
2025-10-26 15:41:44,114 - DEBUG - ya.test - _save_outputs: Command (pid 936440) nsignals: 0
2025-10-26 15:41:44,114 - DEBUG - ya.test - _save_outputs: Command (pid 936440) nswap: 0
2025-10-26 15:41:44,114 - DEBUG - ya.test - _save_outputs: Command (pid 936440) nvcsw: 715
2025-10-26 15:41:44,115 - DEBUG - ya.test - _save_outputs: Command (pid 936440) oublock: 8
2025-10-26 15:41:44,115 - DEBUG - ya.test - _save_outputs: Command (pid 936440) stime: 0.17152199999999998
2025-10-26 15:41:44,115 - DEBUG - ya.test - _save_outputs: Command (pid 936440) utime: 0.351921
2025-10-26 15:41:44,115 - DEBUG - ya.test - _save_outputs: Command (pid 936440) wtime: 0.548
2025-10-26 15:41:44,115 - DEBUG - ya.test - _save_outputs: Command (pid 936440) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-26 15:41:44,116 - DEBUG - ya.test - _save_outputs: Command (pid 936440) errors:

2025-10-26 15:41:44,116 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-10-26 15:41:44,335 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-26 15:41:44,904 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd
2025-10-26 15:41:44,905 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64800
2025-10-26 15:41:44,905 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22103
2025-10-26 15:41:44,905 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25655
2025-10-26 15:41:44,906 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22121
2025-10-26 15:41:44,906 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9184
2025-10-26 15:41:44,907 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7ljz/001d5d/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-10-26 15:41:44,907 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17841 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/7ljz/001d5d/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_aq82lyif.log --grpc-port=64800 --mon-port=22103 --ic-port=25655
2025-10-26 15:41:44,908 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/7ljz/001d5d/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:17841', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/7ljz/001d5d/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_aq82lyif.log', '--grpc-port=64800', '--mon-port=22103', '--ic-port=25655')' in '/home/runner/.ya/build/build_root/7ljz/001d5d/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-10-26 15:41:44,910 - DEBUG - ya.test - execute: Command pid: 936599
2025-10-26 15:41:44,911 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:64800/1
2025-10-26 15:41:46,925 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydb/ydb
2025-10-26 15:41:46,927 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-26 15:41:46,927 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-26 15:41:46,928 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17841', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'init', '--store=column']' in 'None' (waiting)
2025-10-26 15:41:46,930 - DEBUG - ya.test - execute: Command pid: 936841
2025-10-26 15:41:59,972 - DEBUG - ya.test - _save_outputs: Command (pid 936841) rc: 0
2025-10-26 15:41:59,972 - DEBUG - ya.test - _save_outputs: Command (pid 936841) elapsed time (sec): 13.040592908859253
2025-10-26 15:41:59,973 - DEBUG - ya.test - _save_outputs: Command (pid 936841) idrss: 0
2025-10-26 15:41:59,973 - DEBUG - ya.test - _save_outputs: Command (pid 936841) inblock: 436280
2025-10-26 15:41:59,973 - DEBUG - ya.test - _save_outputs: Command (pid 936841) isrss: 0
2025-10-26 15:41:59,973 - DEBUG - ya.test - _save_outputs: Command (pid 936841) ixrss: 0
2025-10-26 15:41:59,973 - DEBUG - ya.test - _save_outputs: Command (pid 936841) majflt: 48
2025-10-26 15:41:59,974 - DEBUG - ya.test - _save_outputs: Command (pid 936841) maxrss: 592764
2025-10-26 15:41:59,974 - DEBUG - ya.test - _save_outputs: Command (pid 936841) minflt: 105303
2025-10-26 15:41:59,974 - DEBUG - ya.test - _save_outputs: Command (pid 936841) msgrcv: 0
2025-10-26 15:41:59,974 - DEBUG - ya.test - _save_outputs: Command (pid 936841) msgsnd: 0
2025-10-26 15:41:59,974 - DEBUG - ya.test - _save_outputs: Command (pid 936841) nivcsw: 31
2025-10-26 15:41:59,975 - DEBUG - ya.test - _save_outputs: Command (pid 936841) nsignals: 0
2025-10-26 15:41:59,975 - DEBUG - ya.test - _save_outputs: Command (pid 936841) nswap: 0
2025-10-26 15:41:59,975 - DEBUG - ya.test - _save_outputs: Command (pid 936841) nvcsw: 3713
2025-10-26 15:41:59,975 - DEBUG - ya.test - _save_outputs: Command (pid 936841) oublock: 32
2025-10-26 15:41:59,976 - DEBUG - ya.test - _save_outputs: Command (pid 936841) stime: 0.364374
2025-10-26 15:41:59,976 - DEBUG - ya.test - _save_outputs: Command (pid 936841) utime: 0.301685
2025-10-26 15:41:59,976 - DEBUG - ya.test - _save_outputs: Command (pid 936841) wtime: 13.043
2025-10-26 15:41:59,976 - DEBUG - ya.test - _save_outputs: Command (pid 936841) output:
Init tables ...
Init tables ...Ok

2025-10-26 15:41:59,976 - DEBUG - ya.test - _save_outputs: Command (pid 936841) 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-10-26 15:41:59,977 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-10-26 15:41:59,978 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydb/ydb
2025-10-26 15:41:59,979 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-26 15:41:59,979 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-26 15:41:59,979 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/7ljz/001d5d/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17841', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'import', 'generator', '--scale=1']' in 'None' (waiting)
2025-10-26 15:41:59,981 - DEBUG - ya.test - execute: Command pid: 940375
