2025-10-31 08:41:57,333 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:57,334 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args2-column]
2025-10-31 08:41:57,334 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:57,334 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:41:57,402 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd
2025-10-31 08:41:57,405 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28806
2025-10-31 08:41:57,406 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/pogg/001413/r3tmp/kikimr_cluster_2aqx0hl0
2025-10-31 08:41:57,443 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd
2025-10-31 08:41:57,444 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10052
2025-10-31 08:41:57,444 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62870
2025-10-31 08:41:57,444 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29595
2025-10-31 08:41:57,445 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23146
2025-10-31 08:41:57,445 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs"
2025-10-31 08:41:57,445 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001413/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/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_hqxx96nr.log --grpc-port=10052 --mon-port=62870 --ic-port=28806
2025-10-31 08:41:57,446 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10052/1, disk_size 68719476736
2025-10-31 08:41:57,446 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pogg/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs"
2025-10-31 08:41:57,446 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/pogg/001413/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/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_hqxx96nr.log --grpc-port=10052 --mon-port=62870 --ic-port=28806
2025-10-31 08:41:57,447 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pogg/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pogg/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1/logfile_hqxx96nr.log', '--grpc-port=10052', '--mon-port=62870', '--ic-port=28806')' in '/home/runner/.ya/build/build_root/pogg/001413/ydb/tests/functional/ydb_cli/test-results/py3test/testing_out_stuff/test_ydb_impex/chunk16/testing_out_stuff/test_ydb_impex.py.TestImpex.test_multiple_files.tsv-additional_args2-column/cluster/node_1' (no wait)
2025-10-31 08:41:57,448 - DEBUG - ya.test - execute: Command pid: 3254501
2025-10-31 08:41:57,449 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10052/1
2025-10-31 08:41:57,450 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 08:41:57,450 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 08:41:57,450 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 08:41:57,451 - DEBUG - ya.test - execute: Command pid: 3254503
2025-10-31 08:41:58,081 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) rc: 0
2025-10-31 08:41:58,081 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) elapsed time (sec): 0.6279144287109375
2025-10-31 08:41:58,081 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) idrss: 0
2025-10-31 08:41:58,082 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) inblock: 0
2025-10-31 08:41:58,082 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) isrss: 0
2025-10-31 08:41:58,082 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) ixrss: 0
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) majflt: 1
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) maxrss: 538184
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) minflt: 21522
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) msgrcv: 0
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) msgsnd: 0
2025-10-31 08:41:58,083 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) nivcsw: 6
2025-10-31 08:41:58,084 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) nsignals: 0
2025-10-31 08:41:58,084 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) nswap: 0
2025-10-31 08:41:58,084 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) nvcsw: 18
2025-10-31 08:41:58,084 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) oublock: 8
2025-10-31 08:41:58,086 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) stime: 0.45047299999999996
2025-10-31 08:41:58,086 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) utime: 0.129295
2025-10-31 08:41:58,087 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) wtime: 0.629
2025-10-31 08:41:58,087 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) 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:41:58,088 - DEBUG - ya.test - _save_outputs: Command (pid 3254503) errors:

2025-10-31 08:41:58,089 - 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:41:59,971 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd
2025-10-31 08:41:59,972 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10052', '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: 28806\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 08:41:59,973 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 08:41:59,973 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 08:41:59,973 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10052', '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: 28806\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 08:41:59,974 - DEBUG - ya.test - execute: Command pid: 3256277
2025-10-31 08:42:00,442 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) rc: 0
2025-10-31 08:42:00,443 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) elapsed time (sec): 0.46721506118774414
2025-10-31 08:42:00,443 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) idrss: 0
2025-10-31 08:42:00,443 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) inblock: 0
2025-10-31 08:42:00,443 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) isrss: 0
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) ixrss: 0
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) majflt: 0
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) maxrss: 546652
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) minflt: 18271
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) msgrcv: 0
2025-10-31 08:42:00,444 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) msgsnd: 0
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) nivcsw: 33
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) nsignals: 0
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) nswap: 0
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) nvcsw: 108
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) oublock: 8
2025-10-31 08:42:00,445 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) stime: 0.287215
2025-10-31 08:42:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) utime: 0.12985
2025-10-31 08:42:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) wtime: 0.468
2025-10-31 08:42:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-31 08:42:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3256277) errors:

2025-10-31 08:42:00,447 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd
2025-10-31 08:42:00,448 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10052', '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:00,449 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 08:42:00,449 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 08:42:00,449 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10052', '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:00,451 - DEBUG - ya.test - execute: Command pid: 3256796
2025-10-31 08:42:00,894 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) rc: 0
2025-10-31 08:42:00,894 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) elapsed time (sec): 0.4423856735229492
2025-10-31 08:42:00,894 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) idrss: 0
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) inblock: 0
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) isrss: 0
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) ixrss: 0
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) majflt: 0
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) maxrss: 546664
2025-10-31 08:42:00,895 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) minflt: 18270
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) msgrcv: 0
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) msgsnd: 0
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) nivcsw: 31
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) nsignals: 0
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) nswap: 0
2025-10-31 08:42:00,896 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) nvcsw: 131
2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) oublock: 8
2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) stime: 0.284437
2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) utime: 0.111661
2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) wtime: 0.444
2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 08:42:00,897 - DEBUG - ya.test - _save_outputs: Command (pid 3256796) errors:

2025-10-31 08:42:00,898 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 08:42:00,915 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 08:42:00,928 - 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:00,931 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 08:42:00,933 - 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:00,946 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 08:42:00,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6a6b16aa-7cff-4a6b-8f87-b48fbeef49be, localhost:10052): creating call state
2025-10-31 08:42:00,948 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6a6b16aa-7cff-4a6b-8f87-b48fbeef49be, localhost:10052): request = { database: "/Root" }
2025-10-31 08:42:00,954 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6a6b16aa-7cff-4a6b-8f87-b48fbeef49be, localhost:10052): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uabpkrvm6i.auto.internal" port: 10052 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:00,954 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uabpkrvm6i.auto.internal:10052, location 1, ssl: False>]>
2025-10-31 08:42:00,955 - 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:00,964 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10052
2025-10-31 08:42:00,965 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, bf07392f-5d9a-439b-bbc6-a4a14f6dd90f, ghrun-uabpkrvm6i.auto.internal:10052): creating call state
2025-10-31 08:42:00,967 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, bf07392f-5d9a-439b-bbc6-a4a14f6dd90f, ghrun-uabpkrvm6i.auto.internal:10052): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 08:42:00,970 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, bf07392f-5d9a-439b-bbc6-a4a14f6dd90f, ghrun-uabpkrvm6i.auto.internal:10052): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NThhNmY0ODctNDQ3ZDY4NWEtZmE0YjZjNmUtOWE3MDRmYTM=" } } } }
2025-10-31 08:42:00,975 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files[tsv-additional_args2-column])
2025-10-31 08:42:00,975 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 3155861c-3ba7-488e-86be-4489d985cd2d, ghrun-uabpkrvm6i.auto.internal:10052): creating call state
2025-10-31 08:42:00,976 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 3155861c-3ba7-488e-86be-4489d985cd2d, ghrun-uabpkrvm6i.auto.internal:10052): request = { session_id: "ydb://session/3?node_id=1&id=NThhNmY0ODctNDQ3ZDY4NWEtZmE0YjZjNmUtOWE3MDRmYTM=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files[tsv-additional_args2-column]` (\n            key Uint32 NOT NULL,\n            id Uint64,\n            value Utf8,\n            PRIMARY KEY(key)\n        )\n        PARTITION BY HASH(key)\n        WITH (\n            STORE = column\n        )\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 08:42:01,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 3155861c-3ba7-488e-86be-4489d985cd2d, ghrun-uabpkrvm6i.auto.internal:10052): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:42:01,324 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydb/ydb
2025-10-31 08:42:01,325 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-31 08:42:01,326 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-31 08:42:01,326 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10052', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args2-column]', '--header', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi0/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi0/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi0/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi0/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:42:01,327 - DEBUG - ya.test - execute: Command pid: 3257619
2025-10-31 08:42:01,496 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) rc: 0
2025-10-31 08:42:01,496 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) elapsed time (sec): 0.16825437545776367
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) idrss: 0
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) inblock: 0
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) isrss: 0
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) ixrss: 0
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) majflt: 0
2025-10-31 08:42:01,497 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) maxrss: 184388
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) minflt: 4145
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) msgrcv: 0
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) msgsnd: 0
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) nivcsw: 13
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) nsignals: 0
2025-10-31 08:42:01,498 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) nswap: 0
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) nvcsw: 558
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) oublock: 80
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) stime: 0.036995
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) utime: 0.07399
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) wtime: 0.169
2025-10-31 08:42:01,499 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) output:

2025-10-31 08:42:01,500 - DEBUG - ya.test - _save_outputs: Command (pid 3257619) errors:
Elapsed: 0.0191 sec. Total read size: 290B. Average processing speed: 14.8KiB/s.


2025-10-31 08:42:01,500 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:42:01,500 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydb/ydb
2025-10-31 08:42:01,501 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-31 08:42:01,502 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001413/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10052', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files[tsv-additional_args2-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 08:42:01,503 - DEBUG - ya.test - execute: Command pid: 3257882
2025-10-31 08:42:01,730 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) rc: 0
2025-10-31 08:42:01,730 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) elapsed time (sec): 0.21967649459838867
2025-10-31 08:42:01,731 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) idrss: 0
2025-10-31 08:42:01,731 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) inblock: 0
2025-10-31 08:42:01,732 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) isrss: 0
2025-10-31 08:42:01,732 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) ixrss: 0
2025-10-31 08:42:01,732 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) majflt: 0
2025-10-31 08:42:01,732 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) maxrss: 184388
2025-10-31 08:42:01,732 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) minflt: 3436
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) msgrcv: 0
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) msgsnd: 0
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) nivcsw: 5
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) nsignals: 0
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) nswap: 0
2025-10-31 08:42:01,733 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) nvcsw: 192
2025-10-31 08:42:01,734 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) oublock: 8
2025-10-31 08:42:01,734 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) stime: 0.020283
2025-10-31 08:42:01,735 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) utime: 0.056794
2025-10-31 08:42:01,735 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) wtime: 0.221
2025-10-31 08:42:01,736 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) 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:01,736 - DEBUG - ya.test - _save_outputs: Command (pid 3257882) errors:

2025-10-31 08:42:01,736 - 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:01,738 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:42:25,378 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 08:42:25,383 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args2-column] status: good
