2025-10-31 08:42:54,844 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:54,844 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-row]
2025-10-31 08:42:54,845 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:54,845 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:42:54,906 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd
2025-10-31 08:42:54,907 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27953
2025-10-31 08:42:54,908 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/pogg/001612/r3tmp/kikimr_cluster_zsnqazbq
2025-10-31 08:42:54,940 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd
2025-10-31 08:42:54,941 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6662
2025-10-31 08:42:54,941 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4824
2025-10-31 08:42:54,941 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25187
2025-10-31 08:42:54,941 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14684
2025-10-31 08:42:54,942 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001612/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 08:42:54,942 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001612/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/pogg/001612/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_l0knvc6j.log --grpc-port=6662 --mon-port=4824 --ic-port=27953
2025-10-31 08:42:54,942 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6662/1, disk_size 68719476736
2025-10-31 08:42:54,942 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001612/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 08:42:54,942 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001612/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/pogg/001612/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_l0knvc6j.log --grpc-port=6662 --mon-port=4824 --ic-port=27953
2025-10-31 08:42:54,943 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pogg/001612/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/pogg/001612/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_l0knvc6j.log', '--grpc-port=6662', '--mon-port=4824', '--ic-port=27953')' in '/home/runner/.ya/build/build_root/pogg/001612/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 08:42:54,944 - DEBUG - ya.test - execute: Command pid: 3305061
2025-10-31 08:42:54,945 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6662/1
2025-10-31 08:42:54,946 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 08:42:54,946 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 08:42:54,947 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 08:42:54,948 - DEBUG - ya.test - execute: Command pid: 3305062
2025-10-31 08:42:55,537 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) rc: 0
2025-10-31 08:42:55,537 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) elapsed time (sec): 0.5889713764190674
2025-10-31 08:42:55,538 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) idrss: 0
2025-10-31 08:42:55,538 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) inblock: 0
2025-10-31 08:42:55,538 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) isrss: 0
2025-10-31 08:42:55,538 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) ixrss: 0
2025-10-31 08:42:55,538 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) majflt: 0
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) maxrss: 538184
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) minflt: 16128
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) msgrcv: 0
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) msgsnd: 0
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) nivcsw: 4
2025-10-31 08:42:55,539 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) nsignals: 0
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) nswap: 0
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) nvcsw: 13
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) oublock: 8
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) stime: 0.424602
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) utime: 0.11616399999999999
2025-10-31 08:42:55,540 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) wtime: 0.59
2025-10-31 08:42:55,541 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) output:
Git info:
    Commit: 82bf8aab4f547668632ff26479ecdb9996428c61
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 43cd17c8a58e28cd156b58873e20cf204c35cf0d into e300863ab69d6519229a61bcfeb54a36b8d86bd0

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uabpkrvm6i
    Host information: 
        Linux ghrun-uabpkrvm6i 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 08:42:55,541 - DEBUG - ya.test - _save_outputs: Command (pid 3305062) errors:

2025-10-31 08:42:55,541 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 82bf8aab4f547668632ff26479ecdb9996428c61
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 43cd17c8a58e28cd156b58873e20cf204c35cf0d into e300863ab69d6519229a61bcfeb54a36b8d86bd0
2025-10-31 08:42:57,478 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd
2025-10-31 08:42:57,478 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6662', '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: 27953\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 08:42:57,479 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 08:42:57,480 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 08:42:57,480 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6662', '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: 27953\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 08:42:57,481 - DEBUG - ya.test - execute: Command pid: 3306677
2025-10-31 08:42:58,016 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) rc: 0
2025-10-31 08:42:58,017 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) elapsed time (sec): 0.5344564914703369
2025-10-31 08:42:58,017 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) idrss: 0
2025-10-31 08:42:58,017 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) inblock: 0
2025-10-31 08:42:58,017 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) isrss: 0
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) ixrss: 0
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) majflt: 0
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) maxrss: 546684
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) minflt: 18271
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) msgrcv: 0
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) msgsnd: 0
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) nivcsw: 1
2025-10-31 08:42:58,018 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) nsignals: 0
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) nswap: 0
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) nvcsw: 144
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) oublock: 8
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) stime: 0.357118
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) utime: 0.134465
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) wtime: 0.536
2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 08:42:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 3306677) errors:

2025-10-31 08:42:58,020 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd
2025-10-31 08:42:58,021 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6662', '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 08:42:58,022 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 08:42:58,022 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 08:42:58,023 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6662', '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 08:42:58,024 - DEBUG - ya.test - execute: Command pid: 3307109
2025-10-31 08:42:58,464 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) rc: 0
2025-10-31 08:42:58,465 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) elapsed time (sec): 0.4393796920776367
2025-10-31 08:42:58,465 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) idrss: 0
2025-10-31 08:42:58,465 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) inblock: 0
2025-10-31 08:42:58,465 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) isrss: 0
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) ixrss: 0
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) majflt: 0
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) maxrss: 546656
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) minflt: 18270
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) msgrcv: 0
2025-10-31 08:42:58,466 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) msgsnd: 0
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) nivcsw: 2
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) nsignals: 0
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) nswap: 0
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) nvcsw: 154
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) oublock: 8
2025-10-31 08:42:58,467 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) stime: 0.28296499999999997
2025-10-31 08:42:58,468 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) utime: 0.113186
2025-10-31 08:42:58,468 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) wtime: 0.44
2025-10-31 08:42:58,468 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 08:42:58,468 - DEBUG - ya.test - _save_outputs: Command (pid 3307109) errors:

2025-10-31 08:42:58,468 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 08:42:58,483 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 08:42:58,493 - 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 08:42:58,495 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 08:42:58,497 - 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 08:42:58,505 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 08:42:58,506 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c80ce495-8b3a-441b-ae99-3f6f2de2a9f5, localhost:6662): creating call state
2025-10-31 08:42:58,506 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c80ce495-8b3a-441b-ae99-3f6f2de2a9f5, localhost:6662): request = { database: "/Root" }
2025-10-31 08:42:58,509 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c80ce495-8b3a-441b-ae99-3f6f2de2a9f5, localhost:6662): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uabpkrvm6i.auto.internal" port: 6662 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 08:42:58,510 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uabpkrvm6i.auto.internal:6662, location 1, ssl: False>]>
2025-10-31 08:42:58,510 - 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 08:42:58,517 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6662
2025-10-31 08:42:58,518 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0d7fe728-cbf8-4e1a-b6f3-cc69d53b336f, ghrun-uabpkrvm6i.auto.internal:6662): creating call state
2025-10-31 08:42:58,519 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0d7fe728-cbf8-4e1a-b6f3-cc69d53b336f, ghrun-uabpkrvm6i.auto.internal:6662): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 08:42:58,521 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0d7fe728-cbf8-4e1a-b6f3-cc69d53b336f, ghrun-uabpkrvm6i.auto.internal:6662): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTFkOTVmZTktMmVlNWIyMWMtZjliM2U1YWMtZWM3ZDkxNjY=" } } } }
2025-10-31 08:42:58,524 - 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 08:42:58,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 93508e39-71d9-4034-b95f-d4e8e625b42c, ghrun-uabpkrvm6i.auto.internal:6662): creating call state
2025-10-31 08:42:58,526 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 93508e39-71d9-4034-b95f-d4e8e625b42c, ghrun-uabpkrvm6i.auto.internal:6662): request = { session_id: "ydb://session/3?node_id=1&id=NTFkOTVmZTktMmVlNWIyMWMtZjliM2U1YWMtZWM3ZDkxNjY=" 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 08:42:58,565 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 93508e39-71d9-4034-b95f-d4e8e625b42c, ghrun-uabpkrvm6i.auto.internal:6662): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:42:58,568 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:58,569 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 08:42:58,570 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 08:42:58,570 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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/pogg/001612/tmp/test_multiple_files_and_column0/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column0/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column0/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:42:58,571 - DEBUG - ya.test - execute: Command pid: 3307608
2025-10-31 08:42:58,727 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) rc: 0
2025-10-31 08:42:58,727 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) elapsed time (sec): 0.1551063060760498
2025-10-31 08:42:58,727 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) idrss: 0
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) inblock: 0
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) isrss: 0
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) ixrss: 0
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) majflt: 0
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) maxrss: 184392
2025-10-31 08:42:58,728 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) minflt: 4159
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) msgrcv: 0
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) msgsnd: 0
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) nivcsw: 2
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) nsignals: 0
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) nswap: 0
2025-10-31 08:42:58,729 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) nvcsw: 550
2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) oublock: 80
2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) stime: 0.054182
2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) utime: 0.041678
2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) wtime: 0.156
2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) output:

2025-10-31 08:42:58,730 - DEBUG - ya.test - _save_outputs: Command (pid 3307608) errors:
Elapsed: 0.00594 sec. Total read size: 290B. Average processing speed: 47.7KiB/s.


2025-10-31 08:42:58,731 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:42:58,731 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:58,732 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 08:42:58,732 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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 08:42:58,733 - DEBUG - ya.test - execute: Command pid: 3307883
2025-10-31 08:42:58,881 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) rc: 0
2025-10-31 08:42:58,881 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) elapsed time (sec): 0.1469590663909912
2025-10-31 08:42:58,881 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) idrss: 0
2025-10-31 08:42:58,881 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) inblock: 0
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) isrss: 0
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) ixrss: 0
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) majflt: 0
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) maxrss: 184392
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) minflt: 3431
2025-10-31 08:42:58,882 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) msgrcv: 0
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) msgsnd: 0
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) nivcsw: 13
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) nsignals: 0
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) nswap: 0
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) nvcsw: 192
2025-10-31 08:42:58,883 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) oublock: 8
2025-10-31 08:42:58,884 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) stime: 0.029654999999999997
2025-10-31 08:42:58,884 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) utime: 0.044482
2025-10-31 08:42:58,884 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) wtime: 0.148
2025-10-31 08:42:58,884 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) 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 08:42:58,884 - DEBUG - ya.test - _save_outputs: Command (pid 3307883) errors:

2025-10-31 08:42:58,885 - 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 08:42:58,886 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:43:22,402 - 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 08:43:22,406 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-row] status: good
