2025-11-01 17:11:22,437 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 17:11:22,437 - INFO - ya.test - pytest_runtest_setup: test[first_query_set.1.sql]
2025-11-01 17:11:22,438 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 17:11:22,438 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 17:11:22,542 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd
2025-11-01 17:11:22,544 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1142
2025-11-01 17:11:22,545 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/y72z/000c2f/r3tmp/kikimr_cluster_cwjvw42o
2025-11-01 17:11:22,587 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd
2025-11-01 17:11:22,587 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26184
2025-11-01 17:11:22,588 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31617
2025-11-01 17:11:22,588 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62372
2025-11-01 17:11:22,589 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23536
2025-11-01 17:11:22,590 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:22,590 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/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/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/node_1/logfile_ny6hg0dd.log --grpc-port=26184 --mon-port=31617 --ic-port=1142
2025-11-01 17:11:22,591 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:26184/1, disk_size 68719476736
2025-11-01 17:11:22,591 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:22,591 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/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/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/node_1/logfile_ny6hg0dd.log --grpc-port=26184 --mon-port=31617 --ic-port=1142
2025-11-01 17:11:22,593 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/node_1/logfile_ny6hg0dd.log', '--grpc-port=26184', '--mon-port=31617', '--ic-port=1142')' in '/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/node_1' (no wait)
2025-11-01 17:11:22,595 - DEBUG - ya.test - execute: Command pid: 1373278
2025-11-01 17:11:22,596 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:26184/1
2025-11-01 17:11:22,597 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 17:11:22,598 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 17:11:22,599 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 17:11:22,601 - DEBUG - ya.test - execute: Command pid: 1373279
2025-11-01 17:11:23,262 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) rc: 0
2025-11-01 17:11:23,262 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) elapsed time (sec): 0.6603434085845947
2025-11-01 17:11:23,263 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) idrss: 0
2025-11-01 17:11:23,263 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) inblock: 0
2025-11-01 17:11:23,263 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) isrss: 0
2025-11-01 17:11:23,263 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) ixrss: 0
2025-11-01 17:11:23,263 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) majflt: 0
2025-11-01 17:11:23,264 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) maxrss: 577428
2025-11-01 17:11:23,264 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) minflt: 45358
2025-11-01 17:11:23,264 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) msgrcv: 0
2025-11-01 17:11:23,264 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) msgsnd: 0
2025-11-01 17:11:23,265 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) nivcsw: 3
2025-11-01 17:11:23,265 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) nsignals: 0
2025-11-01 17:11:23,265 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) nswap: 0
2025-11-01 17:11:23,265 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) nvcsw: 15
2025-11-01 17:11:23,266 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) oublock: 8
2025-11-01 17:11:23,266 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) stime: 0.17032999999999998
2025-11-01 17:11:23,266 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) utime: 0.445467
2025-11-01 17:11:23,266 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) wtime: 0.662
2025-11-01 17:11:23,266 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 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 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-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-01 17:11:23,267 - DEBUG - ya.test - _save_outputs: Command (pid 1373279) errors:

2025-11-01 17:11:23,267 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 17:11:25,734 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd
2025-11-01 17:11:25,735 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26184', '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: 1142\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 17:11:25,736 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 17:11:25,736 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 17:11:25,737 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26184', '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: 1142\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 17:11:25,738 - DEBUG - ya.test - execute: Command pid: 1373879
2025-11-01 17:11:26,467 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) rc: 0
2025-11-01 17:11:26,467 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) elapsed time (sec): 0.7276947498321533
2025-11-01 17:11:26,468 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) idrss: 0
2025-11-01 17:11:26,468 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) inblock: 0
2025-11-01 17:11:26,468 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) isrss: 0
2025-11-01 17:11:26,468 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) ixrss: 0
2025-11-01 17:11:26,469 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) majflt: 0
2025-11-01 17:11:26,469 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) maxrss: 652320
2025-11-01 17:11:26,469 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) minflt: 51544
2025-11-01 17:11:26,469 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) msgrcv: 0
2025-11-01 17:11:26,470 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) msgsnd: 0
2025-11-01 17:11:26,470 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) nivcsw: 1
2025-11-01 17:11:26,470 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) nsignals: 0
2025-11-01 17:11:26,470 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) nswap: 0
2025-11-01 17:11:26,471 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) nvcsw: 758
2025-11-01 17:11:26,471 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) oublock: 8
2025-11-01 17:11:26,471 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) stime: 0.23948899999999998
2025-11-01 17:11:26,471 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) utime: 0.465999
2025-11-01 17:11:26,472 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) wtime: 0.729
2025-11-01 17:11:26,472 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 17:11:26,472 - DEBUG - ya.test - _save_outputs: Command (pid 1373879) errors:

2025-11-01 17:11:26,473 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd
2025-11-01 17:11:26,473 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26184', '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-01 17:11:26,474 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 17:11:26,475 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 17:11:26,475 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26184', '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-01 17:11:26,476 - DEBUG - ya.test - execute: Command pid: 1373977
2025-11-01 17:11:27,274 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) rc: 0
2025-11-01 17:11:27,275 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) elapsed time (sec): 0.7957019805908203
2025-11-01 17:11:27,275 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) idrss: 0
2025-11-01 17:11:27,275 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) inblock: 0
2025-11-01 17:11:27,275 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) isrss: 0
2025-11-01 17:11:27,276 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) ixrss: 0
2025-11-01 17:11:27,276 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) majflt: 0
2025-11-01 17:11:27,276 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) maxrss: 652952
2025-11-01 17:11:27,276 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) minflt: 51770
2025-11-01 17:11:27,276 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) msgrcv: 0
2025-11-01 17:11:27,277 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) msgsnd: 0
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) nivcsw: 62
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) nsignals: 0
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) nswap: 0
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) nvcsw: 775
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) oublock: 8
2025-11-01 17:11:27,278 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) stime: 0.24309899999999998
2025-11-01 17:11:27,279 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) utime: 0.520753
2025-11-01 17:11:27,279 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) wtime: 0.797
2025-11-01 17:11:27,279 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 17:11:27,279 - DEBUG - ya.test - _save_outputs: Command (pid 1373977) errors:

2025-11-01 17:11:27,280 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 17:11:27,319 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 17:11:27,888 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd
2025-11-01 17:11:27,889 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21196
2025-11-01 17:11:27,890 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28417
2025-11-01 17:11:27,890 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20914
2025-11-01 17:11:27,891 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6929
2025-11-01 17:11:27,891 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23104
2025-11-01 17:11:27,892 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:27,892 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26184 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/slot_1/logfile__e7v6f9w.log --grpc-port=21196 --mon-port=28417 --ic-port=20914
2025-11-01 17:11:27,893 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:27,893 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26184 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/slot_1/logfile__e7v6f9w.log --grpc-port=21196 --mon-port=28417 --ic-port=20914
2025-11-01 17:11:27,894 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:26184', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/slot_1/logfile__e7v6f9w.log', '--grpc-port=21196', '--mon-port=28417', '--ic-port=20914')' in '/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/slot_1' (no wait)
2025-11-01 17:11:27,897 - DEBUG - ya.test - execute: Command pid: 1374351
2025-11-01 17:11:27,897 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21196/1
2025-11-01 17:11:27,899 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 17:11:27,899 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 17:11:27,900 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 17:11:27,901 - DEBUG - ya.test - execute: Command pid: 1374352
2025-11-01 17:11:28,579 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) rc: 0
2025-11-01 17:11:28,579 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) elapsed time (sec): 0.6765246391296387
2025-11-01 17:11:28,580 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) idrss: 0
2025-11-01 17:11:28,581 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) inblock: 0
2025-11-01 17:11:28,581 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) isrss: 0
2025-11-01 17:11:28,582 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) ixrss: 0
2025-11-01 17:11:28,583 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) majflt: 0
2025-11-01 17:11:28,584 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) maxrss: 665652
2025-11-01 17:11:28,584 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) minflt: 45308
2025-11-01 17:11:28,584 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) msgrcv: 0
2025-11-01 17:11:28,584 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) msgsnd: 0
2025-11-01 17:11:28,585 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) nivcsw: 19
2025-11-01 17:11:28,586 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) nsignals: 0
2025-11-01 17:11:28,586 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) nswap: 0
2025-11-01 17:11:28,586 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) nvcsw: 14
2025-11-01 17:11:28,587 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) oublock: 8
2025-11-01 17:11:28,588 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) stime: 0.170825
2025-11-01 17:11:28,588 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) utime: 0.455489
2025-11-01 17:11:28,589 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) wtime: 0.678
2025-11-01 17:11:28,589 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 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 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-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-01 17:11:28,590 - DEBUG - ya.test - _save_outputs: Command (pid 1374352) errors:

2025-11-01 17:11:28,590 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 17:11:30,606 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb
2025-11-01 17:11:30,607 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 17:11:30,609 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 17:11:30,609 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26184', '-d', '/local/test_db', 'workload', 'query', '-p', 'e1', 'init', '--suite-path', '/home/runner/.ya/build/build_root/y72z/000c2f/environment/arcadia/ydb/tests/functional/tpc/data/e1']' in 'None' (waiting)
2025-11-01 17:11:30,611 - DEBUG - ya.test - execute: Command pid: 1375343
2025-11-01 17:11:35,734 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) rc: 0
2025-11-01 17:11:35,734 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) elapsed time (sec): 5.121351003646851
2025-11-01 17:11:35,735 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) idrss: 0
2025-11-01 17:11:35,735 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) inblock: 0
2025-11-01 17:11:35,735 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) isrss: 0
2025-11-01 17:11:35,735 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) ixrss: 0
2025-11-01 17:11:35,736 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) majflt: 0
2025-11-01 17:11:35,737 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) maxrss: 666396
2025-11-01 17:11:35,738 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) minflt: 105266
2025-11-01 17:11:35,738 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) msgrcv: 0
2025-11-01 17:11:35,738 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) msgsnd: 0
2025-11-01 17:11:35,739 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) nivcsw: 14
2025-11-01 17:11:35,739 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) nsignals: 0
2025-11-01 17:11:35,739 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) nswap: 0
2025-11-01 17:11:35,739 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) nvcsw: 3337
2025-11-01 17:11:35,740 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) oublock: 8
2025-11-01 17:11:35,740 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) stime: 0.24082199999999998
2025-11-01 17:11:35,740 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) utime: 0.320758
2025-11-01 17:11:35,741 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) wtime: 5.123
2025-11-01 17:11:35,741 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) output:
Init tables ...
Init tables ...Ok

2025-11-01 17:11:35,741 - DEBUG - ya.test - _save_outputs: Command (pid 1375343) errors:

2025-11-01 17:11:35,743 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb
2025-11-01 17:11:35,744 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-01 17:11:35,745 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 17:11:35,746 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26184', '-d', '/local/test_db', 'workload', 'query', '-p', 'e1', 'import', '--suite-path', '/home/runner/.ya/build/build_root/y72z/000c2f/environment/arcadia/ydb/tests/functional/tpc/data/e1']' in 'None' (waiting)
2025-11-01 17:11:35,748 - DEBUG - ya.test - execute: Command pid: 1377910
2025-11-01 17:11:36,780 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) rc: 0
2025-11-01 17:11:36,781 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) elapsed time (sec): 1.0307426452636719
2025-11-01 17:11:36,781 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) idrss: 0
2025-11-01 17:11:36,781 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) inblock: 16
2025-11-01 17:11:36,781 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) isrss: 0
2025-11-01 17:11:36,782 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) ixrss: 0
2025-11-01 17:11:36,782 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) majflt: 0
2025-11-01 17:11:36,783 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) maxrss: 666944
2025-11-01 17:11:36,783 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) minflt: 117641
2025-11-01 17:11:36,783 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) msgrcv: 0
2025-11-01 17:11:36,784 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) msgsnd: 0
2025-11-01 17:11:36,784 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) nivcsw: 109
2025-11-01 17:11:36,784 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) nsignals: 0
2025-11-01 17:11:36,784 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) nswap: 0
2025-11-01 17:11:36,785 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) nvcsw: 8466
2025-11-01 17:11:36,785 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) oublock: 16
2025-11-01 17:11:36,785 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) stime: 0.401153
2025-11-01 17:11:36,786 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) utime: 0.425554
2025-11-01 17:11:36,786 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) wtime: 1.033
2025-11-01 17:11:36,786 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) output:
Fill table test_table_1...
Fill table test_table_1 OK 0 / 0 (0.253536s)
Fill table test_table_2...
Fill table test_table_2 OK 0 / 0 (0.201258s)

2025-11-01 17:11:36,786 - DEBUG - ya.test - _save_outputs: Command (pid 1377910) errors:



2025-11-01 17:11:36,787 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-11-01 17:11:36,795 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:36,799 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:36,816 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-01 17:11:36,819 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:36,819 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 7.79046893119812
2025-11-01 17:11:36,820 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:26184 to local/test_db ydb_access_token is set False
2025-11-01 17:11:36,821 - 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-01 17:11:36,824 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 17:11:36,825 - 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-01 17:11:36,853 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-11-01 17:11:36,854 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cc6c9435-59e4-4898-992e-4315693b3ec8, localhost:26184): creating call state
2025-11-01 17:11:36,855 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cc6c9435-59e4-4898-992e-4315693b3ec8, localhost:26184): request = { database: "local/test_db" }
2025-11-01 17:11:36,861 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cc6c9435-59e4-4898-992e-4315693b3ec8, localhost:26184): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 21196 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-01 17:11:36,862 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:21196, location , ssl: False>]>
2025-11-01 17:11:36,863 - 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-01 17:11:36,874 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:26184
2025-11-01 17:11:36,876 - INFO - root - _create_ydb_driver: Connected
2025-11-01 17:11:36,878 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 07e38d19-64f1-46b9-b4eb-a15ba4c84cae, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:36,879 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 07e38d19-64f1-46b9-b4eb-a15ba4c84cae, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:36,886 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 07e38d19-64f1-46b9-b4eb-a15ba4c84cae, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:37,052 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 7.79046893119812
2025-11-01 17:11:38,057 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:38,062 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:38,073 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-11-01 17:11:38,075 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:38,076 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 9.047400951385498
2025-11-01 17:11:38,077 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 4fa0c762-d5b9-4023-85cb-ff1271b6c073, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:38,078 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 4fa0c762-d5b9-4023-85cb-ff1271b6c073, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:38,080 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 4fa0c762-d5b9-4023-85cb-ff1271b6c073, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:38,140 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 9.047400951385498
2025-11-01 17:11:39,145 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:39,148 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:39,157 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-01 17:11:39,159 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:39,160 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 10.131310939788818
2025-11-01 17:11:39,161 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a6b7a750-50d6-4dc4-a783-39b0d728996f, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:39,162 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a6b7a750-50d6-4dc4-a783-39b0d728996f, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:39,164 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a6b7a750-50d6-4dc4-a783-39b0d728996f, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:39,220 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 10.131310939788818
2025-11-01 17:11:40,224 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:40,227 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:40,236 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-11-01 17:11:40,238 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:40,239 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 11.210514307022095
2025-11-01 17:11:40,240 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 8b97289f-9cd3-4aa3-a876-63940fc8e300, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:40,241 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 8b97289f-9cd3-4aa3-a876-63940fc8e300, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:40,244 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 8b97289f-9cd3-4aa3-a876-63940fc8e300, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:40,294 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 11.210514307022095
2025-11-01 17:11:41,299 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:41,302 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:41,311 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-01 17:11:41,314 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:41,314 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 12.28546929359436
2025-11-01 17:11:41,315 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, f9a06294-738e-4e90-95c0-cddc85d26a24, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:41,316 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, f9a06294-738e-4e90-95c0-cddc85d26a24, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:41,318 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, f9a06294-738e-4e90-95c0-cddc85d26a24, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:41,373 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 12.28546929359436
2025-11-01 17:11:42,377 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:42,381 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:42,389 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-11-01 17:11:42,392 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:42,392 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 13.363391876220703
2025-11-01 17:11:42,393 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, eda8d77a-472d-4057-8cc6-181fc0543793, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:42,394 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, eda8d77a-472d-4057-8cc6-181fc0543793, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:42,396 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, eda8d77a-472d-4057-8cc6-181fc0543793, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:42,451 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 13.363391876220703
2025-11-01 17:11:43,456 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:43,460 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:43,468 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 547
2025-11-01 17:11:43,470 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:43,471 - ERROR - root - _join_errors: Node ghrun-cjocww3of4.auto.internal too yong: 14.44205117225647
2025-11-01 17:11:43,472 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c1327e89-85a8-4253-a93c-53fd07f3f226, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:43,472 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c1327e89-85a8-4253-a93c-53fd07f3f226, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:43,474 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c1327e89-85a8-4253-a93c-53fd07f3f226, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:43,527 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-cjocww3of4.auto.internal too yong: 14.44205117225647
2025-11-01 17:11:44,532 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,535 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:44,545 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-01 17:11:44,548 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:44,549 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 1fdfbbca-2736-42b4-9d8e-7aeb73924917, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:44,550 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 1fdfbbca-2736-42b4-9d8e-7aeb73924917, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:44,551 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 1fdfbbca-2736-42b4-9d8e-7aeb73924917, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:44,613 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,617 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:44,626 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-01 17:11:44,629 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:44,629 - INFO - root - get_tables: get_tables /local/test_db/e1
2025-11-01 17:11:44,632 - INFO - root - _describe_path_impl: describe /local/test_db/e1
2025-11-01 17:11:44,633 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 6ba3ed65-7acd-46d9-a529-d2147fe26195, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:44,634 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 6ba3ed65-7acd-46d9-a529-d2147fe26195, ghrun-cjocww3of4.auto.internal:21196): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/e1" }
2025-11-01 17:11:44,641 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 6ba3ed65-7acd-46d9-a529-d2147fe26195, ghrun-cjocww3of4.auto.internal:21196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "e1" 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: 1762017093640 tx_id: 281474976720659 } } } } } }
2025-11-01 17:11:44,642 - INFO - root - list_directory: list /local/test_db/e1
2025-11-01 17:11:44,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, c7764114-e505-4386-aced-28a848b30477, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:44,643 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, c7764114-e505-4386-aced-28a848b30477, ghrun-cjocww3of4.auto.internal:21196): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/e1" }
2025-11-01 17:11:44,649 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, c7764114-e505-4386-aced-28a848b30477, ghrun-cjocww3of4.auto.internal:21196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "e1" 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: 1762017093640 tx_id: 281474976720659 } } children { name: "test_table_1" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762017093640 tx_id: 281474976720659 } } children { name: "test_table_2" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762017095360 tx_id: 281474976720660 } } } } } }
2025-11-01 17:11:44,653 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,656 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_1&tablets=true HTTP/1.1" 307 0
2025-11-01 17:11:44,688 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_1&tablets=true HTTP/1.1" 200 580
2025-11-01 17:11:44,690 - INFO - root - check_if_ydb_alive: Table /local/test_db/e1/test_table_1 balance: 64-64 shards.
2025-11-01 17:11:44,693 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,698 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_2&tablets=true HTTP/1.1" 307 0
2025-11-01 17:11:44,708 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_2&tablets=true HTTP/1.1" 200 580
2025-11-01 17:11:44,710 - INFO - root - check_if_ydb_alive: Table /local/test_db/e1/test_table_2 balance: 64-64 shards.
2025-11-01 17:11:44,711 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, ec0003ab-54fc-4a73-8648-a45d50188f34, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:44,711 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, ec0003ab-54fc-4a73-8648-a45d50188f34, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:44,713 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, ec0003ab-54fc-4a73-8648-a45d50188f34, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:44,768 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,773 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes HTTP/1.1" 200 788
2025-11-01 17:11:44,778 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:44,781 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:44,789 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-11-01 17:11:45,503 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:45,509 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes HTTP/1.1" 200 791
2025-11-01 17:11:45,514 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:45,517 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:45,526 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 556
2025-11-01 17:11:45,528 - INFO - root - __create_iterations_table: All nodes before filtering: [('20914@ghrun-cjocww3of4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-01 17:11:45,528 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-01 17:11:45,529 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-cjocww3of4.auto.internal', 1)]
2025-11-01 17:11:45,530 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-01 17:11:45,530 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-01 17:11:45,803 - DEBUG - matplotlib - wrapper: CACHEDIR=/home/runner/.cache/matplotlib
2025-11-01 17:11:45,807 - DEBUG - matplotlib.font_manager - _load_fontmanager: Using fontManager instance from /home/runner/.cache/matplotlib/fontlist-v330.json
2025-11-01 17:11:46,877 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:46,878 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/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/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/node_1/logfile_ny6hg0dd.log --grpc-port=26184 --mon-port=31617 --ic-port=1142
2025-11-01 17:11:46,878 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:26184/1
2025-11-01 17:11:46,880 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-11-01 17:11:46,881 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-11-01 17:11:46,881 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 17:11:46,883 - DEBUG - ya.test - execute: Command pid: 1382207
2025-11-01 17:11:47,611 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) rc: 0
2025-11-01 17:11:47,611 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) elapsed time (sec): 0.7270190715789795
2025-11-01 17:11:47,612 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) idrss: 0
2025-11-01 17:11:47,612 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) inblock: 0
2025-11-01 17:11:47,612 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) isrss: 0
2025-11-01 17:11:47,612 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) ixrss: 0
2025-11-01 17:11:47,613 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) majflt: 0
2025-11-01 17:11:47,613 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) maxrss: 754356
2025-11-01 17:11:47,613 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) minflt: 45403
2025-11-01 17:11:47,613 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) msgrcv: 0
2025-11-01 17:11:47,614 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) msgsnd: 0
2025-11-01 17:11:47,614 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) nivcsw: 17
2025-11-01 17:11:47,614 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) nsignals: 0
2025-11-01 17:11:47,614 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) nswap: 0
2025-11-01 17:11:47,614 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) nvcsw: 15
2025-11-01 17:11:47,615 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) oublock: 8
2025-11-01 17:11:47,615 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) stime: 0.208484
2025-11-01 17:11:47,615 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) utime: 0.47036999999999995
2025-11-01 17:11:47,615 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) wtime: 0.729
2025-11-01 17:11:47,616 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 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 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-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-01 17:11:47,616 - DEBUG - ya.test - _save_outputs: Command (pid 1382207) errors:

2025-11-01 17:11:47,617 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 17:11:47,617 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/kikimr_configs"
2025-11-01 17:11:47,617 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26184 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/y72z/000c2f/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_external/testing_out_stuff/test_external.py.TestExternalE1.test.first_query_set.1.sql/cluster/slot_1/logfile__e7v6f9w.log --grpc-port=21196 --mon-port=28417 --ic-port=20914
2025-11-01 17:11:47,618 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21196/1
2025-11-01 17:11:47,619 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-11-01 17:11:47,620 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-11-01 17:11:47,620 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 17:11:47,622 - DEBUG - ya.test - execute: Command pid: 1382479
2025-11-01 17:11:48,342 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) rc: 0
2025-11-01 17:11:48,343 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) elapsed time (sec): 0.7197189331054688
2025-11-01 17:11:48,343 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) idrss: 0
2025-11-01 17:11:48,343 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) inblock: 0
2025-11-01 17:11:48,344 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) isrss: 0
2025-11-01 17:11:48,344 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) ixrss: 0
2025-11-01 17:11:48,344 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) majflt: 0
2025-11-01 17:11:48,344 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) maxrss: 754804
2025-11-01 17:11:48,345 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) minflt: 45323
2025-11-01 17:11:48,345 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) msgrcv: 0
2025-11-01 17:11:48,345 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) msgsnd: 0
2025-11-01 17:11:48,345 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) nivcsw: 32
2025-11-01 17:11:48,346 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) nsignals: 0
2025-11-01 17:11:48,346 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) nswap: 0
2025-11-01 17:11:48,346 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) nvcsw: 15
2025-11-01 17:11:48,347 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) oublock: 8
2025-11-01 17:11:48,347 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) stime: 0.205669
2025-11-01 17:11:48,347 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) utime: 0.46097299999999997
2025-11-01 17:11:48,348 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) wtime: 0.721
2025-11-01 17:11:48,348 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 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 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-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-01 17:11:48,349 - DEBUG - ya.test - _save_outputs: Command (pid 1382479) errors:

2025-11-01 17:11:48,349 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 17:11:48,359 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_external.py::TestExternalE1, test_name: test[first_query_set.1.sql])
2025-11-01 17:11:48,363 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:48,367 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:48,376 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-11-01 17:11:48,383 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:48,387 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:48,396 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-11-01 17:11:48,399 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-11-01 17:11:48,399 - INFO - root - get_tables: get_tables /local/test_db/e1
2025-11-01 17:11:48,399 - INFO - root - _describe_path_impl: describe /local/test_db/e1
2025-11-01 17:11:48,400 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 99cb5309-4c66-4c6e-ab94-3c994157ed4a, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:48,401 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 99cb5309-4c66-4c6e-ab94-3c994157ed4a, ghrun-cjocww3of4.auto.internal:21196): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/e1" }
2025-11-01 17:11:48,406 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 99cb5309-4c66-4c6e-ab94-3c994157ed4a, ghrun-cjocww3of4.auto.internal:21196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "e1" 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: 1762017093640 tx_id: 281474976720659 } } } } } }
2025-11-01 17:11:48,407 - INFO - root - list_directory: list /local/test_db/e1
2025-11-01 17:11:48,407 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, b9a57e86-e3a2-4657-a151-20e697b0682a, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:48,408 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, b9a57e86-e3a2-4657-a151-20e697b0682a, ghrun-cjocww3of4.auto.internal:21196): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/e1" }
2025-11-01 17:11:48,414 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, b9a57e86-e3a2-4657-a151-20e697b0682a, ghrun-cjocww3of4.auto.internal:21196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "e1" 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: 1762017093640 tx_id: 281474976720659 } } children { name: "test_table_1" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762017093640 tx_id: 281474976720659 } } children { name: "test_table_2" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1762017095360 tx_id: 281474976720660 } } } } } }
2025-11-01 17:11:48,417 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:48,421 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_1&tablets=true HTTP/1.1" 307 0
2025-11-01 17:11:48,431 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_1&tablets=true HTTP/1.1" 200 582
2025-11-01 17:11:48,433 - INFO - root - check_if_ydb_alive: Table /local/test_db/e1/test_table_1 balance: 64-64 shards.
2025-11-01 17:11:48,436 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:48,439 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_2&tablets=true HTTP/1.1" 307 0
2025-11-01 17:11:48,449 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/e1/test_table_2&tablets=true HTTP/1.1" 200 582
2025-11-01 17:11:48,451 - INFO - root - check_if_ydb_alive: Table /local/test_db/e1/test_table_2 balance: 64-64 shards.
2025-11-01 17:11:48,452 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 708b2ae1-2ee2-46ac-aa09-164bea0cbf61, ghrun-cjocww3of4.auto.internal:21196): creating call state
2025-11-01 17:11:48,453 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 708b2ae1-2ee2-46ac-aa09-164bea0cbf61, ghrun-cjocww3of4.auto.internal:21196): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-11-01 17:11:48,455 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 708b2ae1-2ee2-46ac-aa09-164bea0cbf61, ghrun-cjocww3of4.auto.internal:21196): response = { <_MultiThreadedRendezvous object> }
2025-11-01 17:11:48,515 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb
2025-11-01 17:11:48,517 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 17:11:48,521 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-01 17:11:48,522 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/000c2f/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:26184', '-d', '/local/test_db', 'workload', 'query', '--path', 'e1', 'run', '--suite-path', '/home/runner/.ya/build/build_root/y72z/000c2f/environment/arcadia/ydb/tests/functional/tpc/data/e1', '--json', 'first_query_set.1.sql.stats.json', '--output', 'first_query_set.1.sql.result', '--include', 'first_query_set.1.sql', '--iterations', '1', '--plan', 'first_query_set.1.sql.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical']' in 'None' (waiting)
2025-11-01 17:11:48,531 - DEBUG - ya.test - execute: Command pid: 1382746
2025-11-01 17:11:49,898 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) rc: 0
2025-11-01 17:11:49,899 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) elapsed time (sec): 1.3648431301116943
2025-11-01 17:11:49,899 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) idrss: 0
2025-11-01 17:11:49,900 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) inblock: 48
2025-11-01 17:11:49,900 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) isrss: 0
2025-11-01 17:11:49,900 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) ixrss: 0
2025-11-01 17:11:49,901 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) majflt: 0
2025-11-01 17:11:49,901 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) maxrss: 757892
2025-11-01 17:11:49,902 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) minflt: 102907
2025-11-01 17:11:49,902 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) msgrcv: 0
2025-11-01 17:11:49,902 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) msgsnd: 0
2025-11-01 17:11:49,902 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) nivcsw: 48
2025-11-01 17:11:49,903 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) nsignals: 0
2025-11-01 17:11:49,903 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) nswap: 0
2025-11-01 17:11:49,903 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) nvcsw: 2504
2025-11-01 17:11:49,903 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) oublock: 504
2025-11-01 17:11:49,904 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) stime: 0.25329199999999996
2025-11-01 17:11:49,906 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) utime: 0.27332999999999996
2025-11-01 17:11:49,907 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) wtime: 1.374
2025-11-01 17:11:49,907 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) output:
first_query_set.1.sql:
Query text:
PRAGMA TablePathPrefix = "/local/test_db/e1";
SELECT * from test_table_1 ORDER BY key_1 LIMIT 10;

	iteration 0:	ok	0.562456s seconds

Results for 1 iterations
┌───────────────────────┬──────────┬─────────┬─────────┬─────────┬─────────┬───────────┬─────────┬─────────┬─────────┬─────────┬────────────────┬────────────────┬────────────────┬───────────┬──────────────┬────────────┬────────────┐
│ Query #               │ ColdTime │ Min     │ Max     │ Mean    │ Median  │ UnixBench │ Std     │ RttMin  │ RttMax  │ RttAvg  │ CompilationMin │ CompilationMax │ CompilationAvg │ GrossTime │ SuccessCount │ FailsCount │ DiffsCount │
├───────────────────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ first_query_set.1.sql │   0.488  │   0.488 │   0.488 │   0.488 │   0.488 │   0.488   │   0.000 │   0.074 │   0.074 │   0.074 │   0.222        │   0.222        │   0.222        │   0.562   │ 1            │            │            │
├───────────────────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Sum                   │   0.488  │   0.488 │   0.488 │   0.488 │   0.488 │   0.488   │   0.000 │   0.074 │   0.074 │   0.074 │   0.222        │   0.222        │   0.223        │   0.895   │ 1            │            │            │
├───────────────────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ Avg                   │   0.488  │   0.488 │   0.488 │   0.488 │   0.488 │   0.488   │   0.000 │   0.074 │   0.074 │   0.074 │   0.222        │   0.222        │   0.223        │   0.895   │ 1            │            │            │
├───────────────────────┼──────────┼─────────┼─────────┼─────────┼─────────┼───────────┼─────────┼─────────┼─────────┼─────────┼────────────────┼────────────────┼────────────────┼───────────┼──────────────┼────────────┼────────────┤
│ GAvg                  │   0.488  │   0.488 │   0.488 │   0.488 │   0.488 │   0.488   │   0.000 │   0.074 │   0.074 │   0.001 │   0.222        │   0.222        │   0.001        │   0.000   │ 1            │            │            │
└───────────────────────┴──────────┴─────────┴─────────┴─────────┴─────────┴───────────┴─────────┴─────────┴─────────┴─────────┴────────────────┴────────────────┴────────────────┴───────────┴──────────────┴────────────┴────────────┘

Results saved to first_query_set.1.sql.result
Json report saved to first_query_set.1.sql.stats.json

2025-11-01 17:11:49,909 - DEBUG - ya.test - _save_outputs: Command (pid 1382746) errors:

[K
[K
2025-11-01 17:11:49,910 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-11-01 17:11:49,937 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:49,944 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:49,956 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 556
2025-11-01 17:11:49,971 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:49,978 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes HTTP/1.1" 200 789
2025-11-01 17:11:49,984 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:31617
2025-11-01 17:11:49,987 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-11-01 17:11:49,996 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:31617 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 556
2025-11-01 17:11:49,998 - INFO - root - __create_iterations_table: All nodes before filtering: [('20914@ghrun-cjocww3of4.auto.internal', <Role.UNKNOWN: 0>)]
2025-11-01 17:11:49,999 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-11-01 17:11:49,999 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-cjocww3of4.auto.internal', 1)]
2025-11-01 17:11:49,999 - INFO - root - allure_test_description: iterations_table created, length: 513
2025-11-01 17:11:50,000 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-11-01 17:11:50,003 - INFO - ya.test - pytest_runtest_teardown: Test teardown
