2025-11-01 04:20:18,773 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:18,773 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-row]
2025-11-01 04:20:18,773 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:18,774 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:20:18,813 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd
2025-11-01 04:20:18,814 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27090
2025-11-01 04:20:18,814 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/g81u/004de3/r3tmp/kikimr_cluster_i61jdyec
2025-11-01 04:20:18,836 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd
2025-11-01 04:20:18,836 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10984
2025-11-01 04:20:18,836 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22299
2025-11-01 04:20:18,836 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8298
2025-11-01 04:20:18,837 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28168
2025-11-01 04:20:18,837 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs"
2025-11-01 04:20:18,837 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004de3/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/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_bjceahfu.log --grpc-port=10984 --mon-port=22299 --ic-port=27090
2025-11-01 04:20:18,837 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10984/1, disk_size 68719476736
2025-11-01 04:20:18,838 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs"
2025-11-01 04:20:18,838 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004de3/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/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_bjceahfu.log --grpc-port=10984 --mon-port=22299 --ic-port=27090
2025-11-01 04:20:18,838 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_bjceahfu.log', '--grpc-port=10984', '--mon-port=22299', '--ic-port=27090')' in '/home/runner/.ya/build/build_root/g81u/004de3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1' (no wait)
2025-11-01 04:20:18,840 - DEBUG - ya.test - execute: Command pid: 2552194
2025-11-01 04:20:18,840 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10984/1
2025-11-01 04:20:18,841 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 04:20:18,841 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 04:20:18,841 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 04:20:18,842 - DEBUG - ya.test - execute: Command pid: 2552195
2025-11-01 04:20:19,483 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) rc: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) elapsed time (sec): 0.6410155296325684
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) idrss: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) inblock: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) isrss: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) ixrss: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) majflt: 0
2025-11-01 04:20:19,484 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) maxrss: 538372
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) minflt: 16135
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) msgrcv: 0
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) msgsnd: 0
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) nivcsw: 1
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) nsignals: 0
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) nswap: 0
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) nvcsw: 25
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) oublock: 8
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) stime: 0.465234
2025-11-01 04:20:19,485 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) utime: 0.127243
2025-11-01 04:20:19,486 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) wtime: 0.642
2025-11-01 04:20:19,486 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) output:
Git info:
    Commit: 767d72532e5716ba790791cf237fdadb24b0e578
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 96af589a3c3d7740081955df0fe84da0a7b35651 into 2afee58ebdae8491f810ad53605d37376c4e5de6

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

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

2025-11-01 04:20:19,486 - DEBUG - ya.test - _save_outputs: Command (pid 2552195) errors:

2025-11-01 04:20:19,486 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 767d72532e5716ba790791cf237fdadb24b0e578
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 96af589a3c3d7740081955df0fe84da0a7b35651 into 2afee58ebdae8491f810ad53605d37376c4e5de6
2025-11-01 04:20:21,186 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd
2025-11-01 04:20:21,186 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10984', '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: 27090\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 04:20:21,187 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 04:20:21,187 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 04:20:21,187 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10984', '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: 27090\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 04:20:21,188 - DEBUG - ya.test - execute: Command pid: 2553497
2025-11-01 04:20:21,589 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) rc: 0
2025-11-01 04:20:21,589 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) elapsed time (sec): 0.40018367767333984
2025-11-01 04:20:21,589 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) idrss: 0
2025-11-01 04:20:21,589 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) inblock: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) isrss: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) ixrss: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) majflt: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) maxrss: 546840
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) minflt: 18271
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) msgrcv: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) msgsnd: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) nivcsw: 3
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) nsignals: 0
2025-11-01 04:20:21,590 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) nswap: 0
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) nvcsw: 133
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) oublock: 8
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) stime: 0.25462399999999996
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) utime: 0.101849
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) wtime: 0.401
2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 04:20:21,591 - DEBUG - ya.test - _save_outputs: Command (pid 2553497) errors:

2025-11-01 04:20:21,592 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd
2025-11-01 04:20:21,592 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10984', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 04:20:21,593 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 04:20:21,593 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 04:20:21,593 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10984', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 04:20:21,594 - DEBUG - ya.test - execute: Command pid: 2553775
2025-11-01 04:20:22,293 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) rc: 0
2025-11-01 04:20:22,293 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) elapsed time (sec): 0.6985471248626709
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) idrss: 0
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) inblock: 0
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) isrss: 0
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) ixrss: 0
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) majflt: 0
2025-11-01 04:20:22,294 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) maxrss: 546820
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) minflt: 18270
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) msgrcv: 0
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) msgsnd: 0
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) nivcsw: 4
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) nsignals: 0
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) nswap: 0
2025-11-01 04:20:22,295 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) nvcsw: 151
2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) oublock: 8
2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) stime: 0.536114
2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) utime: 0.11516499999999999
2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) wtime: 0.699
2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 04:20:22,296 - DEBUG - ya.test - _save_outputs: Command (pid 2553775) errors:

2025-11-01 04:20:22,297 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 04:20:22,310 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 04:20:22,320 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:20:22,322 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 04:20:22,325 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:20:22,336 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 04:20:22,337 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 9af0f675-ccf4-4f04-83ba-6811952bed2b, localhost:10984): creating call state
2025-11-01 04:20:22,337 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 9af0f675-ccf4-4f04-83ba-6811952bed2b, localhost:10984): request = { database: "/Root" }
2025-11-01 04:20:22,340 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 9af0f675-ccf4-4f04-83ba-6811952bed2b, localhost:10984): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-i4gyxlpqdu.auto.internal" port: 10984 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" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 04:20:22,341 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-i4gyxlpqdu.auto.internal:10984, location 1, ssl: False>]>
2025-11-01 04:20:22,341 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:20:22,348 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10984
2025-11-01 04:20:22,349 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4bba2d3e-1874-4f80-ba35-56334d3a1618, ghrun-i4gyxlpqdu.auto.internal:10984): creating call state
2025-11-01 04:20:22,350 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4bba2d3e-1874-4f80-ba35-56334d3a1618, ghrun-i4gyxlpqdu.auto.internal:10984): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 04:20:22,352 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4bba2d3e-1874-4f80-ba35-56334d3a1618, ghrun-i4gyxlpqdu.auto.internal:10984): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NjhmOWYwOGMtZjRiOWFmZjMtZjg2NGYxOWEtNTA4YTIwZjM=" } } } }
2025-11-01 04:20:22,355 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_columns_opt[tsv-additional_args2-row])
2025-11-01 04:20:22,355 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 03057b76-4a2a-44a9-9dcc-5e73dfb172ba, ghrun-i4gyxlpqdu.auto.internal:10984): creating call state
2025-11-01 04:20:22,356 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 03057b76-4a2a-44a9-9dcc-5e73dfb172ba, ghrun-i4gyxlpqdu.auto.internal:10984): request = { session_id: "ydb://session/3?node_id=1&id=NjhmOWYwOGMtZjRiOWFmZjMtZjg2NGYxOWEtNTA4YTIwZjM=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-row]` (\n            key Uint32 NOT NULL,\n            id Uint64,\n            value Utf8,\n            PRIMARY KEY(key)\n        )\n        \n        WITH (\n            STORE = row\n        )\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 04:20:22,388 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 03057b76-4a2a-44a9-9dcc-5e73dfb172ba, ghrun-i4gyxlpqdu.auto.internal:10984): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:20:22,390 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb
2025-11-01 04:20:22,391 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 04:20:22,392 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 04:20:22,392 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10984', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-row]', '--header', '--columns', 'key\tid\tvalue\n', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column0/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column0/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column0/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column0/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:20:22,393 - DEBUG - ya.test - execute: Command pid: 2554397
2025-11-01 04:20:22,546 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) rc: 0
2025-11-01 04:20:22,546 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) elapsed time (sec): 0.1517195701599121
2025-11-01 04:20:22,546 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) idrss: 0
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) inblock: 0
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) isrss: 0
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) ixrss: 0
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) majflt: 0
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) maxrss: 184472
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) minflt: 4169
2025-11-01 04:20:22,547 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) msgrcv: 0
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) msgsnd: 0
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) nivcsw: 1
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) nsignals: 0
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) nswap: 0
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) nvcsw: 559
2025-11-01 04:20:22,548 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) oublock: 80
2025-11-01 04:20:22,549 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) stime: 0.029495999999999998
2025-11-01 04:20:22,549 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) utime: 0.06741799999999999
2025-11-01 04:20:22,549 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) wtime: 0.153
2025-11-01 04:20:22,549 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) output:

2025-11-01 04:20:22,549 - DEBUG - ya.test - _save_outputs: Command (pid 2554397) errors:
Elapsed: 0.00655 sec. Total read size: 290B. Average processing speed: 43.3KiB/s.


2025-11-01 04:20:22,550 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:20:22,550 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb
2025-11-01 04:20:22,551 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 04:20:22,551 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10984', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-11-01 04:20:22,552 - DEBUG - ya.test - execute: Command pid: 2554724
2025-11-01 04:20:22,691 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) rc: 0
2025-11-01 04:20:22,692 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) elapsed time (sec): 0.13834738731384277
2025-11-01 04:20:22,692 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) idrss: 0
2025-11-01 04:20:22,692 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) inblock: 0
2025-11-01 04:20:22,692 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) isrss: 0
2025-11-01 04:20:22,692 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) ixrss: 0
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) majflt: 0
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) maxrss: 184472
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) minflt: 3453
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) msgrcv: 0
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) msgsnd: 0
2025-11-01 04:20:22,693 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) nivcsw: 3
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) nsignals: 0
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) nswap: 0
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) nvcsw: 194
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) oublock: 8
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) stime: 0.025736
2025-11-01 04:20:22,694 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) utime: 0.036766
2025-11-01 04:20:22,695 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) wtime: 0.14
2025-11-01 04:20:22,695 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-11-01 04:20:22,695 - DEBUG - ya.test - _save_outputs: Command (pid 2554724) errors:

2025-11-01 04:20:22,695 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-11-01 04:20:22,697 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:20:46,119 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 04:20:46,123 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] status: good
