2025-10-31 08:42:02,090 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:02,090 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-column]
2025-10-31 08:42:02,091 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:02,091 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:42:02,094 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files[tsv-additional_args3-column])
2025-10-31 08:42:02,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, bc32bf5e-34ec-4992-8002-0451d34a9906, ghrun-uabpkrvm6i.auto.internal:10052): creating call state
2025-10-31 08:42:02,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, bc32bf5e-34ec-4992-8002-0451d34a9906, 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_args3-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:02,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, bc32bf5e-34ec-4992-8002-0451d34a9906, ghrun-uabpkrvm6i.auto.internal:10052): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:42:02,424 - 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:02,425 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 08:42:02,426 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 08:42:02,426 - 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_args3-column]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi2/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi2/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi2/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001413/tmp/test_multiple_files_tsv_additi2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:42:02,427 - DEBUG - ya.test - execute: Command pid: 3258595
2025-10-31 08:42:02,601 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) rc: 0
2025-10-31 08:42:02,601 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) elapsed time (sec): 0.17265796661376953
2025-10-31 08:42:02,601 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) idrss: 0
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) inblock: 0
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) isrss: 0
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) ixrss: 0
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) majflt: 0
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) maxrss: 184388
2025-10-31 08:42:02,602 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) minflt: 4406
2025-10-31 08:42:02,603 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) msgrcv: 0
2025-10-31 08:42:02,603 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) msgsnd: 0
2025-10-31 08:42:02,603 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) nivcsw: 12
2025-10-31 08:42:02,603 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) nsignals: 0
2025-10-31 08:42:02,603 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) nswap: 0
2025-10-31 08:42:02,604 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) nvcsw: 944
2025-10-31 08:42:02,604 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) oublock: 48
2025-10-31 08:42:02,604 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) stime: 0.038702
2025-10-31 08:42:02,604 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) utime: 0.077404
2025-10-31 08:42:02,604 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) wtime: 0.174
2025-10-31 08:42:02,605 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) output:

2025-10-31 08:42:02,605 - DEBUG - ya.test - _save_outputs: Command (pid 3258595) errors:
Elapsed: 0.0301 sec. Total read size: 290B. Average processing speed: 9.42KiB/s.


2025-10-31 08:42:02,605 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:42:02,606 - 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:02,607 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 08:42:02,607 - 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_args3-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 08:42:02,608 - DEBUG - ya.test - execute: Command pid: 3258932
2025-10-31 08:42:02,791 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) rc: 0
2025-10-31 08:42:02,791 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) elapsed time (sec): 0.18175625801086426
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) idrss: 0
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) inblock: 0
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) isrss: 0
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) ixrss: 0
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) majflt: 0
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) maxrss: 184388
2025-10-31 08:42:02,792 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) minflt: 3428
2025-10-31 08:42:02,803 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) msgrcv: 0
2025-10-31 08:42:02,804 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) msgsnd: 0
2025-10-31 08:42:02,804 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) nivcsw: 3
2025-10-31 08:42:02,804 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) nsignals: 0
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) nswap: 0
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) nvcsw: 186
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) oublock: 8
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) stime: 0.024436
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) utime: 0.052945
2025-10-31 08:42:02,805 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) wtime: 0.183
2025-10-31 08:42:02,806 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) 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:02,806 - DEBUG - ya.test - _save_outputs: Command (pid 3258932) errors:

2025-10-31 08:42:02,807 - 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:02,809 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:42:24,811 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10052/1
2025-10-31 08:42:24,812 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23146
2025-10-31 08:42:24,813 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29595
2025-10-31 08:42:24,813 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62870
2025-10-31 08:42:24,813 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10052
2025-10-31 08:42:24,813 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28806
2025-10-31 08:42:25,387 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 08:42:25,388 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] status: good
