2025-11-08 15:43:48,309 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-08 15:43:48,310 - INFO - ya.test - pytest_runtest_setup: test
2025-11-08 15:43:48,310 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-08 15:43:48,310 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-08 15:43:48,380 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd
2025-11-08 15:43:48,381 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31668
2025-11-08 15:43:48,382 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/jwst/0007b7/r3tmp/kikimr_cluster_9wi3apxc
2025-11-08 15:43:48,422 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd
2025-11-08 15:43:48,422 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26684
2025-11-08 15:43:48,423 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22471
2025-11-08 15:43:48,424 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2605
2025-11-08 15:43:48,424 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1863
2025-11-08 15:43:48,425 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:43:48,425 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/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/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/node_1/logfile_48dms8hr.log --grpc-port=26684 --mon-port=22471 --ic-port=31668
2025-11-08 15:43:48,425 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:26684/1, disk_size 68719476736
2025-11-08 15:43:48,425 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:43:48,426 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/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/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/node_1/logfile_48dms8hr.log --grpc-port=26684 --mon-port=22471 --ic-port=31668
2025-11-08 15:43:48,427 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/node_1/logfile_48dms8hr.log', '--grpc-port=26684', '--mon-port=22471', '--ic-port=31668')' in '/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/node_1' (no wait)
2025-11-08 15:43:48,428 - DEBUG - ya.test - execute: Command pid: 405743
2025-11-08 15:43:48,429 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:26684/1
2025-11-08 15:43:48,430 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-11-08 15:43:48,431 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-11-08 15:43:48,431 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-08 15:43:48,432 - DEBUG - ya.test - execute: Command pid: 405744
2025-11-08 15:43:48,887 - DEBUG - ya.test - _save_outputs: Command (pid 405744) rc: 0
2025-11-08 15:43:48,887 - DEBUG - ya.test - _save_outputs: Command (pid 405744) elapsed time (sec): 0.45415616035461426
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) idrss: 0
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) inblock: 0
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) isrss: 0
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) ixrss: 0
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) majflt: 0
2025-11-08 15:43:48,888 - DEBUG - ya.test - _save_outputs: Command (pid 405744) maxrss: 539716
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) minflt: 16148
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) msgrcv: 0
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) msgsnd: 0
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) nivcsw: 11
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) nsignals: 0
2025-11-08 15:43:48,889 - DEBUG - ya.test - _save_outputs: Command (pid 405744) nswap: 0
2025-11-08 15:43:48,890 - DEBUG - ya.test - _save_outputs: Command (pid 405744) nvcsw: 20
2025-11-08 15:43:48,890 - DEBUG - ya.test - _save_outputs: Command (pid 405744) oublock: 8
2025-11-08 15:43:48,890 - DEBUG - ya.test - _save_outputs: Command (pid 405744) stime: 0.27733399999999997
2025-11-08 15:43:48,890 - DEBUG - ya.test - _save_outputs: Command (pid 405744) utime: 0.12459899999999999
2025-11-08 15:43:48,890 - DEBUG - ya.test - _save_outputs: Command (pid 405744) wtime: 0.455
2025-11-08 15:43:48,891 - DEBUG - ya.test - _save_outputs: Command (pid 405744) output:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ajrcojcmo4
    Host information: 
        Linux ghrun-ajrcojcmo4 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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 -UNDEBUG -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-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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-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-11-08 15:43:48,891 - DEBUG - ya.test - _save_outputs: Command (pid 405744) errors:

2025-11-08 15:43:48,891 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f
2025-11-08 15:43:50,656 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd
2025-11-08 15:43:50,656 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26684', '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: 31668\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-08 15:43:50,657 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-11-08 15:43:50,662 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-11-08 15:43:50,663 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26684', '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: 31668\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-08 15:43:50,664 - DEBUG - ya.test - execute: Command pid: 406726
2025-11-08 15:43:51,072 - DEBUG - ya.test - _save_outputs: Command (pid 406726) rc: 0
2025-11-08 15:43:51,073 - DEBUG - ya.test - _save_outputs: Command (pid 406726) elapsed time (sec): 0.4073343276977539
2025-11-08 15:43:51,073 - DEBUG - ya.test - _save_outputs: Command (pid 406726) idrss: 0
2025-11-08 15:43:51,073 - DEBUG - ya.test - _save_outputs: Command (pid 406726) inblock: 0
2025-11-08 15:43:51,073 - DEBUG - ya.test - _save_outputs: Command (pid 406726) isrss: 0
2025-11-08 15:43:51,073 - DEBUG - ya.test - _save_outputs: Command (pid 406726) ixrss: 0
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) majflt: 0
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) maxrss: 548152
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) minflt: 18290
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) msgrcv: 0
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) msgsnd: 0
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) nivcsw: 10
2025-11-08 15:43:51,074 - DEBUG - ya.test - _save_outputs: Command (pid 406726) nsignals: 0
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) nswap: 0
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) nvcsw: 132
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) oublock: 8
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) stime: 0.219082
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) utime: 0.13941499999999998
2025-11-08 15:43:51,075 - DEBUG - ya.test - _save_outputs: Command (pid 406726) wtime: 0.408
2025-11-08 15:43:51,076 - DEBUG - ya.test - _save_outputs: Command (pid 406726) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-08 15:43:51,076 - DEBUG - ya.test - _save_outputs: Command (pid 406726) errors:

2025-11-08 15:43:51,077 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd
2025-11-08 15:43:51,077 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26684', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-08 15:43:51,078 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.7
2025-11-08 15:43:51,078 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.7
2025-11-08 15:43:51,079 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26684', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-08 15:43:51,080 - DEBUG - ya.test - execute: Command pid: 406854
2025-11-08 15:43:51,506 - DEBUG - ya.test - _save_outputs: Command (pid 406854) rc: 0
2025-11-08 15:43:51,507 - DEBUG - ya.test - _save_outputs: Command (pid 406854) elapsed time (sec): 0.425997257232666
2025-11-08 15:43:51,507 - DEBUG - ya.test - _save_outputs: Command (pid 406854) idrss: 0
2025-11-08 15:43:51,507 - DEBUG - ya.test - _save_outputs: Command (pid 406854) inblock: 0
2025-11-08 15:43:51,507 - DEBUG - ya.test - _save_outputs: Command (pid 406854) isrss: 0
2025-11-08 15:43:51,507 - DEBUG - ya.test - _save_outputs: Command (pid 406854) ixrss: 0
2025-11-08 15:43:51,508 - DEBUG - ya.test - _save_outputs: Command (pid 406854) majflt: 0
2025-11-08 15:43:51,508 - DEBUG - ya.test - _save_outputs: Command (pid 406854) maxrss: 548164
2025-11-08 15:43:51,508 - DEBUG - ya.test - _save_outputs: Command (pid 406854) minflt: 18290
2025-11-08 15:43:51,508 - DEBUG - ya.test - _save_outputs: Command (pid 406854) msgrcv: 0
2025-11-08 15:43:51,508 - DEBUG - ya.test - _save_outputs: Command (pid 406854) msgsnd: 0
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) nivcsw: 7
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) nsignals: 0
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) nswap: 0
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) nvcsw: 143
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) oublock: 8
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) stime: 0.229783
2025-11-08 15:43:51,509 - DEBUG - ya.test - _save_outputs: Command (pid 406854) utime: 0.14915699999999998
2025-11-08 15:43:51,510 - DEBUG - ya.test - _save_outputs: Command (pid 406854) wtime: 0.427
2025-11-08 15:43:51,510 - DEBUG - ya.test - _save_outputs: Command (pid 406854) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-08 15:43:51,510 - DEBUG - ya.test - _save_outputs: Command (pid 406854) errors:

2025-11-08 15:43:51,510 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-11-08 15:43:51,525 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-08 15:43:52,043 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd
2025-11-08 15:43:52,043 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62824
2025-11-08 15:43:52,043 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6053
2025-11-08 15:43:52,044 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6318
2025-11-08 15:43:52,044 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23485
2025-11-08 15:43:52,044 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25685
2025-11-08 15:43:52,045 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:43:52,045 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26684 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/slot_1/logfile_ya8pa_p4.log --grpc-port=62824 --mon-port=6053 --ic-port=6318
2025-11-08 15:43:52,045 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:43:52,046 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26684 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/slot_1/logfile_ya8pa_p4.log --grpc-port=62824 --mon-port=6053 --ic-port=6318
2025-11-08 15:43:52,046 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:26684', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/slot_1/logfile_ya8pa_p4.log', '--grpc-port=62824', '--mon-port=6053', '--ic-port=6318')' in '/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/slot_1' (no wait)
2025-11-08 15:43:52,048 - DEBUG - ya.test - execute: Command pid: 406990
2025-11-08 15:43:52,048 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:62824/1
2025-11-08 15:43:52,050 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.8
2025-11-08 15:43:52,050 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.8
2025-11-08 15:43:52,050 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-08 15:43:52,051 - DEBUG - ya.test - execute: Command pid: 406991
2025-11-08 15:43:52,524 - DEBUG - ya.test - _save_outputs: Command (pid 406991) rc: 0
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) elapsed time (sec): 0.47235107421875
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) idrss: 0
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) inblock: 0
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) isrss: 0
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) ixrss: 0
2025-11-08 15:43:52,525 - DEBUG - ya.test - _save_outputs: Command (pid 406991) majflt: 0
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) maxrss: 539836
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) minflt: 17064
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) msgrcv: 0
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) msgsnd: 0
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) nivcsw: 5
2025-11-08 15:43:52,526 - DEBUG - ya.test - _save_outputs: Command (pid 406991) nsignals: 0
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) nswap: 0
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) nvcsw: 23
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) oublock: 8
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) stime: 0.288217
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) utime: 0.12008999999999999
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) wtime: 0.473
2025-11-08 15:43:52,527 - DEBUG - ya.test - _save_outputs: Command (pid 406991) output:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ajrcojcmo4
    Host information: 
        Linux ghrun-ajrcojcmo4 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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 -UNDEBUG -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-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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-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-11-08 15:43:52,528 - DEBUG - ya.test - _save_outputs: Command (pid 406991) errors:

2025-11-08 15:43:52,528 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f
2025-11-08 15:43:54,536 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb
2025-11-08 15:43:54,537 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-08 15:43:54,538 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-08 15:43:54,538 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26684', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s0_1', 'init', '--store=column', '--datetime-types=dt64']' in 'None' (waiting)
2025-11-08 15:43:54,539 - DEBUG - ya.test - execute: Command pid: 408129
2025-11-08 15:43:56,485 - DEBUG - ya.test - _save_outputs: Command (pid 408129) rc: 0
2025-11-08 15:43:56,485 - DEBUG - ya.test - _save_outputs: Command (pid 408129) elapsed time (sec): 1.9449748992919922
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) idrss: 0
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) inblock: 0
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) isrss: 0
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) ixrss: 0
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) majflt: 0
2025-11-08 15:43:56,486 - DEBUG - ya.test - _save_outputs: Command (pid 408129) maxrss: 246092
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) minflt: 3598
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) msgrcv: 0
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) msgsnd: 0
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) nivcsw: 6
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) nsignals: 0
2025-11-08 15:43:56,487 - DEBUG - ya.test - _save_outputs: Command (pid 408129) nswap: 0
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) nvcsw: 256
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) oublock: 8
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) stime: 0.022778
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) utime: 0.060742
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) wtime: 1.946
2025-11-08 15:43:56,488 - DEBUG - ya.test - _save_outputs: Command (pid 408129) output:
Init tables ...
Init tables ...Ok

2025-11-08 15:43:56,489 - DEBUG - ya.test - _save_outputs: Command (pid 408129) errors:

2025-11-08 15:43:56,489 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb
2025-11-08 15:43:56,490 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-08 15:43:56,490 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-08 15:43:56,491 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26684', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s0_1', 'import', 'generator', '--scale=0.1']' in 'None' (waiting)
2025-11-08 15:43:56,492 - DEBUG - ya.test - execute: Command pid: 408890
2025-11-08 15:44:05,685 - DEBUG - ya.test - _save_outputs: Command (pid 408890) rc: 0
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) elapsed time (sec): 9.193020820617676
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) idrss: 0
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) inblock: 0
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) isrss: 0
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) ixrss: 0
2025-11-08 15:44:05,686 - DEBUG - ya.test - _save_outputs: Command (pid 408890) majflt: 0
2025-11-08 15:44:05,687 - DEBUG - ya.test - _save_outputs: Command (pid 408890) maxrss: 601800
2025-11-08 15:44:05,687 - DEBUG - ya.test - _save_outputs: Command (pid 408890) minflt: 181988
2025-11-08 15:44:05,687 - DEBUG - ya.test - _save_outputs: Command (pid 408890) msgrcv: 0
2025-11-08 15:44:05,687 - DEBUG - ya.test - _save_outputs: Command (pid 408890) msgsnd: 0
2025-11-08 15:44:05,687 - DEBUG - ya.test - _save_outputs: Command (pid 408890) nivcsw: 403
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) nsignals: 0
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) nswap: 0
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) nvcsw: 22820
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) oublock: 16
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) stime: 0.639832
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) utime: 9.649443
2025-11-08 15:44:05,688 - DEBUG - ya.test - _save_outputs: Command (pid 408890) wtime: 9.194
2025-11-08 15:44:05,689 - DEBUG - ya.test - _save_outputs: Command (pid 408890) output:
Fill table supplier...
Fill table supplier OK 1000 / 1000 (4.709963s)
Fill table customer...
Fill table customer OK 15000 / 15000 (0.243591s)
Fill table orders...
Fill table orders OK 150000 / 150000 (3.384566s)
Fill table part...
Fill table part OK 20000 / 20000 (0.489470s)
Fill table nation...
Fill table nation OK 25 / 25 (0.103487s)
Fill table region...
Fill table region OK 5 / 5 (0.103250s)

2025-11-08 15:44:05,689 - DEBUG - ya.test - _save_outputs: Command (pid 408890) errors:
1000 / 1000

5000 / 15000
15000 / 15000

10000 / 150000
20000 / 150000
30000 / 150000
40000 / 150000
50000 / 150000
60000 / 150000
70000 / 150000
80000 / 150000
90000 / 150000
100000 / 150000
110000 / 150000
120000 / 150000
130000 / 150000
140000 / 150000
150000 / 150000

10000 / 20000
20000 / 20000

25 / 25

5 / 5


2025-11-08 15:44:05,691 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:05,693 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:05,699 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-11-08 15:44:05,700 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:05,700 - ERROR - root - _join_errors: Node ghrun-ajrcojcmo4.auto.internal too yong: 12.896233797073364
2025-11-08 15:44:05,700 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:26684 to local/test_db ydb_access_token is set False
2025-11-08 15:44:05,701 - 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-11-08 15:44:05,702 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-08 15:44:05,703 - 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-11-08 15:44:05,708 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-11-08 15:44:05,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 16878b6e-c61f-4dc5-ba74-4108a455e858, localhost:26684): creating call state
2025-11-08 15:44:05,709 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 16878b6e-c61f-4dc5-ba74-4108a455e858, localhost:26684): request = { database: "local/test_db" }
2025-11-08 15:44:05,711 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 16878b6e-c61f-4dc5-ba74-4108a455e858, localhost:26684): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ajrcojcmo4.auto.internal" port: 62824 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-11-08 15:44:05,711 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ajrcojcmo4.auto.internal:62824, location , ssl: False>]>
2025-11-08 15:44:05,711 - 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-11-08 15:44:05,718 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:26684
2025-11-08 15:44:05,718 - INFO - root - _create_ydb_driver: Connected
2025-11-08 15:44:05,719 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 76ce77ed-1e7d-4764-add5-cb00f1f3a7d7, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:05,720 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 76ce77ed-1e7d-4764-add5-cb00f1f3a7d7, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:05,721 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 76ce77ed-1e7d-4764-add5-cb00f1f3a7d7, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:05,735 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-ajrcojcmo4.auto.internal too yong: 12.896233797073364
2025-11-08 15:44:06,738 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:06,740 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:06,744 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:06,745 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:06,745 - ERROR - root - _join_errors: Node ghrun-ajrcojcmo4.auto.internal too yong: 13.94147253036499
2025-11-08 15:44:06,746 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a3506335-57a9-4769-908b-cce775ee20c3, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:06,746 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a3506335-57a9-4769-908b-cce775ee20c3, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:06,747 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a3506335-57a9-4769-908b-cce775ee20c3, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:06,758 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-ajrcojcmo4.auto.internal too yong: 13.94147253036499
2025-11-08 15:44:07,761 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:07,762 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:07,767 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:07,769 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:07,769 - ERROR - root - _join_errors: Node ghrun-ajrcojcmo4.auto.internal too yong: 14.965337991714478
2025-11-08 15:44:07,770 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 88624d5b-cd4b-40e0-a40e-862b4d4a55e5, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:07,770 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 88624d5b-cd4b-40e0-a40e-862b4d4a55e5, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:07,771 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 88624d5b-cd4b-40e0-a40e-862b4d4a55e5, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:07,780 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-ajrcojcmo4.auto.internal too yong: 14.965337991714478
2025-11-08 15:44:08,784 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,786 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:08,791 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:08,793 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:08,793 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6378d193-3f1d-417f-a13b-d92ee0e9bf07, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:08,794 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6378d193-3f1d-417f-a13b-d92ee0e9bf07, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:08,795 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6378d193-3f1d-417f-a13b-d92ee0e9bf07, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:08,811 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,813 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:08,818 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:08,819 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:08,819 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:08,820 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:08,820 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 86af5a35-f7a7-4cc9-8fe6-d8bf98eaa632, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:08,821 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 86af5a35-f7a7-4cc9-8fe6-d8bf98eaa632, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-11-08 15:44:08,824 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 86af5a35-f7a7-4cc9-8fe6-d8bf98eaa632, ghrun-ajrcojcmo4.auto.internal:62824): 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: 1762616635010 tx_id: 281474976720659 } } } } } }
2025-11-08 15:44:08,824 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:08,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 3d21dc07-1835-49f6-804e-b948f7343712, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:08,825 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 3d21dc07-1835-49f6-804e-b948f7343712, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-11-08 15:44:08,828 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 3d21dc07-1835-49f6-804e-b948f7343712, ghrun-ajrcojcmo4.auto.internal:62824): 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: 1762616635010 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635010 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635310 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635380 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635610 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635890 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636160 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636230 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636480 tx_id: 281474976720666 } } } } } }
2025-11-08 15:44:08,831 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,833 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,838 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,840 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/customer balance: 64-64 shards.
2025-11-08 15:44:08,842 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,844 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,850 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,851 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/lineitem balance: 64-64 shards.
2025-11-08 15:44:08,853 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,855 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,860 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 578
2025-11-08 15:44:08,862 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/nation balance: 1-1 shards.
2025-11-08 15:44:08,864 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,866 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,871 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,872 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/orders balance: 64-64 shards.
2025-11-08 15:44:08,874 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,876 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,881 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,882 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/part balance: 64-64 shards.
2025-11-08 15:44:08,884 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,886 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,892 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,913 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/partsupp balance: 64-64 shards.
2025-11-08 15:44:08,915 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,917 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,923 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 578
2025-11-08 15:44:08,943 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/region balance: 1-1 shards.
2025-11-08 15:44:08,945 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:08,947 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:08,954 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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 580
2025-11-08 15:44:08,956 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/supplier balance: 64-64 shards.
2025-11-08 15:44:08,957 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b591d7dd-f884-42b3-955e-a6841ecdf655, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:08,957 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b591d7dd-f884-42b3-955e-a6841ecdf655, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:08,958 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b591d7dd-f884-42b3-955e-a6841ecdf655, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:08,971 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6a049f93-1294-43d3-95bc-a4116964ff27, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:08,972 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6a049f93-1294-43d3-95bc-a4116964ff27, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:08,974 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6a049f93-1294-43d3-95bc-a4116964ff27, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:08,974 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:09,161 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 53b78b11-402d-4e25-a824-0ac077853382, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:09,162 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 53b78b11-402d-4e25-a824-0ac077853382, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:09,163 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 53b78b11-402d-4e25-a824-0ac077853382, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:09,163 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:09,342 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 404a93f1-35f5-448b-acd8-e2f2553f0729, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:09,343 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 404a93f1-35f5-448b-acd8-e2f2553f0729, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:09,344 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 404a93f1-35f5-448b-acd8-e2f2553f0729, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:09,344 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:09,531 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 450919b0-e2f7-49ba-931f-12b0a805f2c7, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:09,532 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 450919b0-e2f7-49ba-931f-12b0a805f2c7, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:09,533 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 450919b0-e2f7-49ba-931f-12b0a805f2c7, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:09,533 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:09,705 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 231c0b94-5597-4ee8-b25f-50d7edb15722, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:09,705 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 231c0b94-5597-4ee8-b25f-50d7edb15722, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:09,706 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 231c0b94-5597-4ee8-b25f-50d7edb15722, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:09,707 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:09,868 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c2541241-a635-4b92-9032-132fd615cce1, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:09,869 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c2541241-a635-4b92-9032-132fd615cce1, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:09,870 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c2541241-a635-4b92-9032-132fd615cce1, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:09,870 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:10,037 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 80548090-0061-47c4-aded-61a0edbe8337, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,038 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 80548090-0061-47c4-aded-61a0edbe8337, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:10,039 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 80548090-0061-47c4-aded-61a0edbe8337, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:10,039 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:10,212 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6227341a-c322-40dd-b720-90747da19097, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,212 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6227341a-c322-40dd-b720-90747da19097, ghrun-ajrcojcmo4.auto.internal:62824): 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-11-08 15:44:10,213 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6227341a-c322-40dd-b720-90747da19097, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:10,214 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-11-08 15:44:10,465 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 39f21142-88dd-422e-95e5-c78cf8524c59, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,465 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 39f21142-88dd-422e-95e5-c78cf8524c59, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/.metadata/workload_manager/classifiers/resource_pool_classifiers" }
2025-11-08 15:44:10,467 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 39f21142-88dd-422e-95e5-c78cf8524c59, ghrun-ajrcojcmo4.auto.internal:62824): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-08 15:44:10,468 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 49caabee-aabc-4f49-b816-7b6010c1e172, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,468 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 49caabee-aabc-4f49-b816-7b6010c1e172, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/.metadata/workload_manager/pools/test_pool" }
2025-11-08 15:44:10,470 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 49caabee-aabc-4f49-b816-7b6010c1e172, ghrun-ajrcojcmo4.auto.internal:62824): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-08 15:44:10,471 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-08 15:44:10,471 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c1b0fd9d-774a-4591-9028-4e9f3ace59e3, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,472 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c1b0fd9d-774a-4591-9028-4e9f3ace59e3, ghrun-ajrcojcmo4.auto.internal:62824): request = {  }
2025-11-08 15:44:10,473 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c1b0fd9d-774a-4591-9028-4e9f3ace59e3, ghrun-ajrcojcmo4.auto.internal:62824): response = { status: SUCCESS session_id: "ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=" node_id: 50000 }
2025-11-08 15:44:10,474 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, f2abac05-9e67-4655-b446-55961e53566c, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,474 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, f2abac05-9e67-4655-b446-55961e53566c, ghrun-ajrcojcmo4.auto.internal:62824): request = { session_id: "ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=" }
2025-11-08 15:44:10,475 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, f2abac05-9e67-4655-b446-55961e53566c, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:10,476 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=
2025-11-08 15:44:10,477 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6702b597-fdb2-4b6f-911a-c76065f5a43c, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,477 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6702b597-fdb2-4b6f-911a-c76065f5a43c, ghrun-ajrcojcmo4.auto.internal:62824): request = { session_id: "ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "DROP USER IF EXISTS testuser;\nCREATE USER testuser PASSWORD NULL; GRANT ALL ON `/local/test_db` TO testuser;\n" } stats_mode: STATS_MODE_NONE }
2025-11-08 15:44:10,478 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6702b597-fdb2-4b6f-911a-c76065f5a43c, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:10,491 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=
2025-11-08 15:44:10,492 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=
2025-11-08 15:44:10,492 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 701b6b7c-da74-49ca-8ea4-6a7aae57832c, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:10,493 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 701b6b7c-da74-49ca-8ea4-6a7aae57832c, ghrun-ajrcojcmo4.auto.internal:62824): request = { session_id: "ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "CREATE RESOURCE POOL test_pool WITH(\n    CONCURRENT_QUERY_LIMIT = 2\n);\n\n            CREATE RESOURCE POOL CLASSIFIER test_pool WITH (\n                RESOURCE_POOL = \'test_pool\',\n                MEMBER_NAME = \'testuser\'\n            );\n        " } stats_mode: STATS_MODE_NONE }
2025-11-08 15:44:10,493 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 701b6b7c-da74-49ca-8ea4-6a7aae57832c, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:11,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=50000&id=NjZlOThhMzEtNzhmNzllNjItN2IyNDI2ZGUtYjU5ZTkxZmI=
2025-11-08 15:44:11,061 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,064 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 792
2025-11-08 15:44:11,067 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,070 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 792
2025-11-08 15:44:11,073 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,075 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:11,079 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:11,081 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:11,081 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:11,081 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:11,081 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:11,082 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:11,090 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:44:11,090 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/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/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/node_1/logfile_48dms8hr.log --grpc-port=26684 --mon-port=22471 --ic-port=31668
2025-11-08 15:44:11,090 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:26684/1
2025-11-08 15:44:11,091 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.9
2025-11-08 15:44:11,092 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.9
2025-11-08 15:44:11,092 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-08 15:44:11,093 - DEBUG - ya.test - execute: Command pid: 412108
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) rc: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) elapsed time (sec): 0.3813056945800781
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) idrss: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) inblock: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) isrss: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) ixrss: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) majflt: 0
2025-11-08 15:44:11,475 - DEBUG - ya.test - _save_outputs: Command (pid 412108) maxrss: 539716
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) minflt: 16148
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) msgrcv: 0
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) msgsnd: 0
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) nivcsw: 1
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) nsignals: 0
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) nswap: 0
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) nvcsw: 13
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) oublock: 8
2025-11-08 15:44:11,476 - DEBUG - ya.test - _save_outputs: Command (pid 412108) stime: 0.23416299999999998
2025-11-08 15:44:11,477 - DEBUG - ya.test - _save_outputs: Command (pid 412108) utime: 0.09922099999999999
2025-11-08 15:44:11,477 - DEBUG - ya.test - _save_outputs: Command (pid 412108) wtime: 0.382
2025-11-08 15:44:11,477 - DEBUG - ya.test - _save_outputs: Command (pid 412108) output:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ajrcojcmo4
    Host information: 
        Linux ghrun-ajrcojcmo4 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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 -UNDEBUG -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-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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-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-11-08 15:44:11,477 - DEBUG - ya.test - _save_outputs: Command (pid 412108) errors:

2025-11-08 15:44:11,477 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f
2025-11-08 15:44:11,477 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/kikimr_configs"
2025-11-08 15:44:11,477 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26684 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/jwst/0007b7/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_workload_manager/testing_out_stuff/test_workload_manager.py.TestTpchWMS0_1.test/cluster/slot_1/logfile_ya8pa_p4.log --grpc-port=62824 --mon-port=6053 --ic-port=6318
2025-11-08 15:44:11,478 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:62824/1
2025-11-08 15:44:11,478 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.10
2025-11-08 15:44:11,479 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.10
2025-11-08 15:44:11,479 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-08 15:44:11,480 - DEBUG - ya.test - execute: Command pid: 412218
2025-11-08 15:44:11,814 - DEBUG - ya.test - _save_outputs: Command (pid 412218) rc: 0
2025-11-08 15:44:11,814 - DEBUG - ya.test - _save_outputs: Command (pid 412218) elapsed time (sec): 0.33370161056518555
2025-11-08 15:44:11,815 - DEBUG - ya.test - _save_outputs: Command (pid 412218) idrss: 0
2025-11-08 15:44:11,815 - DEBUG - ya.test - _save_outputs: Command (pid 412218) inblock: 0
2025-11-08 15:44:11,815 - DEBUG - ya.test - _save_outputs: Command (pid 412218) isrss: 0
2025-11-08 15:44:11,815 - DEBUG - ya.test - _save_outputs: Command (pid 412218) ixrss: 0
2025-11-08 15:44:11,815 - DEBUG - ya.test - _save_outputs: Command (pid 412218) majflt: 0
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) maxrss: 539716
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) minflt: 16149
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) msgrcv: 0
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) msgsnd: 0
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) nivcsw: 3
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) nsignals: 0
2025-11-08 15:44:11,816 - DEBUG - ya.test - _save_outputs: Command (pid 412218) nswap: 0
2025-11-08 15:44:11,817 - DEBUG - ya.test - _save_outputs: Command (pid 412218) nvcsw: 23
2025-11-08 15:44:11,817 - DEBUG - ya.test - _save_outputs: Command (pid 412218) oublock: 8
2025-11-08 15:44:11,817 - DEBUG - ya.test - _save_outputs: Command (pid 412218) stime: 0.168236
2025-11-08 15:44:11,817 - DEBUG - ya.test - _save_outputs: Command (pid 412218) utime: 0.11737399999999999
2025-11-08 15:44:11,817 - DEBUG - ya.test - _save_outputs: Command (pid 412218) wtime: 0.335
2025-11-08 15:44:11,818 - DEBUG - ya.test - _save_outputs: Command (pid 412218) output:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ajrcojcmo4
    Host information: 
        Linux ghrun-ajrcojcmo4 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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 -UNDEBUG -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-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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-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-11-08 15:44:11,818 - DEBUG - ya.test - _save_outputs: Command (pid 412218) errors:

2025-11-08 15:44:11,818 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: e8c009320f9ebe71c941d3a756968592984d23ee
    Branch: 
    Author: Sergey Uzhakov <uzhastik@gmail.com>
    Summary: Merge d1bd6e2be1b302dd525875836c7e1f3dbad74c83 into 61c53ab1198f03f33692b0125af8cf7fb7e68f7f
2025-11-08 15:44:11,823 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_workload_manager.py::TestTpchWMS0_1, test_name: test)
2025-11-08 15:44:11,826 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,828 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:11,833 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:11,836 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,838 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:11,843 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:11,847 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,849 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:11,853 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:11,855 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-08 15:44:11,855 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:11,855 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:11,856 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 5a04ffe9-c344-4261-84cb-dea5f86e1895, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:11,856 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 5a04ffe9-c344-4261-84cb-dea5f86e1895, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-11-08 15:44:11,859 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 5a04ffe9-c344-4261-84cb-dea5f86e1895, ghrun-ajrcojcmo4.auto.internal:62824): 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" } effective_permissions { subject: "testuser" permission_names: "ydb.generic.full" } created_at { plan_step: 1762616635010 tx_id: 281474976720659 } } } } } }
2025-11-08 15:44:11,860 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s0_1
2025-11-08 15:44:11,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 27d94a19-7678-4d5c-84b4-24212d8f7664, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:11,860 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 27d94a19-7678-4d5c-84b4-24212d8f7664, ghrun-ajrcojcmo4.auto.internal:62824): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s0_1" }
2025-11-08 15:44:11,864 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 27d94a19-7678-4d5c-84b4-24212d8f7664, ghrun-ajrcojcmo4.auto.internal:62824): 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" } effective_permissions { subject: "testuser" permission_names: "ydb.generic.full" } created_at { plan_step: 1762616635010 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635010 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635310 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635380 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635610 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616635890 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636160 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636230 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762616636480 tx_id: 281474976720666 } } } } } }
2025-11-08 15:44:11,867 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,869 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,874 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,875 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/customer balance: 64-64 shards.
2025-11-08 15:44:11,876 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,878 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,883 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,884 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/lineitem balance: 64-64 shards.
2025-11-08 15:44:11,886 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,888 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,892 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,893 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/nation balance: 1-1 shards.
2025-11-08 15:44:11,895 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,896 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,901 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,902 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/orders balance: 64-64 shards.
2025-11-08 15:44:11,905 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,906 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,912 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,914 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/part balance: 64-64 shards.
2025-11-08 15:44:11,917 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,919 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,924 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,926 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/partsupp balance: 64-64 shards.
2025-11-08 15:44:11,928 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,930 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,936 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,937 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/region balance: 1-1 shards.
2025-11-08 15:44:11,939 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:11,940 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,946 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "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-11-08 15:44:11,948 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s0_1/supplier balance: 64-64 shards.
2025-11-08 15:44:11,949 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6a95b2bc-dd0f-4ee6-a903-f4e27a9b05f5, ghrun-ajrcojcmo4.auto.internal:62824): creating call state
2025-11-08 15:44:11,949 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6a95b2bc-dd0f-4ee6-a903-f4e27a9b05f5, ghrun-ajrcojcmo4.auto.internal:62824): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-08 15:44:11,950 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6a95b2bc-dd0f-4ee6-a903-f4e27a9b05f5, ghrun-ajrcojcmo4.auto.internal:62824): response = { <_MultiThreadedRendezvous object> }
2025-11-08 15:44:11,963 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb
2025-11-08 15:44:11,964 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-11-08 15:44:11,965 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-08 15:44:11,965 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/jwst/0007b7/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26684', '-d', '/local/test_db', '--user', 'testuser', '--no-password', 'workload', 'tpch', '--path', 'olap_yatests/tpch/s0_1', 'run', '--json', 'f824baa9cfbbd7f7b5193c0ff93cc740.testuser.stats.json', '--output', 'f824baa9cfbbd7f7b5193c0ff93cc740.testuser.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.testuser.plan', '--global-timeout', '1800.0s', '--verbose', '--scale', '0.1', '--threads', '6']' in 'None' (waiting)
2025-11-08 15:44:11,967 - DEBUG - ya.test - execute: Command pid: 412531
2025-11-08 15:44:12,826 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:12,829 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:12,908 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:12,911 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:13,036 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 3539
2025-11-08 15:44:14,047 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:14,049 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:14,139 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-08 15:44:14,142 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:14,365 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 3840
2025-11-08 15:44:15,523 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:15,525 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:15,576 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:15,579 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:15,596 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 3915
2025-11-08 15:44:16,603 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:16,607 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:16,640 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:16,643 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:16,654 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4012
2025-11-08 15:44:17,661 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:17,663 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:17,775 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:17,778 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:17,784 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4081
2025-11-08 15:44:18,792 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:18,794 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:18,839 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:18,843 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:18,949 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4114
2025-11-08 15:44:19,958 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:19,961 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:20,167 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-11-08 15:44:20,171 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:20,292 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4121
2025-11-08 15:44:21,298 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:21,305 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:21,818 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:21,820 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:21,914 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4135
2025-11-08 15:44:22,922 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:22,926 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:22,932 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-08 15:44:22,935 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:22,939 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4108
2025-11-08 15:44:23,947 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:23,949 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:24,058 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-11-08 15:44:24,061 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:24,118 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4144
2025-11-08 15:44:25,125 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:25,127 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:25,733 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:25,736 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:25,815 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4153
2025-11-08 15:44:26,823 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:26,825 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:26,894 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:26,897 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:26,919 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4144
2025-11-08 15:44:27,925 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:27,931 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:28,014 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:28,016 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:28,074 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4176
2025-11-08 15:44:29,082 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:29,086 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:29,114 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-11-08 15:44:29,116 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:29,142 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4168
2025-11-08 15:44:30,155 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:30,165 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:30,198 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:30,200 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:30,626 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4179
2025-11-08 15:44:31,639 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:31,647 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:31,690 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:31,694 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:31,721 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4184
2025-11-08 15:44:32,730 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:32,732 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:32,773 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-08 15:44:32,776 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:32,794 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4192
2025-11-08 15:44:33,802 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:33,805 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:33,825 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:33,828 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:33,841 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4213
2025-11-08 15:44:34,852 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:34,854 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:34,885 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-08 15:44:34,888 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:34,912 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4219
2025-11-08 15:44:35,920 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:35,922 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:35,958 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:35,961 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:35,971 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4224
2025-11-08 15:44:36,979 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:36,981 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:37,047 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-11-08 15:44:37,050 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:37,057 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4215
2025-11-08 15:44:38,065 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:38,068 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:38,140 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-11-08 15:44:38,143 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:38,185 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4224
2025-11-08 15:44:38,909 - DEBUG - ya.test - _save_outputs: Command (pid 412531) rc: 0
2025-11-08 15:44:38,910 - DEBUG - ya.test - _save_outputs: Command (pid 412531) elapsed time (sec): 26.941153287887573
2025-11-08 15:44:38,911 - DEBUG - ya.test - _save_outputs: Command (pid 412531) idrss: 0
2025-11-08 15:44:38,911 - DEBUG - ya.test - _save_outputs: Command (pid 412531) inblock: 0
2025-11-08 15:44:38,911 - DEBUG - ya.test - _save_outputs: Command (pid 412531) isrss: 0
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) ixrss: 0
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) majflt: 0
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) maxrss: 246448
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) minflt: 8506
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) msgrcv: 0
2025-11-08 15:44:38,912 - DEBUG - ya.test - _save_outputs: Command (pid 412531) msgsnd: 0
2025-11-08 15:44:38,913 - DEBUG - ya.test - _save_outputs: Command (pid 412531) nivcsw: 179
2025-11-08 15:44:38,913 - DEBUG - ya.test - _save_outputs: Command (pid 412531) nsignals: 0
2025-11-08 15:44:38,914 - DEBUG - ya.test - _save_outputs: Command (pid 412531) nswap: 0
2025-11-08 15:44:38,915 - DEBUG - ya.test - _save_outputs: Command (pid 412531) nvcsw: 1914
2025-11-08 15:44:38,915 - DEBUG - ya.test - _save_outputs: Command (pid 412531) oublock: 67808
2025-11-08 15:44:38,916 - DEBUG - ya.test - _save_outputs: Command (pid 412531) stime: 0.62335
2025-11-08 15:44:38,917 - DEBUG - ya.test - _save_outputs: Command (pid 412531) utime: 0.692224
2025-11-08 15:44:38,918 - DEBUG - ya.test - _save_outputs: Command (pid 412531) wtime: 26.942
2025-11-08 15:44:38,919 - DEBUG - ya.test - _save_outputs: Command (pid 412531) 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	2.621282s 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	3.456472s 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	4.616277s 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	4.768513s 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	9.630798s 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	2.571639s 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	4.379716s 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	11.756395s 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	3.903494s 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	3.396551s 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	5.587493s 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	2.975777s 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	3.575158s 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	2.481544s 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	3.387166s 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	2.569348s 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	9.431575s 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	8.192155s 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	2.859115s 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	4.836537s 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	6.165996s 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	3.872555s seconds

Results for 1 iterations
┌─────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬────────────────┬────────────────┬────────────────┬───────────┬──────────────┬────────────┬────────────┐
│ Query # │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ CompilationMin │ CompilationMax │ CompilationAvg │ GrossTime │ SuccessCount │ FailsCount │ DiffsCount │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query01 │   2.584  │   2.584 │   2.584 │   2.584 │   2.583 │   2.584   │   0.001 │   0.038 │   0.038 │   0.038 │   0.227        │   0.227        │   0.227        │   2.621   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query02 │   3.355  │   3.355 │   3.355 │   3.355 │   3.355 │   3.355   │   0.000 │   0.101 │   0.101 │   0.101 │   0.520        │   0.520        │   0.520        │   3.456   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query03 │   4.561  │   4.561 │   4.561 │   4.561 │   4.561 │   4.561   │   0.000 │   0.055 │   0.055 │   0.055 │   0.293        │   0.293        │   0.293        │   4.616   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query04 │   4.713  │   4.713 │   4.713 │   4.713 │   4.712 │   4.713   │   0.001 │   0.056 │   0.056 │   0.056 │   0.313        │   0.313        │   0.313        │   4.769   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query05 │   9.531  │   9.531 │   9.531 │   9.531 │   9.530 │   9.531   │   0.001 │   0.100 │   0.100 │   0.100 │   0.342        │   0.342        │   0.342        │   9.631   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query06 │   2.527  │   2.527 │   2.527 │   2.527 │   2.527 │   2.527   │   0.000 │   0.045 │   0.045 │   0.045 │   0.092        │   0.092        │   0.092        │   2.572   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query07 │   4.320  │   4.320 │   4.320 │   4.320 │   4.319 │   4.320   │   0.001 │   0.060 │   0.060 │   0.060 │   0.363        │   0.363        │   0.363        │   4.380   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query08 │  11.465  │  11.465 │  11.465 │  11.465 │  11.465 │  11.465   │   0.000 │   0.291 │   0.291 │   0.291 │   0.677        │   0.677        │   0.677        │  11.756   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query09 │   3.770  │   3.770 │   3.770 │   3.770 │   3.769 │   3.770   │   0.001 │   0.134 │   0.134 │   0.134 │   0.367        │   0.367        │   0.367        │   3.903   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query10 │   2.865  │   2.865 │   2.865 │   2.865 │   2.864 │   2.865   │   0.001 │   0.532 │   0.532 │   0.532 │   0.664        │   0.664        │   0.664        │   3.397   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query11 │   3.890  │   3.890 │   3.890 │   3.890 │   3.889 │   3.890   │   0.001 │   1.698 │   1.698 │   1.698 │   0.421        │   0.421        │   0.421        │   5.587   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query12 │   2.927  │   2.927 │   2.927 │   2.927 │   2.926 │   2.927   │   0.001 │   0.049 │   0.049 │   0.049 │   0.340        │   0.340        │   0.340        │   2.976   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query13 │   3.521  │   3.521 │   3.521 │   3.521 │   3.521 │   3.521   │   0.000 │   0.054 │   0.054 │   0.054 │   0.255        │   0.255        │   0.255        │   3.575   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query14 │   2.455  │   2.455 │   2.455 │   2.455 │   2.455 │   2.455   │   0.000 │   0.026 │   0.026 │   0.026 │   0.168        │   0.168        │   0.168        │   2.482   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query15 │   3.261  │   3.261 │   3.261 │   3.261 │   3.260 │   3.261   │   0.001 │   0.127 │   0.127 │   0.127 │   0.373        │   0.373        │   0.373        │   3.387   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query16 │   2.409  │   2.409 │   2.409 │   2.409 │   2.409 │   2.409   │   0.000 │   0.160 │   0.160 │   0.160 │   0.421        │   0.421        │   0.421        │   2.569   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query17 │   9.345  │   9.345 │   9.345 │   9.345 │   9.344 │   9.345   │   0.001 │   0.087 │   0.087 │   0.087 │   0.303        │   0.303        │   0.303        │   9.432   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query18 │   8.110  │   8.110 │   8.110 │   8.110 │   8.110 │   8.110   │   0.000 │   0.082 │   0.082 │   0.082 │   0.484        │   0.484        │   0.484        │   8.192   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query19 │   2.829  │   2.829 │   2.829 │   2.829 │   2.829 │   2.829   │   0.000 │   0.030 │   0.030 │   0.030 │   0.278        │   0.278        │   0.278        │   2.859   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query20 │   4.469  │   4.469 │   4.469 │   4.469 │   4.468 │   4.469   │   0.001 │   0.368 │   0.368 │   0.368 │   0.618        │   0.618        │   0.618        │   4.837   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query21 │   6.034  │   6.034 │   6.034 │   6.034 │   6.034 │   6.034   │   0.000 │   0.132 │   0.132 │   0.132 │   0.539        │   0.539        │   0.539        │   6.166   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Query22 │   3.789  │   3.789 │   3.789 │   3.789 │   3.788 │   3.789   │   0.001 │   0.084 │   0.084 │   0.084 │   0.280        │   0.280        │   0.280        │   3.873   │ 1            │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Sum     │ 102.729  │ 102.729 │ 102.729 │ 102.729 │ 102.718 │ 102.729   │   0.000 │   4.306 │   4.306 │   4.306 │   8.338        │   8.338        │   8.339        │  26.526   │ 22           │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Avg     │   4.670  │   4.670 │   4.670 │   4.670 │   4.669 │   4.670   │   0.000 │   0.196 │   0.196 │   0.196 │   0.379        │   0.379        │   0.379        │   1.206   │ 22           │            │            │
├─────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ GAvg    │   4.155  │   4.155 │   4.155 │   4.155 │   4.155 │   4.155   │   0.000 │   0.101 │   0.101 │   0.001 │   0.347        │   0.347        │   0.001        │   0.000   │ 22           │            │            │
└─────────┴──────────┴─────────┴─────────┴─────────┴─────────┴───────────┴─────────┴─────────┴─────────┴─────────┴────────────────┴────────────────┴────────────────┴───────────┴──────────────┴────────────┴────────────┘

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

2025-11-08 15:44:38,922 - DEBUG - ya.test - _save_outputs: Command (pid 412531) 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-11-08 15:44:39,193 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,205 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,212 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,215 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:39,227 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4197
2025-11-08 15:44:39,509 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,512 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,518 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,522 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,532 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,536 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,538 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,544 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,547 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,548 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,549 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,549 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,550 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,565 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,573 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,576 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,581 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,593 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,595 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,595 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,597 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,598 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,598 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,608 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,613 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,618 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,620 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,626 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,633 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,634 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,634 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,634 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,636 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,645 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,655 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,663 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,665 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,674 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,676 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,676 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,676 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,677 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,677 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,693 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,697 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,700 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,703 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,710 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,715 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,716 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,717 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,718 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,719 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,726 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,734 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,738 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,740 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,752 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,753 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,754 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,754 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,755 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,755 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,770 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,775 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,778 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,780 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,789 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,791 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,791 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,791 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,792 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,792 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,807 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,810 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 803
2025-11-08 15:44:39,814 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,817 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,831 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-08 15:44:39,832 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,833 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,833 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,835 - INFO - root - allure_test_description: iterations_table created, length: 514
2025-11-08 15:44:39,835 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,848 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,851 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:39,855 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,857 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,864 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:39,869 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,871 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,871 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,872 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,874 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,885 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,890 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 800
2025-11-08 15:44:39,893 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,895 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,902 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:39,904 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,904 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,905 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,905 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,905 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,916 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,919 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 800
2025-11-08 15:44:39,922 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,926 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,932 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:39,935 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,936 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,936 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,937 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,938 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:39,947 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,950 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 800
2025-11-08 15:44:39,969 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:39,974 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:39,988 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:39,992 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:39,992 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:39,994 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:39,995 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:39,995 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,006 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,014 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,018 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,020 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,028 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,030 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,031 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,040 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,042 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,042 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,056 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,066 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,081 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,100 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,110 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,111 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,111 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,113 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,117 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,118 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,127 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,137 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,140 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,149 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,156 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,166 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,168 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,168 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,168 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,168 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,179 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,182 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,187 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,189 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,195 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,197 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,197 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,199 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,199 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,200 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,211 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,220 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,223 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,226 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,235 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,236 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,241 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,244 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,244 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,244 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,245 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,246 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,254 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,255 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,259 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-ajrcojcmo4.auto.internal:6053
2025-11-08 15:44:40,260 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,264 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,264 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-ajrcojcmo4.auto.internal:6053 "GET /counters/counters=kqp/json HTTP/1.1" 200 4199
2025-11-08 15:44:40,270 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,279 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,281 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,281 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,287 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,288 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,289 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,296 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,303 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,307 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,309 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,315 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,318 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,318 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,318 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,319 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,319 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,332 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,336 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,340 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,347 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,353 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,354 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,355 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,355 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,355 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,356 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,371 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,374 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,377 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,380 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,385 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,386 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,387 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,387 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,387 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,387 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,395 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,405 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,409 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,411 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,418 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,420 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,420 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,422 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,422 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,423 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,426 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,430 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,434 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,436 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,442 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,447 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,448 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,448 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,449 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,450 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,452 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,456 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,461 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,463 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,469 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,470 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,476 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,476 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,476 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,476 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,480 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,485 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,488 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,493 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,500 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,501 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,502 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,502 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,503 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-08 15:44:40,503 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,535 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,539 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-11-08 15:44:40,543 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22471
2025-11-08 15:44:40,546 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-08 15:44:40,556 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22471 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 546
2025-11-08 15:44:40,557 - INFO - root - __create_iterations_table: All nodes before filtering: [('6318@ghrun-ajrcojcmo4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-08 15:44:40,557 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-08 15:44:40,563 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-ajrcojcmo4.auto.internal', 1)]
2025-11-08 15:44:40,563 - INFO - root - allure_test_description: iterations_table created, length: 526
2025-11-08 15:44:40,564 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-08 15:44:40,605 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-08 15:44:40,605 - INFO - root - teardown_class: No custom teardown defined for TestTpchWMS0_1
