2025-12-05 14:31:46,221 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-05 14:31:46,223 - INFO - ya.test - pytest_runtest_setup: test_tpch[1]
2025-12-05 14:31:46,223 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-05 14:31:46,224 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-05 14:31:46,328 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd
2025-12-05 14:31:46,330 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26379
2025-12-05 14:31:46,333 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/kvke/000c9e/r3tmp/kikimr_cluster_19ojpu_q
2025-12-05 14:31:46,373 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd
2025-12-05 14:31:46,374 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2031
2025-12-05 14:31:46,375 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7890
2025-12-05 14:31:46,376 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6920
2025-12-05 14:31:46,377 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25400
2025-12-05 14:31:46,378 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:31:46,378 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/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/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_wy8xjwz3.log --grpc-port=2031 --mon-port=7890 --ic-port=26379
2025-12-05 14:31:46,379 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:2031/1, disk_size 68719476736
2025-12-05 14:31:46,379 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:31:46,380 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/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/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_wy8xjwz3.log --grpc-port=2031 --mon-port=7890 --ic-port=26379
2025-12-05 14:31:46,381 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_wy8xjwz3.log', '--grpc-port=2031', '--mon-port=7890', '--ic-port=26379')' in '/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/node_1' (no wait)
2025-12-05 14:31:46,384 - DEBUG - ya.test - execute: Command pid: 1323896
2025-12-05 14:31:46,386 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:2031/1
2025-12-05 14:31:46,388 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-05 14:31:46,389 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-05 14:31:46,390 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 14:31:46,393 - DEBUG - ya.test - execute: Command pid: 1323897
2025-12-05 14:31:47,101 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) rc: 0
2025-12-05 14:31:47,101 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) elapsed time (sec): 0.7070457935333252
2025-12-05 14:31:47,102 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) idrss: 0
2025-12-05 14:31:47,102 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) inblock: 0
2025-12-05 14:31:47,103 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) isrss: 0
2025-12-05 14:31:47,103 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) ixrss: 0
2025-12-05 14:31:47,104 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) majflt: 0
2025-12-05 14:31:47,104 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) maxrss: 551760
2025-12-05 14:31:47,105 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) minflt: 45487
2025-12-05 14:31:47,106 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) msgrcv: 0
2025-12-05 14:31:47,106 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) msgsnd: 0
2025-12-05 14:31:47,106 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) nivcsw: 13
2025-12-05 14:31:47,107 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) nsignals: 0
2025-12-05 14:31:47,107 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) nswap: 0
2025-12-05 14:31:47,107 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) nvcsw: 14
2025-12-05 14:31:47,108 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) oublock: 8
2025-12-05 14:31:47,108 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) stime: 0.19672599999999998
2025-12-05 14:31:47,108 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) utime: 0.46196099999999996
2025-12-05 14:31:47,109 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) wtime: 0.709
2025-12-05 14:31:47,109 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) output:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mj3z7qugmi
    Host information: 
        Linux ghrun-mj3z7qugmi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-05 14:31:47,110 - DEBUG - ya.test - _save_outputs: Command (pid 1323897) errors:

2025-12-05 14:31:47,111 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2
2025-12-05 14:31:49,802 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd
2025-12-05 14:31:49,803 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2031', '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: 26379\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-05 14:31:49,804 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-05 14:31:49,805 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-05 14:31:49,806 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2031', '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: 26379\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-05 14:31:49,808 - DEBUG - ya.test - execute: Command pid: 1325006
2025-12-05 14:31:50,711 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) rc: 0
2025-12-05 14:31:50,711 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) elapsed time (sec): 0.9019742012023926
2025-12-05 14:31:50,712 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) idrss: 0
2025-12-05 14:31:50,712 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) inblock: 0
2025-12-05 14:31:50,712 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) isrss: 0
2025-12-05 14:31:50,713 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) ixrss: 0
2025-12-05 14:31:50,713 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) majflt: 0
2025-12-05 14:31:50,713 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) maxrss: 648988
2025-12-05 14:31:50,714 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) minflt: 51744
2025-12-05 14:31:50,714 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) msgrcv: 0
2025-12-05 14:31:50,719 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) msgsnd: 0
2025-12-05 14:31:50,719 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) nivcsw: 113
2025-12-05 14:31:50,719 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) nsignals: 0
2025-12-05 14:31:50,720 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) nswap: 0
2025-12-05 14:31:50,720 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) nvcsw: 911
2025-12-05 14:31:50,720 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) oublock: 8
2025-12-05 14:31:50,721 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) stime: 0.273395
2025-12-05 14:31:50,721 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) utime: 0.5441779999999999
2025-12-05 14:31:50,721 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) wtime: 0.904
2025-12-05 14:31:50,722 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-05 14:31:50,722 - DEBUG - ya.test - _save_outputs: Command (pid 1325006) errors:

2025-12-05 14:31:50,725 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd
2025-12-05 14:31:50,725 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2031', '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-12-05 14:31:50,727 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-05 14:31:50,729 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-05 14:31:50,729 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2031', '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-12-05 14:31:50,731 - DEBUG - ya.test - execute: Command pid: 1325376
2025-12-05 14:31:51,660 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) rc: 0
2025-12-05 14:31:51,661 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) elapsed time (sec): 0.9267435073852539
2025-12-05 14:31:51,661 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) idrss: 0
2025-12-05 14:31:51,662 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) inblock: 0
2025-12-05 14:31:51,662 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) isrss: 0
2025-12-05 14:31:51,663 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) ixrss: 0
2025-12-05 14:31:51,664 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) majflt: 0
2025-12-05 14:31:51,664 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) maxrss: 649952
2025-12-05 14:31:51,669 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) minflt: 51621
2025-12-05 14:31:51,670 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) msgrcv: 0
2025-12-05 14:31:51,670 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) msgsnd: 0
2025-12-05 14:31:51,672 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) nivcsw: 133
2025-12-05 14:31:51,672 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) nsignals: 0
2025-12-05 14:31:51,673 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) nswap: 0
2025-12-05 14:31:51,682 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) nvcsw: 537
2025-12-05 14:31:51,683 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) oublock: 8
2025-12-05 14:31:51,689 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) stime: 0.277847
2025-12-05 14:31:51,696 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) utime: 0.519977
2025-12-05 14:31:51,698 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) wtime: 0.929
2025-12-05 14:31:51,699 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-05 14:31:51,699 - DEBUG - ya.test - _save_outputs: Command (pid 1325376) errors:

2025-12-05 14:31:51,700 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-12-05 14:31:51,796 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-05 14:31:52,404 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd
2025-12-05 14:31:52,404 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9794
2025-12-05 14:31:52,406 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5741
2025-12-05 14:31:52,407 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12935
2025-12-05 14:31:52,409 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24675
2025-12-05 14:31:52,410 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23771
2025-12-05 14:31:52,412 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:31:52,413 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:2031 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_63gicq9a.log --grpc-port=9794 --mon-port=5741 --ic-port=12935
2025-12-05 14:31:52,417 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:31:52,420 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:2031 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_63gicq9a.log --grpc-port=9794 --mon-port=5741 --ic-port=12935
2025-12-05 14:31:52,423 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:2031', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_63gicq9a.log', '--grpc-port=9794', '--mon-port=5741', '--ic-port=12935')' in '/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/slot_1' (no wait)
2025-12-05 14:31:52,430 - DEBUG - ya.test - execute: Command pid: 1325924
2025-12-05 14:31:52,431 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9794/1
2025-12-05 14:31:52,433 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-05 14:31:52,434 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-05 14:31:52,434 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 14:31:52,436 - DEBUG - ya.test - execute: Command pid: 1325927
2025-12-05 14:31:53,230 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) rc: 0
2025-12-05 14:31:53,232 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) elapsed time (sec): 0.7925784587860107
2025-12-05 14:31:53,232 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) idrss: 0
2025-12-05 14:31:53,233 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) inblock: 0
2025-12-05 14:31:53,233 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) isrss: 0
2025-12-05 14:31:53,233 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) ixrss: 0
2025-12-05 14:31:53,234 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) majflt: 0
2025-12-05 14:31:53,234 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) maxrss: 661040
2025-12-05 14:31:53,234 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) minflt: 45395
2025-12-05 14:31:53,234 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) msgrcv: 0
2025-12-05 14:31:53,241 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) msgsnd: 0
2025-12-05 14:31:53,241 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) nivcsw: 105
2025-12-05 14:31:53,241 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) nsignals: 0
2025-12-05 14:31:53,242 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) nswap: 0
2025-12-05 14:31:53,242 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) nvcsw: 15
2025-12-05 14:31:53,242 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) oublock: 8
2025-12-05 14:31:53,242 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) stime: 0.196523
2025-12-05 14:31:53,243 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) utime: 0.502149
2025-12-05 14:31:53,243 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) wtime: 0.795
2025-12-05 14:31:53,243 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) output:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mj3z7qugmi
    Host information: 
        Linux ghrun-mj3z7qugmi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-05 14:31:53,244 - DEBUG - ya.test - _save_outputs: Command (pid 1325927) errors:

2025-12-05 14:31:53,245 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2
2025-12-05 14:31:59,276 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb
2025-12-05 14:31:59,277 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-12-05 14:31:59,281 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-12-05 14:31:59,288 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:2031', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'init', '--store=column', '--datetime-types=dt64']' in 'None' (waiting)
2025-12-05 14:31:59,290 - DEBUG - ya.test - execute: Command pid: 1328144
2025-12-05 14:32:14,436 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) rc: 0
2025-12-05 14:32:14,437 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) elapsed time (sec): 15.142102241516113
2025-12-05 14:32:14,437 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) idrss: 0
2025-12-05 14:32:14,438 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) inblock: 0
2025-12-05 14:32:14,438 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) isrss: 0
2025-12-05 14:32:14,439 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) ixrss: 0
2025-12-05 14:32:14,439 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) majflt: 0
2025-12-05 14:32:14,440 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) maxrss: 661040
2025-12-05 14:32:14,440 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) minflt: 110577
2025-12-05 14:32:14,440 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) msgrcv: 0
2025-12-05 14:32:14,440 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) msgsnd: 0
2025-12-05 14:32:14,441 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) nivcsw: 107
2025-12-05 14:32:14,441 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) nsignals: 0
2025-12-05 14:32:14,441 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) nswap: 0
2025-12-05 14:32:14,442 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) nvcsw: 4255
2025-12-05 14:32:14,442 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) oublock: 8
2025-12-05 14:32:14,442 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) stime: 0.3443
2025-12-05 14:32:14,444 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) utime: 0.432116
2025-12-05 14:32:14,444 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) wtime: 15.147
2025-12-05 14:32:14,445 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) output:
Init tables ...
Init tables ...Ok

2025-12-05 14:32:14,446 - DEBUG - ya.test - _save_outputs: Command (pid 1328144) errors:

2025-12-05 14:32:14,447 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb
2025-12-05 14:32:14,449 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-12-05 14:32:14,450 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-12-05 14:32:14,451 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:2031', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'import', 'generator', '--scale=1']' in 'None' (waiting)
2025-12-05 14:32:14,452 - DEBUG - ya.test - execute: Command pid: 1331069
2025-12-05 14:36:14,848 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) rc: 0
2025-12-05 14:36:14,849 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) elapsed time (sec): 240.39513540267944
2025-12-05 14:36:14,849 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) idrss: 0
2025-12-05 14:36:14,850 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) inblock: 0
2025-12-05 14:36:14,850 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) isrss: 0
2025-12-05 14:36:14,850 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) ixrss: 0
2025-12-05 14:36:14,856 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) majflt: 0
2025-12-05 14:36:14,857 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) maxrss: 2443256
2025-12-05 14:36:14,857 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) minflt: 5028209
2025-12-05 14:36:14,858 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) msgrcv: 0
2025-12-05 14:36:14,858 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) msgsnd: 0
2025-12-05 14:36:14,858 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) nivcsw: 26725
2025-12-05 14:36:14,858 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) nsignals: 0
2025-12-05 14:36:14,859 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) nswap: 0
2025-12-05 14:36:14,859 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) nvcsw: 1052290
2025-12-05 14:36:14,860 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) oublock: 40
2025-12-05 14:36:14,860 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) stime: 35.562389
2025-12-05 14:36:14,869 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) utime: 218.463005
2025-12-05 14:36:14,869 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) wtime: 240.397
2025-12-05 14:36:14,870 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) output:
Fill table supplier...
Fill table supplier OK 10000 / 10000 (11.906164s)
Fill table customer...
Fill table customer OK 150000 / 150000 (6.123211s)
Fill table orders...
Fill table orders OK 1500000 / 1500000 (190.508678s)
Fill table part...
Fill table part OK 200000 / 200000 (29.860084s)
Fill table nation...
Fill table nation OK 25 / 25 (0.257593s)
Fill table region...
Fill table region OK 5 / 5 (0.307968s)

2025-12-05 14:36:14,870 - DEBUG - ya.test - _save_outputs: Command (pid 1331069) errors:







2025-12-05 14:36:14,871 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-05 14:36:14,896 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:14,922 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:14,940 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-12-05 14:36:14,944 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-05 14:36:14,946 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:2031 to local/test_db ydb_access_token is set False
2025-12-05 14:36:14,947 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 14:36:14,956 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 14:36:14,957 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 14:36:14,994 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-12-05 14:36:14,995 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 4e089937-ac8c-45e8-ae2f-fe72649c7d95, localhost:2031): creating call state
2025-12-05 14:36:14,996 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 4e089937-ac8c-45e8-ae2f-fe72649c7d95, localhost:2031): request = { database: "local/test_db" }
2025-12-05 14:36:15,001 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 4e089937-ac8c-45e8-ae2f-fe72649c7d95, localhost:2031): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mj3z7qugmi.auto.internal" port: 9794 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-12-05 14:36:15,003 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mj3z7qugmi.auto.internal:9794, location , ssl: False>]>
2025-12-05 14:36:15,003 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 14:36:15,037 - INFO - ydb.connection - close: Closing channel for endpoint localhost:2031
2025-12-05 14:36:15,038 - INFO - root - _create_ydb_driver: Connected
2025-12-05 14:36:15,039 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b7b81c2e-89ed-483c-af83-22c8c1f2c054, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:15,040 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b7b81c2e-89ed-483c-af83-22c8c1f2c054, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:15,053 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b7b81c2e-89ed-483c-af83-22c8c1f2c054, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:15,272 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,278 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:15,294 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-12-05 14:36:15,297 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-05 14:36:15,298 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:15,298 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:15,299 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, b7f3f88f-8cd5-4bb9-b612-0e7494145c0a, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:15,300 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, b7f3f88f-8cd5-4bb9-b612-0e7494145c0a, ghrun-mj3z7qugmi.auto.internal:9794): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-12-05 14:36:15,310 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, b7f3f88f-8cd5-4bb9-b612-0e7494145c0a, ghrun-mj3z7qugmi.auto.internal:9794): 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: 1764945122230 tx_id: 281474976720659 } } } } } }
2025-12-05 14:36:15,312 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:15,313 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 94dcdf41-b131-4d54-a6e7-336a6c0610a0, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:15,313 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 94dcdf41-b131-4d54-a6e7-336a6c0610a0, ghrun-mj3z7qugmi.auto.internal:9794): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-12-05 14:36:15,325 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 94dcdf41-b131-4d54-a6e7-336a6c0610a0, ghrun-mj3z7qugmi.auto.internal:9794): 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: 1764945122230 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945122230 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945124590 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945125070 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945127260 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945129340 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945131540 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945131970 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945133840 tx_id: 281474976720666 } } } } } }
2025-12-05 14:36:15,332 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,336 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,355 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:15,358 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-12-05 14:36:15,362 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,372 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,387 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:15,391 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-12-05 14:36:15,395 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,400 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,415 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 578
2025-12-05 14:36:15,418 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-12-05 14:36:15,422 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,431 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,448 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:15,451 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-12-05 14:36:15,455 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,461 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,487 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:15,490 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-12-05 14:36:15,494 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,499 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,517 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:15,520 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-12-05 14:36:15,525 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,529 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,547 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 578
2025-12-05 14:36:15,549 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-12-05 14:36:15,555 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:15,559 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:15,581 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 581
2025-12-05 14:36:15,584 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-12-05 14:36:15,586 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2c9b2450-6635-42a4-8023-3b92cae647c6, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:15,586 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2c9b2450-6635-42a4-8023-3b92cae647c6, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:15,588 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2c9b2450-6635-42a4-8023-3b92cae647c6, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:15,679 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 8a4961ca-256d-4fdd-a9b8-551785f9e12f, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:15,680 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 8a4961ca-256d-4fdd-a9b8-551785f9e12f, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/customer`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:15,682 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 8a4961ca-256d-4fdd-a9b8-551785f9e12f, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:15,683 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:17,772 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b946494a-dd37-4a57-8509-94fc53ea2453, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:17,773 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b946494a-dd37-4a57-8509-94fc53ea2453, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/nation`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:17,775 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b946494a-dd37-4a57-8509-94fc53ea2453, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:17,775 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:18,736 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 3b28a2b5-9b8d-4cf9-8139-3533350dc411, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:18,737 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 3b28a2b5-9b8d-4cf9-8139-3533350dc411, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/orders`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:18,739 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 3b28a2b5-9b8d-4cf9-8139-3533350dc411, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:18,742 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:20,280 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 91b8088a-c1c0-4d05-b2cc-58276d770cb5, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:20,286 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 91b8088a-c1c0-4d05-b2cc-58276d770cb5, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/part`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:20,299 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 91b8088a-c1c0-4d05-b2cc-58276d770cb5, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:20,307 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:21,734 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a983e693-8996-4071-8941-395d0482691a, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:21,735 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a983e693-8996-4071-8941-395d0482691a, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/partsupp`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:21,745 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a983e693-8996-4071-8941-395d0482691a, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:21,746 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:24,556 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 7084d012-4f7a-4f90-a2af-8f3326a7c89c, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:24,557 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 7084d012-4f7a-4f90-a2af-8f3326a7c89c, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/region`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:24,577 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 7084d012-4f7a-4f90-a2af-8f3326a7c89c, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:24,578 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:25,661 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 847bdd66-a839-46ee-a6cf-75d36336ac1a, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:25,662 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 847bdd66-a839-46ee-a6cf-75d36336ac1a, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/supplier`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:25,679 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 847bdd66-a839-46ee-a6cf-75d36336ac1a, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:25,680 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:27,323 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, bc4a2e5c-0945-436a-93b9-fcda33f952d1, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:27,324 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, bc4a2e5c-0945-436a-93b9-fcda33f952d1, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/lineitem`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:27,337 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, bc4a2e5c-0945-436a-93b9-fcda33f952d1, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:27,338 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-12-05 14:36:51,858 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:51,873 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes HTTP/1.1" 200 797
2025-12-05 14:36:51,885 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:51,890 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:51,913 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-12-05 14:36:52,884 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:52,930 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes HTTP/1.1" 200 802
2025-12-05 14:36:52,940 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:52,953 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:53,003 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-12-05 14:36:53,005 - INFO - root - __create_iterations_table: All nodes before filtering: [('12935@ghrun-mj3z7qugmi.auto.internal', <Role.UNKNOWN: 0>)]
2025-12-05 14:36:53,006 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-12-05 14:36:53,006 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-mj3z7qugmi.auto.internal', 1)]
2025-12-05 14:36:53,007 - INFO - root - allure_test_description: iterations_table created, length: 514
2025-12-05 14:36:53,007 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-12-05 14:36:53,444 - DEBUG - matplotlib - wrapper: CACHEDIR=/home/runner/.cache/matplotlib
2025-12-05 14:36:53,449 - DEBUG - matplotlib.font_manager - _load_fontmanager: Using fontManager instance from /home/runner/.cache/matplotlib/fontlist-v330.json
2025-12-05 14:36:55,047 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:36:55,048 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/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/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/node_1/logfile_wy8xjwz3.log --grpc-port=2031 --mon-port=7890 --ic-port=26379
2025-12-05 14:36:55,048 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:2031/1
2025-12-05 14:36:55,050 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-12-05 14:36:55,052 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-12-05 14:36:55,052 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 14:36:55,055 - DEBUG - ya.test - execute: Command pid: 1374646
2025-12-05 14:36:56,271 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) rc: 0
2025-12-05 14:36:56,271 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) elapsed time (sec): 1.2129814624786377
2025-12-05 14:36:56,272 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) idrss: 0
2025-12-05 14:36:56,272 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) inblock: 0
2025-12-05 14:36:56,272 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) isrss: 0
2025-12-05 14:36:56,273 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) ixrss: 0
2025-12-05 14:36:56,273 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) majflt: 0
2025-12-05 14:36:56,273 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) maxrss: 743580
2025-12-05 14:36:56,273 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) minflt: 45363
2025-12-05 14:36:56,274 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) msgrcv: 0
2025-12-05 14:36:56,274 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) msgsnd: 0
2025-12-05 14:36:56,274 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) nivcsw: 196
2025-12-05 14:36:56,274 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) nsignals: 0
2025-12-05 14:36:56,275 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) nswap: 0
2025-12-05 14:36:56,275 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) nvcsw: 15
2025-12-05 14:36:56,275 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) oublock: 8
2025-12-05 14:36:56,277 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) stime: 0.314504
2025-12-05 14:36:56,277 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) utime: 0.485129
2025-12-05 14:36:56,277 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) wtime: 1.217
2025-12-05 14:36:56,278 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) output:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mj3z7qugmi
    Host information: 
        Linux ghrun-mj3z7qugmi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-05 14:36:56,278 - DEBUG - ya.test - _save_outputs: Command (pid 1374646) errors:

2025-12-05 14:36:56,279 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2
2025-12-05 14:36:56,279 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/kikimr_configs"
2025-12-05 14:36:56,280 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:2031 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/kvke/000c9e/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_s1/testing_out_stuff/test_s1.py.TestTpchS1.test_tpch.1/cluster/slot_1/logfile_63gicq9a.log --grpc-port=9794 --mon-port=5741 --ic-port=12935
2025-12-05 14:36:56,281 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9794/1
2025-12-05 14:36:56,282 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-12-05 14:36:56,283 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-12-05 14:36:56,284 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 14:36:56,286 - DEBUG - ya.test - execute: Command pid: 1374853
2025-12-05 14:36:57,405 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) rc: 0
2025-12-05 14:36:57,406 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) elapsed time (sec): 1.1180906295776367
2025-12-05 14:36:57,406 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) idrss: 0
2025-12-05 14:36:57,407 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) inblock: 0
2025-12-05 14:36:57,407 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) isrss: 0
2025-12-05 14:36:57,407 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) ixrss: 0
2025-12-05 14:36:57,408 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) majflt: 0
2025-12-05 14:36:57,409 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) maxrss: 743896
2025-12-05 14:36:57,410 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) minflt: 45444
2025-12-05 14:36:57,410 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) msgrcv: 0
2025-12-05 14:36:57,410 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) msgsnd: 0
2025-12-05 14:36:57,411 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) nivcsw: 163
2025-12-05 14:36:57,411 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) nsignals: 0
2025-12-05 14:36:57,411 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) nswap: 0
2025-12-05 14:36:57,412 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) nvcsw: 13
2025-12-05 14:36:57,412 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) oublock: 8
2025-12-05 14:36:57,413 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) stime: 0.24137899999999998
2025-12-05 14:36:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) utime: 0.579409
2025-12-05 14:36:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) wtime: 1.12
2025-12-05 14:36:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) output:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mj3z7qugmi
    Host information: 
        Linux ghrun-mj3z7qugmi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-05 14:36:57,415 - DEBUG - ya.test - _save_outputs: Command (pid 1374853) errors:

2025-12-05 14:36:57,415 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 9a02a3b9d304ae3db299329ab9f1e2779d1ed4ff
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge 110da6e58e84d1d47f793feefdb3f536909c7fc7 into 585820908c1313b1f0acebdafacf03477ac71cd2
2025-12-05 14:36:57,427 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_s1.py::TestTpchS1, test_name: test_tpch[1])
2025-12-05 14:36:57,433 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,437 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:57,467 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-12-05 14:36:57,475 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,479 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:36:57,498 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-12-05 14:36:57,500 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-12-05 14:36:57,501 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:57,501 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:57,502 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 92af3227-b1ec-4c5c-bd4f-360bf0dbf95f, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:57,503 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 92af3227-b1ec-4c5c-bd4f-360bf0dbf95f, ghrun-mj3z7qugmi.auto.internal:9794): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-12-05 14:36:57,511 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 92af3227-b1ec-4c5c-bd4f-360bf0dbf95f, ghrun-mj3z7qugmi.auto.internal:9794): 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: 1764945122230 tx_id: 281474976720659 } } } } } }
2025-12-05 14:36:57,515 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-12-05 14:36:57,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, e6d0818d-baad-490c-93ae-2467538ab5f9, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:57,516 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, e6d0818d-baad-490c-93ae-2467538ab5f9, ghrun-mj3z7qugmi.auto.internal:9794): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-12-05 14:36:57,580 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, e6d0818d-baad-490c-93ae-2467538ab5f9, ghrun-mj3z7qugmi.auto.internal:9794): 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: 1764945122230 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945122230 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945124590 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945125070 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945127260 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945129340 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945131540 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945131970 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1764945133840 tx_id: 281474976720666 } } } } } }
2025-12-05 14:36:57,585 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,590 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,622 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:57,625 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-12-05 14:36:57,630 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,638 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,669 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 580
2025-12-05 14:36:57,671 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-12-05 14:36:57,674 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,679 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,695 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 579
2025-12-05 14:36:57,698 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-12-05 14:36:57,701 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,705 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,729 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 579
2025-12-05 14:36:57,731 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-12-05 14:36:57,737 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,747 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,768 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 579
2025-12-05 14:36:57,771 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-12-05 14:36:57,775 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,782 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,801 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 579
2025-12-05 14:36:57,804 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-12-05 14:36:57,808 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,813 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,830 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 578
2025-12-05 14:36:57,833 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-12-05 14:36:57,837 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:36:57,842 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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-12-05 14:36:57,866 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "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 579
2025-12-05 14:36:57,869 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-12-05 14:36:57,870 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, bca96ea7-e820-4072-bb28-cf126383f829, ghrun-mj3z7qugmi.auto.internal:9794): creating call state
2025-12-05 14:36:57,871 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, bca96ea7-e820-4072-bb28-cf126383f829, ghrun-mj3z7qugmi.auto.internal:9794): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-12-05 14:36:57,874 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, bca96ea7-e820-4072-bb28-cf126383f829, ghrun-mj3z7qugmi.auto.internal:9794): response = { <_MultiThreadedRendezvous object> }
2025-12-05 14:36:57,989 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb
2025-12-05 14:36:57,997 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-12-05 14:36:57,999 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-12-05 14:36:57,999 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/kvke/000c9e/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:2031', '-d', '/local/test_db', 'workload', 'tpch', '--path', 'olap_yatests/tpch/s1', 'run', '--json', 'Query01.stats.json', '--output', 'Query01.result', '--include', 'Query01', '--iterations', '1', '--plan', 'Query01.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical', '--scale', '1']' in 'None' (waiting)
2025-12-05 14:36:58,002 - DEBUG - ya.test - execute: Command pid: 1375208
2025-12-05 14:37:22,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 14:37:22,809 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 14:37:22,840 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-12-05 14:37:22,841 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 76a3b768-a60d-408a-b76a-411f24f2f628, localhost:2031): creating call state
2025-12-05 14:37:22,842 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 76a3b768-a60d-408a-b76a-411f24f2f628, localhost:2031): request = { database: "local/test_db" }
2025-12-05 14:37:22,857 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 76a3b768-a60d-408a-b76a-411f24f2f628, localhost:2031): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mj3z7qugmi.auto.internal" port: 9794 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-12-05 14:37:22,859 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mj3z7qugmi.auto.internal:9794, location , ssl: False>]>
2025-12-05 14:37:22,860 - INFO - ydb.connection - close: Closing channel for endpoint localhost:2031
2025-12-05 14:37:42,749 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) rc: 0
2025-12-05 14:37:42,750 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) elapsed time (sec): 44.745912075042725
2025-12-05 14:37:42,750 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) idrss: 0
2025-12-05 14:37:42,750 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) inblock: 0
2025-12-05 14:37:42,750 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) isrss: 0
2025-12-05 14:37:42,750 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) ixrss: 0
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) majflt: 0
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) maxrss: 746488
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) minflt: 123487
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) msgrcv: 0
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) msgsnd: 0
2025-12-05 14:37:42,751 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) nivcsw: 335
2025-12-05 14:37:42,752 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) nsignals: 0
2025-12-05 14:37:42,752 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) nswap: 0
2025-12-05 14:37:42,752 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) nvcsw: 5634
2025-12-05 14:37:42,753 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) oublock: 5552
2025-12-05 14:37:42,753 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) stime: 0.410138
2025-12-05 14:37:42,753 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) utime: 0.912721
2025-12-05 14:37:42,754 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) wtime: 44.748
2025-12-05 14:37:42,754 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) 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	39.572255s seconds

Results for 1 iterations
┌─────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬────────────────┬────────────────┬────────────────┬───────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ CompilationMin │ CompilationMax │ CompilationAvg │ GrossTime │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query01 │  39.286  │  39.286 │  39.286 │  39.286 │  39.285 │  39.286   │   0.001 │   0.286 │   0.286 │   0.286 │   2.412        │   2.412        │   2.412        │  39.572   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Sum     │  39.286  │  39.286 │  39.286 │  39.286 │  39.285 │  39.286   │   0.000 │   0.286 │   0.286 │   0.286 │   2.412        │   2.412        │   2.413        │  43.566   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Avg     │  39.286  │  39.286 │  39.286 │  39.286 │  39.285 │  39.286   │   0.000 │   0.286 │   0.286 │   0.286 │   2.412        │   2.412        │   2.413        │  43.566   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ GAvg    │  39.286  │  39.286 │  39.286 │  39.286 │  39.285 │  39.286   │   0.000 │   0.286 │   0.286 │   0.001 │   2.412        │   2.412        │   0.001        │   0.000   │ 1            │            │            │
└─────────┴──────────┴─────────┴─────────┴─────────┴─────────┴───────────┴─────────┴─────────┴─────────┴─────────┴────────────────┴────────────────┴────────────────┴───────────┴──────────────┴────────────┴────────────┘

Results saved to Query01.result
Json report saved to Query01.stats.json

2025-12-05 14:37:42,755 - DEBUG - ya.test - _save_outputs: Command (pid 1375208) errors:

[K
[K
2025-12-05 14:37:42,755 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-12-05 14:37:42,815 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:37:42,829 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:37:42,846 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-12-05 14:37:42,874 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:37:42,927 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes HTTP/1.1" 200 808
2025-12-05 14:37:42,938 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:7890
2025-12-05 14:37:42,949 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-12-05 14:37:42,993 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:7890 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-12-05 14:37:42,999 - INFO - root - __create_iterations_table: All nodes before filtering: [('12935@ghrun-mj3z7qugmi.auto.internal', <Role.UNKNOWN: 0>)]
2025-12-05 14:37:43,000 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-12-05 14:37:43,001 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-mj3z7qugmi.auto.internal', 1)]
2025-12-05 14:37:43,001 - INFO - root - allure_test_description: iterations_table created, length: 514
2025-12-05 14:37:43,001 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-12-05 14:37:43,007 - INFO - ya.test - pytest_runtest_teardown: Test teardown
