2025-11-01 16:49:51,551 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:49:51,552 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-row]
2025-11-01 16:49:51,561 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:49:51,561 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:49:51,638 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd
2025-11-01 16:49:51,656 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64865
2025-11-01 16:49:51,671 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/q6a6/001987/r3tmp/kikimr_cluster_fgmg5b24
2025-11-01 16:49:51,769 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd
2025-11-01 16:49:51,776 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13499
2025-11-01 16:49:51,780 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25419
2025-11-01 16:49:51,781 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10055
2025-11-01 16:49:51,782 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4429
2025-11-01 16:49:51,785 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/001987/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-11-01 16:49:51,786 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/001987/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/q6a6/001987/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_4q_u_v06.log --grpc-port=13499 --mon-port=25419 --ic-port=64865
2025-11-01 16:49:51,787 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:13499/1, disk_size 68719476736
2025-11-01 16:49:51,791 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/001987/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-11-01 16:49:51,792 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/001987/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/q6a6/001987/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_4q_u_v06.log --grpc-port=13499 --mon-port=25419 --ic-port=64865
2025-11-01 16:49:51,795 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/001987/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/q6a6/001987/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_4q_u_v06.log', '--grpc-port=13499', '--mon-port=25419', '--ic-port=64865')' in '/home/runner/.ya/build/build_root/q6a6/001987/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-11-01 16:49:51,798 - DEBUG - ya.test - execute: Command pid: 3197299
2025-11-01 16:49:51,800 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:13499/1
2025-11-01 16:49:51,803 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:49:51,804 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:49:51,804 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:49:51,807 - DEBUG - ya.test - execute: Command pid: 3197302
2025-11-01 16:49:53,446 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) rc: 0
2025-11-01 16:49:53,446 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) elapsed time (sec): 1.6381633281707764
2025-11-01 16:49:53,447 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) idrss: 0
2025-11-01 16:49:53,451 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) inblock: 0
2025-11-01 16:49:53,451 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) isrss: 0
2025-11-01 16:49:53,455 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) ixrss: 0
2025-11-01 16:49:53,455 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) majflt: 0
2025-11-01 16:49:53,456 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) maxrss: 538512
2025-11-01 16:49:53,456 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) minflt: 16134
2025-11-01 16:49:53,457 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) msgrcv: 0
2025-11-01 16:49:53,457 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) msgsnd: 0
2025-11-01 16:49:53,461 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) nivcsw: 375
2025-11-01 16:49:53,461 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) nsignals: 0
2025-11-01 16:49:53,463 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) nswap: 0
2025-11-01 16:49:53,463 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) nvcsw: 46
2025-11-01 16:49:53,465 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) oublock: 8
2025-11-01 16:49:53,465 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) stime: 0.796281
2025-11-01 16:49:53,468 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) utime: 0.118832
2025-11-01 16:49:53,471 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) wtime: 1.641
2025-11-01 16:49:53,472 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 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 16:49:53,477 - DEBUG - ya.test - _save_outputs: Command (pid 3197302) errors:

2025-11-01 16:49:53,478 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:49:57,637 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd
2025-11-01 16:49:57,638 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13499', '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: 64865\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:49:57,640 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:49:57,641 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:49:57,641 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13499', '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: 64865\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:49:57,647 - DEBUG - ya.test - execute: Command pid: 3203040
2025-11-01 16:49:59,074 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) rc: 0
2025-11-01 16:49:59,079 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) elapsed time (sec): 1.4179973602294922
2025-11-01 16:49:59,080 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) idrss: 0
2025-11-01 16:49:59,080 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) inblock: 0
2025-11-01 16:49:59,081 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) isrss: 0
2025-11-01 16:49:59,081 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) ixrss: 0
2025-11-01 16:49:59,081 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) majflt: 0
2025-11-01 16:49:59,082 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) maxrss: 546940
2025-11-01 16:49:59,082 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) minflt: 18278
2025-11-01 16:49:59,082 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) msgrcv: 0
2025-11-01 16:49:59,083 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) msgsnd: 0
2025-11-01 16:49:59,084 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) nivcsw: 267
2025-11-01 16:49:59,085 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) nsignals: 0
2025-11-01 16:49:59,088 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) nswap: 0
2025-11-01 16:49:59,089 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) nvcsw: 142
2025-11-01 16:49:59,090 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) oublock: 8
2025-11-01 16:49:59,090 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) stime: 1.066371
2025-11-01 16:49:59,090 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) utime: 0.126181
2025-11-01 16:49:59,093 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) wtime: 1.428
2025-11-01 16:49:59,093 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 16:49:59,097 - DEBUG - ya.test - _save_outputs: Command (pid 3203040) errors:

2025-11-01 16:49:59,099 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd
2025-11-01 16:49:59,107 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13499', '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 16:49:59,109 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:49:59,110 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:49:59,110 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:13499', '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 16:49:59,127 - DEBUG - ya.test - execute: Command pid: 3204333
2025-11-01 16:50:00,552 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) rc: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) elapsed time (sec): 1.4232068061828613
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) idrss: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) inblock: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) isrss: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) ixrss: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) majflt: 0
2025-11-01 16:50:00,553 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) maxrss: 546964
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) minflt: 18276
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) msgrcv: 0
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) msgsnd: 0
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) nivcsw: 326
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) nsignals: 0
2025-11-01 16:50:00,554 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) nswap: 0
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) nvcsw: 132
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) oublock: 8
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) stime: 0.7236309999999999
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) utime: 0.133275
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) wtime: 1.44
2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:50:00,555 - DEBUG - ya.test - _save_outputs: Command (pid 3204333) errors:

2025-11-01 16:50:00,556 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:50:00,590 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:50:00,615 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:50:00,618 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:50:00,618 - 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 16:50:00,633 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:50:00,634 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cc5bef09-f635-44c1-a425-b75c46d6514f, localhost:13499): creating call state
2025-11-01 16:50:00,634 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cc5bef09-f635-44c1-a425-b75c46d6514f, localhost:13499): request = { database: "/Root" }
2025-11-01 16:50:00,637 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cc5bef09-f635-44c1-a425-b75c46d6514f, localhost:13499): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 13499 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 16:50:00,638 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:13499, location 1, ssl: False>]>
2025-11-01 16:50:00,638 - 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 16:50:00,646 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13499
2025-11-01 16:50:00,648 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f9a54a3d-9141-474a-9460-3253ca7f65b4, ghrun-tvlw7frnpa.auto.internal:13499): creating call state
2025-11-01 16:50:00,648 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f9a54a3d-9141-474a-9460-3253ca7f65b4, ghrun-tvlw7frnpa.auto.internal:13499): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:50:00,650 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f9a54a3d-9141-474a-9460-3253ca7f65b4, ghrun-tvlw7frnpa.auto.internal:13499): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWU3ZThlMi0zOGM3YmY1Yy1kMTBiNGQzYi1jNzBhMWI2MA==" } } } }
2025-11-01 16:50:00,654 - 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-11-01 16:50:00,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, f688687d-5a06-4c47-9f5d-aa2ff7ecdd0a, ghrun-tvlw7frnpa.auto.internal:13499): creating call state
2025-11-01 16:50:00,655 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, f688687d-5a06-4c47-9f5d-aa2ff7ecdd0a, ghrun-tvlw7frnpa.auto.internal:13499): request = { session_id: "ydb://session/3?node_id=1&id=OWU3ZThlMi0zOGM3YmY1Yy1kMTBiNGQzYi1jNzBhMWI2MA==" 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-11-01 16:50:00,708 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, f688687d-5a06-4c47-9f5d-aa2ff7ecdd0a, ghrun-tvlw7frnpa.auto.internal:13499): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:50:00,711 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb
2025-11-01 16:50:00,712 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-01 16:50:00,712 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-01 16:50:00,713 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13499', '--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/q6a6/001987/tmp/test_multiple_files_and_column0/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column0/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column0/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column0/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 16:50:00,721 - DEBUG - ya.test - execute: Command pid: 3206124
2025-11-01 16:50:01,031 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) rc: 0
2025-11-01 16:50:01,031 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) elapsed time (sec): 0.30847716331481934
2025-11-01 16:50:01,031 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) idrss: 0
2025-11-01 16:50:01,031 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) inblock: 0
2025-11-01 16:50:01,031 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) isrss: 0
2025-11-01 16:50:01,032 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) ixrss: 0
2025-11-01 16:50:01,032 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) majflt: 0
2025-11-01 16:50:01,032 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) maxrss: 195640
2025-11-01 16:50:01,032 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) minflt: 4163
2025-11-01 16:50:01,033 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) msgrcv: 0
2025-11-01 16:50:01,033 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) msgsnd: 0
2025-11-01 16:50:01,033 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) nivcsw: 42
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) nsignals: 0
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) nswap: 0
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) nvcsw: 550
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) oublock: 80
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) stime: 0.053473
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) utime: 0.056991999999999994
2025-11-01 16:50:01,034 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) wtime: 0.317
2025-11-01 16:50:01,035 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) output:

2025-11-01 16:50:01,035 - DEBUG - ya.test - _save_outputs: Command (pid 3206124) errors:
Elapsed: 0.0251 sec. Total read size: 290B. Average processing speed: 11.3KiB/s.


2025-11-01 16:50:01,039 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 16:50:01,040 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb
2025-11-01 16:50:01,041 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-01 16:50:01,041 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13499', '--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-11-01 16:50:01,042 - DEBUG - ya.test - execute: Command pid: 3206436
2025-11-01 16:50:01,213 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) rc: 0
2025-11-01 16:50:01,213 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) elapsed time (sec): 0.17047834396362305
2025-11-01 16:50:01,213 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) idrss: 0
2025-11-01 16:50:01,214 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) inblock: 0
2025-11-01 16:50:01,214 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) isrss: 0
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) ixrss: 0
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) majflt: 0
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) maxrss: 195640
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) minflt: 3433
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) msgrcv: 0
2025-11-01 16:50:01,215 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) msgsnd: 0
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) nivcsw: 25
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) nsignals: 0
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) nswap: 0
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) nvcsw: 149
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) oublock: 8
2025-11-01 16:50:01,216 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) stime: 0.036791
2025-11-01 16:50:01,217 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) utime: 0.036791
2025-11-01 16:50:01,217 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) wtime: 0.172
2025-11-01 16:50:01,217 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) 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 16:50:01,217 - DEBUG - ya.test - _save_outputs: Command (pid 3206436) errors:

2025-11-01 16:50:01,217 - 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 16:50:01,220 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:50:25,262 - 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-11-01 16:50:25,267 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] status: good
