2025-10-31 08:41:43,119 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:43,119 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-row]
2025-10-31 08:41:43,120 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:43,120 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:41:43,188 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd
2025-10-31 08:41:43,190 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15178
2025-10-31 08:41:43,191 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/pogg/001e66/r3tmp/kikimr_cluster_bbhrcmtk
2025-10-31 08:41:43,229 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd
2025-10-31 08:41:43,229 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24484
2025-10-31 08:41:43,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14754
2025-10-31 08:41:43,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12322
2025-10-31 08:41:43,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13904
2025-10-31 08:41:43,231 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001e66/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 08:41:43,231 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001e66/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/pogg/001e66/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_eadfahk4.log --grpc-port=24484 --mon-port=14754 --ic-port=15178
2025-10-31 08:41:43,232 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:24484/1, disk_size 68719476736
2025-10-31 08:41:43,232 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001e66/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 08:41:43,232 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001e66/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/pogg/001e66/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_eadfahk4.log --grpc-port=24484 --mon-port=14754 --ic-port=15178
2025-10-31 08:41:43,233 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pogg/001e66/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/pogg/001e66/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_eadfahk4.log', '--grpc-port=24484', '--mon-port=14754', '--ic-port=15178')' in '/home/runner/.ya/build/build_root/pogg/001e66/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 08:41:43,235 - DEBUG - ya.test - execute: Command pid: 3245544
2025-10-31 08:41:43,236 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:24484/1
2025-10-31 08:41:43,237 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 08:41:43,238 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 08:41:43,238 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 08:41:43,239 - DEBUG - ya.test - execute: Command pid: 3245545
2025-10-31 08:41:43,687 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) rc: 0
2025-10-31 08:41:43,688 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) elapsed time (sec): 0.447648286819458
2025-10-31 08:41:43,688 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) idrss: 0
2025-10-31 08:41:43,689 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) inblock: 0
2025-10-31 08:41:43,689 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) isrss: 0
2025-10-31 08:41:43,689 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) ixrss: 0
2025-10-31 08:41:43,689 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) majflt: 0
2025-10-31 08:41:43,689 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) maxrss: 538184
2025-10-31 08:41:43,690 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) minflt: 16134
2025-10-31 08:41:43,690 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) msgrcv: 0
2025-10-31 08:41:43,691 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) msgsnd: 0
2025-10-31 08:41:43,691 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) nivcsw: 4
2025-10-31 08:41:43,691 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) nsignals: 0
2025-10-31 08:41:43,692 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) nswap: 0
2025-10-31 08:41:43,692 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) nvcsw: 14
2025-10-31 08:41:43,693 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) oublock: 8
2025-10-31 08:41:43,693 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) stime: 0.26337299999999997
2025-10-31 08:41:43,694 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) utime: 0.135677
2025-10-31 08:41:43,694 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) wtime: 0.449
2025-10-31 08:41:43,694 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) output:
Git info:
    Commit: 82bf8aab4f547668632ff26479ecdb9996428c61
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 43cd17c8a58e28cd156b58873e20cf204c35cf0d into e300863ab69d6519229a61bcfeb54a36b8d86bd0

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uabpkrvm6i
    Host information: 
        Linux ghrun-uabpkrvm6i 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 08:41:43,694 - DEBUG - ya.test - _save_outputs: Command (pid 3245545) errors:

2025-10-31 08:41:43,695 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 82bf8aab4f547668632ff26479ecdb9996428c61
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 43cd17c8a58e28cd156b58873e20cf204c35cf0d into e300863ab69d6519229a61bcfeb54a36b8d86bd0
2025-10-31 08:41:45,616 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd
2025-10-31 08:41:45,616 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24484', '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: 15178\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 08:41:45,617 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 08:41:45,617 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 08:41:45,618 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24484', '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: 15178\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 08:41:45,619 - DEBUG - ya.test - execute: Command pid: 3246804
2025-10-31 08:41:46,094 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) rc: 0
2025-10-31 08:41:46,094 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) elapsed time (sec): 0.47417545318603516
2025-10-31 08:41:46,094 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) idrss: 0
2025-10-31 08:41:46,094 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) inblock: 0
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) isrss: 0
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) ixrss: 0
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) majflt: 0
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) maxrss: 546660
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) minflt: 18272
2025-10-31 08:41:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) msgrcv: 0
2025-10-31 08:41:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) msgsnd: 0
2025-10-31 08:41:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) nivcsw: 16
2025-10-31 08:41:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) nsignals: 0
2025-10-31 08:41:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) nswap: 0
2025-10-31 08:41:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) nvcsw: 141
2025-10-31 08:41:46,097 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) oublock: 8
2025-10-31 08:41:46,097 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) stime: 0.29703799999999997
2025-10-31 08:41:46,097 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) utime: 0.132024
2025-10-31 08:41:46,097 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) wtime: 0.475
2025-10-31 08:41:46,097 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 08:41:46,098 - DEBUG - ya.test - _save_outputs: Command (pid 3246804) errors:

2025-10-31 08:41:46,099 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd
2025-10-31 08:41:46,099 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24484', '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 08:41:46,100 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 08:41:46,100 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 08:41:46,101 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24484', '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 08:41:46,102 - DEBUG - ya.test - execute: Command pid: 3246948
2025-10-31 08:41:46,583 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) rc: 0
2025-10-31 08:41:46,583 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) elapsed time (sec): 0.48009204864501953
2025-10-31 08:41:46,583 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) idrss: 0
2025-10-31 08:41:46,583 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) inblock: 0
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) isrss: 0
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) ixrss: 0
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) majflt: 0
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) maxrss: 546656
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) minflt: 18270
2025-10-31 08:41:46,584 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) msgrcv: 0
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) msgsnd: 0
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) nivcsw: 16
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) nsignals: 0
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) nswap: 0
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) nvcsw: 133
2025-10-31 08:41:46,585 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) oublock: 8
2025-10-31 08:41:46,586 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) stime: 0.31638
2025-10-31 08:41:46,586 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) utime: 0.120394
2025-10-31 08:41:46,586 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) wtime: 0.481
2025-10-31 08:41:46,586 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 08:41:46,586 - DEBUG - ya.test - _save_outputs: Command (pid 3246948) errors:

2025-10-31 08:41:46,587 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 08:41:46,603 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 08:41:46,615 - 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 08:41:46,618 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 08:41:46,620 - 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 08:41:46,630 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 08:41:46,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3c3d3f5c-fafe-4478-b802-aa16ecffcf74, localhost:24484): creating call state
2025-10-31 08:41:46,631 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3c3d3f5c-fafe-4478-b802-aa16ecffcf74, localhost:24484): request = { database: "/Root" }
2025-10-31 08:41:46,635 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3c3d3f5c-fafe-4478-b802-aa16ecffcf74, localhost:24484): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uabpkrvm6i.auto.internal" port: 24484 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 08:41:46,635 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uabpkrvm6i.auto.internal:24484, location 1, ssl: False>]>
2025-10-31 08:41:46,636 - 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 08:41:46,644 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:24484
2025-10-31 08:41:46,645 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ebff4cb6-92ee-440d-8b5b-0c5746f816ee, ghrun-uabpkrvm6i.auto.internal:24484): creating call state
2025-10-31 08:41:46,646 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ebff4cb6-92ee-440d-8b5b-0c5746f816ee, ghrun-uabpkrvm6i.auto.internal:24484): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 08:41:46,649 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ebff4cb6-92ee-440d-8b5b-0c5746f816ee, ghrun-uabpkrvm6i.auto.internal:24484): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MmUzYzRkYzctOWU0YjM1MWYtY2EzZjgwNjgtNjI3ZDEzNWI=" } } } }
2025-10-31 08:41:46,653 - 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 08:41:46,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 895bf6d0-1a8e-4e6e-a1b6-8b1246cad13f, ghrun-uabpkrvm6i.auto.internal:24484): creating call state
2025-10-31 08:41:46,654 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 895bf6d0-1a8e-4e6e-a1b6-8b1246cad13f, ghrun-uabpkrvm6i.auto.internal:24484): request = { session_id: "ydb://session/3?node_id=1&id=MmUzYzRkYzctOWU0YjM1MWYtY2EzZjgwNjgtNjI3ZDEzNWI=" 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 08:41:46,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 895bf6d0-1a8e-4e6e-a1b6-8b1246cad13f, ghrun-uabpkrvm6i.auto.internal:24484): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:41:46,697 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydb/ydb
2025-10-31 08:41:46,698 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 08:41:46,699 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 08:41:46,699 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:24484', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-row]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/pogg/001e66/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001e66/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001e66/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001e66/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:41:46,700 - DEBUG - ya.test - execute: Command pid: 3247397
2025-10-31 08:41:46,888 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) rc: 0
2025-10-31 08:41:46,888 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) elapsed time (sec): 0.18661236763000488
2025-10-31 08:41:46,888 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) idrss: 0
2025-10-31 08:41:46,888 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) inblock: 0
2025-10-31 08:41:46,888 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) isrss: 0
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) ixrss: 0
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) majflt: 0
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) maxrss: 184408
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) minflt: 4417
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) msgrcv: 0
2025-10-31 08:41:46,889 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) msgsnd: 0
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) nivcsw: 19
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) nsignals: 0
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) nswap: 0
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) nvcsw: 915
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) oublock: 48
2025-10-31 08:41:46,890 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) stime: 0.052916
2025-10-31 08:41:46,891 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) utime: 0.07055399999999999
2025-10-31 08:41:46,891 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) wtime: 0.188
2025-10-31 08:41:46,891 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) output:

2025-10-31 08:41:46,891 - DEBUG - ya.test - _save_outputs: Command (pid 3247397) errors:
Elapsed: 0.0154 sec. Total read size: 290B. Average processing speed: 18.4KiB/s.


2025-10-31 08:41:46,891 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:41:46,892 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydb/ydb
2025-10-31 08:41:46,893 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 08:41:46,893 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001e66/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:24484', '--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 08:41:46,894 - DEBUG - ya.test - execute: Command pid: 3247839
2025-10-31 08:41:47,055 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) rc: 0
2025-10-31 08:41:47,056 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) elapsed time (sec): 0.1608562469482422
2025-10-31 08:41:47,056 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) idrss: 0
2025-10-31 08:41:47,056 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) inblock: 0
2025-10-31 08:41:47,056 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) isrss: 0
2025-10-31 08:41:47,057 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) ixrss: 0
2025-10-31 08:41:47,057 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) majflt: 0
2025-10-31 08:41:47,057 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) maxrss: 184408
2025-10-31 08:41:47,057 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) minflt: 3439
2025-10-31 08:41:47,057 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) msgrcv: 0
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) msgsnd: 0
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) nivcsw: 8
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) nsignals: 0
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) nswap: 0
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) nvcsw: 174
2025-10-31 08:41:47,058 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) oublock: 8
2025-10-31 08:41:47,059 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) stime: 0.033902999999999996
2025-10-31 08:41:47,059 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) utime: 0.046030999999999996
2025-10-31 08:41:47,059 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) wtime: 0.162
2025-10-31 08:41:47,059 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) 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 08:41:47,059 - DEBUG - ya.test - _save_outputs: Command (pid 3247839) errors:

2025-10-31 08:41:47,060 - 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 08:41:47,062 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:42:10,870 - 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 08:42:10,876 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-row] status: good
