2025-11-01 16:48:52,123 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:48:52,123 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args2-column]
2025-11-01 16:48:52,124 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:48:52,124 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:48:52,216 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd
2025-11-01 16:48:52,217 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18782
2025-11-01 16:48:52,220 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/q6a6/001b47/r3tmp/kikimr_cluster_kzfqop3r
2025-11-01 16:48:52,256 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd
2025-11-01 16:48:52,256 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3812
2025-11-01 16:48:52,256 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21329
2025-11-01 16:48:52,257 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22702
2025-11-01 16:48:52,258 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1137
2025-11-01 16:48:52,260 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs"
2025-11-01 16:48:52,260 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/001b47/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/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_7cnsf480.log --grpc-port=3812 --mon-port=21329 --ic-port=18782
2025-11-01 16:48:52,261 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:3812/1, disk_size 68719476736
2025-11-01 16:48:52,268 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs"
2025-11-01 16:48:52,269 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/001b47/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/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_7cnsf480.log --grpc-port=3812 --mon-port=21329 --ic-port=18782
2025-11-01 16:48:52,271 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_7cnsf480.log', '--grpc-port=3812', '--mon-port=21329', '--ic-port=18782')' in '/home/runner/.ya/build/build_root/q6a6/001b47/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1' (no wait)
2025-11-01 16:48:52,273 - DEBUG - ya.test - execute: Command pid: 3152729
2025-11-01 16:48:52,273 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3812/1
2025-11-01 16:48:52,274 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:48:52,276 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:48:52,276 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:48:52,277 - DEBUG - ya.test - execute: Command pid: 3152739
2025-11-01 16:48:52,776 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) rc: 0
2025-11-01 16:48:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) elapsed time (sec): 0.49771690368652344
2025-11-01 16:48:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) idrss: 0
2025-11-01 16:48:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) inblock: 0
2025-11-01 16:48:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) isrss: 0
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) ixrss: 0
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) majflt: 0
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) maxrss: 538512
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) minflt: 16130
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) msgrcv: 0
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) msgsnd: 0
2025-11-01 16:48:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) nivcsw: 69
2025-11-01 16:48:52,783 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) nsignals: 0
2025-11-01 16:48:52,784 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) nswap: 0
2025-11-01 16:48:52,785 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) nvcsw: 14
2025-11-01 16:48:52,785 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) oublock: 8
2025-11-01 16:48:52,786 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) stime: 0.29936999999999997
2025-11-01 16:48:52,786 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) utime: 0.132374
2025-11-01 16:48:52,786 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) wtime: 0.499
2025-11-01 16:48:52,786 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 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 16:48:52,786 - DEBUG - ya.test - _save_outputs: Command (pid 3152739) errors:

2025-11-01 16:48:52,787 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:48:54,785 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd
2025-11-01 16:48:54,786 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3812', '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: 18782\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:48:54,787 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:48:54,788 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:48:54,789 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3812', '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: 18782\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:48:54,791 - DEBUG - ya.test - execute: Command pid: 3154193
2025-11-01 16:48:55,207 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) rc: 0
2025-11-01 16:48:55,208 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) elapsed time (sec): 0.4142487049102783
2025-11-01 16:48:55,208 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) idrss: 0
2025-11-01 16:48:55,208 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) inblock: 0
2025-11-01 16:48:55,209 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) isrss: 0
2025-11-01 16:48:55,209 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) ixrss: 0
2025-11-01 16:48:55,209 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) majflt: 0
2025-11-01 16:48:55,211 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) maxrss: 546976
2025-11-01 16:48:55,214 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) minflt: 18272
2025-11-01 16:48:55,215 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) msgrcv: 0
2025-11-01 16:48:55,216 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) msgsnd: 0
2025-11-01 16:48:55,216 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) nivcsw: 42
2025-11-01 16:48:55,217 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) nsignals: 0
2025-11-01 16:48:55,217 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) nswap: 0
2025-11-01 16:48:55,217 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) nvcsw: 133
2025-11-01 16:48:55,218 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) oublock: 8
2025-11-01 16:48:55,218 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) stime: 0.244172
2025-11-01 16:48:55,223 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) utime: 0.11420899999999999
2025-11-01 16:48:55,223 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) wtime: 0.415
2025-11-01 16:48:55,224 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 16:48:55,224 - DEBUG - ya.test - _save_outputs: Command (pid 3154193) errors:

2025-11-01 16:48:55,225 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd
2025-11-01 16:48:55,225 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3812', '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 16:48:55,226 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:48:55,235 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:48:55,239 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3812', '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 16:48:55,255 - DEBUG - ya.test - execute: Command pid: 3154484
2025-11-01 16:48:55,701 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) rc: 0
2025-11-01 16:48:55,705 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) elapsed time (sec): 0.44530677795410156
2025-11-01 16:48:55,706 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) idrss: 0
2025-11-01 16:48:55,706 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) inblock: 0
2025-11-01 16:48:55,708 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) isrss: 0
2025-11-01 16:48:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) ixrss: 0
2025-11-01 16:48:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) majflt: 0
2025-11-01 16:48:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) maxrss: 546960
2025-11-01 16:48:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) minflt: 18273
2025-11-01 16:48:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) msgrcv: 0
2025-11-01 16:48:55,710 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) msgsnd: 0
2025-11-01 16:48:55,711 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) nivcsw: 57
2025-11-01 16:48:55,711 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) nsignals: 0
2025-11-01 16:48:55,712 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) nswap: 0
2025-11-01 16:48:55,712 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) nvcsw: 134
2025-11-01 16:48:55,713 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) oublock: 8
2025-11-01 16:48:55,719 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) stime: 0.281385
2025-11-01 16:48:55,721 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) utime: 0.10815
2025-11-01 16:48:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) wtime: 0.452
2025-11-01 16:48:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:48:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 3154484) errors:

2025-11-01 16:48:55,723 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:48:55,740 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:48:55,761 - 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 16:48:55,772 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:48:55,773 - 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 16:48:55,786 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:48:55,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 9c3d7ec3-516d-467a-b642-98e2f5f243a5, localhost:3812): creating call state
2025-11-01 16:48:55,787 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 9c3d7ec3-516d-467a-b642-98e2f5f243a5, localhost:3812): request = { database: "/Root" }
2025-11-01 16:48:55,791 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 9c3d7ec3-516d-467a-b642-98e2f5f243a5, localhost:3812): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 3812 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 16:48:55,791 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:3812, location 1, ssl: False>]>
2025-11-01 16:48:55,792 - 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 16:48:55,807 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3812
2025-11-01 16:48:55,808 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 9d9d9a98-52f2-44a0-8705-48acb7dc436e, ghrun-tvlw7frnpa.auto.internal:3812): creating call state
2025-11-01 16:48:55,810 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 9d9d9a98-52f2-44a0-8705-48acb7dc436e, ghrun-tvlw7frnpa.auto.internal:3812): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:48:55,813 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 9d9d9a98-52f2-44a0-8705-48acb7dc436e, ghrun-tvlw7frnpa.auto.internal:3812): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MmUyYmUzNi0zYmY1Y2IzNi1jYjYyYzI2NC0xNTExYjNhYQ==" } } } }
2025-11-01 16:48:55,816 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files[tsv-additional_args2-column])
2025-11-01 16:48:55,829 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, eea8a8af-8547-400a-8bac-30404d571d2a, ghrun-tvlw7frnpa.auto.internal:3812): creating call state
2025-11-01 16:48:55,829 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, eea8a8af-8547-400a-8bac-30404d571d2a, ghrun-tvlw7frnpa.auto.internal:3812): request = { session_id: "ydb://session/3?node_id=1&id=MmUyYmUzNi0zYmY1Y2IzNi1jYjYyYzI2NC0xNTExYjNhYQ==" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files[tsv-additional_args2-column]` (\n            key Uint32 NOT NULL,\n            id Uint64,\n            value Utf8,\n            PRIMARY KEY(key)\n        )\n        PARTITION BY HASH(key)\n        WITH (\n            STORE = column\n        )\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:48:56,237 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, eea8a8af-8547-400a-8bac-30404d571d2a, ghrun-tvlw7frnpa.auto.internal:3812): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:48:56,243 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb
2025-11-01 16:48:56,244 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 16:48:56,245 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 16:48:56,245 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:3812', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args2-column]', '--header', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 16:48:56,246 - DEBUG - ya.test - execute: Command pid: 3155782
2025-11-01 16:48:56,447 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) rc: 0
2025-11-01 16:48:56,447 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) elapsed time (sec): 0.19978547096252441
2025-11-01 16:48:56,448 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) idrss: 0
2025-11-01 16:48:56,448 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) inblock: 0
2025-11-01 16:48:56,448 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) isrss: 0
2025-11-01 16:48:56,449 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) ixrss: 0
2025-11-01 16:48:56,449 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) majflt: 0
2025-11-01 16:48:56,449 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) maxrss: 187444
2025-11-01 16:48:56,449 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) minflt: 4143
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) msgrcv: 0
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) msgsnd: 0
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) nivcsw: 35
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) nsignals: 0
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) nswap: 0
2025-11-01 16:48:56,450 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) nvcsw: 535
2025-11-01 16:48:56,451 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) oublock: 80
2025-11-01 16:48:56,452 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) stime: 0.05234
2025-11-01 16:48:56,452 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) utime: 0.039255
2025-11-01 16:48:56,455 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) wtime: 0.201
2025-11-01 16:48:56,457 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) output:

2025-11-01 16:48:56,457 - DEBUG - ya.test - _save_outputs: Command (pid 3155782) errors:
Elapsed: 0.0388 sec. Total read size: 290B. Average processing speed: 7.31KiB/s.


2025-11-01 16:48:56,457 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 16:48:56,463 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb
2025-11-01 16:48:56,466 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 16:48:56,467 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:3812', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files[tsv-additional_args2-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-11-01 16:48:56,469 - DEBUG - ya.test - execute: Command pid: 3156219
2025-11-01 16:48:56,758 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) rc: 0
2025-11-01 16:48:56,759 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) elapsed time (sec): 0.28867506980895996
2025-11-01 16:48:56,759 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) idrss: 0
2025-11-01 16:48:56,759 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) inblock: 0
2025-11-01 16:48:56,761 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) isrss: 0
2025-11-01 16:48:56,761 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) ixrss: 0
2025-11-01 16:48:56,761 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) majflt: 0
2025-11-01 16:48:56,762 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) maxrss: 187444
2025-11-01 16:48:56,762 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) minflt: 3433
2025-11-01 16:48:56,762 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) msgrcv: 0
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) msgsnd: 0
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) nivcsw: 15
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) nsignals: 0
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) nswap: 0
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) nvcsw: 188
2025-11-01 16:48:56,763 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) oublock: 8
2025-11-01 16:48:56,764 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) stime: 0.012466
2025-11-01 16:48:56,764 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) utime: 0.058175
2025-11-01 16:48:56,764 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) wtime: 0.29
2025-11-01 16:48:56,764 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) 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 16:48:56,764 - DEBUG - ya.test - _save_outputs: Command (pid 3156219) errors:

2025-11-01 16:48:56,765 - 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 16:48:56,767 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:49:20,694 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 16:49:20,699 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] status: good
