2025-11-01 04:29:49,513 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:29:49,517 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args2-column]
2025-11-01 04:29:49,517 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:29:49,518 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:29:49,611 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd
2025-11-01 04:29:49,612 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21291
2025-11-01 04:29:49,612 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/g81u/004d80/r3tmp/kikimr_cluster_8gu6hdd9
2025-11-01 04:29:49,657 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd
2025-11-01 04:29:49,658 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28853
2025-11-01 04:29:49,658 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27591
2025-11-01 04:29:49,659 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61235
2025-11-01 04:29:49,659 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26477
2025-11-01 04:29:49,660 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004d80/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 04:29:49,660 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/g81u/004d80/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_uu2psclq.log --grpc-port=28853 --mon-port=27591 --ic-port=21291
2025-11-01 04:29:49,660 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:28853/1, disk_size 68719476736
2025-11-01 04:29:49,661 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/g81u/004d80/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 04:29:49,661 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/g81u/004d80/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_uu2psclq.log --grpc-port=28853 --mon-port=27591 --ic-port=21291
2025-11-01 04:29:49,662 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/g81u/004d80/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/g81u/004d80/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_uu2psclq.log', '--grpc-port=28853', '--mon-port=27591', '--ic-port=21291')' in '/home/runner/.ya/build/build_root/g81u/004d80/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 04:29:49,664 - DEBUG - ya.test - execute: Command pid: 2895315
2025-11-01 04:29:49,665 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:28853/1
2025-11-01 04:29:49,666 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 04:29:49,666 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 04:29:49,667 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 04:29:49,668 - DEBUG - ya.test - execute: Command pid: 2895318
2025-11-01 04:29:50,205 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) rc: 0
2025-11-01 04:29:50,205 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) elapsed time (sec): 0.5365464687347412
2025-11-01 04:29:50,206 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) idrss: 0
2025-11-01 04:29:50,206 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) inblock: 0
2025-11-01 04:29:50,206 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) isrss: 0
2025-11-01 04:29:50,206 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) ixrss: 0
2025-11-01 04:29:50,206 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) majflt: 0
2025-11-01 04:29:50,207 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) maxrss: 538372
2025-11-01 04:29:50,208 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) minflt: 16200
2025-11-01 04:29:50,208 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) msgrcv: 0
2025-11-01 04:29:50,208 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) msgsnd: 0
2025-11-01 04:29:50,208 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) nivcsw: 102
2025-11-01 04:29:50,209 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) nsignals: 0
2025-11-01 04:29:50,209 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) nswap: 0
2025-11-01 04:29:50,209 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) nvcsw: 24
2025-11-01 04:29:50,209 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) oublock: 8
2025-11-01 04:29:50,210 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) stime: 0.2671
2025-11-01 04:29:50,210 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) utime: 0.13033799999999998
2025-11-01 04:29:50,210 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) wtime: 0.538
2025-11-01 04:29:50,211 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) output:
Git info:
    Commit: 767d72532e5716ba790791cf237fdadb24b0e578
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 96af589a3c3d7740081955df0fe84da0a7b35651 into 2afee58ebdae8491f810ad53605d37376c4e5de6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-i4gyxlpqdu
    Host information: 
        Linux ghrun-i4gyxlpqdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 04:29:50,211 - DEBUG - ya.test - _save_outputs: Command (pid 2895318) errors:

2025-11-01 04:29:50,211 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 767d72532e5716ba790791cf237fdadb24b0e578
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 96af589a3c3d7740081955df0fe84da0a7b35651 into 2afee58ebdae8491f810ad53605d37376c4e5de6
2025-11-01 04:29:52,555 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd
2025-11-01 04:29:52,556 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28853', '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: 21291\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 04:29:52,557 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 04:29:52,557 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 04:29:52,557 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28853', '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: 21291\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 04:29:52,559 - DEBUG - ya.test - execute: Command pid: 2897908
2025-11-01 04:29:53,324 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) rc: 0
2025-11-01 04:29:53,324 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) elapsed time (sec): 0.7643592357635498
2025-11-01 04:29:53,324 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) idrss: 0
2025-11-01 04:29:53,325 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) inblock: 0
2025-11-01 04:29:53,347 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) isrss: 0
2025-11-01 04:29:53,347 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) ixrss: 0
2025-11-01 04:29:53,347 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) majflt: 0
2025-11-01 04:29:53,347 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) maxrss: 546788
2025-11-01 04:29:53,347 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) minflt: 18279
2025-11-01 04:29:53,348 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) msgrcv: 0
2025-11-01 04:29:53,348 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) msgsnd: 0
2025-11-01 04:29:53,348 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) nivcsw: 144
2025-11-01 04:29:53,348 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) nsignals: 0
2025-11-01 04:29:53,348 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) nswap: 0
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) nvcsw: 102
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) oublock: 8
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) stime: 0.374895
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) utime: 0.120045
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) wtime: 0.766
2025-11-01 04:29:53,349 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 04:29:53,350 - DEBUG - ya.test - _save_outputs: Command (pid 2897908) errors:

2025-11-01 04:29:53,351 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd
2025-11-01 04:29:53,351 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28853', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 04:29:53,352 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 04:29:53,353 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 04:29:53,353 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28853', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 04:29:53,358 - DEBUG - ya.test - execute: Command pid: 2898580
2025-11-01 04:29:54,354 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) rc: 0
2025-11-01 04:29:54,355 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) elapsed time (sec): 0.9952270984649658
2025-11-01 04:29:54,355 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) idrss: 0
2025-11-01 04:29:54,355 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) inblock: 0
2025-11-01 04:29:54,355 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) isrss: 0
2025-11-01 04:29:54,356 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) ixrss: 0
2025-11-01 04:29:54,356 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) majflt: 0
2025-11-01 04:29:54,356 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) maxrss: 546800
2025-11-01 04:29:54,356 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) minflt: 18271
2025-11-01 04:29:54,362 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) msgrcv: 0
2025-11-01 04:29:54,363 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) msgsnd: 0
2025-11-01 04:29:54,363 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) nivcsw: 148
2025-11-01 04:29:54,363 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) nsignals: 0
2025-11-01 04:29:54,363 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) nswap: 0
2025-11-01 04:29:54,363 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) nvcsw: 107
2025-11-01 04:29:54,370 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) oublock: 8
2025-11-01 04:29:54,371 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) stime: 0.477498
2025-11-01 04:29:54,374 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) utime: 0.12653499999999998
2025-11-01 04:29:54,378 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) wtime: 1.001
2025-11-01 04:29:54,380 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 04:29:54,383 - DEBUG - ya.test - _save_outputs: Command (pid 2898580) errors:

2025-11-01 04:29:54,383 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 04:29:54,411 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 04:29:54,443 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:29:54,446 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 04:29:54,446 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:29:54,469 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 04:29:54,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 582bfea6-482a-4c94-ac21-2b1db348dd05, localhost:28853): creating call state
2025-11-01 04:29:54,470 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 582bfea6-482a-4c94-ac21-2b1db348dd05, localhost:28853): request = { database: "/Root" }
2025-11-01 04:29:54,476 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 582bfea6-482a-4c94-ac21-2b1db348dd05, localhost:28853): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-i4gyxlpqdu.auto.internal" port: 28853 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 04:29:54,477 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-i4gyxlpqdu.auto.internal:28853, location 1, ssl: False>]>
2025-11-01 04:29:54,477 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 04:29:54,499 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:28853
2025-11-01 04:29:54,503 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, bc84d375-11af-44f3-8d25-535586480fac, ghrun-i4gyxlpqdu.auto.internal:28853): creating call state
2025-11-01 04:29:54,503 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, bc84d375-11af-44f3-8d25-535586480fac, ghrun-i4gyxlpqdu.auto.internal:28853): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 04:29:54,539 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, bc84d375-11af-44f3-8d25-535586480fac, ghrun-i4gyxlpqdu.auto.internal:28853): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MzZlN2M5ZTAtZTRkNWVmZmQtOTAyNGQ4MzEtM2QxY2VhNGY=" } } } }
2025-11-01 04:29:54,555 - 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 04:29:54,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 23df5581-d22a-4a24-8db8-7f52e186bad6, ghrun-i4gyxlpqdu.auto.internal:28853): creating call state
2025-11-01 04:29:54,567 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 23df5581-d22a-4a24-8db8-7f52e186bad6, ghrun-i4gyxlpqdu.auto.internal:28853): request = { session_id: "ydb://session/3?node_id=1&id=MzZlN2M5ZTAtZTRkNWVmZmQtOTAyNGQ4MzEtM2QxY2VhNGY=" 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 04:29:55,274 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 23df5581-d22a-4a24-8db8-7f52e186bad6, ghrun-i4gyxlpqdu.auto.internal:28853): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:29:55,276 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb
2025-11-01 04:29:55,282 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 04:29:55,283 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 04:29:55,283 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28853', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args2-column]', '--header', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:29:55,289 - DEBUG - ya.test - execute: Command pid: 2899952
2025-11-01 04:29:55,575 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) rc: 0
2025-11-01 04:29:55,575 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) elapsed time (sec): 0.2707507610321045
2025-11-01 04:29:55,576 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) idrss: 0
2025-11-01 04:29:55,577 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) inblock: 0
2025-11-01 04:29:55,578 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) isrss: 0
2025-11-01 04:29:55,578 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) ixrss: 0
2025-11-01 04:29:55,579 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) majflt: 0
2025-11-01 04:29:55,580 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) maxrss: 187756
2025-11-01 04:29:55,581 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) minflt: 4140
2025-11-01 04:29:55,581 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) msgrcv: 0
2025-11-01 04:29:55,599 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) msgsnd: 0
2025-11-01 04:29:55,608 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) nivcsw: 44
2025-11-01 04:29:55,609 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) nsignals: 0
2025-11-01 04:29:55,615 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) nswap: 0
2025-11-01 04:29:55,615 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) nvcsw: 534
2025-11-01 04:29:55,615 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) oublock: 80
2025-11-01 04:29:55,615 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) stime: 0.033316
2025-11-01 04:29:55,620 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) utime: 0.071393
2025-11-01 04:29:55,631 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) wtime: 0.277
2025-11-01 04:29:55,632 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) output:

2025-11-01 04:29:55,639 - DEBUG - ya.test - _save_outputs: Command (pid 2899952) errors:
Elapsed: 0.0605 sec. Total read size: 290B. Average processing speed: 4.68KiB/s.


2025-11-01 04:29:55,639 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:29:55,640 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb
2025-11-01 04:29:55,643 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 04:29:55,643 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28853', '--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 04:29:55,649 - DEBUG - ya.test - execute: Command pid: 2900347
2025-11-01 04:29:56,028 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) rc: 0
2025-11-01 04:29:56,028 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) elapsed time (sec): 0.3780708312988281
2025-11-01 04:29:56,028 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) idrss: 0
2025-11-01 04:29:56,028 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) inblock: 0
2025-11-01 04:29:56,031 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) isrss: 0
2025-11-01 04:29:56,032 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) ixrss: 0
2025-11-01 04:29:56,033 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) majflt: 0
2025-11-01 04:29:56,033 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) maxrss: 187756
2025-11-01 04:29:56,033 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) minflt: 3456
2025-11-01 04:29:56,034 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) msgrcv: 0
2025-11-01 04:29:56,041 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) msgsnd: 0
2025-11-01 04:29:56,042 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) nivcsw: 48
2025-11-01 04:29:56,042 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) nsignals: 0
2025-11-01 04:29:56,042 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) nswap: 0
2025-11-01 04:29:56,042 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) nvcsw: 131
2025-11-01 04:29:56,043 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) oublock: 8
2025-11-01 04:29:56,043 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) stime: 0.025778
2025-11-01 04:29:56,043 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) utime: 0.058856
2025-11-01 04:29:56,043 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) wtime: 0.382
2025-11-01 04:29:56,043 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-11-01 04:29:56,044 - DEBUG - ya.test - _save_outputs: Command (pid 2900347) errors:

2025-11-01 04:29:56,044 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-11-01 04:29:56,057 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:30:20,969 - 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 04:30:20,978 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] status: good
