2025-10-27 12:18:27,130 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:18:27,131 - INFO - ya.test - pytest_runtest_setup: test[Query01]
2025-10-27 12:18:27,131 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:18:27,132 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-27 12:18:27,244 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd
2025-10-27 12:18:27,246 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26758
2025-10-27 12:18:27,248 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/ccie/001fc7/r3tmp/kikimr_cluster_5dn9ox0a
2025-10-27 12:18:27,289 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd
2025-10-27 12:18:27,289 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18874
2025-10-27 12:18:27,290 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10355
2025-10-27 12:18:27,291 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24913
2025-10-27 12:18:27,291 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29103
2025-10-27 12:18:27,292 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:18:27,293 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/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/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/node_1/logfile_nsmvfjkk.log --grpc-port=18874 --mon-port=10355 --ic-port=26758
2025-10-27 12:18:27,293 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18874/1, disk_size 68719476736
2025-10-27 12:18:27,293 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:18:27,294 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/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/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/node_1/logfile_nsmvfjkk.log --grpc-port=18874 --mon-port=10355 --ic-port=26758
2025-10-27 12:18:27,295 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/node_1/logfile_nsmvfjkk.log', '--grpc-port=18874', '--mon-port=10355', '--ic-port=26758')' in '/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/node_1' (no wait)
2025-10-27 12:18:27,298 - DEBUG - ya.test - execute: Command pid: 1225035
2025-10-27 12:18:27,300 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18874/1
2025-10-27 12:18:27,301 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-27 12:18:27,302 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-27 12:18:27,303 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:18:27,305 - DEBUG - ya.test - execute: Command pid: 1225036
2025-10-27 12:18:28,088 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) rc: 0
2025-10-27 12:18:28,088 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) elapsed time (sec): 0.7662415504455566
2025-10-27 12:18:28,088 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) idrss: 0
2025-10-27 12:18:28,089 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) inblock: 0
2025-10-27 12:18:28,089 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) isrss: 0
2025-10-27 12:18:28,089 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) ixrss: 0
2025-10-27 12:18:28,090 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) majflt: 1
2025-10-27 12:18:28,094 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) maxrss: 567492
2025-10-27 12:18:28,094 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) minflt: 45517
2025-10-27 12:18:28,095 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) msgrcv: 0
2025-10-27 12:18:28,095 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) msgsnd: 0
2025-10-27 12:18:28,095 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) nivcsw: 63
2025-10-27 12:18:28,096 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) nsignals: 0
2025-10-27 12:18:28,101 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) nswap: 0
2025-10-27 12:18:28,102 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) nvcsw: 16
2025-10-27 12:18:28,102 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) oublock: 8
2025-10-27 12:18:28,102 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) stime: 0.205849
2025-10-27 12:18:28,102 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) utime: 0.509122
2025-10-27 12:18:28,103 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) wtime: 0.768
2025-10-27 12:18:28,104 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) output:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-s4m7ghuofe
    Host information: 
        Linux ghrun-s4m7ghuofe 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-10-27 12:18:28,105 - DEBUG - ya.test - _save_outputs: Command (pid 1225036) errors:

2025-10-27 12:18:28,105 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211
2025-10-27 12:18:30,729 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd
2025-10-27 12:18:30,731 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18874', '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: 26758\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-27 12:18:30,732 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-27 12:18:30,736 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-27 12:18:30,737 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18874', '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: 26758\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-27 12:18:30,739 - DEBUG - ya.test - execute: Command pid: 1225774
2025-10-27 12:18:31,588 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) rc: 0
2025-10-27 12:18:31,590 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) elapsed time (sec): 0.8303337097167969
2025-10-27 12:18:31,591 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) idrss: 0
2025-10-27 12:18:31,591 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) inblock: 0
2025-10-27 12:18:31,591 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) isrss: 0
2025-10-27 12:18:31,592 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) ixrss: 0
2025-10-27 12:18:31,594 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) majflt: 0
2025-10-27 12:18:31,594 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) maxrss: 642772
2025-10-27 12:18:31,595 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) minflt: 51555
2025-10-27 12:18:31,595 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) msgrcv: 0
2025-10-27 12:18:31,596 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) msgsnd: 0
2025-10-27 12:18:31,596 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) nivcsw: 62
2025-10-27 12:18:31,596 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) nsignals: 0
2025-10-27 12:18:31,597 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) nswap: 0
2025-10-27 12:18:31,598 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) nvcsw: 732
2025-10-27 12:18:31,599 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) oublock: 8
2025-10-27 12:18:31,599 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) stime: 0.24020899999999998
2025-10-27 12:18:31,600 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) utime: 0.563265
2025-10-27 12:18:31,600 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) wtime: 0.832
2025-10-27 12:18:31,600 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-27 12:18:31,601 - DEBUG - ya.test - _save_outputs: Command (pid 1225774) errors:

2025-10-27 12:18:31,603 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd
2025-10-27 12:18:31,604 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18874', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-27 12:18:31,605 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-27 12:18:31,606 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-27 12:18:31,606 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18874', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-27 12:18:31,610 - DEBUG - ya.test - execute: Command pid: 1226035
2025-10-27 12:18:32,415 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) rc: 0
2025-10-27 12:18:32,415 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) elapsed time (sec): 0.804084300994873
2025-10-27 12:18:32,416 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) idrss: 0
2025-10-27 12:18:32,416 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) inblock: 0
2025-10-27 12:18:32,417 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) isrss: 0
2025-10-27 12:18:32,418 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) ixrss: 0
2025-10-27 12:18:32,419 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) majflt: 0
2025-10-27 12:18:32,419 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) maxrss: 642828
2025-10-27 12:18:32,419 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) minflt: 51669
2025-10-27 12:18:32,420 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) msgrcv: 0
2025-10-27 12:18:32,421 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) msgsnd: 0
2025-10-27 12:18:32,421 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) nivcsw: 3
2025-10-27 12:18:32,422 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) nsignals: 0
2025-10-27 12:18:32,422 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) nswap: 0
2025-10-27 12:18:32,423 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) nvcsw: 724
2025-10-27 12:18:32,423 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) oublock: 8
2025-10-27 12:18:32,425 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) stime: 0.21603799999999998
2025-10-27 12:18:32,425 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) utime: 0.565608
2025-10-27 12:18:32,426 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) wtime: 0.806
2025-10-27 12:18:32,427 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-27 12:18:32,428 - DEBUG - ya.test - _save_outputs: Command (pid 1226035) errors:

2025-10-27 12:18:32,434 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-10-27 12:18:32,477 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-27 12:18:33,026 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd
2025-10-27 12:18:33,027 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16124
2025-10-27 12:18:33,028 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8779
2025-10-27 12:18:33,028 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10127
2025-10-27 12:18:33,029 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10697
2025-10-27 12:18:33,029 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15805
2025-10-27 12:18:33,030 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:18:33,031 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18874 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/slot_1/logfile_3fajo5kt.log --grpc-port=16124 --mon-port=8779 --ic-port=10127
2025-10-27 12:18:33,031 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:18:33,033 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18874 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/slot_1/logfile_3fajo5kt.log --grpc-port=16124 --mon-port=8779 --ic-port=10127
2025-10-27 12:18:33,035 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:18874', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/slot_1/logfile_3fajo5kt.log', '--grpc-port=16124', '--mon-port=8779', '--ic-port=10127')' in '/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/slot_1' (no wait)
2025-10-27 12:18:33,038 - DEBUG - ya.test - execute: Command pid: 1226503
2025-10-27 12:18:33,039 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16124/1
2025-10-27 12:18:33,042 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-27 12:18:33,043 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-27 12:18:33,043 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:18:33,045 - DEBUG - ya.test - execute: Command pid: 1226504
2025-10-27 12:18:33,782 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) rc: 0
2025-10-27 12:18:33,782 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) elapsed time (sec): 0.735029935836792
2025-10-27 12:18:33,782 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) idrss: 0
2025-10-27 12:18:33,783 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) inblock: 0
2025-10-27 12:18:33,783 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) isrss: 0
2025-10-27 12:18:33,783 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) ixrss: 0
2025-10-27 12:18:33,783 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) majflt: 0
2025-10-27 12:18:33,784 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) maxrss: 655080
2025-10-27 12:18:33,784 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) minflt: 45282
2025-10-27 12:18:33,784 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) msgrcv: 0
2025-10-27 12:18:33,784 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) msgsnd: 0
2025-10-27 12:18:33,784 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) nivcsw: 19
2025-10-27 12:18:33,785 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) nsignals: 0
2025-10-27 12:18:33,785 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) nswap: 0
2025-10-27 12:18:33,785 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) nvcsw: 15
2025-10-27 12:18:33,786 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) oublock: 8
2025-10-27 12:18:33,787 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) stime: 0.212343
2025-10-27 12:18:33,787 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) utime: 0.475941
2025-10-27 12:18:33,787 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) wtime: 0.737
2025-10-27 12:18:33,788 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) output:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-s4m7ghuofe
    Host information: 
        Linux ghrun-s4m7ghuofe 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-10-27 12:18:33,789 - DEBUG - ya.test - _save_outputs: Command (pid 1226504) errors:

2025-10-27 12:18:33,789 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211
2025-10-27 12:18:35,810 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb
2025-10-27 12:18:35,812 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-27 12:18:35,816 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-27 12:18:35,817 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18874', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s0_1', 'init', '--store=column', '--datetime-types=dt64']' in 'None' (waiting)
2025-10-27 12:18:35,819 - DEBUG - ya.test - execute: Command pid: 1228001
2025-10-27 12:18:51,485 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) rc: 0
2025-10-27 12:18:51,486 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) elapsed time (sec): 15.660905838012695
2025-10-27 12:18:51,486 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) idrss: 0
2025-10-27 12:18:51,487 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) inblock: 0
2025-10-27 12:18:51,487 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) isrss: 0
2025-10-27 12:18:51,487 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) ixrss: 0
2025-10-27 12:18:51,488 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) majflt: 0
2025-10-27 12:18:51,488 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) maxrss: 655784
2025-10-27 12:18:51,488 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) minflt: 109565
2025-10-27 12:18:51,489 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) msgrcv: 0
2025-10-27 12:18:51,489 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) msgsnd: 0
2025-10-27 12:18:51,489 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) nivcsw: 103
2025-10-27 12:18:51,492 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) nsignals: 0
2025-10-27 12:18:51,493 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) nswap: 0
2025-10-27 12:18:51,493 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) nvcsw: 3655
2025-10-27 12:18:51,494 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) oublock: 8
2025-10-27 12:18:51,495 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) stime: 0.274455
2025-10-27 12:18:51,495 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) utime: 0.43272
2025-10-27 12:18:51,495 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) wtime: 15.667
2025-10-27 12:18:51,496 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) output:
Init tables ...
Init tables ...Ok

2025-10-27 12:18:51,497 - DEBUG - ya.test - _save_outputs: Command (pid 1228001) errors:

2025-10-27 12:18:51,498 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb
2025-10-27 12:18:51,499 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-27 12:18:51,501 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-27 12:18:51,502 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18874', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s0_1', 'import', 'generator', '--scale=0.1']' in 'None' (waiting)
2025-10-27 12:18:51,504 - DEBUG - ya.test - execute: Command pid: 1230562
2025-10-27 12:19:22,974 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) rc: 0
2025-10-27 12:19:22,974 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) elapsed time (sec): 31.468939542770386
2025-10-27 12:19:22,975 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) idrss: 0
2025-10-27 12:19:22,975 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) inblock: 0
2025-10-27 12:19:22,975 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) isrss: 0
2025-10-27 12:19:22,976 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) ixrss: 0
2025-10-27 12:19:22,976 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) majflt: 0
2025-10-27 12:19:22,976 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) maxrss: 1175376
2025-10-27 12:19:22,977 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) minflt: 739032
2025-10-27 12:19:22,977 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) msgrcv: 0
2025-10-27 12:19:22,977 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) msgsnd: 0
2025-10-27 12:19:22,978 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) nivcsw: 3616
2025-10-27 12:19:22,978 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) nsignals: 0
2025-10-27 12:19:22,978 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) nswap: 0
2025-10-27 12:19:22,979 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) nvcsw: 124650
2025-10-27 12:19:22,979 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) oublock: 32
2025-10-27 12:19:22,979 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) stime: 5.053465
2025-10-27 12:19:22,980 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) utime: 31.564954
2025-10-27 12:19:22,980 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) wtime: 31.471
2025-10-27 12:19:22,980 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) output:
Fill table supplier...
Fill table supplier OK 1000 / 1000 (11.821629s)
Fill table customer...
Fill table customer OK 15000 / 15000 (0.951046s)
Fill table orders...
Fill table orders OK 150000 / 150000 (14.075275s)
Fill table part...
Fill table part OK 20000 / 20000 (2.498422s)
Fill table nation...
Fill table nation OK 25 / 25 (0.379806s)
Fill table region...
Fill table region OK 5 / 5 (0.319575s)

2025-10-27 12:19:22,980 - DEBUG - ya.test - _save_outputs: Command (pid 1230562) errors:







2025-10-27 12:19:22,981 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-10-27 12:19:22,998 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,003 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:19:23,037 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 545
2025-10-27 12:19:23,040 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-27 12:19:23,041 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:18874 to local/test_db ydb_access_token is set False
2025-10-27 12:19:23,043 - 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-10-27 12:19:23,047 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:19:23,048 - 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-10-27 12:19:23,081 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-27 12:19:23,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 92314851-30d1-45dd-811a-cc67e0e5a502, localhost:18874): creating call state
2025-10-27 12:19:23,083 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 92314851-30d1-45dd-811a-cc67e0e5a502, localhost:18874): request = { database: "local/test_db" }
2025-10-27 12:19:23,089 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 92314851-30d1-45dd-811a-cc67e0e5a502, localhost:18874): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-s4m7ghuofe.auto.internal" port: 16124 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-10-27 12:19:23,090 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-s4m7ghuofe.auto.internal:16124, location , ssl: False>]>
2025-10-27 12:19:23,091 - 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-10-27 12:19:23,106 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18874
2025-10-27 12:19:23,110 - INFO - root - _create_ydb_driver: Connected
2025-10-27 12:19:23,112 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 4464804a-1988-4f14-ad03-00adfc6a757a, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:23,113 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 4464804a-1988-4f14-ad03-00adfc6a757a, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:23,122 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 4464804a-1988-4f14-ad03-00adfc6a757a, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:23,398 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,402 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:19:23,426 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-10-27 12:19:23,435 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-27 12:19:23,436 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:23,436 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:23,437 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e1cca670-37b6-4cd6-a575-685a6297a8e0, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:23,438 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e1cca670-37b6-4cd6-a575-685a6297a8e0, ghrun-s4m7ghuofe.auto.internal:16124): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-10-27 12:19:23,454 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e1cca670-37b6-4cd6-a575-685a6297a8e0, ghrun-s4m7ghuofe.auto.internal:16124): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s0_1" 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: 1761567520020 tx_id: 281474976720659 } } } } } }
2025-10-27 12:19:23,455 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:23,456 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 5ffb9df0-0dfa-480d-b737-10e8328ae206, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:23,456 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 5ffb9df0-0dfa-480d-b737-10e8328ae206, ghrun-s4m7ghuofe.auto.internal:16124): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-10-27 12:19:23,471 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 5ffb9df0-0dfa-480d-b737-10e8328ae206, ghrun-s4m7ghuofe.auto.internal:16124): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s0_1" 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: 1761567520020 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567520020 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567522150 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567522550 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567524500 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567526690 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567528770 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567529080 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567530970 tx_id: 281474976720666 } } } } } }
2025-10-27 12:19:23,477 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,486 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/customer&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,501 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/customer&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,504 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/customer balance: 64-64 shards.
2025-10-27 12:19:23,508 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,514 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/lineitem&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,564 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/lineitem&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,568 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/lineitem balance: 64-64 shards.
2025-10-27 12:19:23,573 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,577 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/nation&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,596 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/nation&tablets=true HTTP/1.1" 200 574
2025-10-27 12:19:23,599 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/nation balance: 1-1 shards.
2025-10-27 12:19:23,614 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,619 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/orders&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,659 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/orders&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,662 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/orders balance: 64-64 shards.
2025-10-27 12:19:23,666 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,679 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/part&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,703 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/part&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,709 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/part balance: 64-64 shards.
2025-10-27 12:19:23,714 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,717 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/partsupp&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,754 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/partsupp&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,757 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/partsupp balance: 64-64 shards.
2025-10-27 12:19:23,761 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,771 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/region&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,786 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/region&tablets=true HTTP/1.1" 200 574
2025-10-27 12:19:23,790 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/region balance: 1-1 shards.
2025-10-27 12:19:23,794 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:23,798 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/supplier&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:23,830 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/supplier&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:23,833 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/supplier balance: 64-64 shards.
2025-10-27 12:19:23,835 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 9cd80576-7490-4ac0-bbf7-0bff468c4b0a, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:23,836 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 9cd80576-7490-4ac0-bbf7-0bff468c4b0a, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:23,838 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 9cd80576-7490-4ac0-bbf7-0bff468c4b0a, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:23,935 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, cdaa6e95-38dd-487e-8d91-e8f6eed6f6a7, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:23,936 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, cdaa6e95-38dd-487e-8d91-e8f6eed6f6a7, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/customer`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:23,939 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, cdaa6e95-38dd-487e-8d91-e8f6eed6f6a7, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:23,946 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:25,894 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 498643ec-7303-48d0-bac1-1a1f623e1746, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:25,895 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 498643ec-7303-48d0-bac1-1a1f623e1746, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/nation`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:25,898 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 498643ec-7303-48d0-bac1-1a1f623e1746, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:25,899 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:26,983 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b4ce5d6c-43d9-4342-9169-ae0c7c21f129, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:26,984 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b4ce5d6c-43d9-4342-9169-ae0c7c21f129, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/orders`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:26,986 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b4ce5d6c-43d9-4342-9169-ae0c7c21f129, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:26,986 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:28,925 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, fe1b22b6-16a2-4e39-89f1-52978732387a, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:28,925 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, fe1b22b6-16a2-4e39-89f1-52978732387a, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/part`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:28,927 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, fe1b22b6-16a2-4e39-89f1-52978732387a, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:28,928 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:30,244 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, e5ac4714-338f-4783-b011-b42aa732d7da, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:30,245 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, e5ac4714-338f-4783-b011-b42aa732d7da, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/partsupp`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:30,248 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, e5ac4714-338f-4783-b011-b42aa732d7da, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:30,248 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:31,685 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, f8b06425-8aeb-4266-9b1e-c69146bebf37, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:31,686 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, f8b06425-8aeb-4266-9b1e-c69146bebf37, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/region`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:31,698 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, f8b06425-8aeb-4266-9b1e-c69146bebf37, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:31,699 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:32,858 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 709bf887-4998-40f6-baf4-4edcf6b7ebe9, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:32,859 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 709bf887-4998-40f6-baf4-4edcf6b7ebe9, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/supplier`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:32,863 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 709bf887-4998-40f6-baf4-4edcf6b7ebe9, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:32,863 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:34,423 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c0d17a5a-bc1d-4899-a402-267bffaa8d00, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:34,424 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c0d17a5a-bc1d-4899-a402-267bffaa8d00, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s0_1/lineitem`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:34,426 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c0d17a5a-bc1d-4899-a402-267bffaa8d00, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:34,427 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-27 12:19:36,581 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,592 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:19:36,618 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-10-27 12:19:36,634 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,639 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:19:36,653 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-10-27 12:19:36,657 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-27 12:19:36,657 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:36,658 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:36,658 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e50b8d46-2f52-41b5-bc26-eef5cdf4a562, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:36,659 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e50b8d46-2f52-41b5-bc26-eef5cdf4a562, ghrun-s4m7ghuofe.auto.internal:16124): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-10-27 12:19:36,669 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e50b8d46-2f52-41b5-bc26-eef5cdf4a562, ghrun-s4m7ghuofe.auto.internal:16124): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s0_1" 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: 1761567520020 tx_id: 281474976720659 } } } } } }
2025-10-27 12:19:36,670 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s0_1
2025-10-27 12:19:36,670 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, c16684cf-d252-4dc8-bbfd-76c9afcdfc13, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:36,671 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, c16684cf-d252-4dc8-bbfd-76c9afcdfc13, ghrun-s4m7ghuofe.auto.internal:16124): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-10-27 12:19:36,681 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, c16684cf-d252-4dc8-bbfd-76c9afcdfc13, ghrun-s4m7ghuofe.auto.internal:16124): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s0_1" 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: 1761567520020 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567520020 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567522150 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567522550 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567524500 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567526690 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567528770 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567529080 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1761567530970 tx_id: 281474976720666 } } } } } }
2025-10-27 12:19:36,687 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,692 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/customer&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,706 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/customer&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,708 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/customer balance: 64-64 shards.
2025-10-27 12:19:36,712 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,716 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/lineitem&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,728 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/lineitem&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,731 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/lineitem balance: 64-64 shards.
2025-10-27 12:19:36,735 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,739 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/nation&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,755 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/nation&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:36,757 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/nation balance: 1-1 shards.
2025-10-27 12:19:36,760 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,769 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/orders&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,782 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/orders&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,784 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/orders balance: 64-64 shards.
2025-10-27 12:19:36,787 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,792 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/part&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,808 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/part&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,811 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/part balance: 64-64 shards.
2025-10-27 12:19:36,817 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,828 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/partsupp&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,842 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/partsupp&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,844 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/partsupp balance: 64-64 shards.
2025-10-27 12:19:36,850 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,855 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/region&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,871 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/region&tablets=true HTTP/1.1" 200 575
2025-10-27 12:19:36,873 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/region balance: 1-1 shards.
2025-10-27 12:19:36,877 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:19:36,883 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/supplier&tablets=true HTTP/1.1" 307 0
2025-10-27 12:19:36,909 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s0_1/supplier&tablets=true HTTP/1.1" 200 576
2025-10-27 12:19:36,911 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/supplier balance: 64-64 shards.
2025-10-27 12:19:36,913 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 9ed306db-d664-41d3-92ae-0fad7addeb0a, ghrun-s4m7ghuofe.auto.internal:16124): creating call state
2025-10-27 12:19:36,914 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 9ed306db-d664-41d3-92ae-0fad7addeb0a, ghrun-s4m7ghuofe.auto.internal:16124): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-27 12:19:36,917 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 9ed306db-d664-41d3-92ae-0fad7addeb0a, ghrun-s4m7ghuofe.auto.internal:16124): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:19:37,027 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb
2025-10-27 12:19:37,029 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-27 12:19:37,030 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-27 12:19:37,031 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18874', '-d', '/local/test_db', 'workload', 'tpch', '--path', 'olap_yatests/tpch/s0_1', 'run', '--json', 'f824baa9cfbbd7f7b5193c0ff93cc740.stats.json', '--output', 'f824baa9cfbbd7f7b5193c0ff93cc740.result', '--include', 'Query01,Query02,Query03,Query04,Query05,Query06,Query07,Query08,Query09,Query10,Query11,Query12,Query13,Query14,Query15,Query16,Query17,Query18,Query19,Query20,Query21,Query22', '--iterations', '1', '--plan', 'f824baa9cfbbd7f7b5193c0ff93cc740.plan', '--global-timeout', '1800.0s', '--verbose', '--scale', '0.1', '--threads', '10']' in 'None' (waiting)
2025-10-27 12:19:37,042 - DEBUG - ya.test - execute: Command pid: 1239715
2025-10-27 12:21:16,711 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:21:16,712 - 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-10-27 12:21:16,723 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-27 12:21:16,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, edf3a2e1-546a-4c4a-902b-e74b563c3fa2, localhost:18874): creating call state
2025-10-27 12:21:16,725 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, edf3a2e1-546a-4c4a-902b-e74b563c3fa2, localhost:18874): request = { database: "local/test_db" }
2025-10-27 12:21:16,730 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, edf3a2e1-546a-4c4a-902b-e74b563c3fa2, localhost:18874): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-s4m7ghuofe.auto.internal" port: 16124 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-10-27 12:21:16,731 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-s4m7ghuofe.auto.internal:16124, location , ssl: False>]>
2025-10-27 12:21:16,732 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18874
2025-10-27 12:22:31,846 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:22:31,846 - 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-10-27 12:22:31,859 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-27 12:22:31,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e582d713-ca96-455b-9948-999b5faa27b5, localhost:18874): creating call state
2025-10-27 12:22:31,861 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e582d713-ca96-455b-9948-999b5faa27b5, localhost:18874): request = { database: "local/test_db" }
2025-10-27 12:22:31,869 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e582d713-ca96-455b-9948-999b5faa27b5, localhost:18874): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-s4m7ghuofe.auto.internal" port: 16124 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-10-27 12:22:31,870 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-s4m7ghuofe.auto.internal:16124, location , ssl: False>]>
2025-10-27 12:22:31,871 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18874
2025-10-27 12:23:58,677 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:23:58,678 - 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-10-27 12:23:58,688 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-27 12:23:58,689 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e1125fbb-ee44-49b4-828c-dfe155f5a217, localhost:18874): creating call state
2025-10-27 12:23:58,690 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e1125fbb-ee44-49b4-828c-dfe155f5a217, localhost:18874): request = { database: "local/test_db" }
2025-10-27 12:23:58,695 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e1125fbb-ee44-49b4-828c-dfe155f5a217, localhost:18874): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-s4m7ghuofe.auto.internal" port: 16124 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-10-27 12:23:58,696 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-s4m7ghuofe.auto.internal:16124, location , ssl: False>]>
2025-10-27 12:23:58,697 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18874
2025-10-27 12:24:16,628 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) rc: 0
2025-10-27 12:24:16,628 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) elapsed time (sec): 279.57144808769226
2025-10-27 12:24:16,628 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) idrss: 0
2025-10-27 12:24:16,628 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) inblock: 0
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) isrss: 0
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) ixrss: 0
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) majflt: 0
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) maxrss: 660804
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) minflt: 836623
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) msgrcv: 0
2025-10-27 12:24:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) msgsnd: 0
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) nivcsw: 5206
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) nsignals: 0
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) nswap: 0
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) nvcsw: 28180
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) oublock: 1447448
2025-10-27 12:24:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) stime: 5.05232
2025-10-27 12:24:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) utime: 46.128079
2025-10-27 12:24:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) wtime: 279.581
2025-10-27 12:24:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) 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/s0_1/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	18.505673s seconds
Query02:
Query text:
$r = (
select
    r_regionkey
from 
    `olap_yatests/tpch/s0_1/region`
where
    r_name='EUROPE'
);
$n = (
select
    n_name,
    n_nationkey
from
    `olap_yatests/tpch/s0_1/nation` as n 
left semi join
    $r as r
on 
    n.n_regionkey = r.r_regionkey
);
$s1 = (
select
    s_suppkey
from
    `olap_yatests/tpch/s0_1/supplier` as s
left semi join
    $n as n
on
    s.s_nationkey = n.n_nationkey
);
$min_ps_supplycost = (
select
    min(ps_supplycost) as min_ps_supplycost,
    ps.ps_partkey as ps_partkey
from
    `olap_yatests/tpch/s0_1/partsupp` as ps
left semi join
    $s1 as s
on
    ps.ps_suppkey = s.s_suppkey
group by
    ps.ps_partkey
);
$p = (
select
    p_partkey,
    p_mfgr
from
    `olap_yatests/tpch/s0_1/part`
where
    p_size = 15
    and p_type like '%BRASS'
);
$ps = (
select
    ps.ps_partkey as ps_partkey,
    p.p_mfgr as p_mfgr,
    ps.ps_supplycost as ps_supplycost,
    ps.ps_suppkey as ps_suppkey
from
    `olap_yatests/tpch/s0_1/partsupp` as ps
join
    $p as p
on
    p.p_partkey = ps.ps_partkey
);
$s2 = (
select
    s_acctbal,
    s_name,
    s_address,
    s_phone,
    s_comment,
    s_suppkey,
    n_name
from
    `olap_yatests/tpch/s0_1/supplier` as s
join
    $n as n
on
    s.s_nationkey = n.n_nationkey
);
$jp =(
select
    ps_partkey,
    ps_supplycost,
    p_mfgr,
    s_acctbal,
    s_name,
    s_address,
    s_phone,
    s_comment,
    n_name
from
    $ps as ps
join    
    $s2 as s
on
    ps.ps_suppkey = s.s_suppkey  
);
select
    s_acctbal,
    s_name,
    n_name,
    jp.ps_partkey as p_partkey,
    p_mfgr,
    s_address,
    s_phone,
    s_comment
from
    $jp as jp
join
    $min_ps_supplycost as m
on
    jp.ps_partkey = m.ps_partkey
where
    min_ps_supplycost = ps_supplycost
order by
    s_acctbal desc,
    n_name,
    s_name,
    p_partkey
limit 100;

	iteration 0:	ok	48.831767s seconds
Query03:
Query text:
$z1_12 = 1;
$round = ($x, $y) -> { return Math::Round($x, $y); };
$c = (
select
    c_custkey
from
    `olap_yatests/tpch/s0_1/customer`
where
    c_mktsegment = 'BUILDING'
);
$o = (
select
    o_orderdate,
    o_shippriority,
    o_orderkey
from
    `olap_yatests/tpch/s0_1/orders` as o
left semi join
    $c as c
on
    c.c_custkey = o.o_custkey
where
    o_orderdate < Date('1995-03-15')
);
$join2 = (
select
    o.o_orderdate as o_orderdate,
    o.o_shippriority as o_shippriority,
    l.l_orderkey as l_orderkey,
    l.l_discount as l_discount,
    l.l_extendedprice as l_extendedprice
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join
    $o as o
on
    l.l_orderkey = o.o_orderkey
where
    l_shipdate > Date('1995-03-15')
);
select
    l_orderkey,
    $round(sum(l_extendedprice * ($z1_12 - l_discount)), -3) as revenue,
    o_orderdate,
    o_shippriority
from
    $join2
group by
    l_orderkey,
    o_orderdate,
    o_shippriority
order by
    revenue desc,
    o_orderdate,
    l_orderkey
limit 10;

	iteration 0:	ok	234.124743s seconds
Query04:
Query text:
$border = Date("1993-07-01");
$o = (
select
    o_orderpriority,
    o_orderkey
from
    `olap_yatests/tpch/s0_1/orders`
where
    o_orderdate >= $border
    and o_orderdate < DateTime::MakeDate(DateTime::ShiftMonths($border, 3))
);
$l = (
select
    distinct l_orderkey
from
    `olap_yatests/tpch/s0_1/lineitem`
where
    l_commitdate < l_receiptdate
);
select
    o.o_orderpriority as o_orderpriority,
    count(*) as order_count
from
    $o as o
join
    $l as l
on
    o.o_orderkey = l.l_orderkey
group by
    o.o_orderpriority
order by
    o_orderpriority;

	iteration 0:	ok	50.593339s seconds
Query05:
Query text:
$z1_12 = 1;
$border = Date("1994-01-01");
$j1 = (
select
    n.n_name as n_name,
    n.n_nationkey as n_nationkey
from
    `olap_yatests/tpch/s0_1/nation` as n
join
    `olap_yatests/tpch/s0_1/region` as r
on
    n.n_regionkey = r.r_regionkey
where
    r_name = 'ASIA'
);
$j2 = (
select
    j.n_name as n_name,
    j.n_nationkey as n_nationkey,
    s.s_suppkey as s_suppkey
from
    `olap_yatests/tpch/s0_1/supplier` as s
join
    $j1 as j
on
    j.n_nationkey = s.s_nationkey
);
$j3 = (
select
    j.n_name as n_name,
    j.n_nationkey as n_nationkey,
    l.l_extendedprice as l_extendedprice,
    l.l_discount as l_discount,
    l.l_orderkey as l_orderkey
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join
    $j2 as j
on
    l.l_suppkey = j.s_suppkey
);
$j4 = (
select
    o.o_orderkey as o_orderkey,
    c.c_nationkey as c_nationkey
from
    `olap_yatests/tpch/s0_1/orders` as o
join
    `olap_yatests/tpch/s0_1/customer` as c
on
    c.c_custkey = o.o_custkey
where
    o.o_orderdate >= $border
    and o.o_orderdate < ($border + Interval("P365D"))
);
$j5 = (
select
    j3.n_name as n_name,
    j3.l_extendedprice as l_extendedprice,
    j3.l_discount as l_discount
from
    $j3 as j3
join
    $j4 as j4
on
    j3.n_nationkey = j4.c_nationkey
    and j3.l_orderkey = j4.o_orderkey
);
select
    n_name,
    sum(l_extendedprice * ($z1_12 - l_discount)) as revenue
from
    $j5
group by
    n_name
order by
    revenue desc;

	iteration 0:	ok	37.811542s seconds
Query06:
Query text:
$z0_0100001_12 = 0.0100001;
$z0_06_12 = 0.06;
$border = Date("1994-01-01");
select
    sum(l_extendedprice * l_discount) as revenue
from
    `olap_yatests/tpch/s0_1/lineitem`
where
    l_shipdate >= $border 
    and l_shipdate < ($border + Interval("P365D"))
    and l_discount between $z0_06_12 - $z0_0100001_12 and $z0_06_12 + $z0_0100001_12
    and l_quantity < 24;

	iteration 0:	ok	159.087389s seconds
Query07:
Query text:
$z1_12 = 1;
$n = select n_name, n_nationkey from `olap_yatests/tpch/s0_1/nation` as n
    where n_name = 'FRANCE' or n_name = 'GERMANY';
$l = select 
    l_orderkey, l_suppkey,
    DateTime::GetYear(cast(l_shipdate as timestamp)) as l_year,
    l_extendedprice * ($z1_12 - l_discount) as volume
from 
    `olap_yatests/tpch/s0_1/lineitem` as l
where 
    l.l_shipdate between Date('1995-01-01') and Date('1996-12-31');
$j1 = select 
    n_name as supp_nation,
    s_suppkey
from 
    `olap_yatests/tpch/s0_1/supplier` as supplier
join 
    $n as n1
on 
    supplier.s_nationkey = n1.n_nationkey;
$j2 = select
    n_name as cust_nation,
    c_custkey
from 
    `olap_yatests/tpch/s0_1/customer` as customer
join 
    $n as n2
on 
    customer.c_nationkey = n2.n_nationkey;
$j3 = select
    cust_nation, o_orderkey
from 
    `olap_yatests/tpch/s0_1/orders` as orders
join 
    $j2 as customer
on 
    orders.o_custkey = customer.c_custkey;
$j4 = select
    cust_nation,
    l_orderkey, l_suppkey,
    l_year,
    volume
from 
    $l as lineitem
join 
    $j3 as orders
on 
    lineitem.l_orderkey = orders.o_orderkey;
$j5 = select
    supp_nation, cust_nation,
    l_year, volume
from 
    $j4 as lineitem
join 
    $j1 as supplier
on 
    lineitem.l_suppkey = supplier.s_suppkey
where (supp_nation = 'FRANCE' and cust_nation = 'GERMANY')
    OR (supp_nation = 'GERMANY' and cust_nation = 'FRANCE');
select
    supp_nation,
    cust_nation,
    l_year, 
    sum(volume) as revenue
from
    $j5 as shipping
group by
    supp_nation,
    cust_nation,
    l_year
order by
    supp_nation,
    cust_nation,
    l_year;

	iteration 0:	ok	215.286682s seconds
Query08:
Query text:
$z0_12 = 0.;
$z1_12 = 1;
$join1 = (
select
    l.l_extendedprice * ($z1_12 - l.l_discount) as volume,
    l.l_suppkey as l_suppkey,
    l.l_orderkey as l_orderkey
from
    `olap_yatests/tpch/s0_1/part` as p
join
    `olap_yatests/tpch/s0_1/lineitem` as l
on
    p.p_partkey = l.l_partkey
where
    p.p_type = 'ECONOMY ANODIZED STEEL'
);
$join2 = (
select
    j.volume as volume,
    j.l_orderkey as l_orderkey,
    s.s_nationkey as s_nationkey
from
    $join1 as j
join
    `olap_yatests/tpch/s0_1/supplier` as s
on
    s.s_suppkey = j.l_suppkey
);
$join3 = (
select
    j.volume as volume,
    j.l_orderkey as l_orderkey,
    n.n_name as nation
from
    $join2 as j
join
    `olap_yatests/tpch/s0_1/nation` as n
on
    n.n_nationkey = j.s_nationkey
);
$join4 = (
select
    j.volume as volume,
    j.nation as nation,
    DateTime::GetYear(cast(o.o_orderdate as Timestamp)) as o_year,
    o.o_custkey as o_custkey
from
    $join3 as j
join
    `olap_yatests/tpch/s0_1/orders` as o
on
    o.o_orderkey = j.l_orderkey
where o_orderdate between Date('1995-01-01') and Date('1996-12-31')
);
$join5 = (
select
    j.volume as volume,
    j.nation as nation,
    j.o_year as o_year,
    c.c_nationkey as c_nationkey
from
    $join4 as j
join
    `olap_yatests/tpch/s0_1/customer` as c
on
    c.c_custkey = j.o_custkey
);
$join6 = (
select
    j.volume as volume,
    j.nation as nation,
    j.o_year as o_year,
    n.n_regionkey as n_regionkey
from
    $join5 as j
join
    `olap_yatests/tpch/s0_1/nation` as n
on
    n.n_nationkey = j.c_nationkey
);
$join7 = (
select
    j.volume as volume,
    j.nation as nation,
    j.o_year as o_year
from
    $join6 as j
join
    `olap_yatests/tpch/s0_1/region` as r
on
    r.r_regionkey = j.n_regionkey
where
    r.r_name = 'AMERICA'
);
select
    o_year,
    sum(case
        when nation = 'BRAZIL' then volume
        else $z0_12
    end) / sum(volume) as mkt_share
from
    $join7 as all_nations
group by
    o_year
order by
    o_year;

	iteration 0:	ok	232.546484s seconds
Query09:
Query text:
$z1_12 = 1;
$p = (select p_partkey, p_name
from
    `olap_yatests/tpch/s0_1/part`
where p_name like '%green%');
$j1 = (select ps_partkey, ps_suppkey, ps_supplycost
from
    `olap_yatests/tpch/s0_1/partsupp` as ps
join $p as p
on ps.ps_partkey = p.p_partkey);
$j2 = (select l_suppkey, l_partkey, l_orderkey, l_extendedprice, l_discount, ps_supplycost, l_quantity
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join $j1 as j
on l.l_suppkey = j.ps_suppkey AND l.l_partkey = j.ps_partkey);
$j3 = (select l_orderkey, s_nationkey, l_extendedprice, l_discount, ps_supplycost, l_quantity
from
    `olap_yatests/tpch/s0_1/supplier` as s
join $j2 as j
on j.l_suppkey = s.s_suppkey);
$j4 = (select o_orderdate, l_extendedprice, l_discount, ps_supplycost, l_quantity, s_nationkey
from
    `olap_yatests/tpch/s0_1/orders` as o
join $j3 as j
on o.o_orderkey = j.l_orderkey);
$j5 = (select n_name, o_orderdate, l_extendedprice, l_discount, ps_supplycost, l_quantity
from
    `olap_yatests/tpch/s0_1/nation` as n
join $j4 as j
on j.s_nationkey = n.n_nationkey
);
$profit = (select 
    n_name as nation,
    DateTime::GetYear(cast(o_orderdate as timestamp)) as o_year,
    l_extendedprice * ($z1_12 - l_discount) - ps_supplycost * l_quantity as amount
from $j5);
select
    nation,
    o_year,
    sum(amount) as sum_profit
from $profit
group by
    nation,
    o_year
order by
    nation,
    o_year desc;

	iteration 0:	ok	75.018044s seconds
Query10:
Query text:
$z1_12 = 1;
$border = Date("1993-10-01");
$join1 = (
select
    c.c_custkey as c_custkey,
    c.c_name as c_name,
    c.c_acctbal as c_acctbal,
    c.c_address as c_address,
    c.c_phone as c_phone,
    c.c_comment as c_comment,
    c.c_nationkey as c_nationkey,
    o.o_orderkey as o_orderkey
from
    `olap_yatests/tpch/s0_1/customer` as c
join
    `olap_yatests/tpch/s0_1/orders` as o
on
    c.c_custkey = o.o_custkey
where
    o.o_orderdate >= $border
    and o.o_orderdate < ($border + Interval("P92D"))
);
$join2 = (
select
    j.c_custkey as c_custkey,
    j.c_name as c_name,
    j.c_acctbal as c_acctbal,
    j.c_address as c_address,
    j.c_phone as c_phone,
    j.c_comment as c_comment,
    j.c_nationkey as c_nationkey,
    l.l_extendedprice as l_extendedprice,
    l.l_discount as l_discount
from
    $join1 as j 
join
    `olap_yatests/tpch/s0_1/lineitem` as l
on
    l.l_orderkey = j.o_orderkey
where
    l.l_returnflag = 'R'
);
$join3 = (
select
    j.c_custkey as c_custkey,
    j.c_name as c_name,
    j.c_acctbal as c_acctbal,
    j.c_address as c_address,
    j.c_phone as c_phone,
    j.c_comment as c_comment,
    j.c_nationkey as c_nationkey,
    j.l_extendedprice as l_extendedprice,
    j.l_discount as l_discount,
    n.n_name as n_name
from
    $join2 as j 
join
    `olap_yatests/tpch/s0_1/nation` as n
on
    n.n_nationkey = j.c_nationkey
);
select
    c_custkey,
    c_name,
    sum(l_extendedprice * ($z1_12 - l_discount)) as revenue,
    c_acctbal,
    n_name,
    c_address,
    c_phone,
    c_comment
from
    $join3
group by
    c_custkey,
    c_name,
    c_acctbal,
    c_phone,
    n_name,
    c_address,
    c_comment
order by
    revenue desc
limit 20;

	iteration 0:	ok	244.017800s seconds
Query11:
Query text:
$z0_0001_35 = 0.0001;
$scale_factor = 0.1;
$j1 = (
select
    s.s_suppkey as s_suppkey
from
    `olap_yatests/tpch/s0_1/supplier` as s
join
    `olap_yatests/tpch/s0_1/nation` as n
on
    n.n_nationkey = s.s_nationkey
where
    n.n_name = 'GERMANY'
);
$j2 = (
select
    ps.ps_partkey as ps_partkey,
    ps.ps_supplycost as ps_supplycost,
    ps.ps_availqty as ps_availqty
from
    `olap_yatests/tpch/s0_1/partsupp` as ps
join
    $j1 as j
on
    ps.ps_suppkey = j.s_suppkey
);
$threshold = (
select
    sum(ps_supplycost * ps_availqty) * $z0_0001_35 / $scale_factor as threshold
from
    $j2
);
$values = (
select
    ps_partkey,
    sum(ps_supplycost * ps_availqty) as value
from
    $j2
group by
    ps_partkey
);
select
    v.ps_partkey as ps_partkey,
    v.value as value
from
    $values as v
cross join
    $threshold as t
where
    v.value > t.threshold
order by
    value desc;

	iteration 0:	ok	117.362101s seconds
Query12:
Query text:
$join = (
    select
        l.l_shipmode as l_shipmode,
        o.o_orderpriority as o_orderpriority,
        l.l_commitdate as l_commitdate,
        l.l_shipdate as l_shipdate,
        l.l_receiptdate as l_receiptdate
    from
        `olap_yatests/tpch/s0_1/orders` as o
        join `olap_yatests/tpch/s0_1/lineitem` as l
        on o.o_orderkey = l.l_orderkey
);
$border = Date("1994-01-01");
select
    l_shipmode,
    sum(case
        when o_orderpriority = '1-URGENT'
            or o_orderpriority = '2-HIGH'
            then 1
        else 0
    end) as high_line_count,
    sum(case
        when o_orderpriority <> '1-URGENT'
            and o_orderpriority <> '2-HIGH'
            then 1
        else 0
    end) as low_line_count
from $join
where
    (l_shipmode = 'MAIL' or l_shipmode = 'SHIP')
    and l_commitdate < l_receiptdate
    and l_shipdate < l_commitdate
    and l_receiptdate >= $border
    and l_receiptdate < ($border + Interval("P365D"))
group by
    l_shipmode
order by
    l_shipmode;

	iteration 0:	ok	243.050324s seconds
Query13:
Query text:
$orders = (
    select
        o_orderkey,
        o_custkey
    from
        `olap_yatests/tpch/s0_1/orders`
    where
        o_comment NOT LIKE "%special%requests%"
);
select
    c_count as c_count,
    count(*) as custdist
from
    (
        select
            c.c_custkey as c_custkey,
            count(o.o_orderkey) as c_count
        from
            `olap_yatests/tpch/s0_1/customer` as c left outer join $orders as o on
                c.c_custkey = o.o_custkey
        group by
            c.c_custkey
    ) as c_orders
group by
    c_count
order by
    custdist desc,
    c_count desc;

	iteration 0:	ok	24.315531s seconds
Query14:
Query text:
$z100_35 = 100.;
$z0_12 = 0.;
$z1_12 = 1;
$border = Date("1995-09-01");
select
    $z100_35 * sum(case
        when p.p_type like 'PROMO%'
            then l.l_extendedprice * ($z1_12 - l.l_discount)
        else $z0_12
    end) / sum(l.l_extendedprice * ($z1_12 - l.l_discount)) as promo_revenue
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join
    `olap_yatests/tpch/s0_1/part` as p
on
    l.l_partkey = p.p_partkey
where
    l.l_shipdate >= $border
    and l.l_shipdate < ($border + Interval("P30D"));

	iteration 0:	ok	47.937309s seconds
Query15:
Query text:
$z1_12 = 1;
$round = ($x, $y) -> { return Math::Round($x, $y); };
$border = Date("1996-01-01");
$revenue0 = (
    select
        l_suppkey as supplier_no,
        $round(sum(l_extendedprice * ($z1_12 - l_discount)), -8) as total_revenue
    from
        `olap_yatests/tpch/s0_1/lineitem`
    where
        l_shipdate  >= $border
        and l_shipdate < ($border + Interval("P91D"))
    group by
        l_suppkey
);
$max_revenue = (
select
    max(total_revenue) as max_revenue
from
    $revenue0
);
$join1 = (
select
    s.s_suppkey as s_suppkey,
    s.s_name as s_name,
    s.s_address as s_address,
    s.s_phone as s_phone,
    r.total_revenue as total_revenue
from
    `olap_yatests/tpch/s0_1/supplier` as s
join
    $revenue0 as r
on
    s.s_suppkey = r.supplier_no
);
select
    j.s_suppkey as s_suppkey,
    j.s_name as s_name,
    j.s_address as s_address,
    j.s_phone as s_phone,
    j.total_revenue as total_revenue
from
    $join1 as j
join
    $max_revenue as m
on
    j.total_revenue = m.max_revenue
order by
    s_suppkey;

	iteration 0:	ok	42.524654s seconds
Query16:
Query text:
$p = (
select
    p.p_brand as p_brand,
    p.p_type as p_type,
    p.p_size as p_size,
    ps.ps_suppkey as ps_suppkey
from
    `olap_yatests/tpch/s0_1/part` as p
join
    `olap_yatests/tpch/s0_1/partsupp` as ps
on
    p.p_partkey = ps.ps_partkey
where
    p.p_brand <> 'Brand#45'
    and p.p_type not like 'MEDIUM POLISHED%'
    and (p.p_size = 49 or p.p_size = 14 or p.p_size = 23 or p.p_size = 45 or p.p_size = 19 or p.p_size = 3 or p.p_size = 36 or p.p_size = 9)
);
$s = (
select
    s_suppkey
from
    `olap_yatests/tpch/s0_1/supplier`
where
    s_comment like "%Customer%Complaints%"
);
$j = (
select
    p.p_brand as p_brand,
    p.p_type as p_type,
    p.p_size as p_size,
    p.ps_suppkey as ps_suppkey
from
    $p as p
left only join
    $s as s
on
    p.ps_suppkey = s.s_suppkey
);
select
    j.p_brand as p_brand,
    j.p_type as p_type,
    j.p_size as p_size,
    count(distinct j.ps_suppkey) as supplier_cnt
from
    $j as j
group by
    j.p_brand,
    j.p_type,
    j.p_size
order by
    supplier_cnt desc,
    p_brand,
    p_type,
    p_size
;

	iteration 0:	ok	41.758086s seconds
Query17:
Query text:
$z7_35 = 7.;
$z0_2_12 = 0.2;
$p = select p_partkey from `olap_yatests/tpch/s0_1/part`
where
    p_brand = 'Brand#23'
    and p_container = 'MED BOX'
;
$threshold = (
select
    $z0_2_12 * avg(l_quantity) as threshold,
    l.l_partkey as l_partkey
from
    `olap_yatests/tpch/s0_1/lineitem` as l
left semi join
    $p as p
on  
    p.p_partkey = l.l_partkey
group by
    l.l_partkey
);
$l = select l.l_partkey as l_partkey, l.l_quantity as l_quantity, l.l_extendedprice as l_extendedprice
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join
    $p as p
on  
    p.p_partkey = l.l_partkey;
select
    sum(l.l_extendedprice) / $z7_35 as avg_yearly
from
    $l as l
join
    $threshold as t
on  
    t.l_partkey = l.l_partkey
where
    l.l_quantity < t.threshold;

	iteration 0:	ok	22.761863s seconds
Query18:
Query text:
$in = (
select
    l_orderkey,
    sum(l_quantity) as sum_l_quantity
from
    `olap_yatests/tpch/s0_1/lineitem`
group by
    l_orderkey having
        sum(l_quantity) > 300
);
$join1 = (
select
    c.c_name as c_name,
    c.c_custkey as c_custkey,
    o.o_orderkey as o_orderkey,
    o.o_orderdate as o_orderdate,
    o.o_totalprice as o_totalprice
from
    `olap_yatests/tpch/s0_1/customer` as c
join
    `olap_yatests/tpch/s0_1/orders` as o
on
    c.c_custkey = o.o_custkey
);
select
    j.c_name as c_name,
    j.c_custkey as c_custkey,
    j.o_orderkey as o_orderkey,
    j.o_orderdate as o_orderdate,
    j.o_totalprice as o_totalprice,
    sum(i.sum_l_quantity) as sum_l_quantity
from
    $join1 as j
join
    $in as i
on
    i.l_orderkey = j.o_orderkey
group by
    j.c_name,
    j.c_custkey,
    j.o_orderkey,
    j.o_orderdate,
    j.o_totalprice
order by
    o_totalprice desc,
    o_orderdate,
    o_orderkey
limit 100;

	iteration 0:	ok	41.844312s seconds
Query19:
Query text:
$z1_12 = 1;
select
    sum(l.l_extendedprice* ($z1_12 - l.l_discount)) as revenue
from
    `olap_yatests/tpch/s0_1/lineitem` as l
join
    `olap_yatests/tpch/s0_1/part` as p
on
    p.p_partkey = l.l_partkey
where
    (
        p.p_brand = 'Brand#12'
        and (p.p_container = 'SM CASE' or p.p_container = 'SM BOX' or p.p_container = 'SM PACK' or p.p_container = 'SM PKG')
        and l.l_quantity >= 1 and l.l_quantity <= 1 + 10
        and p.p_size between 1 and 5
        and (l.l_shipmode = 'AIR' or l.l_shipmode = 'AIR REG')
        and l.l_shipinstruct = 'DELIVER IN PERSON'
    )
    or
    (
        p.p_brand = 'Brand#23'
        and (p.p_container = 'MED BAG' or p.p_container = 'MED BOX' or p.p_container = 'MED PKG' or p.p_container = 'MED PACK')
        and l.l_quantity >= 10 and l.l_quantity <= 10 + 10
        and p.p_size between 1 and 10
        and (l.l_shipmode = 'AIR' or l.l_shipmode = 'AIR REG')
        and l.l_shipinstruct = 'DELIVER IN PERSON'
    )
    or
    (
        p.p_brand = 'Brand#34'
        and (p.p_container = 'LG CASE' or p.p_container = 'LG BOX' or p.p_container = 'LG PACK' or p.p_container = 'LG PKG')
        and l.l_quantity >= 20 and l.l_quantity <= 20 + 10
        and p.p_size between 1 and 15
        and (l.l_shipmode = 'AIR' or l.l_shipmode = 'AIR REG')
        and l.l_shipinstruct = 'DELIVER IN PERSON'
    );

	iteration 0:	ok	215.480478s seconds
Query20:
Query text:
$z0_5_35 = 0.5;
$border = Date("1994-01-01");
$threshold = (
select
    $z0_5_35 * sum(l_quantity) as threshold,
    l_partkey as l_partkey,
    l_suppkey as l_suppkey
from
    `olap_yatests/tpch/s0_1/lineitem`
where
    l_shipdate >= $border
    and l_shipdate < ($border + Interval("P365D"))
group by
    l_partkey, l_suppkey
);
$parts = (
select
    p_partkey
from
    `olap_yatests/tpch/s0_1/part`
where
    p_name like 'forest%'
);
$join1 = (
select
    ps.ps_suppkey as ps_suppkey,
    ps.ps_availqty as ps_availqty,
    ps.ps_partkey as ps_partkey
from
    `olap_yatests/tpch/s0_1/partsupp` as ps
join any
    $parts as p
on
    ps.ps_partkey = p.p_partkey
);
$join2 = (
select
    distinct(j.ps_suppkey) as ps_suppkey
from
    $join1 as j
join any
    $threshold as t
on
    j.ps_partkey = t.l_partkey and j.ps_suppkey = t.l_suppkey
where
    j.ps_availqty > t.threshold
);
$join3 = (
select
    j.ps_suppkey as ps_suppkey,
    s.s_name as s_name,
    s.s_address as s_address,
    s.s_nationkey as s_nationkey
from
    $join2 as j
join any
    `olap_yatests/tpch/s0_1/supplier` as s
on
    j.ps_suppkey = s.s_suppkey
);
select
    j.s_name as s_name,
    j.s_address as s_address
from
    $join3 as j
join
    `olap_yatests/tpch/s0_1/nation` as n
on
    j.s_nationkey = n.n_nationkey
where
    n.n_name = 'CANADA'
order by
    s_name;

	iteration 0:	ok	43.850266s seconds
Query21:
Query text:
$n = select n_nationkey from `olap_yatests/tpch/s0_1/nation`
where n_name = 'SAUDI ARABIA';
$s = select s_name, s_suppkey from `olap_yatests/tpch/s0_1/supplier` as supplier
join $n as nation
on supplier.s_nationkey = nation.n_nationkey;
$l = select l_suppkey, l_orderkey from `olap_yatests/tpch/s0_1/lineitem`
where l_receiptdate > l_commitdate;
$j1 = select s_name, l_suppkey, l_orderkey from $l as l1
join $s as supplier
on l1.l_suppkey = supplier.s_suppkey;
$j2 = select l1.l_orderkey as l_orderkey, l1.l_suppkey as l_suppkey, l1.s_name as s_name, l2.l_receiptdate as l_receiptdate, l2.l_commitdate as l_commitdate from $j1 as l1
join `olap_yatests/tpch/s0_1/lineitem` as l2
on l1.l_orderkey = l2.l_orderkey
where l2.l_suppkey <> l1.l_suppkey;
$j2_1 = select s_name, l1.l_suppkey as l_suppkey, l1.l_orderkey as l_orderkey from $j1 as l1
left semi join $j2 as l2
on l1.l_orderkey = l2.l_orderkey;
$j2_2 = select l_orderkey from $j2 where l_receiptdate > l_commitdate;
$j3 = select s_name, l_suppkey, l_orderkey from $j2_1 as l1
left only join $j2_2 as l3
on l1.l_orderkey = l3.l_orderkey;
$j4 = select s_name from $j3 as l1
join `olap_yatests/tpch/s0_1/orders` as orders
on orders.o_orderkey = l1.l_orderkey
where o_orderstatus = 'F';
select s_name,
    count(*) as numwait from $j4
group by
    s_name
order by
    numwait desc,
    s_name
limit 100;

	iteration 0:	ok	196.602745s seconds
Query22:
Query text:
$z0_12 = 0.;
$customers = (
select
    c_acctbal,
    c_custkey,
    Substring(CAST(c_phone AS STRING), 0u, 2u) as cntrycode
from
    `olap_yatests/tpch/s0_1/customer`
);
$c = (
select
    c_acctbal,
    c_custkey,
    cntrycode
from
    $customers
where
    cntrycode = '13' or cntrycode = '31' or cntrycode = '23' or cntrycode = '29' or cntrycode = '30' or cntrycode = '18' or cntrycode = '17'
);
$avg = (
select
    avg(c_acctbal) as a
from
    $c
where
    c_acctbal > $z0_12
);
$join1 = (
select
    c.c_acctbal as c_acctbal,
    c.c_custkey as c_custkey,
    c.cntrycode as cntrycode
from
    $c as c
cross join
    $avg as a
where
    c.c_acctbal > a.a
);
$join2 = (
select
    j.cntrycode as cntrycode,
    c_custkey,
    j.c_acctbal as c_acctbal
from
    $join1 as j
left only join 
    `olap_yatests/tpch/s0_1/orders` as o
on
    o.o_custkey = j.c_custkey
);
select
    cntrycode,
    count(*) as numcust,
    sum(c_acctbal) as totacctbal
from
    $join2 as custsale
group by
    cntrycode
order by
    cntrycode;

	iteration 0:	ok	139.008668s seconds

Results for 1 iterations
┌─────────┬──────────┬──────────┬──────────┬──────────┬──────────┬───────────┬─────────┬─────────┬─────────┬─────────┬────────────────┬────────────────┬────────────────┬───────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min      │ Max      │ Mean     │ Median   │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ CompilationMin │ CompilationMax │ CompilationAvg │ GrossTime │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query01 │  17.658  │  17.658  │  17.658  │  17.658  │  17.657  │  17.658   │   0.001 │   0.848 │   0.848 │   0.848 │   3.011        │   3.011        │   3.011        │  18.506   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query02 │  46.743  │  46.743  │  46.743  │  46.743  │  46.742  │  46.743   │   0.001 │   2.089 │   2.089 │   2.089 │   9.283        │   9.283        │   9.283        │  48.832   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query03 │ 232.890  │ 232.890  │ 232.890  │ 232.890  │ 232.890  │ 232.890   │   0.000 │   1.234 │   1.234 │   1.234 │   4.939        │   4.939        │   4.939        │ 234.125   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query04 │  49.808  │  49.808  │  49.808  │  49.808  │  49.808  │  49.808   │   0.000 │   0.785 │   0.785 │   0.785 │   3.497        │   3.497        │   3.497        │  50.593   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query05 │  36.459  │  36.459  │  36.459  │  36.459  │  36.459  │  36.459   │   0.000 │   1.352 │   1.352 │   1.352 │   5.286        │   5.286        │   5.286        │  37.812   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query06 │ 158.811  │ 158.811  │ 158.811  │ 158.811  │ 158.811  │ 158.811   │   0.000 │   0.276 │   0.276 │   0.276 │   1.603        │   1.603        │   1.603        │ 159.087   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query07 │ 213.619  │ 213.619  │ 213.619  │ 213.619  │ 213.619  │ 213.619   │   0.000 │   1.667 │   1.667 │   1.667 │   6.794        │   6.794        │   6.794        │ 215.287   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query08 │ 230.870  │ 230.870  │ 230.870  │ 230.870  │ 230.869  │ 230.870   │   0.001 │   1.677 │   1.677 │   1.677 │  13.167        │  13.167        │  13.167        │ 232.546   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query09 │  73.535  │  73.535  │  73.535  │  73.535  │  73.534  │  73.535   │   0.001 │   1.483 │   1.483 │   1.483 │   7.933        │   7.933        │   7.933        │  75.018   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query10 │ 243.206  │ 243.206  │ 243.206  │ 243.206  │ 243.205  │ 243.206   │   0.001 │   0.812 │   0.812 │   0.812 │   7.785        │   7.785        │   7.785        │ 244.018   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query11 │ 116.543  │ 116.543  │ 116.543  │ 116.543  │ 116.542  │ 116.543   │   0.001 │   0.820 │   0.820 │   0.820 │   4.437        │   4.437        │   4.437        │ 117.362   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query12 │ 242.548  │ 242.548  │ 242.548  │ 242.548  │ 242.547  │ 242.548   │   0.001 │   0.502 │   0.502 │   0.502 │   3.970        │   3.970        │   3.970        │ 243.050   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query13 │  23.668  │  23.668  │  23.668  │  23.668  │  23.667  │  23.668   │   0.001 │   0.648 │   0.648 │   0.648 │   3.515        │   3.515        │   3.515        │  24.316   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query14 │  47.300  │  47.300  │  47.300  │  47.300  │  47.300  │  47.300   │   0.000 │   0.637 │   0.637 │   0.637 │   9.095        │   9.095        │   9.095        │  47.937   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query15 │  39.023  │  39.023  │  39.023  │  39.023  │  39.023  │  39.023   │   0.000 │   3.501 │   3.501 │   3.501 │   8.869        │   8.869        │   8.869        │  42.525   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query16 │  40.554  │  40.554  │  40.554  │  40.554  │  40.554  │  40.554   │   0.000 │   1.204 │   1.204 │   1.204 │   5.005        │   5.005        │   5.005        │  41.758   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query17 │  21.915  │  21.915  │  21.915  │  21.915  │  21.914  │  21.915   │   0.001 │   0.847 │   0.847 │   0.847 │   5.666        │   5.666        │   5.666        │  22.762   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query18 │  39.998  │  39.998  │  39.998  │  39.998  │  39.997  │  39.998   │   0.001 │   1.846 │   1.846 │   1.846 │   6.783        │   6.783        │   6.783        │  41.844   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query19 │ 215.038  │ 215.038  │ 215.038  │ 215.038  │ 215.037  │ 215.038   │   0.001 │   0.442 │   0.442 │   0.442 │   4.003        │   4.003        │   4.003        │ 215.480   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query20 │  41.433  │  41.433  │  41.433  │  41.433  │  41.432  │  41.433   │   0.001 │   2.418 │   2.418 │   2.418 │   9.546        │   9.546        │   9.546        │  43.850   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query21 │ 195.391  │ 195.391  │ 195.391  │ 195.391  │ 195.391  │ 195.391   │   0.000 │   1.211 │   1.211 │   1.211 │  10.420        │  10.420        │  10.420        │ 196.603   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query22 │ 138.538  │ 138.538  │ 138.538  │ 138.538  │ 138.537  │ 138.538   │   0.001 │   0.471 │   0.471 │   0.471 │   4.870        │   4.870        │   4.870        │ 139.009   │ 1            │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Sum     │ 2465.548 │ 2465.548 │ 2465.548 │ 2465.548 │ 2465.535 │ 2465.548  │   0.000 │  26.772 │  26.772 │  26.772 │ 139.476        │ 139.476        │ 139.477        │ 278.270   │ 22           │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Avg     │ 112.070  │ 112.070  │ 112.070  │ 112.070  │ 112.070  │ 112.070   │   0.000 │   1.217 │   1.217 │   1.217 │   6.340        │   6.340        │   6.340        │  12.649   │ 22           │            │            │
├─────────┼──────────┼──────────┼──────────┼──────────┼──────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ GAvg    │  78.721  │  78.721  │  78.721  │  78.721  │  78.720  │  78.721   │   0.000 │   1.018 │   1.018 │   0.001 │   5.697        │   5.697        │   0.001        │   0.000   │ 22           │            │            │
└─────────┴──────────┴──────────┴──────────┴──────────┴──────────┴───────────┴─────────┴─────────┴─────────┴─────────┴────────────────┴────────────────┴────────────────┴───────────┴──────────────┴────────────┴────────────┘

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

2025-10-27 12:24:16,632 - DEBUG - ya.test - _save_outputs: Command (pid 1239715) errors:

[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
[K
2025-10-27 12:24:16,633 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-10-27 12:24:18,054 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:18,060 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes HTTP/1.1" 200 797
2025-10-27 12:24:18,065 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:18,068 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:24:18,077 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-10-27 12:24:18,082 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:18,085 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:24:18,094 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-27 12:24:18,721 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:18,727 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes HTTP/1.1" 200 800
2025-10-27 12:24:18,732 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:18,735 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:24:18,745 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-10-27 12:24:18,748 - INFO - root - __create_iterations_table: All nodes before filtering: [('10127@ghrun-s4m7ghuofe.auto.internal', <Role.UNKNOWN: 0>)]
2025-10-27 12:24:18,748 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-10-27 12:24:18,748 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-s4m7ghuofe.auto.internal', 1)]
2025-10-27 12:24:18,749 - INFO - root - allure_test_description: iterations_table created, length: 515
2025-10-27 12:24:18,749 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-10-27 12:24:19,022 - DEBUG - matplotlib - wrapper: CACHEDIR=/home/runner/.cache/matplotlib
2025-10-27 12:24:19,026 - DEBUG - matplotlib.font_manager - _load_fontmanager: Using fontManager instance from /home/runner/.cache/matplotlib/fontlist-v330.json
2025-10-27 12:24:19,924 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:24:19,924 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/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/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/node_1/logfile_nsmvfjkk.log --grpc-port=18874 --mon-port=10355 --ic-port=26758
2025-10-27 12:24:19,925 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18874/1
2025-10-27 12:24:19,926 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-27 12:24:19,927 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-27 12:24:19,927 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:24:19,929 - DEBUG - ya.test - execute: Command pid: 1291332
2025-10-27 12:24:20,594 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) rc: 0
2025-10-27 12:24:20,594 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) elapsed time (sec): 0.6646661758422852
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) idrss: 0
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) inblock: 0
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) isrss: 0
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) ixrss: 0
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) majflt: 0
2025-10-27 12:24:20,595 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) maxrss: 895372
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) minflt: 45248
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) msgrcv: 0
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) msgsnd: 0
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) nivcsw: 3
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) nsignals: 0
2025-10-27 12:24:20,596 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) nswap: 0
2025-10-27 12:24:20,597 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) nvcsw: 15
2025-10-27 12:24:20,597 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) oublock: 8
2025-10-27 12:24:20,597 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) stime: 0.214825
2025-10-27 12:24:20,597 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) utime: 0.405153
2025-10-27 12:24:20,597 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) wtime: 0.666
2025-10-27 12:24:20,598 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) output:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-s4m7ghuofe
    Host information: 
        Linux ghrun-s4m7ghuofe 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-10-27 12:24:20,598 - DEBUG - ya.test - _save_outputs: Command (pid 1291332) errors:

2025-10-27 12:24:20,599 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211
2025-10-27 12:24:20,600 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/kikimr_configs"
2025-10-27 12:24:20,601 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18874 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/ccie/001fc7/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_parallel/testing_out_stuff/test_parallel.py.TestTpchParallelS0_1T10.test.Query01/cluster/slot_1/logfile_3fajo5kt.log --grpc-port=16124 --mon-port=8779 --ic-port=10127
2025-10-27 12:24:20,601 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16124/1
2025-10-27 12:24:20,602 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-27 12:24:20,603 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-27 12:24:20,604 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ccie/001fc7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:24:20,605 - DEBUG - ya.test - execute: Command pid: 1291410
2025-10-27 12:24:21,301 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) rc: 0
2025-10-27 12:24:21,302 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) elapsed time (sec): 0.6953675746917725
2025-10-27 12:24:21,302 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) idrss: 0
2025-10-27 12:24:21,302 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) inblock: 0
2025-10-27 12:24:21,303 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) isrss: 0
2025-10-27 12:24:21,303 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) ixrss: 0
2025-10-27 12:24:21,303 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) majflt: 0
2025-10-27 12:24:21,303 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) maxrss: 895372
2025-10-27 12:24:21,304 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) minflt: 45331
2025-10-27 12:24:21,304 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) msgrcv: 0
2025-10-27 12:24:21,304 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) msgsnd: 0
2025-10-27 12:24:21,304 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) nivcsw: 1
2025-10-27 12:24:21,305 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) nsignals: 0
2025-10-27 12:24:21,305 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) nswap: 0
2025-10-27 12:24:21,305 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) nvcsw: 14
2025-10-27 12:24:21,306 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) oublock: 8
2025-10-27 12:24:21,306 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) stime: 0.19182
2025-10-27 12:24:21,306 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) utime: 0.459061
2025-10-27 12:24:21,306 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) wtime: 0.697
2025-10-27 12:24:21,307 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) output:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-s4m7ghuofe
    Host information: 
        Linux ghrun-s4m7ghuofe 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-10-27 12:24:21,307 - DEBUG - ya.test - _save_outputs: Command (pid 1291410) errors:

2025-10-27 12:24:21,308 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 3c869ad547d1a3a016f8a9fe801c9957db29e0b2
    Branch: 
    Author: Vitaliy Filippov <vitalif@mail.ru>
    Summary: Merge 5ee1ca3d2f0821c8a74105784fcea023141452e6 into 4618ed6cf7eaff3573653a8547db92604845a211
2025-10-27 12:24:21,318 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_parallel.py::TestTpchParallelS0_1T10, test_name: test[Query01])
2025-10-27 12:24:21,335 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:21,342 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes HTTP/1.1" 200 796
2025-10-27 12:24:21,348 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:10355
2025-10-27 12:24:21,352 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-27 12:24:21,362 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:10355 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-27 12:24:21,365 - INFO - root - __create_iterations_table: All nodes before filtering: [('10127@ghrun-s4m7ghuofe.auto.internal', <Role.UNKNOWN: 0>)]
2025-10-27 12:24:21,365 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-10-27 12:24:21,365 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-s4m7ghuofe.auto.internal', 1)]
2025-10-27 12:24:21,366 - INFO - root - allure_test_description: iterations_table created, length: 514
2025-10-27 12:24:21,366 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-10-27 12:24:21,368 - INFO - ya.test - pytest_runtest_teardown: Test teardown
