2025-10-12 09:14:12,166 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-12 09:14:12,167 - INFO - ya.test - pytest_runtest_setup: test
2025-10-12 09:14:12,167 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-12 09:14:12,167 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-12 09:14:12,285 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd
2025-10-12 09:14:12,287 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1168
2025-10-12 09:14:12,289 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/kikimr_cluster_txd4cmf0
2025-10-12 09:14:12,332 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd
2025-10-12 09:14:12,332 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5151
2025-10-12 09:14:12,333 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64783
2025-10-12 09:14:12,334 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8702
2025-10-12 09:14:12,334 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4336
2025-10-12 09:14:12,336 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:14:12,336 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/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/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/node_1/logfile_kwha520d.log --grpc-port=5151 --mon-port=64783 --ic-port=1168
2025-10-12 09:14:12,337 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5151/1, disk_size 68719476736
2025-10-12 09:14:12,337 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:14:12,338 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/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/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/node_1/logfile_kwha520d.log --grpc-port=5151 --mon-port=64783 --ic-port=1168
2025-10-12 09:14:12,339 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/node_1/logfile_kwha520d.log', '--grpc-port=5151', '--mon-port=64783', '--ic-port=1168')' in '/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/node_1' (no wait)
2025-10-12 09:14:12,342 - DEBUG - ya.test - execute: Command pid: 1288112
2025-10-12 09:14:12,344 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5151/1
2025-10-12 09:14:12,346 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-12 09:14:12,347 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-12 09:14:12,347 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-12 09:14:12,349 - DEBUG - ya.test - execute: Command pid: 1288114
2025-10-12 09:14:13,206 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) rc: 0
2025-10-12 09:14:13,207 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) elapsed time (sec): 0.8560676574707031
2025-10-12 09:14:13,207 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) idrss: 0
2025-10-12 09:14:13,207 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) inblock: 0
2025-10-12 09:14:13,208 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) isrss: 0
2025-10-12 09:14:13,208 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) ixrss: 0
2025-10-12 09:14:13,208 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) majflt: 0
2025-10-12 09:14:13,208 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) maxrss: 553012
2025-10-12 09:14:13,209 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) minflt: 45285
2025-10-12 09:14:13,209 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) msgrcv: 0
2025-10-12 09:14:13,209 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) msgsnd: 0
2025-10-12 09:14:13,210 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) nivcsw: 43
2025-10-12 09:14:13,210 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) nsignals: 0
2025-10-12 09:14:13,210 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) nswap: 0
2025-10-12 09:14:13,211 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) nvcsw: 15
2025-10-12 09:14:13,211 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) oublock: 8
2025-10-12 09:14:13,211 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) stime: 0.237178
2025-10-12 09:14:13,212 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) utime: 0.562219
2025-10-12 09:14:13,212 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) wtime: 0.858
2025-10-12 09:14:13,212 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) output:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e

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

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

2025-10-12 09:14:13,213 - DEBUG - ya.test - _save_outputs: Command (pid 1288114) errors:

2025-10-12 09:14:13,213 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e
2025-10-12 09:14:15,993 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd
2025-10-12 09:14:15,994 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5151', '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: 1168\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-12 09:14:15,995 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-12 09:14:15,996 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-12 09:14:15,997 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5151', '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: 1168\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-12 09:14:15,998 - DEBUG - ya.test - execute: Command pid: 1288526
2025-10-12 09:14:16,990 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) rc: 0
2025-10-12 09:14:16,991 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) elapsed time (sec): 0.9907245635986328
2025-10-12 09:14:16,991 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) idrss: 0
2025-10-12 09:14:16,992 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) inblock: 0
2025-10-12 09:14:16,992 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) isrss: 0
2025-10-12 09:14:16,992 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) ixrss: 0
2025-10-12 09:14:16,993 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) majflt: 0
2025-10-12 09:14:16,993 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) maxrss: 650412
2025-10-12 09:14:16,993 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) minflt: 51648
2025-10-12 09:14:16,993 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) msgrcv: 0
2025-10-12 09:14:16,994 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) msgsnd: 0
2025-10-12 09:14:16,994 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) nivcsw: 36
2025-10-12 09:14:16,994 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) nsignals: 0
2025-10-12 09:14:16,995 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) nswap: 0
2025-10-12 09:14:16,995 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) nvcsw: 1104
2025-10-12 09:14:16,996 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) oublock: 8
2025-10-12 09:14:16,996 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) stime: 0.312735
2025-10-12 09:14:16,996 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) utime: 0.641461
2025-10-12 09:14:16,996 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) wtime: 0.993
2025-10-12 09:14:16,997 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-12 09:14:16,997 - DEBUG - ya.test - _save_outputs: Command (pid 1288526) errors:

2025-10-12 09:14:16,999 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd
2025-10-12 09:14:16,999 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5151', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-12 09:14:17,000 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-12 09:14:17,001 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-12 09:14:17,002 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:5151', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-12 09:14:17,003 - DEBUG - ya.test - execute: Command pid: 1288751
2025-10-12 09:14:17,990 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) rc: 0
2025-10-12 09:14:17,991 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) elapsed time (sec): 0.985938310623169
2025-10-12 09:14:17,991 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) idrss: 0
2025-10-12 09:14:17,992 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) inblock: 0
2025-10-12 09:14:17,992 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) isrss: 0
2025-10-12 09:14:18,015 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) ixrss: 0
2025-10-12 09:14:18,015 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) majflt: 0
2025-10-12 09:14:18,016 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) maxrss: 651556
2025-10-12 09:14:18,016 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) minflt: 51620
2025-10-12 09:14:18,016 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) msgrcv: 0
2025-10-12 09:14:18,017 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) msgsnd: 0
2025-10-12 09:14:18,017 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) nivcsw: 64
2025-10-12 09:14:18,017 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) nsignals: 0
2025-10-12 09:14:18,018 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) nswap: 0
2025-10-12 09:14:18,018 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) nvcsw: 707
2025-10-12 09:14:18,018 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) oublock: 8
2025-10-12 09:14:18,018 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) stime: 0.320295
2025-10-12 09:14:18,019 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) utime: 0.599893
2025-10-12 09:14:18,019 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) wtime: 0.988
2025-10-12 09:14:18,019 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-12 09:14:18,020 - DEBUG - ya.test - _save_outputs: Command (pid 1288751) errors:

2025-10-12 09:14:18,020 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-10-12 09:14:18,253 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-12 09:14:18,815 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd
2025-10-12 09:14:18,816 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3797
2025-10-12 09:14:18,817 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1621
2025-10-12 09:14:18,818 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11507
2025-10-12 09:14:18,818 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63219
2025-10-12 09:14:18,819 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63220
2025-10-12 09:14:18,820 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19843
2025-10-12 09:14:18,821 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:14:18,821 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:5151 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/slot_1/logfile_sn4gbjfk.log --grpc-port=3797 --mon-port=1621 --ic-port=11507
2025-10-12 09:14:18,822 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:14:18,823 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:5151 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/slot_1/logfile_sn4gbjfk.log --grpc-port=3797 --mon-port=1621 --ic-port=11507
2025-10-12 09:14:18,824 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:5151', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/slot_1/logfile_sn4gbjfk.log', '--grpc-port=3797', '--mon-port=1621', '--ic-port=11507')' in '/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/slot_1' (no wait)
2025-10-12 09:14:18,836 - DEBUG - ya.test - execute: Command pid: 1289021
2025-10-12 09:14:18,837 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3797/1
2025-10-12 09:14:18,839 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-12 09:14:18,840 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-12 09:14:18,841 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-12 09:14:18,843 - DEBUG - ya.test - execute: Command pid: 1289022
2025-10-12 09:14:19,705 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) rc: 0
2025-10-12 09:14:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) elapsed time (sec): 0.8616495132446289
2025-10-12 09:14:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) idrss: 0
2025-10-12 09:14:19,707 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) inblock: 0
2025-10-12 09:14:19,707 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) isrss: 0
2025-10-12 09:14:19,708 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) ixrss: 0
2025-10-12 09:14:19,708 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) majflt: 0
2025-10-12 09:14:19,708 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) maxrss: 651716
2025-10-12 09:14:19,709 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) minflt: 45204
2025-10-12 09:14:19,709 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) msgrcv: 0
2025-10-12 09:14:19,709 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) msgsnd: 0
2025-10-12 09:14:19,710 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) nivcsw: 40
2025-10-12 09:14:19,710 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) nsignals: 0
2025-10-12 09:14:19,710 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) nswap: 0
2025-10-12 09:14:19,711 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) nvcsw: 15
2025-10-12 09:14:19,711 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) oublock: 8
2025-10-12 09:14:19,712 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) stime: 0.253465
2025-10-12 09:14:19,712 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) utime: 0.557775
2025-10-12 09:14:19,712 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) wtime: 0.864
2025-10-12 09:14:19,713 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) output:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e

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

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

2025-10-12 09:14:19,713 - DEBUG - ya.test - _save_outputs: Command (pid 1289022) errors:

2025-10-12 09:14:19,714 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e
2025-10-12 09:14:25,757 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:25,762 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:25,787 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 544
2025-10-12 09:14:25,792 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:25,793 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 5.485923767089844
2025-10-12 09:14:25,794 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:5151 to local/test_db ydb_access_token is set False
2025-10-12 09:14:25,795 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-12 09:14:25,805 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-12 09:14:25,807 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-12 09:14:25,831 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-12 09:14:25,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 35f98176-90ac-45c0-b584-5a4160c98c4d, localhost:5151): creating call state
2025-10-12 09:14:25,833 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 35f98176-90ac-45c0-b584-5a4160c98c4d, localhost:5151): request = { database: "local/test_db" }
2025-10-12 09:14:25,841 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 35f98176-90ac-45c0-b584-5a4160c98c4d, localhost:5151): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-djbupjqxve.auto.internal" port: 3797 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-10-12 09:14:25,842 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-djbupjqxve.auto.internal:3797, location , ssl: False>]>
2025-10-12 09:14:25,843 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-12 09:14:25,859 - INFO - ydb.connection - close: Closing channel for endpoint localhost:5151
2025-10-12 09:14:25,860 - INFO - root - _create_ydb_driver: Connected
2025-10-12 09:14:25,862 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 96079984-1aab-45e2-90ee-d857143e5fcc, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:25,863 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 96079984-1aab-45e2-90ee-d857143e5fcc, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:25,866 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 96079984-1aab-45e2-90ee-d857143e5fcc, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:26,007 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 5.485923767089844
2025-10-12 09:14:27,017 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:27,021 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:27,034 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-10-12 09:14:27,038 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:27,039 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 6.732287406921387
2025-10-12 09:14:27,041 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 3fa5087c-1206-446c-a89e-ced7f126b46b, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:27,042 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 3fa5087c-1206-446c-a89e-ced7f126b46b, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:27,045 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 3fa5087c-1206-446c-a89e-ced7f126b46b, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:27,120 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 6.732287406921387
2025-10-12 09:14:28,136 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:28,142 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:28,156 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-12 09:14:28,159 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:28,159 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 7.852663516998291
2025-10-12 09:14:28,160 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 17c7e172-cae1-45ff-b812-0b9b93318661, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:28,161 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 17c7e172-cae1-45ff-b812-0b9b93318661, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:28,164 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 17c7e172-cae1-45ff-b812-0b9b93318661, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:28,246 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 7.852663516998291
2025-10-12 09:14:29,251 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:29,261 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:29,282 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-10-12 09:14:29,285 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:29,286 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 8.979355335235596
2025-10-12 09:14:29,288 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 5398bbf0-1149-4635-8c0c-cc30f92c1780, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:29,289 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 5398bbf0-1149-4635-8c0c-cc30f92c1780, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:29,292 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 5398bbf0-1149-4635-8c0c-cc30f92c1780, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:29,417 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 8.979355335235596
2025-10-12 09:14:30,423 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:30,427 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:30,441 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-12 09:14:30,444 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:30,445 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 10.138347387313843
2025-10-12 09:14:30,446 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 7ad60ce8-a17d-4f7c-93f3-f92eb3618c81, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:30,448 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 7ad60ce8-a17d-4f7c-93f3-f92eb3618c81, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:30,450 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 7ad60ce8-a17d-4f7c-93f3-f92eb3618c81, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:30,522 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 10.138347387313843
2025-10-12 09:14:31,528 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:31,533 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:31,544 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-10-12 09:14:31,547 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:31,548 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 11.241297483444214
2025-10-12 09:14:31,549 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, ce147537-ee86-4d49-bbb4-13fa0d417936, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:31,550 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, ce147537-ee86-4d49-bbb4-13fa0d417936, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:31,553 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, ce147537-ee86-4d49-bbb4-13fa0d417936, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:31,628 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 11.241297483444214
2025-10-12 09:14:32,635 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:32,639 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:32,651 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-10-12 09:14:32,653 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:32,653 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 12.346786499023438
2025-10-12 09:14:32,654 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 4c50e685-ffa6-45fd-87a6-ca36dd7a7db5, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:32,655 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 4c50e685-ffa6-45fd-87a6-ca36dd7a7db5, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:32,657 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 4c50e685-ffa6-45fd-87a6-ca36dd7a7db5, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:32,727 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 12.346786499023438
2025-10-12 09:14:33,732 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:33,736 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:33,746 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 548
2025-10-12 09:14:33,749 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:33,750 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 13.443094730377197
2025-10-12 09:14:33,751 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6aa1778b-1b63-49e8-96f7-b25931f868b6, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:33,752 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6aa1778b-1b63-49e8-96f7-b25931f868b6, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:33,754 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6aa1778b-1b63-49e8-96f7-b25931f868b6, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:33,834 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 13.443094730377197
2025-10-12 09:14:34,842 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:34,846 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:34,858 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 550
2025-10-12 09:14:34,861 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:34,861 - ERROR - root - _join_errors: Node ghrun-djbupjqxve.auto.internal too yong: 14.554800271987915
2025-10-12 09:14:34,863 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, daddb28e-af8b-4d9b-85c6-3638fd3f0867, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:34,864 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, daddb28e-af8b-4d9b-85c6-3638fd3f0867, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:34,879 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, daddb28e-af8b-4d9b-85c6-3638fd3f0867, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:34,953 - ERROR - root - _join_errors: Only 0 from 1 dynnodes are ok: Node ghrun-djbupjqxve.auto.internal too yong: 14.554800271987915
2025-10-12 09:14:35,959 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:35,967 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:35,978 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-10-12 09:14:35,981 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-12 09:14:35,982 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 371ab216-6a4b-41c6-9680-22ddfb80911e, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:14:35,983 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 371ab216-6a4b-41c6-9680-22ddfb80911e, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:14:35,986 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 371ab216-6a4b-41c6-9680-22ddfb80911e, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:14:36,063 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:36,075 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 789
2025-10-12 09:14:36,079 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydb/ydb
2025-10-12 09:14:36,081 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1
2025-10-12 09:14:36,083 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to mkdir.out
2025-10-12 09:14:36,084 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to mkdir.err
2025-10-12 09:14:36,085 - DEBUG - ya.test - execute: Executing 'mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1' in 'None' (waiting)
2025-10-12 09:14:36,086 - DEBUG - ya.test - execute: Command pid: 1291070
2025-10-12 09:14:36,188 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-12 09:14:36,189 - DEBUG - ya.test - _save_outputs: Command (pid 1291070) rc: 0
2025-10-12 09:14:36,189 - DEBUG - ya.test - _save_outputs: Command (pid 1291070) elapsed time (sec): 0.10125350952148438
2025-10-12 09:14:36,190 - DEBUG - ya.test - _save_outputs: Command (pid 1291070) wtime: 0.104
2025-10-12 09:14:36,190 - DEBUG - ya.test - _save_outputs: Command (pid 1291070) output:

2025-10-12 09:14:36,190 - DEBUG - ya.test - _save_outputs: Command (pid 1291070) errors:

2025-10-12 09:14:36,191 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1
2025-10-12 09:14:36,193 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to mkdir.out.0
2025-10-12 09:14:36,194 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to mkdir.err.0
2025-10-12 09:14:36,194 - DEBUG - ya.test - execute: Executing 'mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1' in 'None' (waiting)
2025-10-12 09:14:36,196 - DEBUG - ya.test - execute: Command pid: 1291073
2025-10-12 09:14:36,297 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-12 09:14:36,298 - DEBUG - ya.test - _save_outputs: Command (pid 1291073) rc: 0
2025-10-12 09:14:36,299 - DEBUG - ya.test - _save_outputs: Command (pid 1291073) elapsed time (sec): 0.10125589370727539
2025-10-12 09:14:36,299 - DEBUG - ya.test - _save_outputs: Command (pid 1291073) wtime: 0.103
2025-10-12 09:14:36,300 - DEBUG - ya.test - _save_outputs: Command (pid 1291073) output:

2025-10-12 09:14:36,300 - DEBUG - ya.test - _save_outputs: Command (pid 1291073) errors:

2025-10-12 09:14:36,301 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1
2025-10-12 09:14:36,303 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to mkdir.out.1
2025-10-12 09:14:36,304 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to mkdir.err.1
2025-10-12 09:14:36,304 - DEBUG - ya.test - execute: Executing 'mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1' in 'None' (waiting)
2025-10-12 09:14:36,306 - DEBUG - ya.test - execute: Command pid: 1291076
2025-10-12 09:14:36,407 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-12 09:14:36,408 - DEBUG - ya.test - _save_outputs: Command (pid 1291076) rc: 0
2025-10-12 09:14:36,409 - DEBUG - ya.test - _save_outputs: Command (pid 1291076) elapsed time (sec): 0.10140037536621094
2025-10-12 09:14:36,409 - DEBUG - ya.test - _save_outputs: Command (pid 1291076) wtime: 0.103
2025-10-12 09:14:36,409 - DEBUG - ya.test - _save_outputs: Command (pid 1291076) output:

2025-10-12 09:14:36,410 - DEBUG - ya.test - _save_outputs: Command (pid 1291076) errors:

2025-10-12 09:14:36,920 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: chmod +x /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1/ydb
2025-10-12 09:14:36,922 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to chmod.out
2025-10-12 09:14:36,923 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to chmod.err
2025-10-12 09:14:36,924 - DEBUG - ya.test - execute: Executing 'chmod +x /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1/ydb' in 'None' (waiting)
2025-10-12 09:14:36,925 - DEBUG - ya.test - execute: Command pid: 1291079
2025-10-12 09:14:36,933 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) rc: 0
2025-10-12 09:14:36,934 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) elapsed time (sec): 0.006571054458618164
2025-10-12 09:14:36,934 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) idrss: 0
2025-10-12 09:14:36,935 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) inblock: 0
2025-10-12 09:14:36,935 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) isrss: 0
2025-10-12 09:14:36,935 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) ixrss: 0
2025-10-12 09:14:36,936 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) majflt: 0
2025-10-12 09:14:36,936 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) maxrss: 651716
2025-10-12 09:14:36,937 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) minflt: 154
2025-10-12 09:14:36,937 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) msgrcv: 0
2025-10-12 09:14:36,938 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) msgsnd: 0
2025-10-12 09:14:36,938 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) nivcsw: 1
2025-10-12 09:14:36,938 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) nsignals: 0
2025-10-12 09:14:36,939 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) nswap: 0
2025-10-12 09:14:36,939 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) nvcsw: 4
2025-10-12 09:14:36,939 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) oublock: 0
2025-10-12 09:14:36,940 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) stime: 0.0
2025-10-12 09:14:36,940 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) utime: 0.002887
2025-10-12 09:14:36,940 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) wtime: 0.008
2025-10-12 09:14:36,941 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) output:

2025-10-12 09:14:36,941 - DEBUG - ya.test - _save_outputs: Command (pid 1291079) errors:

2025-10-12 09:14:36,943 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1
2025-10-12 09:14:36,945 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to mkdir.out.2
2025-10-12 09:14:36,946 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to mkdir.err.2
2025-10-12 09:14:36,946 - DEBUG - ya.test - execute: Executing 'mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1' in 'None' (waiting)
2025-10-12 09:14:36,948 - DEBUG - ya.test - execute: Command pid: 1291081
2025-10-12 09:14:37,050 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-12 09:14:37,051 - DEBUG - ya.test - _save_outputs: Command (pid 1291081) rc: 0
2025-10-12 09:14:37,051 - DEBUG - ya.test - _save_outputs: Command (pid 1291081) elapsed time (sec): 0.10138249397277832
2025-10-12 09:14:37,051 - DEBUG - ya.test - _save_outputs: Command (pid 1291081) wtime: 0.104
2025-10-12 09:14:37,052 - DEBUG - ya.test - _save_outputs: Command (pid 1291081) output:

2025-10-12 09:14:37,052 - DEBUG - ya.test - _save_outputs: Command (pid 1291081) errors:

2025-10-12 09:14:37,053 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1
2025-10-12 09:14:37,055 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to mkdir.out.3
2025-10-12 09:14:37,057 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to mkdir.err.3
2025-10-12 09:14:37,057 - DEBUG - ya.test - execute: Executing 'mkdir -p /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1 && chmod 777 /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1' in 'None' (waiting)
2025-10-12 09:14:37,059 - DEBUG - ya.test - execute: Command pid: 1291084
2025-10-12 09:14:37,160 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-12 09:14:37,161 - DEBUG - ya.test - _save_outputs: Command (pid 1291084) rc: 0
2025-10-12 09:14:37,162 - DEBUG - ya.test - _save_outputs: Command (pid 1291084) elapsed time (sec): 0.10141539573669434
2025-10-12 09:14:37,162 - DEBUG - ya.test - _save_outputs: Command (pid 1291084) wtime: 0.104
2025-10-12 09:14:37,163 - DEBUG - ya.test - _save_outputs: Command (pid 1291084) output:

2025-10-12 09:14:37,163 - DEBUG - ya.test - _save_outputs: Command (pid 1291084) errors:

2025-10-12 09:14:37,165 - INFO - ydb.tests.olap.lib.remote_execution - _execute_local_command: Detected localhost (ghrun-djbupjqxve.auto.internal), executing command locally: chmod +x /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1/ydb_upload_tpch.sh
2025-10-12 09:14:37,167 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to chmod.out.0
2025-10-12 09:14:37,168 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to chmod.err.0
2025-10-12 09:14:37,168 - DEBUG - ya.test - execute: Executing 'chmod +x /home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1/ydb_upload_tpch.sh' in 'None' (waiting)
2025-10-12 09:14:37,170 - DEBUG - ya.test - execute: Command pid: 1291088
2025-10-12 09:14:37,173 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) rc: 0
2025-10-12 09:14:37,174 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) elapsed time (sec): 0.002075672149658203
2025-10-12 09:14:37,175 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) idrss: 0
2025-10-12 09:14:37,175 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) inblock: 0
2025-10-12 09:14:37,175 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) isrss: 0
2025-10-12 09:14:37,176 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) ixrss: 0
2025-10-12 09:14:37,176 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) majflt: 0
2025-10-12 09:14:37,176 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) maxrss: 652328
2025-10-12 09:14:37,177 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) minflt: 154
2025-10-12 09:14:37,177 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) msgrcv: 0
2025-10-12 09:14:37,177 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) msgsnd: 0
2025-10-12 09:14:37,178 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) nivcsw: 2
2025-10-12 09:14:37,178 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) nsignals: 0
2025-10-12 09:14:37,179 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) nswap: 0
2025-10-12 09:14:37,179 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) nvcsw: 4
2025-10-12 09:14:37,179 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) oublock: 0
2025-10-12 09:14:37,180 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) stime: 0.0016079999999999998
2025-10-12 09:14:37,180 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) utime: 0.001645
2025-10-12 09:14:37,180 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) wtime: 0.004
2025-10-12 09:14:37,181 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) output:

2025-10-12 09:14:37,181 - DEBUG - ya.test - _save_outputs: Command (pid 1291088) errors:

2025-10-12 09:14:37,185 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:37,193 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 799
2025-10-12 09:14:37,200 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:37,204 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:14:37,217 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-10-12 09:14:37,220 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydb/ydb
2025-10-12 09:14:37,222 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-12 09:14:37,222 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-12 09:14:37,223 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5151', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'upload/tpch/s0.1', 'init', '--store=column', '--clear']' in 'None' (waiting)
2025-10-12 09:14:37,225 - DEBUG - ya.test - execute: Command pid: 1291092
2025-10-12 09:14:53,224 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) rc: 0
2025-10-12 09:14:53,224 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) elapsed time (sec): 15.997314691543579
2025-10-12 09:14:53,225 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) idrss: 0
2025-10-12 09:14:53,225 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) inblock: 0
2025-10-12 09:14:53,226 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) isrss: 0
2025-10-12 09:14:53,226 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) ixrss: 0
2025-10-12 09:14:53,226 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) majflt: 0
2025-10-12 09:14:53,227 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) maxrss: 653856
2025-10-12 09:14:53,227 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) minflt: 126279
2025-10-12 09:14:53,227 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) msgrcv: 0
2025-10-12 09:14:53,228 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) msgsnd: 0
2025-10-12 09:14:53,228 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) nivcsw: 126
2025-10-12 09:14:53,228 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) nsignals: 0
2025-10-12 09:14:53,229 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) nswap: 0
2025-10-12 09:14:53,229 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) nvcsw: 10251
2025-10-12 09:14:53,229 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) oublock: 8
2025-10-12 09:14:53,230 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) stime: 0.641783
2025-10-12 09:14:53,230 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) utime: 0.7426729999999999
2025-10-12 09:14:53,230 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) wtime: 16.0
2025-10-12 09:14:53,231 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) output:
Remove path upload/tpch/s0.1/customer...
Remove path upload/tpch/s0.1/customer...Ok
Remove path upload/tpch/s0.1/lineitem...
Remove path upload/tpch/s0.1/lineitem...Ok
Remove path upload/tpch/s0.1/nation...
Remove path upload/tpch/s0.1/nation...Ok
Remove path upload/tpch/s0.1/orders...
Remove path upload/tpch/s0.1/orders...Ok
Remove path upload/tpch/s0.1/part...
Remove path upload/tpch/s0.1/part...Ok
Remove path upload/tpch/s0.1/partsupp...
Remove path upload/tpch/s0.1/partsupp...Ok
Remove path upload/tpch/s0.1/region...
Remove path upload/tpch/s0.1/region...Ok
Remove path upload/tpch/s0.1/supplier...
Remove path upload/tpch/s0.1/supplier...Ok
Init tables ...
Init tables ...Ok

2025-10-12 09:14:53,231 - DEBUG - ya.test - _save_outputs: Command (pid 1291092) errors:

2025-10-12 09:14:53,236 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:14:53,248 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 793
2025-10-12 09:14:53,255 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-djbupjqxve.auto.internal:64783
2025-10-12 09:14:53,430 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-djbupjqxve.auto.internal:64783 "GET /counters/counters=tablets/json HTTP/1.1" 200 79107
2025-10-12 09:14:54,107 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-djbupjqxve.auto.internal:1621
2025-10-12 09:14:54,422 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-djbupjqxve.auto.internal:1621 "GET /counters/counters=tablets/json HTTP/1.1" 200 95096
2025-10-12 09:14:55,593 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb_upload_tpch.sh.out
2025-10-12 09:14:55,594 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb_upload_tpch.sh.err
2025-10-12 09:14:55,594 - DEBUG - ya.test - execute: Executing '/home/runner/.ya/build/build_root/vx9u/0019a0/r3tmp/scripts/upload/tpch/s0.1/ydb_upload_tpch.sh' in 'None' (no wait)
2025-10-12 09:14:55,596 - DEBUG - ya.test - execute: Command pid: 1294584
2025-10-12 09:15:27,587 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) rc: 0
2025-10-12 09:15:27,587 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) elapsed time (sec): 31.98960781097412
2025-10-12 09:15:27,587 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) idrss: 0
2025-10-12 09:15:27,588 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) inblock: 2880
2025-10-12 09:15:27,588 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) isrss: 0
2025-10-12 09:15:27,588 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) ixrss: 0
2025-10-12 09:15:27,589 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) majflt: 5
2025-10-12 09:15:27,589 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) maxrss: 1282716
2025-10-12 09:15:27,589 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) minflt: 751137
2025-10-12 09:15:27,589 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) msgrcv: 0
2025-10-12 09:15:27,589 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) msgsnd: 0
2025-10-12 09:15:27,590 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) nivcsw: 2820
2025-10-12 09:15:27,590 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) nsignals: 0
2025-10-12 09:15:27,590 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) nswap: 0
2025-10-12 09:15:27,597 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) nvcsw: 71407
2025-10-12 09:15:27,598 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) oublock: 64
2025-10-12 09:15:27,598 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) stime: 4.730002
2025-10-12 09:15:27,598 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) utime: 30.810799
2025-10-12 09:15:27,598 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) wtime: 31.992
2025-10-12 09:15:27,599 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) output:
Fill table supplier...
Fill table supplier OK 1000 / 1000 (12.154759s)
Fill table customer...
Fill table customer OK 15000 / 15000 (0.991938s)
Fill table orders...
Fill table orders OK 150000 / 150000 (14.293737s)
Fill table part...
Fill table part OK 20000 / 20000 (2.597827s)
Fill table nation...
Fill table nation OK 25 / 25 (0.184137s)
Fill table region...
Fill table region OK 5 / 5 (0.213952s)

2025-10-12 09:15:27,599 - DEBUG - ya.test - _save_outputs: Command (pid 1294584) errors:







2025-10-12 09:15:27,600 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:27,600 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:27,601 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 4cfb3b7b-85df-4336-9e58-41e01698f927, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:27,601 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 4cfb3b7b-85df-4336-9e58-41e01698f927, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:27,620 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 4cfb3b7b-85df-4336-9e58-41e01698f927, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:15:27,621 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:27,621 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 4d559176-1851-487d-94c3-adb6a3aa2f56, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:27,622 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 4d559176-1851-487d-94c3-adb6a3aa2f56, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:27,632 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 4d559176-1851-487d-94c3-adb6a3aa2f56, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:15:27,635 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, bf0a7395-825b-47e4-9137-2a45fd73e1c4, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:27,636 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, bf0a7395-825b-47e4-9137-2a45fd73e1c4, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:27,638 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, bf0a7395-825b-47e4-9137-2a45fd73e1c4, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:27,639 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:30,018 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 16c11ad5-a960-4bec-8672-457c2d4d3e34, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:30,020 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 16c11ad5-a960-4bec-8672-457c2d4d3e34, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:30,027 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 16c11ad5-a960-4bec-8672-457c2d4d3e34, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:30,028 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:32,925 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:32,926 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:32,927 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, cf3c29ad-bced-4866-9a69-851028025dbe, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:32,928 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, cf3c29ad-bced-4866-9a69-851028025dbe, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:32,937 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, cf3c29ad-bced-4866-9a69-851028025dbe, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:15:32,939 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:32,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 00940239-9a41-441d-b0b5-df03848ced81, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:32,941 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 00940239-9a41-441d-b0b5-df03848ced81, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:32,956 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 00940239-9a41-441d-b0b5-df03848ced81, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:15:32,961 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 1b65c308-5326-44fe-86a5-dc8e8787e680, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:32,963 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 1b65c308-5326-44fe-86a5-dc8e8787e680, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:32,966 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 1b65c308-5326-44fe-86a5-dc8e8787e680, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:32,967 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:34,790 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 67ce21c5-f4ce-46e2-be78-4007c24973b2, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:34,791 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 67ce21c5-f4ce-46e2-be78-4007c24973b2, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:34,794 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 67ce21c5-f4ce-46e2-be78-4007c24973b2, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:34,794 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:37,551 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:37,551 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:37,552 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 87424cf1-d73b-44f2-96ae-c07959118f7f, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:37,553 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 87424cf1-d73b-44f2-96ae-c07959118f7f, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:37,568 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 87424cf1-d73b-44f2-96ae-c07959118f7f, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:15:37,570 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:37,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 97bbc015-74da-4961-bbfa-77999e95a87f, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:37,572 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 97bbc015-74da-4961-bbfa-77999e95a87f, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:37,580 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 97bbc015-74da-4961-bbfa-77999e95a87f, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:15:37,589 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 47a726b5-fe36-4e80-a31e-18ecd68ee56a, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:37,590 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 47a726b5-fe36-4e80-a31e-18ecd68ee56a, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:37,593 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 47a726b5-fe36-4e80-a31e-18ecd68ee56a, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:37,594 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:39,227 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 5d93537e-c73a-4b92-baf0-890c30de52f0, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:39,228 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 5d93537e-c73a-4b92-baf0-890c30de52f0, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:39,230 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 5d93537e-c73a-4b92-baf0-890c30de52f0, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:39,231 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:41,996 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:41,997 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:41,997 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 98f0f1e2-9551-45cc-85b5-6ec8e93a94d9, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:41,998 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 98f0f1e2-9551-45cc-85b5-6ec8e93a94d9, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:42,005 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 98f0f1e2-9551-45cc-85b5-6ec8e93a94d9, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:15:42,007 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:42,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 528eb244-60ab-4c01-94b1-b4f4a95bf286, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:42,008 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 528eb244-60ab-4c01-94b1-b4f4a95bf286, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:42,016 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 528eb244-60ab-4c01-94b1-b4f4a95bf286, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:15:42,019 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, ab884885-14c0-4b89-9af3-5a31dec025a7, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:42,020 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, ab884885-14c0-4b89-9af3-5a31dec025a7, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:42,023 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, ab884885-14c0-4b89-9af3-5a31dec025a7, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:42,023 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:43,713 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2596ae71-9fe3-4eac-8819-412b15bfab3f, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:43,714 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2596ae71-9fe3-4eac-8819-412b15bfab3f, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:43,717 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2596ae71-9fe3-4eac-8819-412b15bfab3f, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:43,717 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:46,417 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:46,418 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:46,419 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e3c5ca96-8cbe-40e0-9636-aebbdda15596, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:46,420 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e3c5ca96-8cbe-40e0-9636-aebbdda15596, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:46,429 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e3c5ca96-8cbe-40e0-9636-aebbdda15596, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:15:46,430 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:15:46,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 2863be27-cbc2-47d6-98bf-9337329f0af8, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:46,432 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 2863be27-cbc2-47d6-98bf-9337329f0af8, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:15:46,458 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 2863be27-cbc2-47d6-98bf-9337329f0af8, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:15:46,461 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 99c96b91-4d07-47b1-b1f0-edb4e3f2eaf6, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:46,461 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 99c96b91-4d07-47b1-b1f0-edb4e3f2eaf6, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:46,464 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 99c96b91-4d07-47b1-b1f0-edb4e3f2eaf6, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:46,465 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:48,478 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 800bf35f-04f5-4ea5-994f-4f0814f0ad0a, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:48,479 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 800bf35f-04f5-4ea5-994f-4f0814f0ad0a, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:48,483 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 800bf35f-04f5-4ea5-994f-4f0814f0ad0a, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:48,483 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:50,192 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, c87f63bf-7260-4aad-9620-9bc777722671, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:50,193 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, c87f63bf-7260-4aad-9620-9bc777722671, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/nation/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:50,196 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, c87f63bf-7260-4aad-9620-9bc777722671, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:50,197 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:51,790 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 26a0ebbb-f30b-4bf5-a366-0a5e77289f95, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:51,791 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 26a0ebbb-f30b-4bf5-a366-0a5e77289f95, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/orders/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:51,793 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 26a0ebbb-f30b-4bf5-a366-0a5e77289f95, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:51,794 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:53,502 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, ff75d2a2-d2c1-4610-bb6e-dfa72a6318a2, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:53,504 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, ff75d2a2-d2c1-4610-bb6e-dfa72a6318a2, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/part/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:53,508 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, ff75d2a2-d2c1-4610-bb6e-dfa72a6318a2, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:53,509 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:54,737 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-12 09:15:54,738 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-12 09:15:54,749 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-12 09:15:54,750 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fabcc266-183d-4fad-8408-dc5e30bfb81a, localhost:5151): creating call state
2025-10-12 09:15:54,751 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fabcc266-183d-4fad-8408-dc5e30bfb81a, localhost:5151): request = { database: "local/test_db" }
2025-10-12 09:15:54,757 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fabcc266-183d-4fad-8408-dc5e30bfb81a, localhost:5151): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-djbupjqxve.auto.internal" port: 3797 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-10-12 09:15:54,758 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-djbupjqxve.auto.internal:3797, location , ssl: False>]>
2025-10-12 09:15:54,759 - INFO - ydb.connection - close: Closing channel for endpoint localhost:5151
2025-10-12 09:15:55,337 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, e1c3f02c-3f24-4fd9-ac19-6097e406e59f, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:55,338 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, e1c3f02c-3f24-4fd9-ac19-6097e406e59f, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/partsupp/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:55,343 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, e1c3f02c-3f24-4fd9-ac19-6097e406e59f, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:55,343 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:57,053 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 99607234-34b3-47d1-936e-be369ee5295d, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:57,054 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 99607234-34b3-47d1-936e-be369ee5295d, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/region/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:57,063 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 99607234-34b3-47d1-936e-be369ee5295d, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:57,064 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:15:58,534 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 5192aa96-927c-4a5c-af56-f34c69dffc94, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:15:58,536 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 5192aa96-927c-4a5c-af56-f34c69dffc94, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "\n            SELECT COUNT(*)\n            FROM `/local/test_db/upload/tpch/s0.1/supplier/.sys/primary_index_optimizer_stats`\n            WHERE CAST(JSON_VALUE(CAST(Details AS JsonDocument), \"$.weight\") AS Uint64) > 0\n        " } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:15:58,540 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 5192aa96-927c-4a5c-af56-f34c69dffc94, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:15:58,541 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:00,572 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:00,596 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 786
2025-10-12 09:16:00,664 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-djbupjqxve.auto.internal:64783
2025-10-12 09:16:00,920 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-djbupjqxve.auto.internal:64783 "GET /counters/counters=tablets/json HTTP/1.1" 200 79197
2025-10-12 09:16:01,703 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): ghrun-djbupjqxve.auto.internal:1621
2025-10-12 09:16:02,373 - DEBUG - urllib3.connectionpool - _make_request: http://ghrun-djbupjqxve.auto.internal:1621 "GET /counters/counters=tablets/json HTTP/1.1" 200 106443
2025-10-12 09:16:04,196 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:04,197 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:04,198 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 064c63a9-278d-4b3a-886d-beef9eeb9907, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:04,199 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 064c63a9-278d-4b3a-886d-beef9eeb9907, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:04,208 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 064c63a9-278d-4b3a-886d-beef9eeb9907, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:04,209 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:04,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, a1ff15e8-9982-4673-b203-c801768ddf37, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:04,211 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, a1ff15e8-9982-4673-b203-c801768ddf37, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:04,226 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, a1ff15e8-9982-4673-b203-c801768ddf37, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:04,231 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, d5634bf2-263c-436e-b8de-515a26deb356, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:04,232 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, d5634bf2-263c-436e-b8de-515a26deb356, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/customer/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:04,236 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, d5634bf2-263c-436e-b8de-515a26deb356, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:04,236 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:06,351 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 513d996c-0a70-4a0e-8b64-d5cd33435ae5, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:06,352 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 513d996c-0a70-4a0e-8b64-d5cd33435ae5, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/lineitem/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:06,362 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 513d996c-0a70-4a0e-8b64-d5cd33435ae5, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:06,363 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:09,116 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, a107f7c3-30ba-4bf4-9d18-60deac5f0c20, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:09,118 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, a107f7c3-30ba-4bf4-9d18-60deac5f0c20, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/nation/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:09,129 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, a107f7c3-30ba-4bf4-9d18-60deac5f0c20, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:09,130 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:09,751 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 5da1bd5c-1605-46c8-bb38-b49e260d4261, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:09,752 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 5da1bd5c-1605-46c8-bb38-b49e260d4261, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/orders/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:09,774 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 5da1bd5c-1605-46c8-bb38-b49e260d4261, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:09,775 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:11,995 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 67fd8647-b282-4ba0-9c9c-420dec7132ed, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:11,996 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 67fd8647-b282-4ba0-9c9c-420dec7132ed, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/part/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:12,015 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 67fd8647-b282-4ba0-9c9c-420dec7132ed, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:12,016 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:13,666 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 64178803-5da9-4cf2-9859-6e64c5dc2a46, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:13,668 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 64178803-5da9-4cf2-9859-6e64c5dc2a46, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/partsupp/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:13,675 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 64178803-5da9-4cf2-9859-6e64c5dc2a46, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:13,676 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:15,009 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, f6718646-625a-4006-85cb-d81845bbdbb3, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:15,010 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, f6718646-625a-4006-85cb-d81845bbdbb3, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/region/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:15,013 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, f6718646-625a-4006-85cb-d81845bbdbb3, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:15,013 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:15,586 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2c861180-fbad-4456-849a-6ea31b0d0564, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:15,587 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2c861180-fbad-4456-849a-6ea31b0d0564, ghrun-djbupjqxve.auto.internal:3797): request = { query { yql_text: "SELECT * FROM `/local/test_db/upload/tpch/s0.1/supplier/.sys/primary_index_stats` WHERE Activity == 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-12 09:16:15,599 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2c861180-fbad-4456-849a-6ea31b0d0564, ghrun-djbupjqxve.auto.internal:3797): response = { <_MultiThreadedRendezvous object> }
2025-10-12 09:16:15,600 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-12 09:16:17,217 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:17,218 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:17,218 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, acfc2711-c27c-4b6b-98f0-8c7d26a24a5a, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:17,219 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, acfc2711-c27c-4b6b-98f0-8c7d26a24a5a, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:17,235 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, acfc2711-c27c-4b6b-98f0-8c7d26a24a5a, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:17,237 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:17,238 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, e4eeaf53-c39f-4977-b694-1d233520e583, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:17,239 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, e4eeaf53-c39f-4977-b694-1d233520e583, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:17,249 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, e4eeaf53-c39f-4977-b694-1d233520e583, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:17,252 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f3ff4a80-5e7c-407b-9f6b-a9eecff3e3bb, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:17,253 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f3ff4a80-5e7c-407b-9f6b-a9eecff3e3bb, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:17,262 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f3ff4a80-5e7c-407b-9f6b-a9eecff3e3bb, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZDIwYzA5ZTUtNTMxYzJkMGEtZjA1NmExNjQtMTQyYjBmOTM=" } } } }
2025-10-12 09:16:17,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 8ab6bbe3-b434-4a3c-aa98-25621166d8d2, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:17,264 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 8ab6bbe3-b434-4a3c-aa98-25621166d8d2, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=ZDIwYzA5ZTUtNTMxYzJkMGEtZjA1NmExNjQtMTQyYjBmOTM=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/customer`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:18,246 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 8ab6bbe3-b434-4a3c-aa98-25621166d8d2, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'696) \'(\'\"_id\" \'\"5612872-8d7a7d3e-e56187a8-339932ab\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/customer\" \'\"72075186224037897:9\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"c_custkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"c_custkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1394) \'(\'\"_id\" \'\"58e6a94d-18669640-3525599c-8815addf\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/customer\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"E-Rows\":\"0\",\"Table\":\"upload\\/tpch\\/s0.1\\/customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"reads\":[{\"columns\":[\"c_custkey\"],\"scan_by\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"E-Rows\":\"0\",\"Table\":\"customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:19,249 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:19,250 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:19,250 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, da1021f5-9063-4b1a-9fca-30269ab1f16b, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:19,252 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, da1021f5-9063-4b1a-9fca-30269ab1f16b, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:19,260 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, da1021f5-9063-4b1a-9fca-30269ab1f16b, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:19,261 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:19,262 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 3cad89f0-ea1d-42e6-a38f-a088903441c0, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:19,263 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 3cad89f0-ea1d-42e6-a38f-a088903441c0, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:19,272 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 3cad89f0-ea1d-42e6-a38f-a088903441c0, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:19,275 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 226c2fc7-0c9d-4de5-a8e6-9ac9f0ec56f4, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:19,276 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 226c2fc7-0c9d-4de5-a8e6-9ac9f0ec56f4, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:19,286 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 226c2fc7-0c9d-4de5-a8e6-9ac9f0ec56f4, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=MzFkNjIzODgtZTcyYTFkNjYtNDUxNGU5NjYtOTRkYjcwMDQ=" } } } }
2025-10-12 09:16:19,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 64101ced-3a6e-47eb-8025-d9bbd137f6e3, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:19,289 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 64101ced-3a6e-47eb-8025-d9bbd137f6e3, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=MzFkNjIzODgtZTcyYTFkNjYtNDUxNGU5NjYtOTRkYjcwMDQ=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/customer`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:20,024 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 64101ced-3a6e-47eb-8025-d9bbd137f6e3, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'696) \'(\'\"_id\" \'\"8f964d40-10b85d52-74d46b68-e1430089\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/customer\" \'\"72075186224037897:9\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"c_custkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"c_custkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1394) \'(\'\"_id\" \'\"cc86fe7e-549fb38d-640f1067-d6814f60\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/customer\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"E-Rows\":\"0\",\"Table\":\"upload\\/tpch\\/s0.1\\/customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"reads\":[{\"columns\":[\"c_custkey\"],\"scan_by\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"E-Rows\":\"0\",\"Table\":\"customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:21,027 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:21,028 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:21,029 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 96c30a59-b250-4a8f-9911-e621d754d2c3, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:21,030 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 96c30a59-b250-4a8f-9911-e621d754d2c3, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:21,044 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 96c30a59-b250-4a8f-9911-e621d754d2c3, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:21,045 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:21,046 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 44f6dbc1-ed1e-445a-b0fd-3803d210bad9, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:21,050 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 44f6dbc1-ed1e-445a-b0fd-3803d210bad9, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:21,063 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 44f6dbc1-ed1e-445a-b0fd-3803d210bad9, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:21,066 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2df2e3f8-c999-4824-b8e9-3422abf9a08b, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:21,067 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2df2e3f8-c999-4824-b8e9-3422abf9a08b, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:21,078 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2df2e3f8-c999-4824-b8e9-3422abf9a08b, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YzM5YzZkMjQtNWFmODVmZS05MDhiMTBhZi1iMDliMzUzOQ==" } } } }
2025-10-12 09:16:21,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, ba7a5d4f-861d-46ed-a50f-018166cd878c, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:21,081 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, ba7a5d4f-861d-46ed-a50f-018166cd878c, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=YzM5YzZkMjQtNWFmODVmZS05MDhiMTBhZi1iMDliMzUzOQ==" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/customer`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:21,970 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, ba7a5d4f-861d-46ed-a50f-018166cd878c, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'696) \'(\'\"_id\" \'\"fd0a6574-398fadd4-37694f6f-da56d484\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/customer\" \'\"72075186224037897:9\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"c_custkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"c_custkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1394) \'(\'\"_id\" \'\"f7cac708-b4622e2c-f6e1522a-65010c1b\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/customer\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"E-Rows\":\"0\",\"Table\":\"upload\\/tpch\\/s0.1\\/customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"reads\":[{\"columns\":[\"c_custkey\"],\"scan_by\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"E-Rows\":\"0\",\"Table\":\"customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:22,980 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:22,980 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:22,981 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 98ba6657-09d3-4306-b49f-a592a447ea88, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:22,982 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 98ba6657-09d3-4306-b49f-a592a447ea88, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:22,991 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 98ba6657-09d3-4306-b49f-a592a447ea88, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:22,993 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:22,993 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, caefeaf3-4a5d-4f3d-a2ee-96a7a816dbd6, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:22,994 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, caefeaf3-4a5d-4f3d-a2ee-96a7a816dbd6, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:23,003 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, caefeaf3-4a5d-4f3d-a2ee-96a7a816dbd6, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:23,005 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 324a0489-9393-4221-b7e1-93e86c7fc4fa, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:23,006 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 324a0489-9393-4221-b7e1-93e86c7fc4fa, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:23,013 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 324a0489-9393-4221-b7e1-93e86c7fc4fa, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YWQ3YTFiZDEtYTY4YWIzMzgtNDc5MmM0MS0xYjMwMmM2Yw==" } } } }
2025-10-12 09:16:23,015 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, a5c7eb45-d027-481d-a04a-8cd92d6adf94, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:23,016 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, a5c7eb45-d027-481d-a04a-8cd92d6adf94, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=YWQ3YTFiZDEtYTY4YWIzMzgtNDc5MmM0MS0xYjMwMmM2Yw==" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/customer`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:23,883 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, a5c7eb45-d027-481d-a04a-8cd92d6adf94, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'696) \'(\'\"_id\" \'\"6a5d3dfd-c08e0265-6d13e54c-c831dfb6\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/customer\" \'\"72075186224037897:9\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"c_custkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"c_custkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1394) \'(\'\"_id\" \'\"ed0153d-bfb14a52-7e1da412-274ccaff\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/customer\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"E-Rows\":\"0\",\"Table\":\"upload\\/tpch\\/s0.1\\/customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"reads\":[{\"columns\":[\"c_custkey\"],\"scan_by\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"0\",\"Name\":\"TableFullScan\",\"E-Rows\":\"0\",\"Table\":\"customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:24,903 - INFO - root - get_tables: get_tables /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:24,904 - INFO - root - _describe_path_impl: describe /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:24,905 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 7df12dc0-c989-42bd-9c01-c4b3d565d181, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:24,906 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 7df12dc0-c989-42bd-9c01-c4b3d565d181, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:24,915 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 7df12dc0-c989-42bd-9c01-c4b3d565d181, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } } } } }
2025-10-12 09:16:24,917 - INFO - root - list_directory: list /local/test_db/upload/tpch/s0.1
2025-10-12 09:16:24,918 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 68cda4c6-3ec0-4c46-9b14-c0c4d53f1485, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:24,919 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 68cda4c6-3ec0-4c46-9b14-c0c4d53f1485, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/upload/tpch/s0.1" }
2025-10-12 09:16:24,929 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 68cda4c6-3ec0-4c46-9b14-c0c4d53f1485, ghrun-djbupjqxve.auto.internal:3797): 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: 1760260480810 tx_id: 281474976720679 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260480810 tx_id: 281474976720679 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483050 tx_id: 281474976720680 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260483430 tx_id: 281474976720681 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260485370 tx_id: 281474976720682 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260487610 tx_id: 281474976720683 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490040 tx_id: 281474976720684 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260490440 tx_id: 281474976720685 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760260492620 tx_id: 281474976720686 } } } } } }
2025-10-12 09:16:24,933 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 826556a1-90cb-434e-bac6-4a4e1aaaef5d, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:24,934 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 826556a1-90cb-434e-bac6-4a4e1aaaef5d, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:24,942 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 826556a1-90cb-434e-bac6-4a4e1aaaef5d, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NTE0YjFhMDQtZDQwNzVkYTItOGY2NTliYzItNjliN2FhYTY=" } } } }
2025-10-12 09:16:24,944 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 261dca29-b5c5-4e96-918c-d402c449f0c7, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:24,944 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 261dca29-b5c5-4e96-918c-d402c449f0c7, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=NTE0YjFhMDQtZDQwNzVkYTItOGY2NTliYzItNjliN2FhYTY=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/customer`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:25,890 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 261dca29-b5c5-4e96-918c-d402c449f0c7, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'696) \'(\'\"_id\" \'\"87103122-238fd657-b5392c44-451af51\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/customer\" \'\"72075186224037897:9\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"c_custkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"c_custkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1394) \'(\'\"_id\" \'\"6827a546-575a8b5d-35c2d04b-bd8f673e\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/customer\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"220833\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"E-Rows\":\"15000\",\"Table\":\"upload\\/tpch\\/s0.1\\/customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/customer\",\"reads\":[{\"columns\":[\"c_custkey\"],\"scan_by\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"220833\",\"Name\":\"TableFullScan\",\"E-Rows\":\"15000\",\"Table\":\"customer\",\"ReadColumns\":[\"c_custkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":4}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:25,897 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2befa6b5-3bac-4474-ad82-54509c5556fe, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:25,898 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2befa6b5-3bac-4474-ad82-54509c5556fe, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:25,910 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2befa6b5-3bac-4474-ad82-54509c5556fe, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=N2E2ZGNmZDItMmYwOGRhYzAtZWExYzU0NGQtN2ZiOWYyMTQ=" } } } }
2025-10-12 09:16:25,911 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 94faacb2-db0b-4a34-a374-2f3b0452b9df, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:25,912 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 94faacb2-db0b-4a34-a374-2f3b0452b9df, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=N2E2ZGNmZDItMmYwOGRhYzAtZWExYzU0NGQtN2ZiOWYyMTQ=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/lineitem`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:26,756 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 94faacb2-db0b-4a34-a374-2f3b0452b9df, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'770) \'(\'\"_id\" \'\"6ad1b3da-2784f69f-6a278921-f143f2d3\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/lineitem\" \'\"72075186224037897:10\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"l_orderkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"l_orderkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1468) \'(\'\"_id\" \'\"ce5f8ff0-c30baea4-a83dd23d-d7dd6e96\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/lineitem\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"2553630.5\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/lineitem\",\"E-Rows\":\"600572\",\"Table\":\"upload\\/tpch\\/s0.1\\/lineitem\",\"ReadColumns\":[\"l_orderkey (-\342\210\236, +\342\210\236)\",\"l_linenumber (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":7}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/lineitem\",\"reads\":[{\"columns\":[\"l_orderkey\"],\"scan_by\":[\"l_orderkey (-\342\210\236, +\342\210\236)\",\"l_linenumber (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"2553630.5\",\"Name\":\"TableFullScan\",\"E-Rows\":\"600572\",\"Table\":\"lineitem\",\"ReadColumns\":[\"l_orderkey (-\342\210\236, +\342\210\236)\",\"l_linenumber (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":7}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:26,760 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4e97c23f-61a5-480b-a527-7016736cb752, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:26,761 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4e97c23f-61a5-480b-a527-7016736cb752, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:26,769 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4e97c23f-61a5-480b-a527-7016736cb752, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NDJhMzViMmUtMzhkZjk1N2MtOTcwOWYwZDMtYTdmNjU2ZWQ=" } } } }
2025-10-12 09:16:26,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 87c5353d-f9f8-4e30-848a-d649ab0db1cb, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:26,772 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 87c5353d-f9f8-4e30-848a-d649ab0db1cb, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=NDJhMzViMmUtMzhkZjk1N2MtOTcwOWYwZDMtYTdmNjU2ZWQ=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/nation`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:27,784 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 87c5353d-f9f8-4e30-848a-d649ab0db1cb, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'656) \'(\'\"_id\" \'\"1fe3952c-821fccb2-f34965a4-89d78e30\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/nation\" \'\"72075186224037897:11\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"n_nationkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"n_nationkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1354) \'(\'\"_id\" \'\"86ef5ca8-6e882c8d-6751f182-df5dfcd\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/nation\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"606\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/nation\",\"E-Rows\":\"25\",\"Table\":\"upload\\/tpch\\/s0.1\\/nation\",\"ReadColumns\":[\"n_nationkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/nation\",\"reads\":[{\"columns\":[\"n_nationkey\"],\"scan_by\":[\"n_nationkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"606\",\"Name\":\"TableFullScan\",\"E-Rows\":\"25\",\"Table\":\"nation\",\"ReadColumns\":[\"n_nationkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:27,788 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4716a28f-fd50-4c6e-85ab-4241a40a0d21, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:27,789 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4716a28f-fd50-4c6e-85ab-4241a40a0d21, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:27,797 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4716a28f-fd50-4c6e-85ab-4241a40a0d21, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YWRjYmZkMC0yMWI1Y2M2Mi00MTNlOTAwOC0yYWI0ZDZkYg==" } } } }
2025-10-12 09:16:27,798 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 20a20ae4-16ca-4ce4-a302-dc8491bbe0b2, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:27,799 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 20a20ae4-16ca-4ce4-a302-dc8491bbe0b2, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=YWRjYmZkMC0yMWI1Y2M2Mi00MTNlOTAwOC0yYWI0ZDZkYg==" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/orders`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:28,606 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 20a20ae4-16ca-4ce4-a302-dc8491bbe0b2, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'707) \'(\'\"_id\" \'\"6b6036de-c9ef0c64-b0568925-7ec84105\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/orders\" \'\"72075186224037897:12\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"o_orderkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"o_orderkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1405) \'(\'\"_id\" \'\"256492f5-c8e52f3f-9af9c69f-6222850b\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/orders\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"1034795.556\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/orders\",\"E-Rows\":\"150000\",\"Table\":\"upload\\/tpch\\/s0.1\\/orders\",\"ReadColumns\":[\"o_orderkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":5}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/orders\",\"reads\":[{\"columns\":[\"o_orderkey\"],\"scan_by\":[\"o_orderkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"1034795.556\",\"Name\":\"TableFullScan\",\"E-Rows\":\"150000\",\"Table\":\"orders\",\"ReadColumns\":[\"o_orderkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":5}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:28,609 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 496f4d1c-d74e-4727-a9e8-b9ca53e31a07, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:28,619 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 496f4d1c-d74e-4727-a9e8-b9ca53e31a07, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:28,629 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 496f4d1c-d74e-4727-a9e8-b9ca53e31a07, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZjkyMTlhYWYtZjZiOTY3YjQtZDg1ZmY5YWYtYjk1MjA5MmY=" } } } }
2025-10-12 09:16:28,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 09dd14f3-697e-4a69-8408-173292ad21ab, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:28,631 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 09dd14f3-697e-4a69-8408-173292ad21ab, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=ZjkyMTlhYWYtZjZiOTY3YjQtZDg1ZmY5YWYtYjk1MjA5MmY=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/part`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:29,410 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 09dd14f3-697e-4a69-8408-173292ad21ab, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'705) \'(\'\"_id\" \'\"dc7996b4-cef26fb4-9ecb16e1-91f151ff\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/part\" \'\"72075186224037897:13\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"p_partkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"p_partkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1403) \'(\'\"_id\" \'\"9f34fc51-79855f68-573bdfc7-19003172\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/part\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"185404.4444\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/part\",\"E-Rows\":\"20000\",\"Table\":\"upload\\/tpch\\/s0.1\\/part\",\"ReadColumns\":[\"p_partkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":6}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/part\",\"reads\":[{\"columns\":[\"p_partkey\"],\"scan_by\":[\"p_partkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"185404.4444\",\"Name\":\"TableFullScan\",\"E-Rows\":\"20000\",\"Table\":\"part\",\"ReadColumns\":[\"p_partkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":6}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:29,452 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6aebe686-6ed0-4ac5-90be-3462335649a7, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:29,452 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6aebe686-6ed0-4ac5-90be-3462335649a7, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:29,460 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6aebe686-6ed0-4ac5-90be-3462335649a7, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NDU0YzBhZTktNWM2YzYxM2ItZTI5ZTBmMGEtODM2NzZlZTU=" } } } }
2025-10-12 09:16:29,461 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, bb2ee000-ec3c-4e9f-827d-8539598e7cc2, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:29,462 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, bb2ee000-ec3c-4e9f-827d-8539598e7cc2, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=NDU0YzBhZTktNWM2YzYxM2ItZTI5ZTBmMGEtODM2NzZlZTU=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/partsupp`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:30,256 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, bb2ee000-ec3c-4e9f-827d-8539598e7cc2, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'669) \'(\'\"_id\" \'\"3e5f8b12-1a85d68b-403976f8-163dc902\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/partsupp\" \'\"72075186224037897:14\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"ps_partkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"ps_partkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1367) \'(\'\"_id\" \'\"77ee77f4-85d6dfac-2abc56b0-504149cc\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/partsupp\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"1064321.6\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/partsupp\",\"E-Rows\":\"80000\",\"Table\":\"upload\\/tpch\\/s0.1\\/partsupp\",\"ReadColumns\":[\"ps_partkey (-\342\210\236, +\342\210\236)\",\"ps_suppkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/partsupp\",\"reads\":[{\"columns\":[\"ps_partkey\"],\"scan_by\":[\"ps_partkey (-\342\210\236, +\342\210\236)\",\"ps_suppkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"1064321.6\",\"Name\":\"TableFullScan\",\"E-Rows\":\"80000\",\"Table\":\"partsupp\",\"ReadColumns\":[\"ps_partkey (-\342\210\236, +\342\210\236)\",\"ps_suppkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:30,258 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 29738fb3-c0e6-4f41-aa8d-99b63917eb15, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:30,259 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 29738fb3-c0e6-4f41-aa8d-99b63917eb15, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:30,268 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 29738fb3-c0e6-4f41-aa8d-99b63917eb15, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=MzRhNTcyYWQtNTEzZjc4MWEtNjc2ZDNiOWEtNDZmMWY3YmI=" } } } }
2025-10-12 09:16:30,269 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, 105f4c56-f881-465c-a919-d9376b63541d, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:30,270 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, 105f4c56-f881-465c-a919-d9376b63541d, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=MzRhNTcyYWQtNTEzZjc4MWEtNjc2ZDNiOWEtNDZmMWY3YmI=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/region`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:30,991 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, 105f4c56-f881-465c-a919-d9376b63541d, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'647) \'(\'\"_id\" \'\"b9591cb4-d50134aa-d45f3028-6ac28f98\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/region\" \'\"72075186224037897:15\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"r_regionkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"r_regionkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1345) \'(\'\"_id\" \'\"87459750-3c09cb91-2488e9b5-151f44f7\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/region\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"336\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/region\",\"E-Rows\":\"5\",\"Table\":\"upload\\/tpch\\/s0.1\\/region\",\"ReadColumns\":[\"r_regionkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/region\",\"reads\":[{\"columns\":[\"r_regionkey\"],\"scan_by\":[\"r_regionkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"336\",\"Name\":\"TableFullScan\",\"E-Rows\":\"5\",\"Table\":\"region\",\"ReadColumns\":[\"r_regionkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":3}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:30,994 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 564aa741-a935-4fd1-a719-b5f6a77c48fb, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:30,995 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 564aa741-a935-4fd1-a719-b5f6a77c48fb, ghrun-djbupjqxve.auto.internal:3797): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:31,003 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 564aa741-a935-4fd1-a719-b5f6a77c48fb, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YjNkNDRlNmItODI3NmIxODYtNzcyYjRiMDctM2U5NDlkZWM=" } } } }
2025-10-12 09:16:31,004 - DEBUG - ydb.connection - _prepare_call: RpcState(ExplainDataQuery, e9967266-d674-42d1-a9e3-1c94eacd2514, ghrun-djbupjqxve.auto.internal:3797): creating call state
2025-10-12 09:16:31,007 - DEBUG - ydb.connection - _log_request: RpcState(ExplainDataQuery, e9967266-d674-42d1-a9e3-1c94eacd2514, ghrun-djbupjqxve.auto.internal:3797): request = { session_id: "ydb://session/3?node_id=50000&id=YjNkNDRlNmItODI3NmIxODYtNzcyYjRiMDctM2U5NDlkZWM=" yql_text: "SELECT COUNT(*) FROM `/local/test_db/upload/tpch/s0.1/supplier`" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-12 09:16:31,809 - DEBUG - ydb.connection - _log_response: RpcState(ExplainDataQuery, e9967266-d674-42d1-a9e3-1c94eacd2514, ghrun-djbupjqxve.auto.internal:3797): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExplainQueryResult] { query_ast: "(\n(let $1 (Uint64 \'1))\n(let $2 (Bool \'false))\n(let $3 (lambda \'($20) $20))\n(let $4 (DataType \'Uint64))\n(let $5 \'(\'(\'\"_logical_id\" \'687) \'(\'\"_id\" \'\"331bfd86-cc9b1e02-50bf6296-f868ea2c\") \'(\'\"_wide_channels\" (StructType \'(\'_yql_agg_0 $4)))))\n(let $6 (DqPhyStage \'() (lambda \'() (block \'(\n  (let $10 (KqpTable \'\"/local/test_db/upload/tpch/s0.1/supplier\" \'\"72075186224037897:16\" \'\"\" \'1))\n  (let $11 (KqpBlockReadOlapTableRanges $10 (Void) \'(\'\"s_suppkey\") \'() \'() (lambda \'($13) $13)))\n  (let $12 (Condense1 (NarrowMap (ToFlow (WideFromBlocks (FromFlow $11))) (lambda \'($14) (AsStruct \'(\'\"s_suppkey\" $14)))) (lambda \'($15) $1) (lambda \'($16 $17) $2) (lambda \'($18 $19) (Inc $19))))\n  (return (FromFlow (ExpandMap $12 $3)))\n))) $5))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'0)))\n(let $8 (DqPhyStage \'($7) (lambda \'($21) (block \'(\n  (let $22 (WideCondense1 (ToFlow $21) $3 (lambda \'($24 $25) $2) (lambda \'($26 $27) (AggrAdd $26 $27))))\n  (let $23 (Condense (NarrowMap (Take $22 $1) (lambda \'($28) (AsStruct \'(\'Count0 $28)))) (Nothing (OptionalType (StructType \'(\'Count0 $4)))) (lambda \'($29 $30) $2) (lambda \'($31 $32) (Just $31))))\n  (return (FromFlow (Map $23 (lambda \'($33) (AsStruct \'(\'\"column0\" (Coalesce (Member $33 \'Count0) (Uint64 \'0))))))))\n))) \'(\'(\'\"_logical_id\" \'1385) \'(\'\"_id\" \'\"886e6d2-447919ca-8b030812-c0164166\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'0) \'(\'\"column0\")))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"data\")))) \'((KqpTxResultBinding (ListType (StructType \'(\'\"column0\" $4))) \'0 \'0)) \'(\'(\'\"type\" \'\"data_query\"))))\n)\n" query_plan: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"Tables\":[\"upload\\/tpch\\/s0.1\\/supplier\"],\"PlanNodeId\":1,\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"},{\"Scan\":\"Parallel\",\"E-Size\":\"31089.14286\",\"Name\":\"TableFullScan\",\"Inputs\":[],\"Path\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/supplier\",\"E-Rows\":\"1000\",\"Table\":\"upload\\/tpch\\/s0.1\\/supplier\",\"ReadColumns\":[\"s_suppkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":7}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"Aggregate-TableFullScan\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Operators\":[{\"Inputs\":[{\"InternalOperatorId\":1}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"},{\"Inputs\":[{\"InternalOperatorId\":2}],\"Name\":\"Limit\",\"Limit\":\"1\"},{\"Inputs\":[{\"ExternalPlanNodeId\":2}],\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate-Limit-Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"Stats\":{\"ResourcePoolId\":\"default\"},\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"},\"tables\":[{\"name\":\"\\/local\\/test_db\\/upload\\/tpch\\/s0.1\\/supplier\",\"reads\":[{\"columns\":[\"s_suppkey\"],\"scan_by\":[\"s_suppkey (-\342\210\236, +\342\210\236)\"],\"type\":\"FullScan\"}]}],\"SimplifiedPlan\":{\"PlanNodeId\":0,\"Plans\":[{\"PlanNodeId\":1,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":6,\"Plans\":[{\"PlanNodeId\":7,\"Operators\":[{\"E-Size\":\"31089.14286\",\"Name\":\"TableFullScan\",\"E-Rows\":\"1000\",\"Table\":\"supplier\",\"ReadColumns\":[\"s_suppkey (-\342\210\236, +\342\210\236)\"],\"SsaProgram\":{\"Command\":[{\"Projection\":{\"Columns\":[{\"Id\":7}]}}]},\"E-Cost\":\"0\"}],\"Node Type\":\"TableFullScan\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Intermediate\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Operators\":[{\"Name\":\"Limit\",\"Limit\":\"1\"}],\"Node Type\":\"Limit\"}],\"Operators\":[{\"Name\":\"Aggregate\",\"Phase\":\"Final\"}],\"Node Type\":\"Aggregate\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"OptimizerStats\":{\"EquiJoinsCount\":0,\"JoinsCount\":0},\"PlanNodeType\":\"Query\"}}" } } } }
2025-10-12 09:16:31,816 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:31,831 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 801
2025-10-12 09:16:31,842 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:31,847 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:16:31,862 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-12 09:16:31,877 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:31,882 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:16:31,894 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-12 09:16:32,925 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:32,939 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 791
2025-10-12 09:16:32,946 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:32,956 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:16:32,969 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 549
2025-10-12 09:16:32,972 - INFO - root - __create_iterations_table: All nodes before filtering: [('11507@ghrun-djbupjqxve.auto.internal', <Role.UNKNOWN: 0>)]
2025-10-12 09:16:32,973 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-10-12 09:16:32,973 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-djbupjqxve.auto.internal', 1)]
2025-10-12 09:16:32,974 - INFO - root - allure_test_description: iterations_table created, length: 515
2025-10-12 09:16:32,974 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-10-12 09:16:33,471 - DEBUG - matplotlib - wrapper: CACHEDIR=/home/runner/.cache/matplotlib
2025-10-12 09:16:33,476 - DEBUG - matplotlib.font_manager - _load_fontmanager: Using fontManager instance from /home/runner/.cache/matplotlib/fontlist-v330.json
2025-10-12 09:16:34,890 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:16:34,891 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/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/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/node_1/logfile_kwha520d.log --grpc-port=5151 --mon-port=64783 --ic-port=1168
2025-10-12 09:16:34,892 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5151/1
2025-10-12 09:16:34,894 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-12 09:16:34,895 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-12 09:16:34,895 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-12 09:16:34,897 - DEBUG - ya.test - execute: Command pid: 1319116
2025-10-12 09:16:35,727 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) rc: 0
2025-10-12 09:16:35,727 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) elapsed time (sec): 0.8284416198730469
2025-10-12 09:16:35,728 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) idrss: 0
2025-10-12 09:16:35,728 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) inblock: 0
2025-10-12 09:16:35,728 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) isrss: 0
2025-10-12 09:16:35,729 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) ixrss: 0
2025-10-12 09:16:35,729 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) majflt: 0
2025-10-12 09:16:35,729 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) maxrss: 919928
2025-10-12 09:16:35,729 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) minflt: 45263
2025-10-12 09:16:35,730 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) msgrcv: 0
2025-10-12 09:16:35,730 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) msgsnd: 0
2025-10-12 09:16:35,730 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) nivcsw: 61
2025-10-12 09:16:35,731 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) nsignals: 0
2025-10-12 09:16:35,732 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) nswap: 0
2025-10-12 09:16:35,732 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) nvcsw: 15
2025-10-12 09:16:35,732 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) oublock: 8
2025-10-12 09:16:35,732 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) stime: 0.19394999999999998
2025-10-12 09:16:35,733 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) utime: 0.577773
2025-10-12 09:16:35,733 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) wtime: 0.831
2025-10-12 09:16:35,733 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) output:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e

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

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

2025-10-12 09:16:35,734 - DEBUG - ya.test - _save_outputs: Command (pid 1319116) errors:

2025-10-12 09:16:35,735 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e
2025-10-12 09:16:35,736 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/kikimr_configs"
2025-10-12 09:16:35,736 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:5151 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/tests/functional/tpc/medium/test-results/py3test/testing_out_stuff/test_upload/testing_out_stuff/test_upload.py.TestUploadTpchS0_1.test/cluster/slot_1/logfile_sn4gbjfk.log --grpc-port=3797 --mon-port=1621 --ic-port=11507
2025-10-12 09:16:35,737 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3797/1
2025-10-12 09:16:35,738 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-12 09:16:35,740 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-12 09:16:35,740 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vx9u/0019a0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-12 09:16:35,744 - DEBUG - ya.test - execute: Command pid: 1319394
2025-10-12 09:16:36,886 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) rc: 0
2025-10-12 09:16:36,887 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) elapsed time (sec): 1.1414854526519775
2025-10-12 09:16:36,887 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) idrss: 0
2025-10-12 09:16:36,887 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) inblock: 0
2025-10-12 09:16:36,888 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) isrss: 0
2025-10-12 09:16:36,888 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) ixrss: 0
2025-10-12 09:16:36,888 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) majflt: 0
2025-10-12 09:16:36,889 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) maxrss: 919928
2025-10-12 09:16:36,889 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) minflt: 45279
2025-10-12 09:16:36,890 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) msgrcv: 0
2025-10-12 09:16:36,890 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) msgsnd: 0
2025-10-12 09:16:36,891 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) nivcsw: 135
2025-10-12 09:16:36,892 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) nsignals: 0
2025-10-12 09:16:36,892 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) nswap: 0
2025-10-12 09:16:36,892 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) nvcsw: 14
2025-10-12 09:16:36,893 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) oublock: 8
2025-10-12 09:16:36,893 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) stime: 0.263256
2025-10-12 09:16:36,893 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) utime: 0.534915
2025-10-12 09:16:36,894 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) wtime: 1.145
2025-10-12 09:16:36,894 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) output:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e

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

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

2025-10-12 09:16:36,894 - DEBUG - ya.test - _save_outputs: Command (pid 1319394) errors:

2025-10-12 09:16:36,896 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 49d54271a3964c44bfebfb4a67c1057e0f2de23f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge c06212ce98a66bc059783f8475c1a8d006f6b363 into 69f8739e8d96b4f4ccafc0fef1d8a193078e8c1e
2025-10-12 09:16:36,914 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_upload.py::TestUploadTpchS0_1, test_name: test)
2025-10-12 09:16:36,922 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:36,936 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes HTTP/1.1" 200 793
2025-10-12 09:16:36,946 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:64783
2025-10-12 09:16:36,954 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-12 09:16:36,980 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:64783 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 553
2025-10-12 09:16:36,984 - INFO - root - __create_iterations_table: All nodes before filtering: [('11507@ghrun-djbupjqxve.auto.internal', <Role.UNKNOWN: 0>)]
2025-10-12 09:16:36,984 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-10-12 09:16:36,985 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-djbupjqxve.auto.internal', 1)]
2025-10-12 09:16:36,985 - INFO - root - allure_test_description: iterations_table created, length: 514
2025-10-12 09:16:36,986 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-10-12 09:16:36,992 - INFO - ya.test - pytest_runtest_teardown: Test teardown
