2025-10-16 17:44:54,547 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:44:54,548 - INFO - ya.test - pytest_runtest_setup: test_tpch[1]
2025-10-16 17:44:54,548 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:44:54,548 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:44:54,637 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd
2025-10-16 17:44:54,639 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24266
2025-10-16 17:44:54,640 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/b9t0/0021c9/r3tmp/kikimr_cluster_e12fnqnd
2025-10-16 17:44:54,684 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd
2025-10-16 17:44:54,685 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17091
2025-10-16 17:44:54,686 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17879
2025-10-16 17:44:54,686 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31735
2025-10-16 17:44:54,687 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10287
2025-10-16 17:44:54,687 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:44:54,688 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/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/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/node_1/logfile_ts6ghq71.log --grpc-port=17091 --mon-port=17879 --ic-port=24266
2025-10-16 17:44:54,688 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17091/1, disk_size 68719476736
2025-10-16 17:44:54,689 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:44:54,689 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/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/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/node_1/logfile_ts6ghq71.log --grpc-port=17091 --mon-port=17879 --ic-port=24266
2025-10-16 17:44:54,690 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/node_1/logfile_ts6ghq71.log', '--grpc-port=17091', '--mon-port=17879', '--ic-port=24266')' in '/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/node_1' (no wait)
2025-10-16 17:44:54,693 - DEBUG - ya.test - execute: Command pid: 1309773
2025-10-16 17:44:54,694 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17091/1
2025-10-16 17:44:54,696 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 17:44:54,697 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 17:44:54,697 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:44:54,698 - DEBUG - ya.test - execute: Command pid: 1309774
2025-10-16 17:44:55,281 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) rc: 0
2025-10-16 17:44:55,282 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) elapsed time (sec): 0.5816473960876465
2025-10-16 17:44:55,282 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) idrss: 0
2025-10-16 17:44:55,282 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) inblock: 0
2025-10-16 17:44:55,282 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) isrss: 0
2025-10-16 17:44:55,283 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) ixrss: 0
2025-10-16 17:44:55,283 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) majflt: 0
2025-10-16 17:44:55,283 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) maxrss: 573320
2025-10-16 17:44:55,283 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) minflt: 45076
2025-10-16 17:44:55,284 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) msgrcv: 0
2025-10-16 17:44:55,284 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) msgsnd: 0
2025-10-16 17:44:55,284 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) nivcsw: 1
2025-10-16 17:44:55,285 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) nsignals: 0
2025-10-16 17:44:55,285 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) nswap: 0
2025-10-16 17:44:55,285 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) nvcsw: 15
2025-10-16 17:44:55,285 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) oublock: 8
2025-10-16 17:44:55,286 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) stime: 0.186502
2025-10-16 17:44:55,286 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) utime: 0.350672
2025-10-16 17:44:55,286 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) wtime: 0.583
2025-10-16 17:44:55,287 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:44:55,287 - DEBUG - ya.test - _save_outputs: Command (pid 1309774) errors:

2025-10-16 17:44:55,288 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:44:57,582 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd
2025-10-16 17:44:57,582 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17091', '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: 24266\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 17:44:57,583 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 17:44:57,584 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 17:44:57,584 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17091', '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: 24266\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:44:57,585 - DEBUG - ya.test - execute: Command pid: 1310535
2025-10-16 17:44:58,241 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) rc: 0
2025-10-16 17:44:58,242 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) elapsed time (sec): 0.6549980640411377
2025-10-16 17:44:58,242 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) idrss: 0
2025-10-16 17:44:58,242 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) inblock: 0
2025-10-16 17:44:58,243 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) isrss: 0
2025-10-16 17:44:58,243 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) ixrss: 0
2025-10-16 17:44:58,243 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) majflt: 0
2025-10-16 17:44:58,243 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) maxrss: 648952
2025-10-16 17:44:58,244 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) minflt: 51264
2025-10-16 17:44:58,244 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) msgrcv: 0
2025-10-16 17:44:58,244 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) msgsnd: 0
2025-10-16 17:44:58,245 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) nivcsw: 3
2025-10-16 17:44:58,245 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) nsignals: 0
2025-10-16 17:44:58,245 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) nswap: 0
2025-10-16 17:44:58,245 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) nvcsw: 736
2025-10-16 17:44:58,246 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) oublock: 8
2025-10-16 17:44:58,246 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) stime: 0.250918
2025-10-16 17:44:58,246 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) utime: 0.37804699999999997
2025-10-16 17:44:58,247 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) wtime: 0.656
2025-10-16 17:44:58,247 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 17:44:58,247 - DEBUG - ya.test - _save_outputs: Command (pid 1310535) errors:

2025-10-16 17:44:58,249 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd
2025-10-16 17:44:58,249 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17091', '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-16 17:44:58,251 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 17:44:58,251 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 17:44:58,252 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17091', '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-16 17:44:58,254 - DEBUG - ya.test - execute: Command pid: 1310673
2025-10-16 17:44:58,898 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) rc: 0
2025-10-16 17:44:58,898 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) elapsed time (sec): 0.6435153484344482
2025-10-16 17:44:58,899 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) idrss: 0
2025-10-16 17:44:58,899 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) inblock: 0
2025-10-16 17:44:58,899 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) isrss: 0
2025-10-16 17:44:58,899 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) ixrss: 0
2025-10-16 17:44:58,900 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) majflt: 0
2025-10-16 17:44:58,900 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) maxrss: 649068
2025-10-16 17:44:58,900 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) minflt: 51386
2025-10-16 17:44:58,900 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) msgrcv: 0
2025-10-16 17:44:58,900 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) msgsnd: 0
2025-10-16 17:44:58,901 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) nivcsw: 1
2025-10-16 17:44:58,901 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) nsignals: 0
2025-10-16 17:44:58,901 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) nswap: 0
2025-10-16 17:44:58,901 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) nvcsw: 699
2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) oublock: 8
2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) stime: 0.208789
2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) utime: 0.408995
2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) wtime: 0.645
2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-16 17:44:58,902 - DEBUG - ya.test - _save_outputs: Command (pid 1310673) errors:

2025-10-16 17:44:58,903 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 17:44:58,942 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 17:44:59,493 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd
2025-10-16 17:44:59,494 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16912
2025-10-16 17:44:59,494 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17448
2025-10-16 17:44:59,495 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4714
2025-10-16 17:44:59,495 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5578
2025-10-16 17:44:59,496 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14707
2025-10-16 17:44:59,496 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:44:59,497 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17091 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/slot_1/logfile_s3vw3q5x.log --grpc-port=16912 --mon-port=17448 --ic-port=4714
2025-10-16 17:44:59,497 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:44:59,498 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17091 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/slot_1/logfile_s3vw3q5x.log --grpc-port=16912 --mon-port=17448 --ic-port=4714
2025-10-16 17:44:59,499 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:17091', '--tenant=/local/test_db', '--log-file-name=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/slot_1/logfile_s3vw3q5x.log', '--grpc-port=16912', '--mon-port=17448', '--ic-port=4714')' in '/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/slot_1' (no wait)
2025-10-16 17:44:59,501 - DEBUG - ya.test - execute: Command pid: 1311102
2025-10-16 17:44:59,502 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16912/1
2025-10-16 17:44:59,503 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 17:44:59,504 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 17:44:59,504 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:44:59,506 - DEBUG - ya.test - execute: Command pid: 1311103
2025-10-16 17:45:00,141 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) rc: 0
2025-10-16 17:45:00,141 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) elapsed time (sec): 0.6341948509216309
2025-10-16 17:45:00,141 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) idrss: 0
2025-10-16 17:45:00,142 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) inblock: 0
2025-10-16 17:45:00,142 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) isrss: 0
2025-10-16 17:45:00,142 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) ixrss: 0
2025-10-16 17:45:00,142 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) majflt: 0
2025-10-16 17:45:00,143 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) maxrss: 661952
2025-10-16 17:45:00,143 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) minflt: 45196
2025-10-16 17:45:00,143 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) msgrcv: 0
2025-10-16 17:45:00,143 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) msgsnd: 0
2025-10-16 17:45:00,144 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) nivcsw: 0
2025-10-16 17:45:00,144 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) nsignals: 0
2025-10-16 17:45:00,144 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) nswap: 0
2025-10-16 17:45:00,145 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) nvcsw: 15
2025-10-16 17:45:00,145 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) oublock: 8
2025-10-16 17:45:00,145 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) stime: 0.17868599999999998
2025-10-16 17:45:00,145 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) utime: 0.41098199999999996
2025-10-16 17:45:00,146 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) wtime: 0.636
2025-10-16 17:45:00,146 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:45:00,146 - DEBUG - ya.test - _save_outputs: Command (pid 1311103) errors:

2025-10-16 17:45:00,147 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:45:02,162 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:02,163 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-16 17:45:02,164 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-16 17:45:02,164 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'init', '--store=column', '--datetime-types=dt64']' in 'None' (waiting)
2025-10-16 17:45:02,166 - DEBUG - ya.test - execute: Command pid: 1312452
2025-10-16 17:45:15,305 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) rc: 0
2025-10-16 17:45:15,306 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) elapsed time (sec): 13.13818359375
2025-10-16 17:45:15,306 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) idrss: 0
2025-10-16 17:45:15,307 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) inblock: 0
2025-10-16 17:45:15,307 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) isrss: 0
2025-10-16 17:45:15,307 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) ixrss: 0
2025-10-16 17:45:15,308 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) majflt: 0
2025-10-16 17:45:15,308 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) maxrss: 662764
2025-10-16 17:45:15,308 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) minflt: 109366
2025-10-16 17:45:15,308 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) msgrcv: 0
2025-10-16 17:45:15,309 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) msgsnd: 0
2025-10-16 17:45:15,309 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) nivcsw: 9
2025-10-16 17:45:15,309 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) nsignals: 0
2025-10-16 17:45:15,310 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) nswap: 0
2025-10-16 17:45:15,310 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) nvcsw: 4058
2025-10-16 17:45:15,310 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) oublock: 8
2025-10-16 17:45:15,310 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) stime: 0.36857
2025-10-16 17:45:15,311 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) utime: 0.332495
2025-10-16 17:45:15,311 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) wtime: 13.14
2025-10-16 17:45:15,312 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) output:
Init tables ...
Init tables ...Ok

2025-10-16 17:45:15,312 - DEBUG - ya.test - _save_outputs: Command (pid 1312452) errors:

2025-10-16 17:45:15,313 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:15,314 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-16 17:45:15,315 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-16 17:45:15,315 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/customer` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:15,318 - DEBUG - ya.test - execute: Command pid: 1315473
2025-10-16 17:45:16,011 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) rc: 0
2025-10-16 17:45:16,011 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) elapsed time (sec): 0.6919136047363281
2025-10-16 17:45:16,011 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) idrss: 0
2025-10-16 17:45:16,012 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) inblock: 0
2025-10-16 17:45:16,012 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) isrss: 0
2025-10-16 17:45:16,012 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) ixrss: 0
2025-10-16 17:45:16,012 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) majflt: 0
2025-10-16 17:45:16,013 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) maxrss: 663320
2025-10-16 17:45:16,013 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) minflt: 103189
2025-10-16 17:45:16,013 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) msgrcv: 0
2025-10-16 17:45:16,013 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) msgsnd: 0
2025-10-16 17:45:16,014 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) nivcsw: 11
2025-10-16 17:45:16,014 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) nsignals: 0
2025-10-16 17:45:16,014 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) nswap: 0
2025-10-16 17:45:16,014 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) nvcsw: 2608
2025-10-16 17:45:16,015 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) oublock: 0
2025-10-16 17:45:16,015 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) stime: 0.24551599999999998
2025-10-16 17:45:16,015 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) utime: 0.326222
2025-10-16 17:45:16,015 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) wtime: 0.694
2025-10-16 17:45:16,016 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) output:

2025-10-16 17:45:16,016 - DEBUG - ya.test - _save_outputs: Command (pid 1315473) errors:

2025-10-16 17:45:16,016 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:16,017 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-16 17:45:16,018 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-16 17:45:16,018 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/nation` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:16,020 - DEBUG - ya.test - execute: Command pid: 1315758
2025-10-16 17:45:16,629 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) rc: 0
2025-10-16 17:45:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) elapsed time (sec): 0.6082918643951416
2025-10-16 17:45:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) idrss: 0
2025-10-16 17:45:16,630 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) inblock: 0
2025-10-16 17:45:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) isrss: 0
2025-10-16 17:45:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) ixrss: 0
2025-10-16 17:45:16,631 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) majflt: 0
2025-10-16 17:45:16,632 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) maxrss: 663812
2025-10-16 17:45:16,632 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) minflt: 103318
2025-10-16 17:45:16,632 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) msgrcv: 0
2025-10-16 17:45:16,633 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) msgsnd: 0
2025-10-16 17:45:16,633 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) nivcsw: 7
2025-10-16 17:45:16,633 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) nsignals: 0
2025-10-16 17:45:16,633 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) nswap: 0
2025-10-16 17:45:16,634 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) nvcsw: 2634
2025-10-16 17:45:16,634 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) oublock: 0
2025-10-16 17:45:16,634 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) stime: 0.23127999999999999
2025-10-16 17:45:16,635 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) utime: 0.315168
2025-10-16 17:45:16,635 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) wtime: 0.61
2025-10-16 17:45:16,635 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) output:

2025-10-16 17:45:16,636 - DEBUG - ya.test - _save_outputs: Command (pid 1315758) errors:

2025-10-16 17:45:16,636 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:16,638 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-10-16 17:45:16,639 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-16 17:45:16,639 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/orders` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:16,641 - DEBUG - ya.test - execute: Command pid: 1316026
2025-10-16 17:45:17,379 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) rc: 0
2025-10-16 17:45:17,380 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) elapsed time (sec): 0.7371625900268555
2025-10-16 17:45:17,380 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) idrss: 0
2025-10-16 17:45:17,381 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) inblock: 0
2025-10-16 17:45:17,381 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) isrss: 0
2025-10-16 17:45:17,381 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) ixrss: 0
2025-10-16 17:45:17,382 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) majflt: 0
2025-10-16 17:45:17,382 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) maxrss: 664276
2025-10-16 17:45:17,382 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) minflt: 103382
2025-10-16 17:45:17,383 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) msgrcv: 0
2025-10-16 17:45:17,383 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) msgsnd: 0
2025-10-16 17:45:17,383 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) nivcsw: 9
2025-10-16 17:45:17,383 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) nsignals: 0
2025-10-16 17:45:17,384 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) nswap: 0
2025-10-16 17:45:17,384 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) nvcsw: 2709
2025-10-16 17:45:17,384 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) oublock: 0
2025-10-16 17:45:17,385 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) stime: 0.274545
2025-10-16 17:45:17,385 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) utime: 0.30194299999999996
2025-10-16 17:45:17,385 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) wtime: 0.739
2025-10-16 17:45:17,386 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) output:

2025-10-16 17:45:17,386 - DEBUG - ya.test - _save_outputs: Command (pid 1316026) errors:

2025-10-16 17:45:17,387 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:17,388 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-10-16 17:45:17,389 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-16 17:45:17,390 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/part` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:17,392 - DEBUG - ya.test - execute: Command pid: 1316153
2025-10-16 17:45:18,032 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) rc: 0
2025-10-16 17:45:18,032 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) elapsed time (sec): 0.6388356685638428
2025-10-16 17:45:18,033 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) idrss: 0
2025-10-16 17:45:18,033 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) inblock: 0
2025-10-16 17:45:18,033 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) isrss: 0
2025-10-16 17:45:18,033 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) ixrss: 0
2025-10-16 17:45:18,034 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) majflt: 0
2025-10-16 17:45:18,034 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) maxrss: 664792
2025-10-16 17:45:18,034 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) minflt: 103228
2025-10-16 17:45:18,035 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) msgrcv: 0
2025-10-16 17:45:18,035 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) msgsnd: 0
2025-10-16 17:45:18,035 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) nivcsw: 6
2025-10-16 17:45:18,035 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) nsignals: 0
2025-10-16 17:45:18,036 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) nswap: 0
2025-10-16 17:45:18,036 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) nvcsw: 2722
2025-10-16 17:45:18,036 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) oublock: 0
2025-10-16 17:45:18,037 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) stime: 0.229741
2025-10-16 17:45:18,037 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) utime: 0.315401
2025-10-16 17:45:18,037 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) wtime: 0.641
2025-10-16 17:45:18,038 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) output:

2025-10-16 17:45:18,038 - DEBUG - ya.test - _save_outputs: Command (pid 1316153) errors:

2025-10-16 17:45:18,039 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:18,040 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-10-16 17:45:18,041 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-16 17:45:18,041 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/partsupp` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:18,043 - DEBUG - ya.test - execute: Command pid: 1316284
2025-10-16 17:45:18,690 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) rc: 0
2025-10-16 17:45:18,691 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) elapsed time (sec): 0.646327018737793
2025-10-16 17:45:18,691 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) idrss: 0
2025-10-16 17:45:18,691 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) inblock: 0
2025-10-16 17:45:18,692 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) isrss: 0
2025-10-16 17:45:18,692 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) ixrss: 0
2025-10-16 17:45:18,692 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) majflt: 0
2025-10-16 17:45:18,693 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) maxrss: 665340
2025-10-16 17:45:18,693 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) minflt: 103302
2025-10-16 17:45:18,693 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) msgrcv: 0
2025-10-16 17:45:18,694 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) msgsnd: 0
2025-10-16 17:45:18,694 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) nivcsw: 4
2025-10-16 17:45:18,694 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) nsignals: 0
2025-10-16 17:45:18,695 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) nswap: 0
2025-10-16 17:45:18,695 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) nvcsw: 2573
2025-10-16 17:45:18,696 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) oublock: 0
2025-10-16 17:45:18,697 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) stime: 0.271187
2025-10-16 17:45:18,697 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) utime: 0.27813299999999996
2025-10-16 17:45:18,697 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) wtime: 0.648
2025-10-16 17:45:18,698 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) output:

2025-10-16 17:45:18,698 - DEBUG - ya.test - _save_outputs: Command (pid 1316284) errors:

2025-10-16 17:45:18,699 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:18,701 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-10-16 17:45:18,702 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-10-16 17:45:18,702 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/region` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:18,704 - DEBUG - ya.test - execute: Command pid: 1316571
2025-10-16 17:45:19,274 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) rc: 0
2025-10-16 17:45:19,275 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) elapsed time (sec): 0.5690040588378906
2025-10-16 17:45:19,275 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) idrss: 0
2025-10-16 17:45:19,275 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) inblock: 0
2025-10-16 17:45:19,276 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) isrss: 0
2025-10-16 17:45:19,276 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) ixrss: 0
2025-10-16 17:45:19,276 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) majflt: 0
2025-10-16 17:45:19,277 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) maxrss: 665964
2025-10-16 17:45:19,277 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) minflt: 103281
2025-10-16 17:45:19,277 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) msgrcv: 0
2025-10-16 17:45:19,278 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) msgsnd: 0
2025-10-16 17:45:19,278 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) nivcsw: 6
2025-10-16 17:45:19,278 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) nsignals: 0
2025-10-16 17:45:19,279 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) nswap: 0
2025-10-16 17:45:19,279 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) nvcsw: 2637
2025-10-16 17:45:19,279 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) oublock: 0
2025-10-16 17:45:19,280 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) stime: 0.24628999999999998
2025-10-16 17:45:19,280 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) utime: 0.31559
2025-10-16 17:45:19,280 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) wtime: 0.571
2025-10-16 17:45:19,281 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) output:

2025-10-16 17:45:19,281 - DEBUG - ya.test - _save_outputs: Command (pid 1316571) errors:

2025-10-16 17:45:19,282 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:19,284 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-10-16 17:45:19,285 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-10-16 17:45:19,285 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'table', 'query', 'execute', '-t', 'scheme', '-q', '\n            ALTER OBJECT `/local/test_db/olap_yatests/tpch/s1/supplier` (TYPE TABLE)\n                SET (ACTION=UPSERT_OPTIONS, `COMPACTION_PLANNER.CLASS_NAME`=`lc-buckets`, `COMPACTION_PLANNER.FEATURES`=`\n                    {"levels" : [{"class_name" : "Zero", "expected_blobs_size" : 2048000},\n                                    {"class_name" : "Zero"}]}`);\n        ']' in 'None' (waiting)
2025-10-16 17:45:19,287 - DEBUG - ya.test - execute: Command pid: 1316680
2025-10-16 17:45:19,949 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) rc: 0
2025-10-16 17:45:19,949 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) elapsed time (sec): 0.6605868339538574
2025-10-16 17:45:19,950 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) idrss: 0
2025-10-16 17:45:19,950 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) inblock: 0
2025-10-16 17:45:19,950 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) isrss: 0
2025-10-16 17:45:19,951 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) ixrss: 0
2025-10-16 17:45:19,951 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) majflt: 0
2025-10-16 17:45:19,951 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) maxrss: 666540
2025-10-16 17:45:19,952 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) minflt: 103370
2025-10-16 17:45:19,952 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) msgrcv: 0
2025-10-16 17:45:19,952 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) msgsnd: 0
2025-10-16 17:45:19,953 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) nivcsw: 5
2025-10-16 17:45:19,953 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) nsignals: 0
2025-10-16 17:45:19,954 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) nswap: 0
2025-10-16 17:45:19,954 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) nvcsw: 2704
2025-10-16 17:45:19,954 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) oublock: 0
2025-10-16 17:45:19,955 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) stime: 0.222082
2025-10-16 17:45:19,955 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) utime: 0.30961299999999997
2025-10-16 17:45:19,955 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) wtime: 0.663
2025-10-16 17:45:19,956 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) output:

2025-10-16 17:45:19,956 - DEBUG - ya.test - _save_outputs: Command (pid 1316680) errors:

2025-10-16 17:45:19,957 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:45:19,958 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-10-16 17:45:19,960 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-10-16 17:45:19,960 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'import', 'generator', '--scale=1']' in 'None' (waiting)
2025-10-16 17:45:19,962 - DEBUG - ya.test - execute: Command pid: 1316810
2025-10-16 17:48:34,586 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) rc: 0
2025-10-16 17:48:34,587 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) elapsed time (sec): 194.62290716171265
2025-10-16 17:48:34,587 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) idrss: 0
2025-10-16 17:48:34,588 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) inblock: 0
2025-10-16 17:48:34,588 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) isrss: 0
2025-10-16 17:48:34,588 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) ixrss: 0
2025-10-16 17:48:34,588 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) majflt: 0
2025-10-16 17:48:34,589 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) maxrss: 2832904
2025-10-16 17:48:34,589 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) minflt: 5359227
2025-10-16 17:48:34,589 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) msgrcv: 0
2025-10-16 17:48:34,590 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) msgsnd: 0
2025-10-16 17:48:34,590 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) nivcsw: 9615
2025-10-16 17:48:34,590 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) nsignals: 0
2025-10-16 17:48:34,591 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) nswap: 0
2025-10-16 17:48:34,591 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) nvcsw: 836946
2025-10-16 17:48:34,591 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) oublock: 32
2025-10-16 17:48:34,592 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) stime: 38.568708
2025-10-16 17:48:34,592 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) utime: 209.212372
2025-10-16 17:48:34,592 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) wtime: 194.625
2025-10-16 17:48:34,593 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) output:
Fill table supplier...
Fill table supplier OK 10000 / 10000 (10.376914s)
Fill table customer...
Fill table customer OK 150000 / 150000 (3.962633s)
Fill table orders...
Fill table orders OK 1500000 / 1500000 (162.245341s)
Fill table part...
Fill table part OK 200000 / 200000 (16.578393s)
Fill table nation...
Fill table nation OK 25 / 25 (0.166079s)
Fill table region...
Fill table region OK 5 / 5 (0.163838s)

2025-10-16 17:48:34,593 - DEBUG - ya.test - _save_outputs: Command (pid 1316810) errors:







2025-10-16 17:48:34,594 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-10-16 17:48:34,595 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:48:34,596 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-10-16 17:48:34,597 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-10-16 17:48:34,598 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'workload', 'tpch', '-p', 'olap_yatests/tpch/s1', 'import', 'generator', '--scale=1']' in 'None' (waiting)
2025-10-16 17:48:34,599 - DEBUG - ya.test - execute: Command pid: 1341839
2025-10-16 17:52:22,420 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) rc: 0
2025-10-16 17:52:22,421 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) elapsed time (sec): 227.8192367553711
2025-10-16 17:52:22,422 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) idrss: 0
2025-10-16 17:52:22,422 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) inblock: 16
2025-10-16 17:52:22,423 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) isrss: 0
2025-10-16 17:52:22,423 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) ixrss: 0
2025-10-16 17:52:22,423 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) majflt: 0
2025-10-16 17:52:22,424 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) maxrss: 2916744
2025-10-16 17:52:22,424 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) minflt: 5544830
2025-10-16 17:52:22,424 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) msgrcv: 0
2025-10-16 17:52:22,425 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) msgsnd: 0
2025-10-16 17:52:22,425 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) nivcsw: 13437
2025-10-16 17:52:22,425 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) nsignals: 0
2025-10-16 17:52:22,426 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) nswap: 0
2025-10-16 17:52:22,426 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) nvcsw: 740531
2025-10-16 17:52:22,426 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) oublock: 32
2025-10-16 17:52:22,427 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) stime: 38.483357
2025-10-16 17:52:22,427 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) utime: 212.268805
2025-10-16 17:52:22,427 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) wtime: 227.821
2025-10-16 17:52:22,428 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) output:
Fill table supplier...
Fill table supplier OK 10000 / 10000 (11.521051s)
Fill table customer...
Fill table customer OK 150000 / 150000 (4.581044s)
Fill table orders...
Fill table orders OK 1500000 / 1500000 (181.790178s)
Fill table part...
Fill table part OK 200000 / 200000 (28.356439s)
Fill table nation...
Fill table nation OK 25 / 25 (0.183930s)
Fill table region...
Fill table region OK 5 / 5 (0.193351s)

2025-10-16 17:52:22,428 - DEBUG - ya.test - _save_outputs: Command (pid 1341839) errors:







2025-10-16 17:52:22,428 - DEBUG - ya.test - verify_sanitize_errors: No sanitizer errors found
2025-10-16 17:52:22,436 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,445 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:52:22,460 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-10-16 17:52:22,464 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-16 17:52:22,465 - INFO - root - _create_ydb_driver: Connecting to grpc://localhost:17091 to local/test_db ydb_access_token is set False
2025-10-16 17:52:22,467 - 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-16 17:52:22,471 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:52:22,472 - 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-16 17:52:22,495 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-16 17:52:22,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 993fd1fc-82d2-4ded-ad33-a53aa5b9e03f, localhost:17091): creating call state
2025-10-16 17:52:22,497 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 993fd1fc-82d2-4ded-ad33-a53aa5b9e03f, localhost:17091): request = { database: "local/test_db" }
2025-10-16 17:52:22,503 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 993fd1fc-82d2-4ded-ad33-a53aa5b9e03f, localhost:17091): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 16912 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-16 17:52:22,504 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:16912, location , ssl: False>]>
2025-10-16 17:52:22,506 - 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-16 17:52:22,520 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17091
2025-10-16 17:52:22,521 - INFO - root - _create_ydb_driver: Connected
2025-10-16 17:52:22,522 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 7adb85e6-3e4e-4887-a977-0108140a495e, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:22,523 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 7adb85e6-3e4e-4887-a977-0108140a495e, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:52:22,526 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 7adb85e6-3e4e-4887-a977-0108140a495e, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:52:22,750 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,755 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:52:22,768 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 554
2025-10-16 17:52:22,771 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-16 17:52:22,772 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:52:22,772 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:52:22,773 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 8e65ac9f-c68b-44f7-99c7-636f0e620072, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:22,775 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 8e65ac9f-c68b-44f7-99c7-636f0e620072, ghrun-2kszfvc6cm.auto.internal:16912): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-10-16 17:52:22,784 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 8e65ac9f-c68b-44f7-99c7-636f0e620072, ghrun-2kszfvc6cm.auto.internal:16912): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } } } } }
2025-10-16 17:52:22,786 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:52:22,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 5e6dfbdc-bd96-4304-ad9f-e5dc0ea324f5, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:22,787 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 5e6dfbdc-bd96-4304-ad9f-e5dc0ea324f5, ghrun-2kszfvc6cm.auto.internal:16912): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-10-16 17:52:22,795 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 5e6dfbdc-bd96-4304-ad9f-e5dc0ea324f5, ghrun-2kszfvc6cm.auto.internal:16912): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636707560 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636707870 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636709500 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636711190 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636712920 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636713190 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636714890 tx_id: 281474976720666 } } } } } }
2025-10-16 17:52:22,800 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,804 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,817 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:22,820 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-10-16 17:52:22,824 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,828 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,841 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:22,844 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-10-16 17:52:22,850 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,854 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,870 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 200 582
2025-10-16 17:52:22,874 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-10-16 17:52:22,878 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,883 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,896 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:22,899 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-10-16 17:52:22,903 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,907 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,921 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:22,924 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-10-16 17:52:22,928 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,932 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,946 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:22,949 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-10-16 17:52:22,968 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,973 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:22,988 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 200 582
2025-10-16 17:52:22,991 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-10-16 17:52:22,995 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:52:22,999 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 307 0
2025-10-16 17:52:23,014 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 200 583
2025-10-16 17:52:23,017 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-10-16 17:52:23,018 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b8dc38da-0375-4304-a644-67b4c304cf15, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:23,019 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b8dc38da-0375-4304-a644-67b4c304cf15, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:52:23,022 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b8dc38da-0375-4304-a644-67b4c304cf15, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:52:23,146 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 2c871d69-06c7-41a8-b7d9-187de1687c5d, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:23,147 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 2c871d69-06c7-41a8-b7d9-187de1687c5d, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/customer`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:52:23,149 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 2c871d69-06c7-41a8-b7d9-187de1687c5d, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:52:23,150 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:52:33,227 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 6104533a-3373-452a-8161-e83456bdc413, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:33,233 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 6104533a-3373-452a-8161-e83456bdc413, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/nation`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:52:33,236 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 6104533a-3373-452a-8161-e83456bdc413, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:52:33,242 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:52:34,951 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 19c072b7-8f14-455d-8cd1-0e74c6a15e4c, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:52:34,952 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 19c072b7-8f14-455d-8cd1-0e74c6a15e4c, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/orders`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:52:34,956 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 19c072b7-8f14-455d-8cd1-0e74c6a15e4c, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:52:34,972 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:53:20,842 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 718177c1-4cb2-4295-ac93-a94c111cfebf, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:53:20,842 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 718177c1-4cb2-4295-ac93-a94c111cfebf, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/part`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:53:20,849 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 718177c1-4cb2-4295-ac93-a94c111cfebf, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:53:20,850 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:53:34,242 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:53:34,243 - 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-16 17:53:34,274 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local/test_db
2025-10-16 17:53:34,277 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cf0400cc-995c-4f60-b4da-415ba4967272, localhost:17091): creating call state
2025-10-16 17:53:34,286 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cf0400cc-995c-4f60-b4da-415ba4967272, localhost:17091): request = { database: "local/test_db" }
2025-10-16 17:53:34,291 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cf0400cc-995c-4f60-b4da-415ba4967272, localhost:17091): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 16912 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-16 17:53:34,292 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local/test_db: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:16912, location , ssl: False>]>
2025-10-16 17:53:34,294 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17091
2025-10-16 17:53:36,479 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, b6489ca2-c1e8-476b-ba60-e4cf7564bf93, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:53:36,480 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, b6489ca2-c1e8-476b-ba60-e4cf7564bf93, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/partsupp`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:53:36,483 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, b6489ca2-c1e8-476b-ba60-e4cf7564bf93, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:53:36,484 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:53:49,615 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 8bd7a67b-caaf-44fe-98a7-997eaf553c58, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:53:49,616 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 8bd7a67b-caaf-44fe-98a7-997eaf553c58, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/region`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:53:49,619 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 8bd7a67b-caaf-44fe-98a7-997eaf553c58, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:53:49,623 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:53:51,005 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, d0844ed6-b954-462f-91ef-b971e3392a9c, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:53:51,006 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, d0844ed6-b954-462f-91ef-b971e3392a9c, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/supplier`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:53:51,008 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, d0844ed6-b954-462f-91ef-b971e3392a9c, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:53:51,009 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:53:53,572 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 816c2141-1f88-46e2-8316-6b5e996bde4d, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:53:53,573 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 816c2141-1f88-46e2-8316-6b5e996bde4d, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "SELECT count(*) FROM `/local/test_db/olap_yatests/tpch/s1/lineitem`" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:53:53,582 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 816c2141-1f88-46e2-8316-6b5e996bde4d, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:53:53,583 - INFO - ydb.tests.olap.scenario.helpers.scenario_tests_helper - _run_with_expected_status: Success operation
2025-10-16 17:54:37,431 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:37,440 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes HTTP/1.1" 200 790
2025-10-16 17:54:37,450 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:37,459 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:54:37,475 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 551
2025-10-16 17:54:38,643 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:38,654 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes HTTP/1.1" 200 794
2025-10-16 17:54:38,660 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:38,675 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:54:38,692 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 552
2025-10-16 17:54:38,695 - INFO - root - __create_iterations_table: All nodes before filtering: [('4714@ghrun-2kszfvc6cm.auto.internal', <Role.UNKNOWN: 0>)]
2025-10-16 17:54:38,695 - INFO - root - __create_iterations_table: Auto-discovered hosts (all roles): 1 hosts from 1 total nodes
2025-10-16 17:54:38,697 - INFO - root - __create_iterations_table: Host to nodes mapping: [('ghrun-2kszfvc6cm.auto.internal', 1)]
2025-10-16 17:54:38,701 - INFO - root - allure_test_description: iterations_table created, length: 515
2025-10-16 17:54:38,702 - INFO - root - allure_test_description: Added iterations table to description HTML
2025-10-16 17:54:39,062 - DEBUG - matplotlib - wrapper: CACHEDIR=/home/runner/.cache/matplotlib
2025-10-16 17:54:39,066 - DEBUG - matplotlib.font_manager - _load_fontmanager: Using fontManager instance from /home/runner/.cache/matplotlib/fontlist-v330.json
2025-10-16 17:54:40,687 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:54:40,693 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/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/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/node_1/logfile_ts6ghq71.log --grpc-port=17091 --mon-port=17879 --ic-port=24266
2025-10-16 17:54:40,693 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17091/1
2025-10-16 17:54:40,699 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-16 17:54:40,705 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-16 17:54:40,706 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:54:40,710 - DEBUG - ya.test - execute: Command pid: 1411408
2025-10-16 17:54:41,584 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) rc: 0
2025-10-16 17:54:41,586 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) elapsed time (sec): 0.8726046085357666
2025-10-16 17:54:41,586 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) idrss: 0
2025-10-16 17:54:41,588 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) inblock: 0
2025-10-16 17:54:41,589 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) isrss: 0
2025-10-16 17:54:41,590 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) ixrss: 0
2025-10-16 17:54:41,591 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) majflt: 0
2025-10-16 17:54:41,591 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) maxrss: 760372
2025-10-16 17:54:41,592 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) minflt: 45138
2025-10-16 17:54:41,592 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) msgrcv: 0
2025-10-16 17:54:41,592 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) msgsnd: 0
2025-10-16 17:54:41,592 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) nivcsw: 371
2025-10-16 17:54:41,593 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) nsignals: 0
2025-10-16 17:54:41,593 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) nswap: 0
2025-10-16 17:54:41,593 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) nvcsw: 15
2025-10-16 17:54:41,594 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) oublock: 8
2025-10-16 17:54:41,594 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) stime: 0.256082
2025-10-16 17:54:41,594 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) utime: 0.495764
2025-10-16 17:54:41,596 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) wtime: 0.876
2025-10-16 17:54:41,599 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:54:41,600 - DEBUG - ya.test - _save_outputs: Command (pid 1411408) errors:

2025-10-16 17:54:41,600 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:54:41,601 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/kikimr_configs"
2025-10-16 17:54:41,602 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17091 --tenant=/local/test_db --log-file-name=/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/tests/functional/tpc/medium/tpch/test-results/py3test/testing_out_stuff/test_duplicates/testing_out_stuff/test_duplicates.py.TestTpchDuplicatesZeroLevel.test_tpch.1/cluster/slot_1/logfile_s3vw3q5x.log --grpc-port=16912 --mon-port=17448 --ic-port=4714
2025-10-16 17:54:41,603 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16912/1
2025-10-16 17:54:41,605 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-16 17:54:41,613 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-16 17:54:41,614 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:54:41,618 - DEBUG - ya.test - execute: Command pid: 1412066
2025-10-16 17:54:42,459 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) rc: 0
2025-10-16 17:54:42,459 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) elapsed time (sec): 0.8394067287445068
2025-10-16 17:54:42,460 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) idrss: 0
2025-10-16 17:54:42,460 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) inblock: 0
2025-10-16 17:54:42,460 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) isrss: 0
2025-10-16 17:54:42,461 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) ixrss: 0
2025-10-16 17:54:42,461 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) majflt: 0
2025-10-16 17:54:42,461 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) maxrss: 760372
2025-10-16 17:54:42,461 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) minflt: 45139
2025-10-16 17:54:42,462 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) msgrcv: 0
2025-10-16 17:54:42,462 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) msgsnd: 0
2025-10-16 17:54:42,462 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) nivcsw: 142
2025-10-16 17:54:42,463 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) nsignals: 0
2025-10-16 17:54:42,463 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) nswap: 0
2025-10-16 17:54:42,463 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) nvcsw: 15
2025-10-16 17:54:42,464 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) oublock: 8
2025-10-16 17:54:42,465 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) stime: 0.191622
2025-10-16 17:54:42,465 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) utime: 0.555184
2025-10-16 17:54:42,465 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) wtime: 0.842
2025-10-16 17:54:42,466 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:54:42,466 - DEBUG - ya.test - _save_outputs: Command (pid 1412066) errors:

2025-10-16 17:54:42,467 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:54:42,480 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_duplicates.py::TestTpchDuplicatesZeroLevel, test_name: test_tpch[1])
2025-10-16 17:54:42,486 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,494 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:54:42,512 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 555
2025-10-16 17:54:42,520 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,525 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db HTTP/1.1" 307 0
2025-10-16 17:54:42,545 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db HTTP/1.1" 200 555
2025-10-16 17:54:42,548 - DEBUG - root - check_if_ydb_alive: Expected nodes count: 1
2025-10-16 17:54:42,549 - INFO - root - get_tables: get_tables /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:54:42,557 - INFO - root - _describe_path_impl: describe /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:54:42,557 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, bc576746-a24d-44fa-8cc0-b3390519dfc7, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:54:42,558 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, bc576746-a24d-44fa-8cc0-b3390519dfc7, ghrun-2kszfvc6cm.auto.internal:16912): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-10-16 17:54:42,569 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, bc576746-a24d-44fa-8cc0-b3390519dfc7, ghrun-2kszfvc6cm.auto.internal:16912): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.DescribePathResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } } } } }
2025-10-16 17:54:42,570 - INFO - root - list_directory: list /local/test_db/olap_yatests/tpch/s1
2025-10-16 17:54:42,570 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 89fa4ec4-3a34-468c-a3b2-7bd236e72a3b, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:54:42,572 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 89fa4ec4-3a34-468c-a3b2-7bd236e72a3b, ghrun-2kszfvc6cm.auto.internal:16912): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/local/test_db/olap_yatests/tpch/s1" }
2025-10-16 17:54:42,580 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 89fa4ec4-3a34-468c-a3b2-7bd236e72a3b, ghrun-2kszfvc6cm.auto.internal:16912): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "s1" owner: "root@builtin" type: DIRECTORY effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } children { name: "customer" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636705820 tx_id: 281474976720659 } } children { name: "lineitem" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636707560 tx_id: 281474976720660 } } children { name: "nation" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636707870 tx_id: 281474976720661 } } children { name: "orders" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636709500 tx_id: 281474976720662 } } children { name: "part" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636711190 tx_id: 281474976720663 } } children { name: "partsupp" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636712920 tx_id: 281474976720664 } } children { name: "region" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636713190 tx_id: 281474976720665 } } children { name: "supplier" owner: "root@builtin" type: COLUMN_TABLE created_at { plan_step: 1760636714890 tx_id: 281474976720666 } } } } } }
2025-10-16 17:54:42,586 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,591 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,607 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/customer&tablets=true HTTP/1.1" 200 584
2025-10-16 17:54:42,610 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/customer balance: 64-64 shards.
2025-10-16 17:54:42,613 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,617 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,633 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/lineitem&tablets=true HTTP/1.1" 200 584
2025-10-16 17:54:42,636 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/lineitem balance: 64-64 shards.
2025-10-16 17:54:42,642 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,646 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,662 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/nation&tablets=true HTTP/1.1" 200 582
2025-10-16 17:54:42,665 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/nation balance: 1-1 shards.
2025-10-16 17:54:42,668 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,672 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,688 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/orders&tablets=true HTTP/1.1" 200 584
2025-10-16 17:54:42,690 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/orders balance: 64-64 shards.
2025-10-16 17:54:42,694 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,705 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,734 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/part&tablets=true HTTP/1.1" 200 584
2025-10-16 17:54:42,738 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/part balance: 64-64 shards.
2025-10-16 17:54:42,741 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,746 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,759 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/partsupp&tablets=true HTTP/1.1" 200 584
2025-10-16 17:54:42,761 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/partsupp balance: 64-64 shards.
2025-10-16 17:54:42,764 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,769 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,799 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/region&tablets=true HTTP/1.1" 200 581
2025-10-16 17:54:42,802 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/region balance: 1-1 shards.
2025-10-16 17:54:42,806 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:17879
2025-10-16 17:54:42,815 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 307 0
2025-10-16 17:54:42,830 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:17879 "GET /node/50000/viewer/json/nodes?database=/local/test_db&path=/local/test_db/olap_yatests/tpch/s1/supplier&tablets=true HTTP/1.1" 200 583
2025-10-16 17:54:42,833 - INFO - root - check_if_ydb_alive: Table /local/test_db/olap_yatests/tpch/s1/supplier balance: 64-64 shards.
2025-10-16 17:54:42,834 - DEBUG - ydb.connection - _prepare_call: RpcState(StreamExecuteScanQuery, 0f78a21a-26f0-40ad-8a59-f10b331698c1, ghrun-2kszfvc6cm.auto.internal:16912): creating call state
2025-10-16 17:54:42,835 - DEBUG - ydb.connection - _log_request: RpcState(StreamExecuteScanQuery, 0f78a21a-26f0-40ad-8a59-f10b331698c1, ghrun-2kszfvc6cm.auto.internal:16912): request = { query { yql_text: "select 1" } mode: MODE_EXEC collect_stats: STATS_COLLECTION_NONE }
2025-10-16 17:54:42,838 - DEBUG - ydb.connection - _log_response: RpcState(StreamExecuteScanQuery, 0f78a21a-26f0-40ad-8a59-f10b331698c1, ghrun-2kszfvc6cm.auto.internal:16912): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:54:42,953 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb
2025-10-16 17:54:42,970 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-10-16 17:54:42,981 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-10-16 17:54:42,982 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0021c9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17091', '-d', '/local/test_db', 'workload', 'tpch', '--path', 'olap_yatests/tpch/s1', 'run', '--json', 'Query01.stats.json', '--output', 'Query01.result', '--include', 'Query01', '--iterations', '1', '--plan', 'Query01.plan', '--global-timeout', '1800.0s', '--verbose', '--check-canonical', '--scale', '1']' in 'None' (waiting)
2025-10-16 17:54:42,996 - DEBUG - ya.test - execute: Command pid: 1412106
2025-10-16 17:54:57,191 - INFO - root - teardown_class: No custom teardown defined for TestTpchDuplicatesZeroLevel
