2025-11-01 04:20:04,680 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:04,681 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-row]
2025-11-01 04:20:04,681 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:04,681 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:20:04,746 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd
2025-11-01 04:20:04,747 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17266
2025-11-01 04:20:04,748 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/g81u/004e01/r3tmp/kikimr_cluster_agdspwd8
2025-11-01 04:20:04,782 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd
2025-11-01 04:20:04,783 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18814
2025-11-01 04:20:04,783 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31784
2025-11-01 04:20:04,783 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24093
2025-11-01 04:20:04,784 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19666
2025-11-01 04:20:04,785 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/kikimr_configs"
2025-11-01 04:20:04,785 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004e01/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/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/node_1/logfile_dekh8f74.log --grpc-port=18814 --mon-port=31784 --ic-port=17266
2025-11-01 04:20:04,785 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18814/1, disk_size 68719476736
2025-11-01 04:20:04,785 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/kikimr_configs"
2025-11-01 04:20:04,786 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004e01/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/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/node_1/logfile_dekh8f74.log --grpc-port=18814 --mon-port=31784 --ic-port=17266
2025-11-01 04:20:04,787 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/g81u/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/g81u/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/node_1/logfile_dekh8f74.log', '--grpc-port=18814', '--mon-port=31784', '--ic-port=17266')' in '/home/runner/.ya/build/build_root/g81u/004e01/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk17/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args3-row/cluster/node_1' (no wait)
2025-11-01 04:20:04,788 - DEBUG - ya.test - execute: Command pid: 2539494
2025-11-01 04:20:04,789 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18814/1
2025-11-01 04:20:04,790 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 04:20:04,791 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 04:20:04,791 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 04:20:04,792 - DEBUG - ya.test - execute: Command pid: 2539495
2025-11-01 04:20:05,270 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) rc: 0
2025-11-01 04:20:05,270 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) elapsed time (sec): 0.4771726131439209
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) idrss: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) inblock: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) isrss: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) ixrss: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) majflt: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) maxrss: 538372
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) minflt: 16129
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) msgrcv: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) msgsnd: 0
2025-11-01 04:20:05,271 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) nivcsw: 3
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) nsignals: 0
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) nswap: 0
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) nvcsw: 15
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) oublock: 8
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) stime: 0.340076
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) utime: 0.089067
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) wtime: 0.478
2025-11-01 04:20:05,272 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) 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:05,273 - DEBUG - ya.test - _save_outputs: Command (pid 2539495) errors:

2025-11-01 04:20:05,273 - 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:07,031 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd
2025-11-01 04:20:07,031 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18814', '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: 17266\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 04:20:07,032 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 04:20:07,032 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 04:20:07,033 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18814', '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: 17266\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 04:20:07,033 - DEBUG - ya.test - execute: Command pid: 2541009
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) rc: 0
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) elapsed time (sec): 0.40889644622802734
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) idrss: 0
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) inblock: 0
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) isrss: 0
2025-11-01 04:20:07,443 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) ixrss: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) majflt: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) maxrss: 546824
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) minflt: 18270
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) msgrcv: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) msgsnd: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) nivcsw: 2
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) nsignals: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) nswap: 0
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) nvcsw: 129
2025-11-01 04:20:07,444 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) oublock: 8
2025-11-01 04:20:07,445 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) stime: 0.26187699999999997
2025-11-01 04:20:07,445 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) utime: 0.10316299999999999
2025-11-01 04:20:07,445 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) wtime: 0.41
2025-11-01 04:20:07,445 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 04:20:07,445 - DEBUG - ya.test - _save_outputs: Command (pid 2541009) errors:

2025-11-01 04:20:07,446 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd
2025-11-01 04:20:07,446 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18814', '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:07,446 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 04:20:07,447 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 04:20:07,447 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18814', '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:07,448 - DEBUG - ya.test - execute: Command pid: 2541191
2025-11-01 04:20:07,877 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) rc: 0
2025-11-01 04:20:07,877 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) elapsed time (sec): 0.4289705753326416
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) idrss: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) inblock: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) isrss: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) ixrss: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) majflt: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) maxrss: 546832
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) minflt: 18271
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) msgrcv: 0
2025-11-01 04:20:07,878 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) msgsnd: 0
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) nivcsw: 0
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) nsignals: 0
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) nswap: 0
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) nvcsw: 134
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) oublock: 8
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) stime: 0.241522
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) utime: 0.143276
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) wtime: 0.43
2025-11-01 04:20:07,879 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 04:20:07,880 - DEBUG - ya.test - _save_outputs: Command (pid 2541191) errors:

2025-11-01 04:20:07,880 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 04:20:07,893 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 04:20:07,902 - 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:07,904 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 04:20:07,906 - 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:07,913 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 04:20:07,913 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2263fcbc-a9de-4f90-b6e4-40a439ce7e12, localhost:18814): creating call state
2025-11-01 04:20:07,914 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2263fcbc-a9de-4f90-b6e4-40a439ce7e12, localhost:18814): request = { database: "/Root" }
2025-11-01 04:20:07,916 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2263fcbc-a9de-4f90-b6e4-40a439ce7e12, localhost:18814): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-i4gyxlpqdu.auto.internal" port: 18814 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:07,917 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-i4gyxlpqdu.auto.internal:18814, location 1, ssl: False>]>
2025-11-01 04:20:07,917 - 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:07,923 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18814
2025-11-01 04:20:07,924 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 579995aa-e57a-4311-afad-0da743611dca, ghrun-i4gyxlpqdu.auto.internal:18814): creating call state
2025-11-01 04:20:07,924 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 579995aa-e57a-4311-afad-0da743611dca, ghrun-i4gyxlpqdu.auto.internal:18814): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 04:20:07,927 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 579995aa-e57a-4311-afad-0da743611dca, ghrun-i4gyxlpqdu.auto.internal:18814): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjlmMzc4NDgtMzIzOTZjNmEtMTI3NTIzMC1iYTBkOTQ4Yg==" } } } }
2025-11-01 04:20:07,930 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files[tsv-additional_args3-row])
2025-11-01 04:20:07,930 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 938d7ec3-176c-4411-92c3-c5f978d4f6de, ghrun-i4gyxlpqdu.auto.internal:18814): creating call state
2025-11-01 04:20:07,931 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 938d7ec3-176c-4411-92c3-c5f978d4f6de, ghrun-i4gyxlpqdu.auto.internal:18814): request = { session_id: "ydb://session/3?node_id=1&id=ZjlmMzc4NDgtMzIzOTZjNmEtMTI3NTIzMC1iYTBkOTQ4Yg==" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files[tsv-additional_args3-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:07,970 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 938d7ec3-176c-4411-92c3-c5f978d4f6de, ghrun-i4gyxlpqdu.auto.internal:18814): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:20:07,972 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydb/ydb
2025-11-01 04:20:07,972 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 04:20:07,973 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 04:20:07,973 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:18814', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-row]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/g81u/004e01/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004e01/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004e01/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004e01/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:20:07,974 - DEBUG - ya.test - execute: Command pid: 2541469
2025-11-01 04:20:08,124 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) rc: 0
2025-11-01 04:20:08,124 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) elapsed time (sec): 0.1488790512084961
2025-11-01 04:20:08,124 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) idrss: 0
2025-11-01 04:20:08,124 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) inblock: 0
2025-11-01 04:20:08,125 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) isrss: 0
2025-11-01 04:20:08,125 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) ixrss: 0
2025-11-01 04:20:08,125 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) majflt: 0
2025-11-01 04:20:08,125 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) maxrss: 184328
2025-11-01 04:20:08,125 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) minflt: 4426
2025-11-01 04:20:08,126 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) msgrcv: 0
2025-11-01 04:20:08,126 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) msgsnd: 0
2025-11-01 04:20:08,126 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) nivcsw: 1
2025-11-01 04:20:08,126 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) nsignals: 0
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) nswap: 0
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) nvcsw: 966
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) oublock: 48
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) stime: 0.059687
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) utime: 0.036475
2025-11-01 04:20:08,127 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) wtime: 0.15
2025-11-01 04:20:08,128 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) output:

2025-11-01 04:20:08,128 - DEBUG - ya.test - _save_outputs: Command (pid 2541469) errors:
Elapsed: 0.00758 sec. Total read size: 290B. Average processing speed: 37.4KiB/s.


2025-11-01 04:20:08,128 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:20:08,129 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydb/ydb
2025-11-01 04:20:08,130 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 04:20:08,130 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e01/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:18814', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files[tsv-additional_args3-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-11-01 04:20:08,131 - DEBUG - ya.test - execute: Command pid: 2541893
2025-11-01 04:20:08,259 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) rc: 0
2025-11-01 04:20:08,259 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) elapsed time (sec): 0.1270465850830078
2025-11-01 04:20:08,259 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) idrss: 0
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) inblock: 0
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) isrss: 0
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) ixrss: 0
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) majflt: 0
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) maxrss: 184328
2025-11-01 04:20:08,260 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) minflt: 3446
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) msgrcv: 0
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) msgsnd: 0
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) nivcsw: 0
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) nsignals: 0
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) nswap: 0
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) nvcsw: 198
2025-11-01 04:20:08,261 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) oublock: 8
2025-11-01 04:20:08,262 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) stime: 0.015534999999999998
2025-11-01 04:20:08,262 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) utime: 0.038839
2025-11-01 04:20:08,262 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) wtime: 0.128
2025-11-01 04:20:08,262 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) 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:08,262 - DEBUG - ya.test - _save_outputs: Command (pid 2541893) errors:

2025-11-01 04:20:08,263 - 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:08,264 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:20:31,704 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-row] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 04:20:31,708 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-row] status: good
