2025-10-31 15:30:57,762 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:30:57,762 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-row]
2025-10-31 15:30:57,763 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:30:57,763 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:30:57,825 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd
2025-10-31 15:30:57,826 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8675
2025-10-31 15:30:57,827 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/stm2/0030b2/r3tmp/kikimr_cluster_69nfobok
2025-10-31 15:30:57,860 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd
2025-10-31 15:30:57,860 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5971
2025-10-31 15:30:57,861 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5482
2025-10-31 15:30:57,861 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14085
2025-10-31 15:30:57,862 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10403
2025-10-31 15:30:57,862 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/0030b2/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-10-31 15:30:57,862 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/0030b2/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/stm2/0030b2/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_ezx5qk7g.log --grpc-port=5971 --mon-port=5482 --ic-port=8675
2025-10-31 15:30:57,863 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5971/1, disk_size 68719476736
2025-10-31 15:30:57,863 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/0030b2/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-10-31 15:30:57,863 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/0030b2/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/stm2/0030b2/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_ezx5qk7g.log --grpc-port=5971 --mon-port=5482 --ic-port=8675
2025-10-31 15:30:57,864 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/stm2/0030b2/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/stm2/0030b2/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_ezx5qk7g.log', '--grpc-port=5971', '--mon-port=5482', '--ic-port=8675')' in '/home/runner/.ya/build/build_root/stm2/0030b2/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-10-31 15:30:57,865 - DEBUG - ya.test - execute: Command pid: 2642584
2025-10-31 15:30:57,866 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5971/1
2025-10-31 15:30:57,867 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 15:30:57,868 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 15:30:57,868 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 15:30:57,869 - DEBUG - ya.test - execute: Command pid: 2642588
2025-10-31 15:30:58,300 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) rc: 0
2025-10-31 15:30:58,300 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) elapsed time (sec): 0.43022847175598145
2025-10-31 15:30:58,300 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) idrss: 0
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) inblock: 0
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) isrss: 0
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) ixrss: 0
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) majflt: 0
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) maxrss: 538348
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) minflt: 16131
2025-10-31 15:30:58,301 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) msgrcv: 0
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) msgsnd: 0
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) nivcsw: 10
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) nsignals: 0
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) nswap: 0
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) nvcsw: 21
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) oublock: 8
2025-10-31 15:30:58,302 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) stime: 0.284095
2025-10-31 15:30:58,303 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) utime: 0.09740399999999999
2025-10-31 15:30:58,303 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) wtime: 0.431
2025-10-31 15:30:58,303 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) output:
Git info:
    Commit: b78d702ce2f71e40f285a60704f6a28670ea8340
    Branch: 
    Author: kungurtsev <alexey66rus@gmail.com>
    Summary: Merge f10023174585e1f3e64dac76a090d0c070f4c2a5 into 4af0266bcdc7df80879f060afb0976424f2118ed

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-upzbhs6y6e
    Host information: 
        Linux ghrun-upzbhs6y6e 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-10-31 15:30:58,303 - DEBUG - ya.test - _save_outputs: Command (pid 2642588) errors:

2025-10-31 15:30:58,303 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b78d702ce2f71e40f285a60704f6a28670ea8340
    Branch: 
    Author: kungurtsev <alexey66rus@gmail.com>
    Summary: Merge f10023174585e1f3e64dac76a090d0c070f4c2a5 into 4af0266bcdc7df80879f060afb0976424f2118ed
2025-10-31 15:31:00,174 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd
2025-10-31 15:31:00,174 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5971', '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: 8675\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 15:31:00,175 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 15:31:00,175 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 15:31:00,176 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5971', '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: 8675\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:31:00,177 - DEBUG - ya.test - execute: Command pid: 2644740
2025-10-31 15:31:00,807 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) rc: 0
2025-10-31 15:31:00,807 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) elapsed time (sec): 0.6297128200531006
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) idrss: 0
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) inblock: 0
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) isrss: 0
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) ixrss: 0
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) majflt: 0
2025-10-31 15:31:00,808 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) maxrss: 546812
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) minflt: 18273
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) msgrcv: 0
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) msgsnd: 0
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) nivcsw: 33
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) nsignals: 0
2025-10-31 15:31:00,809 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) nswap: 0
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) nvcsw: 135
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) oublock: 8
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) stime: 0.454447
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) utime: 0.120652
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) wtime: 0.631
2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 15:31:00,810 - DEBUG - ya.test - _save_outputs: Command (pid 2644740) errors:

2025-10-31 15:31:00,811 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd
2025-10-31 15:31:00,812 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5971', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-31 15:31:00,812 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 15:31:00,813 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 15:31:00,813 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5971', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:31:00,814 - DEBUG - ya.test - execute: Command pid: 2645526
2025-10-31 15:31:01,326 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) rc: 0
2025-10-31 15:31:01,327 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) elapsed time (sec): 0.5117371082305908
2025-10-31 15:31:01,327 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) idrss: 0
2025-10-31 15:31:01,327 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) inblock: 0
2025-10-31 15:31:01,327 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) isrss: 0
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) ixrss: 0
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) majflt: 0
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) maxrss: 546820
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) minflt: 18275
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) msgrcv: 0
2025-10-31 15:31:01,328 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) msgsnd: 0
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) nivcsw: 29
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) nsignals: 0
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) nswap: 0
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) nvcsw: 133
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) oublock: 8
2025-10-31 15:31:01,329 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) stime: 0.362606
2025-10-31 15:31:01,330 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) utime: 0.099617
2025-10-31 15:31:01,330 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) wtime: 0.513
2025-10-31 15:31:01,330 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 15:31:01,330 - DEBUG - ya.test - _save_outputs: Command (pid 2645526) errors:

2025-10-31 15:31:01,330 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 15:31:01,345 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 15:31:01,356 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:01,359 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 15:31:01,361 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:01,368 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 15:31:01,369 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e0e1fd96-4619-48ac-96d8-1147f0547ef6, localhost:5971): creating call state
2025-10-31 15:31:01,369 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e0e1fd96-4619-48ac-96d8-1147f0547ef6, localhost:5971): request = { database: "/Root" }
2025-10-31 15:31:01,372 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e0e1fd96-4619-48ac-96d8-1147f0547ef6, localhost:5971): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-upzbhs6y6e.auto.internal" port: 5971 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-10-31 15:31:01,372 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-upzbhs6y6e.auto.internal:5971, location 1, ssl: False>]>
2025-10-31 15:31:01,373 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:01,381 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:5971
2025-10-31 15:31:01,382 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, dca2193e-d26f-48b4-b65d-4866261b52ee, ghrun-upzbhs6y6e.auto.internal:5971): creating call state
2025-10-31 15:31:01,383 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, dca2193e-d26f-48b4-b65d-4866261b52ee, ghrun-upzbhs6y6e.auto.internal:5971): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:31:01,385 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, dca2193e-d26f-48b4-b65d-4866261b52ee, ghrun-upzbhs6y6e.auto.internal:5971): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjUyMmNhZjMtZDFiN2Q2MzYtYzMyYjEzNTMtODI5OTA1OTg=" } } } }
2025-10-31 15:31:01,388 - 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-10-31 15:31:01,389 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 257a94b7-e4c4-4890-bcc7-62138302cdc1, ghrun-upzbhs6y6e.auto.internal:5971): creating call state
2025-10-31 15:31:01,389 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 257a94b7-e4c4-4890-bcc7-62138302cdc1, ghrun-upzbhs6y6e.auto.internal:5971): request = { session_id: "ydb://session/3?node_id=1&id=YjUyMmNhZjMtZDFiN2Q2MzYtYzMyYjEzNTMtODI5OTA1OTg=" 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-10-31 15:31:01,426 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 257a94b7-e4c4-4890-bcc7-62138302cdc1, ghrun-upzbhs6y6e.auto.internal:5971): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:31:01,428 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydb/ydb
2025-10-31 15:31:01,429 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 15:31:01,429 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 15:31:01,429 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5971', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-row]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/stm2/0030b2/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/0030b2/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/0030b2/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/0030b2/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:31:01,430 - DEBUG - ya.test - execute: Command pid: 2646458
2025-10-31 15:31:01,591 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) rc: 0
2025-10-31 15:31:01,592 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) elapsed time (sec): 0.16016817092895508
2025-10-31 15:31:01,592 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) idrss: 0
2025-10-31 15:31:01,592 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) inblock: 0
2025-10-31 15:31:01,592 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) isrss: 0
2025-10-31 15:31:01,592 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) ixrss: 0
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) majflt: 0
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) maxrss: 181400
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) minflt: 4419
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) msgrcv: 0
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) msgsnd: 0
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) nivcsw: 18
2025-10-31 15:31:01,593 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) nsignals: 0
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) nswap: 0
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) nvcsw: 894
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) oublock: 48
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) stime: 0.051859999999999996
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) utime: 0.051859999999999996
2025-10-31 15:31:01,594 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) wtime: 0.161
2025-10-31 15:31:01,595 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) output:

2025-10-31 15:31:01,595 - DEBUG - ya.test - _save_outputs: Command (pid 2646458) errors:
Elapsed: 0.0114 sec. Total read size: 290B. Average processing speed: 24.9KiB/s.


2025-10-31 15:31:01,595 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:31:01,595 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydb/ydb
2025-10-31 15:31:01,596 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 15:31:01,597 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b2/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5971', '--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-10-31 15:31:01,598 - DEBUG - ya.test - execute: Command pid: 2646747
2025-10-31 15:31:01,744 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) rc: 0
2025-10-31 15:31:01,745 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) elapsed time (sec): 0.14591097831726074
2025-10-31 15:31:01,745 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) idrss: 0
2025-10-31 15:31:01,745 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) inblock: 0
2025-10-31 15:31:01,745 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) isrss: 0
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) ixrss: 0
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) majflt: 0
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) maxrss: 181400
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) minflt: 3439
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) msgrcv: 0
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) msgsnd: 0
2025-10-31 15:31:01,746 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) nivcsw: 7
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) nsignals: 0
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) nswap: 0
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) nvcsw: 173
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) oublock: 8
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) stime: 0.033669
2025-10-31 15:31:01,747 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) utime: 0.033669
2025-10-31 15:31:01,748 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) wtime: 0.147
2025-10-31 15:31:01,748 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) 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-10-31 15:31:01,748 - DEBUG - ya.test - _save_outputs: Command (pid 2646747) errors:

2025-10-31 15:31:01,748 - 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-10-31 15:31:01,750 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:31:25,374 - 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-10-31 15:31:25,379 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-row] status: good
