2025-10-31 15:33:41,782 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:41,782 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args2-column]
2025-10-31 15:33:41,782 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:41,783 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:33:41,843 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd
2025-10-31 15:33:41,844 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18284
2025-10-31 15:33:41,845 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/stm2/002d68/r3tmp/kikimr_cluster_9v9ucdfv
2025-10-31 15:33:41,873 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd
2025-10-31 15:33:41,874 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5082
2025-10-31 15:33:41,874 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31936
2025-10-31 15:33:41,875 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24003
2025-10-31 15:33:41,875 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22898
2025-10-31 15:33:41,875 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002d68/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-10-31 15:33:41,876 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002d68/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/002d68/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_uzj4nvv_.log --grpc-port=5082 --mon-port=31936 --ic-port=18284
2025-10-31 15:33:41,876 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5082/1, disk_size 68719476736
2025-10-31 15:33:41,876 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/002d68/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-10-31 15:33:41,876 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/002d68/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/002d68/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_uzj4nvv_.log --grpc-port=5082 --mon-port=31936 --ic-port=18284
2025-10-31 15:33:41,877 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/stm2/002d68/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/stm2/002d68/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_uzj4nvv_.log', '--grpc-port=5082', '--mon-port=31936', '--ic-port=18284')' in '/home/runner/.ya/build/build_root/stm2/002d68/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-10-31 15:33:41,878 - DEBUG - ya.test - execute: Command pid: 2769230
2025-10-31 15:33:41,879 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5082/1
2025-10-31 15:33:41,880 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 15:33:41,880 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 15:33:41,880 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 15:33:41,881 - DEBUG - ya.test - execute: Command pid: 2769231
2025-10-31 15:33:42,524 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) rc: 0
2025-10-31 15:33:42,524 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) elapsed time (sec): 0.6418366432189941
2025-10-31 15:33:42,524 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) idrss: 0
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) inblock: 0
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) isrss: 0
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) ixrss: 0
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) majflt: 0
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) maxrss: 538348
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) minflt: 16132
2025-10-31 15:33:42,525 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) msgrcv: 0
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) msgsnd: 0
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) nivcsw: 12
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) nsignals: 0
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) nswap: 0
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) nvcsw: 15
2025-10-31 15:33:42,526 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) oublock: 8
2025-10-31 15:33:42,527 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) stime: 0.49302599999999996
2025-10-31 15:33:42,527 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) utime: 0.10020799999999999
2025-10-31 15:33:42,527 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) wtime: 0.643
2025-10-31 15:33:42,527 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) 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:33:42,527 - DEBUG - ya.test - _save_outputs: Command (pid 2769231) errors:

2025-10-31 15:33:42,528 - 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:33:44,337 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd
2025-10-31 15:33:44,337 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5082', '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: 18284\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 15:33:44,338 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 15:33:44,339 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 15:33:44,339 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5082', '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: 18284\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:33:44,340 - DEBUG - ya.test - execute: Command pid: 2771666
2025-10-31 15:33:44,823 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) rc: 0
2025-10-31 15:33:44,823 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) elapsed time (sec): 0.482072114944458
2025-10-31 15:33:44,823 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) idrss: 0
2025-10-31 15:33:44,823 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) inblock: 0
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) isrss: 0
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) ixrss: 0
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) majflt: 0
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) maxrss: 546820
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) minflt: 18275
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) msgrcv: 0
2025-10-31 15:33:44,824 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) msgsnd: 0
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) nivcsw: 8
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) nsignals: 0
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) nswap: 0
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) nvcsw: 136
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) oublock: 8
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) stime: 0.323652
2025-10-31 15:33:44,825 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) utime: 0.11587499999999999
2025-10-31 15:33:44,826 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) wtime: 0.483
2025-10-31 15:33:44,826 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 15:33:44,826 - DEBUG - ya.test - _save_outputs: Command (pid 2771666) errors:

2025-10-31 15:33:44,827 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd
2025-10-31 15:33:44,827 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5082', '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:33:44,828 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 15:33:44,828 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 15:33:44,828 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5082', '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:33:44,829 - DEBUG - ya.test - execute: Command pid: 2772459
2025-10-31 15:33:45,328 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) rc: 0
2025-10-31 15:33:45,328 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) elapsed time (sec): 0.49784111976623535
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) idrss: 0
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) inblock: 0
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) isrss: 0
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) ixrss: 0
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) majflt: 0
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) maxrss: 546816
2025-10-31 15:33:45,329 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) minflt: 18273
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) msgrcv: 0
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) msgsnd: 0
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) nivcsw: 4
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) nsignals: 0
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) nswap: 0
2025-10-31 15:33:45,330 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) nvcsw: 153
2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) oublock: 8
2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) stime: 0.338461
2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) utime: 0.115475
2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) wtime: 0.499
2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 15:33:45,331 - DEBUG - ya.test - _save_outputs: Command (pid 2772459) errors:

2025-10-31 15:33:45,332 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 15:33:45,344 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 15:33:45,354 - 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:33:45,356 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 15:33:45,358 - 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:33:45,365 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 15:33:45,366 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 412504df-9ba1-4a82-adf6-2a0dd6e51872, localhost:5082): creating call state
2025-10-31 15:33:45,366 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 412504df-9ba1-4a82-adf6-2a0dd6e51872, localhost:5082): request = { database: "/Root" }
2025-10-31 15:33:45,369 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 412504df-9ba1-4a82-adf6-2a0dd6e51872, localhost:5082): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-upzbhs6y6e.auto.internal" port: 5082 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:33:45,369 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-upzbhs6y6e.auto.internal:5082, location 1, ssl: False>]>
2025-10-31 15:33:45,370 - 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:33:45,376 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:5082
2025-10-31 15:33:45,377 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1dc8d872-ee2e-47ec-b104-96cac563d6a6, ghrun-upzbhs6y6e.auto.internal:5082): creating call state
2025-10-31 15:33:45,377 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1dc8d872-ee2e-47ec-b104-96cac563d6a6, ghrun-upzbhs6y6e.auto.internal:5082): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:33:45,380 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1dc8d872-ee2e-47ec-b104-96cac563d6a6, ghrun-upzbhs6y6e.auto.internal:5082): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjFhOTgzNWItNWQ5NjU1NzgtMjAyMzk3ZmMtNzliZmZmMDA=" } } } }
2025-10-31 15:33:45,383 - 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-10-31 15:33:45,383 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 10797d1b-b36b-4582-8489-729cdcf4d2c0, ghrun-upzbhs6y6e.auto.internal:5082): creating call state
2025-10-31 15:33:45,384 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 10797d1b-b36b-4582-8489-729cdcf4d2c0, ghrun-upzbhs6y6e.auto.internal:5082): request = { session_id: "ydb://session/3?node_id=1&id=ZjFhOTgzNWItNWQ5NjU1NzgtMjAyMzk3ZmMtNzliZmZmMDA=" 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-10-31 15:33:45,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 10797d1b-b36b-4582-8489-729cdcf4d2c0, ghrun-upzbhs6y6e.auto.internal:5082): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:45,678 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb
2025-10-31 15:33:45,678 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 15:33:45,679 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 15:33:45,679 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5082', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args2-column]', '--header', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:33:45,680 - DEBUG - ya.test - execute: Command pid: 2773191
2025-10-31 15:33:45,825 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) rc: 0
2025-10-31 15:33:45,825 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) elapsed time (sec): 0.14452505111694336
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) idrss: 0
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) inblock: 0
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) isrss: 0
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) ixrss: 0
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) majflt: 0
2025-10-31 15:33:45,826 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) maxrss: 184476
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) minflt: 4165
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) msgrcv: 0
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) msgsnd: 0
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) nivcsw: 5
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) nsignals: 0
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) nswap: 0
2025-10-31 15:33:45,827 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) nvcsw: 520
2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) oublock: 80
2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) stime: 0.03442
2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) utime: 0.053542
2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) wtime: 0.146
2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) output:

2025-10-31 15:33:45,828 - DEBUG - ya.test - _save_outputs: Command (pid 2773191) errors:
Elapsed: 0.0186 sec. Total read size: 290B. Average processing speed: 15.2KiB/s.


2025-10-31 15:33:45,829 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:33:45,829 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb
2025-10-31 15:33:45,831 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 15:33:45,832 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5082', '--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-10-31 15:33:45,833 - DEBUG - ya.test - execute: Command pid: 2773526
2025-10-31 15:33:46,032 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) rc: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) elapsed time (sec): 0.1980443000793457
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) idrss: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) inblock: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) isrss: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) ixrss: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) majflt: 0
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) maxrss: 184476
2025-10-31 15:33:46,033 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) minflt: 3439
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) msgrcv: 0
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) msgsnd: 0
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) nivcsw: 1
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) nsignals: 0
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) nswap: 0
2025-10-31 15:33:46,034 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) nvcsw: 192
2025-10-31 15:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) oublock: 8
2025-10-31 15:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) stime: 0.017903
2025-10-31 15:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) utime: 0.046626
2025-10-31 15:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) wtime: 0.2
2025-10-31 15:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) 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:33:46,035 - DEBUG - ya.test - _save_outputs: Command (pid 2773526) errors:

2025-10-31 15:33:46,035 - 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:33:46,037 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:34:09,683 - 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-10-31 15:34:09,693 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] status: good
