2026-01-08 02:56:39,556 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:39,556 - INFO - ya.test.2397631  - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-row]
2026-01-08 02:56:39,556 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:39,556 - INFO - ya.test.2397631  - pytest_runtest_setup: Test setup
2026-01-08 02:56:39,597 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd
2026-01-08 02:56:39,598 - DEBUG - library.python.filelock.2397631  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26630
2026-01-08 02:56:39,599 - DEBUG - ydb.tests.library.harness.kikimr_runner.2397631  - start: Working directory: /home/runner/.ya/build/build_root/pp6n/0033f3/r3tmp/kikimr_cluster_uy6yantx
2026-01-08 02:56:39,621 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd
2026-01-08 02:56:39,621 - DEBUG - library.python.filelock.2397631  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13093
2026-01-08 02:56:39,622 - DEBUG - library.python.filelock.2397631  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10937
2026-01-08 02:56:39,622 - DEBUG - library.python.filelock.2397631  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4465
2026-01-08 02:56:39,622 - DEBUG - library.python.filelock.2397631  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24815
2026-01-08 02:56:39,622 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs"
2026-01-08 02:56:39,623 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pp6n/0033f3/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/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_jzhy095r.log --grpc-port=13093 --mon-port=10937 --ic-port=26630
2026-01-08 02:56:39,623 - DEBUG - ydb.tests.library.harness.kikimr_runner.2397631  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:13093/1, disk_size 68719476736
2026-01-08 02:56:39,623 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs"
2026-01-08 02:56:39,623 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pp6n/0033f3/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/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_jzhy095r.log --grpc-port=13093 --mon-port=10937 --ic-port=26630
2026-01-08 02:56:39,624 - DEBUG - ya.test.2397631  - execute: Executing '('/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1/logfile_jzhy095r.log', '--grpc-port=13093', '--mon-port=10937', '--ic-port=26630')' in '/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk19/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files_and_columns_opt.tsv-additional_args2-row/cluster/node_1' (no wait)
2026-01-08 02:56:39,625 - DEBUG - ya.test.2397631  - execute: Command pid: 2398504
2026-01-08 02:56:39,625 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - start: Started node localhost:13093/1
2026-01-08 02:56:39,626 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydbd.out
2026-01-08 02:56:39,626 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydbd.err
2026-01-08 02:56:39,626 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2026-01-08 02:56:39,627 - DEBUG - ya.test.2397631  - execute: Command pid: 2398505
2026-01-08 02:56:40,012 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) rc: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) elapsed time (sec): 0.38489437103271484
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) idrss: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) inblock: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) isrss: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) ixrss: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) majflt: 0
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) maxrss: 556284
2026-01-08 02:56:40,013 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) minflt: 16421
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) msgrcv: 0
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) msgsnd: 0
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) nivcsw: 1
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) nsignals: 0
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) nswap: 0
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) nvcsw: 18
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) oublock: 8
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) stime: 0.25252199999999997
2026-01-08 02:56:40,014 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) utime: 0.084174
2026-01-08 02:56:40,015 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) wtime: 0.386
2026-01-08 02:56:40,015 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) output:
Git info:
    Commit: 2498974ca152b7a4dd22679e7971857d09904db4
    Branch: 
    Author: Hor911 <hor911@ydb.tech>
    Summary: Merge 7aca38f946c83b69d6e02768574adc4e08ea0f50 into 05744b79f6132ebbd8de22eb67c4ad9b1d5acbde
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-paw7opzmb4    Host information: 
        Linux ghrun-paw7opzmb4 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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++

2026-01-08 02:56:40,015 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2398505) errors:

2026-01-08 02:56:40,015 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - start: Node 1 version:
Git info:
    Commit: 2498974ca152b7a4dd22679e7971857d09904db4
    Branch: 
    Author: Hor911 <hor911@ydb.tech>
    Summary: Merge 7aca38f946c83b69d6e02768574adc4e08ea0f50 into 05744b79f6132ebbd8de22eb67c4ad9b1d5acbde
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-paw7opzmb4    Host information: 
        Linux ghrun-paw7opzmb4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2026-01-08 02:56:41,715 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd
2026-01-08 02:56:41,715 - DEBUG - ydb.tests.library.harness.kikimr_runner.2397631  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13093', '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: 26630\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2026-01-08 02:56:41,716 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydbd.out.0
2026-01-08 02:56:41,716 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydbd.err.0
2026-01-08 02:56:41,716 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13093', '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: 26630\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2026-01-08 02:56:41,717 - DEBUG - ya.test.2397631  - execute: Command pid: 2401180
2026-01-08 02:56:42,115 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) rc: 0
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) elapsed time (sec): 0.3977773189544678
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) idrss: 0
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) inblock: 0
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) isrss: 0
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) ixrss: 0
2026-01-08 02:56:42,116 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) majflt: 0
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) maxrss: 564736
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) minflt: 18559
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) msgrcv: 0
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) msgsnd: 0
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) nivcsw: 1
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) nsignals: 0
2026-01-08 02:56:42,117 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) nswap: 0
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) nvcsw: 130
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) oublock: 8
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) stime: 0.273067
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) utime: 0.080313
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) wtime: 0.399
2026-01-08 02:56:42,118 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2026-01-08 02:56:42,119 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401180) errors:

2026-01-08 02:56:42,119 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd
2026-01-08 02:56:42,120 - DEBUG - ydb.tests.library.harness.kikimr_runner.2397631  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13093', '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']
2026-01-08 02:56:42,120 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydbd.out.1
2026-01-08 02:56:42,121 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydbd.err.1
2026-01-08 02:56:42,121 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13093', '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)
2026-01-08 02:56:42,122 - DEBUG - ya.test.2397631  - execute: Command pid: 2401399
2026-01-08 02:56:42,542 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) rc: 0
2026-01-08 02:56:42,542 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) elapsed time (sec): 0.4163661003112793
2026-01-08 02:56:42,542 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) idrss: 0
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) inblock: 0
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) isrss: 0
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) ixrss: 0
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) majflt: 0
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) maxrss: 564728
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) minflt: 18558
2026-01-08 02:56:42,543 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) msgrcv: 0
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) msgsnd: 0
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) nivcsw: 1
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) nsignals: 0
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) nswap: 0
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) nvcsw: 133
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) oublock: 8
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) stime: 0.260173
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) utime: 0.112565
2026-01-08 02:56:42,544 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) wtime: 0.417
2026-01-08 02:56:42,545 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2026-01-08 02:56:42,545 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401399) errors:

2026-01-08 02:56:42,545 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2026-01-08 02:56:42,557 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - __run: Cluster started and initialized
2026-01-08 02:56:42,564 - DEBUG - ydb.connection.2397631  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2026-01-08 02:56:42,566 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2397631  - context_resolve: Preparing initial endpoint to resolve endpoints
2026-01-08 02:56:42,568 - DEBUG - ydb.connection.2397631  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2026-01-08 02:56:42,578 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2397631  - context_resolve: Resolving endpoints for database /Root
2026-01-08 02:56:42,579 - DEBUG - ydb.connection.2397631  - _prepare_call: RpcState(ListEndpoints, 1b3e83fd-7ae0-423b-8247-2ed0f140c1f0, localhost:13093): creating call state
2026-01-08 02:56:42,579 - DEBUG - ydb.connection.2397631  - _log_request: RpcState(ListEndpoints, 1b3e83fd-7ae0-423b-8247-2ed0f140c1f0, localhost:13093): request = { database: "/Root" }
2026-01-08 02:56:42,582 - DEBUG - ydb.connection.2397631  - _log_response: RpcState(ListEndpoints, 1b3e83fd-7ae0-423b-8247-2ed0f140c1f0, localhost:13093): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-paw7opzmb4.auto.internal" port: 13093 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" } } } }
2026-01-08 02:56:42,582 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2397631  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-paw7opzmb4.auto.internal:13093, location 1, ssl: False>]>
2026-01-08 02:56:42,582 - DEBUG - ydb.connection.2397631  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2026-01-08 02:56:42,589 - DEBUG - ydb.connection.2397631  - close: Closing channel for endpoint localhost:13093
2026-01-08 02:56:42,590 - DEBUG - ydb.connection.2397631  - _prepare_call: RpcState(CreateSession, f9aed6cd-5971-415b-b6f8-8ea4af9ea0d2, ghrun-paw7opzmb4.auto.internal:13093): creating call state
2026-01-08 02:56:42,590 - DEBUG - ydb.connection.2397631  - _log_request: RpcState(CreateSession, f9aed6cd-5971-415b-b6f8-8ea4af9ea0d2, ghrun-paw7opzmb4.auto.internal:13093): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-01-08 02:56:42,592 - DEBUG - ydb.connection.2397631  - _log_response: RpcState(CreateSession, f9aed6cd-5971-415b-b6f8-8ea4af9ea0d2, ghrun-paw7opzmb4.auto.internal:13093): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZWJhZTExNTItZTUwNzkxM2ItNWFjMDc3MS1jODUyY2I0OQ==" } } } }
2026-01-08 02:56:42,595 - INFO - ya.test.2397631  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_columns_opt[tsv-additional_args2-row])
2026-01-08 02:56:42,595 - DEBUG - ydb.connection.2397631  - _prepare_call: RpcState(ExecuteSchemeQuery, 57cfc7c0-14f4-4f54-8cb4-305f05dd21ad, ghrun-paw7opzmb4.auto.internal:13093): creating call state
2026-01-08 02:56:42,596 - DEBUG - ydb.connection.2397631  - _log_request: RpcState(ExecuteSchemeQuery, 57cfc7c0-14f4-4f54-8cb4-305f05dd21ad, ghrun-paw7opzmb4.auto.internal:13093): request = { session_id: "ydb://session/3?node_id=1&id=ZWJhZTExNTItZTUwNzkxM2ItNWFjMDc3MS1jODUyY2I0OQ==" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-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 } } }
2026-01-08 02:56:42,633 - DEBUG - ydb.connection.2397631  - _log_response: RpcState(ExecuteSchemeQuery, 57cfc7c0-14f4-4f54-8cb4-305f05dd21ad, ghrun-paw7opzmb4.auto.internal:13093): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:56:42,634 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb
2026-01-08 02:56:42,635 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydb.out
2026-01-08 02:56:42,635 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err
2026-01-08 02:56:42,635 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13093', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-row]', '--header', '--columns', 'key\tid\tvalue\n', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column0/tempinput0.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column0/tempinput1.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column0/tempinput2.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column0/tempinput3.tsv']' in 'None' (waiting)
2026-01-08 02:56:42,636 - DEBUG - ya.test.2397631  - execute: Command pid: 2401734
2026-01-08 02:56:42,762 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) rc: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) elapsed time (sec): 0.12595272064208984
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) idrss: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) inblock: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) isrss: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) ixrss: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) majflt: 0
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) maxrss: 183884
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) minflt: 4266
2026-01-08 02:56:42,763 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) msgrcv: 0
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) msgsnd: 0
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) nivcsw: 0
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) nsignals: 0
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) nswap: 0
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) nvcsw: 523
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) oublock: 80
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) stime: 0.044357
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) utime: 0.035484999999999996
2026-01-08 02:56:42,764 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) wtime: 0.127
2026-01-08 02:56:42,765 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) output:

2026-01-08 02:56:42,765 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401734) errors:
Elapsed: 0.00361 sec. Total read size: 290B. Average processing speed: 78.5KiB/s.


2026-01-08 02:56:42,765 - DEBUG - __tests__.test_ydb_impex.2397631  - execute_ydb_cli_command: std_out:

2026-01-08 02:56:42,765 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb
2026-01-08 02:56:42,766 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err.0
2026-01-08 02:56:42,766 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13093', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2026-01-08 02:56:42,767 - DEBUG - ya.test.2397631  - execute: Command pid: 2401999
2026-01-08 02:56:42,891 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) rc: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) elapsed time (sec): 0.12383389472961426
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) idrss: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) inblock: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) isrss: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) ixrss: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) majflt: 0
2026-01-08 02:56:42,892 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) maxrss: 183884
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) minflt: 3565
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) msgrcv: 0
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) msgsnd: 0
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) nivcsw: 0
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) nsignals: 0
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) nswap: 0
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) nvcsw: 194
2026-01-08 02:56:42,893 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) oublock: 8
2026-01-08 02:56:42,894 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) stime: 0.023152
2026-01-08 02:56:42,894 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) utime: 0.032414
2026-01-08 02:56:42,894 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) wtime: 0.125
2026-01-08 02:56:42,894 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) 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"

2026-01-08 02:56:42,894 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2401999) errors:

2026-01-08 02:56:42,894 - DEBUG - __tests__.test_ydb_impex.2397631  - 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"

2026-01-08 02:56:42,896 - INFO - ya.test.2397631  - pytest_runtest_teardown: Test teardown
2026-01-08 02:57:06,027 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:57:06,030 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] status: good
