2025-10-31 15:31:03,498 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:03,499 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-row]
2025-10-31 15:31:03,499 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:03,499 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:31:03,558 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd
2025-10-31 15:31:03,559 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3710
2025-10-31 15:31:03,560 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/stm2/00309a/r3tmp/kikimr_cluster_fc9c2c2c
2025-10-31 15:31:03,593 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd
2025-10-31 15:31:03,594 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30865
2025-10-31 15:31:03,594 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1464
2025-10-31 15:31:03,595 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6085
2025-10-31 15:31:03,595 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1636
2025-10-31 15:31:03,595 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/00309a/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"
2025-10-31 15:31:03,596 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/00309a/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/00309a/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_40wgcqpb.log --grpc-port=30865 --mon-port=1464 --ic-port=3710
2025-10-31 15:31:03,596 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:30865/1, disk_size 68719476736
2025-10-31 15:31:03,596 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/stm2/00309a/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"
2025-10-31 15:31:03,596 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/stm2/00309a/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/00309a/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_40wgcqpb.log --grpc-port=30865 --mon-port=1464 --ic-port=3710
2025-10-31 15:31:03,597 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/stm2/00309a/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/stm2/00309a/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_40wgcqpb.log', '--grpc-port=30865', '--mon-port=1464', '--ic-port=3710')' in '/home/runner/.ya/build/build_root/stm2/00309a/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)
2025-10-31 15:31:03,598 - DEBUG - ya.test - execute: Command pid: 2649049
2025-10-31 15:31:03,599 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:30865/1
2025-10-31 15:31:03,600 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 15:31:03,601 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 15:31:03,601 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 15:31:03,602 - DEBUG - ya.test - execute: Command pid: 2649050
2025-10-31 15:31:04,075 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) rc: 0
2025-10-31 15:31:04,075 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) elapsed time (sec): 0.47200942039489746
2025-10-31 15:31:04,075 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) idrss: 0
2025-10-31 15:31:04,075 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) inblock: 0
2025-10-31 15:31:04,075 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) isrss: 0
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) ixrss: 0
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) majflt: 0
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) maxrss: 538348
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) minflt: 16133
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) msgrcv: 0
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) msgsnd: 0
2025-10-31 15:31:04,076 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) nivcsw: 12
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) nsignals: 0
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) nswap: 0
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) nvcsw: 15
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) oublock: 8
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) stime: 0.327484
2025-10-31 15:31:04,077 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) utime: 0.096096
2025-10-31 15:31:04,078 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) wtime: 0.473
2025-10-31 15:31:04,078 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) 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:31:04,078 - DEBUG - ya.test - _save_outputs: Command (pid 2649050) errors:

2025-10-31 15:31:04,078 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b78d702ce2f71e40f285a60704f6a28670ea8340
    Branch: 
    Author: kungurtsev <alexey66rus@gmail.com>
    Summary: Merge f10023174585e1f3e64dac76a090d0c070f4c2a5 into 4af0266bcdc7df80879f060afb0976424f2118ed
2025-10-31 15:31:05,983 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd
2025-10-31 15:31:05,984 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30865', '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: 3710\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 15:31:05,985 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 15:31:05,986 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 15:31:05,987 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30865', '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: 3710\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:31:05,988 - DEBUG - ya.test - execute: Command pid: 2650371
2025-10-31 15:31:06,476 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) rc: 0
2025-10-31 15:31:06,477 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) elapsed time (sec): 0.4862556457519531
2025-10-31 15:31:06,477 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) idrss: 0
2025-10-31 15:31:06,477 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) inblock: 0
2025-10-31 15:31:06,477 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) isrss: 0
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) ixrss: 0
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) majflt: 0
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) maxrss: 546820
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) minflt: 18274
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) msgrcv: 0
2025-10-31 15:31:06,478 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) msgsnd: 0
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) nivcsw: 2
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) nsignals: 0
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) nswap: 0
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) nvcsw: 137
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) oublock: 8
2025-10-31 15:31:06,479 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) stime: 0.33185699999999996
2025-10-31 15:31:06,480 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) utime: 0.111743
2025-10-31 15:31:06,480 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) wtime: 0.488
2025-10-31 15:31:06,481 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 15:31:06,482 - DEBUG - ya.test - _save_outputs: Command (pid 2650371) errors:

2025-10-31 15:31:06,483 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd
2025-10-31 15:31:06,483 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30865', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-31 15:31:06,484 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 15:31:06,484 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 15:31:06,485 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30865', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 15:31:06,485 - DEBUG - ya.test - execute: Command pid: 2650735
2025-10-31 15:31:06,957 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) rc: 0
2025-10-31 15:31:06,958 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) elapsed time (sec): 0.4713618755340576
2025-10-31 15:31:06,958 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) idrss: 0
2025-10-31 15:31:06,958 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) inblock: 0
2025-10-31 15:31:06,958 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) isrss: 0
2025-10-31 15:31:06,958 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) ixrss: 0
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) majflt: 0
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) maxrss: 546824
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) minflt: 18277
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) msgrcv: 0
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) msgsnd: 0
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) nivcsw: 4
2025-10-31 15:31:06,959 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) nsignals: 0
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) nswap: 0
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) nvcsw: 135
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) oublock: 8
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) stime: 0.32376
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) utime: 0.103923
2025-10-31 15:31:06,960 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) wtime: 0.472
2025-10-31 15:31:06,961 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 15:31:06,961 - DEBUG - ya.test - _save_outputs: Command (pid 2650735) errors:

2025-10-31 15:31:06,961 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 15:31:06,974 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 15:31:06,983 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:06,985 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 15:31:06,986 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:06,994 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 15:31:06,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fcc285b5-fe60-4fb7-8ec0-24791b619fde, localhost:30865): creating call state
2025-10-31 15:31:06,995 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fcc285b5-fe60-4fb7-8ec0-24791b619fde, localhost:30865): request = { database: "/Root" }
2025-10-31 15:31:06,998 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fcc285b5-fe60-4fb7-8ec0-24791b619fde, localhost:30865): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-upzbhs6y6e.auto.internal" port: 30865 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-31 15:31:06,998 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-upzbhs6y6e.auto.internal:30865, location 1, ssl: False>]>
2025-10-31 15:31:06,998 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 15:31:07,004 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:30865
2025-10-31 15:31:07,005 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7ed5109a-535f-4c69-bd74-74576ee8ecf3, ghrun-upzbhs6y6e.auto.internal:30865): creating call state
2025-10-31 15:31:07,006 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7ed5109a-535f-4c69-bd74-74576ee8ecf3, ghrun-upzbhs6y6e.auto.internal:30865): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:31:07,007 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7ed5109a-535f-4c69-bd74-74576ee8ecf3, ghrun-upzbhs6y6e.auto.internal:30865): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NGU3MDAxYzEtNmUwNWUxZWMtYzMxNjlmNTktMzYzYWM0Y2E=" } } } }
2025-10-31 15:31:07,010 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_columns_opt[tsv-additional_args2-row])
2025-10-31 15:31:07,011 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 9c32c0b2-ac02-42c3-bb7b-1fd160a3f9a1, ghrun-upzbhs6y6e.auto.internal:30865): creating call state
2025-10-31 15:31:07,011 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 9c32c0b2-ac02-42c3-bb7b-1fd160a3f9a1, ghrun-upzbhs6y6e.auto.internal:30865): request = { session_id: "ydb://session/3?node_id=1&id=NGU3MDAxYzEtNmUwNWUxZWMtYzMxNjlmNTktMzYzYWM0Y2E=" 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 } } }
2025-10-31 15:31:07,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 9c32c0b2-ac02-42c3-bb7b-1fd160a3f9a1, ghrun-upzbhs6y6e.auto.internal:30865): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:31:07,043 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:07,044 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 15:31:07,044 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 15:31:07,044 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--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/stm2/00309a/tmp/test_multiple_files_and_column0/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column0/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column0/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:31:07,045 - DEBUG - ya.test - execute: Command pid: 2650977
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) rc: 0
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) elapsed time (sec): 0.13703155517578125
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) idrss: 0
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) inblock: 0
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) isrss: 0
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) ixrss: 0
2025-10-31 15:31:07,183 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) majflt: 0
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) maxrss: 184424
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) minflt: 4167
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) msgrcv: 0
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) msgsnd: 0
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) nivcsw: 2
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) nsignals: 0
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) nswap: 0
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) nvcsw: 526
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) oublock: 72
2025-10-31 15:31:07,184 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) stime: 0.036983999999999996
2025-10-31 15:31:07,185 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) utime: 0.041607
2025-10-31 15:31:07,185 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) wtime: 0.138
2025-10-31 15:31:07,185 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) output:

2025-10-31 15:31:07,185 - DEBUG - ya.test - _save_outputs: Command (pid 2650977) errors:
Elapsed: 0.00417 sec. Total read size: 290B. Average processing speed: 67.9KiB/s.


2025-10-31 15:31:07,185 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:31:07,185 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:07,186 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 15:31:07,186 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--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)
2025-10-31 15:31:07,187 - DEBUG - ya.test - execute: Command pid: 2651220
2025-10-31 15:31:07,319 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) rc: 0
2025-10-31 15:31:07,320 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) elapsed time (sec): 0.13207650184631348
2025-10-31 15:31:07,320 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) idrss: 0
2025-10-31 15:31:07,320 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) inblock: 0
2025-10-31 15:31:07,320 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) isrss: 0
2025-10-31 15:31:07,320 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) ixrss: 0
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) majflt: 0
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) maxrss: 184424
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) minflt: 3443
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) msgrcv: 0
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) msgsnd: 0
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) nivcsw: 0
2025-10-31 15:31:07,321 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) nsignals: 0
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) nswap: 0
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) nvcsw: 193
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) oublock: 8
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) stime: 0.019368
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) utime: 0.038736
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) wtime: 0.133
2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-10-31 15:31:07,322 - DEBUG - ya.test - _save_outputs: Command (pid 2651220) errors:

2025-10-31 15:31:07,322 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-10-31 15:31:07,324 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:31:30,765 - 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
2025-10-31 15:31:30,771 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] status: good
