2025-10-31 15:33:46,362 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:46,363 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-column]
2025-10-31 15:33:46,363 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:46,363 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:33:46,366 - 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 15:33:46,367 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, f83d5cdb-65de-4e18-a522-e1dc1ebea4a7, ghrun-upzbhs6y6e.auto.internal:5082): creating call state
2025-10-31 15:33:46,368 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, f83d5cdb-65de-4e18-a522-e1dc1ebea4a7, ghrun-upzbhs6y6e.auto.internal:5082): request = { session_id: "ydb://session/3?node_id=1&id=ZjFhOTgzNWItNWQ5NjU1NzgtMjAyMzk3ZmMtNzliZmZmMDA=" 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 15:33:46,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, f83d5cdb-65de-4e18-a522-e1dc1ebea4a7, ghrun-upzbhs6y6e.auto.internal:5082): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:46,652 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb
2025-10-31 15:33:46,653 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:33:46,653 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:33:46,653 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5082', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-column]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi2/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi2/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi2/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/002d68/tmp/test_multiple_files_tsv_additi2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:33:46,654 - DEBUG - ya.test - execute: Command pid: 2774731
2025-10-31 15:33:46,806 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) rc: 0
2025-10-31 15:33:46,806 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) elapsed time (sec): 0.15108847618103027
2025-10-31 15:33:46,806 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) idrss: 0
2025-10-31 15:33:46,806 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) inblock: 0
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) isrss: 0
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) ixrss: 0
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) majflt: 0
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) maxrss: 184476
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) minflt: 4413
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) msgrcv: 0
2025-10-31 15:33:46,807 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) msgsnd: 0
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) nivcsw: 7
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) nsignals: 0
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) nswap: 0
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) nvcsw: 893
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) oublock: 48
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) stime: 0.042717
2025-10-31 15:33:46,808 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) utime: 0.058251
2025-10-31 15:33:46,809 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) wtime: 0.152
2025-10-31 15:33:46,809 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) output:

2025-10-31 15:33:46,809 - DEBUG - ya.test - _save_outputs: Command (pid 2774731) errors:
Elapsed: 0.0217 sec. Total read size: 290B. Average processing speed: 13.1KiB/s.


2025-10-31 15:33:46,809 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:33:46,809 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb
2025-10-31 15:33:46,810 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:33:46,811 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002d68/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5082', '--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 15:33:46,812 - DEBUG - ya.test - execute: Command pid: 2775290
2025-10-31 15:33:46,974 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) rc: 0
2025-10-31 15:33:46,974 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) elapsed time (sec): 0.16199994087219238
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) idrss: 0
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) inblock: 0
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) isrss: 0
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) ixrss: 0
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) majflt: 0
2025-10-31 15:33:46,975 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) maxrss: 184476
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) minflt: 3433
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) msgrcv: 0
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) msgsnd: 0
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) nivcsw: 0
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) nsignals: 0
2025-10-31 15:33:46,976 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) nswap: 0
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) nvcsw: 190
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) oublock: 8
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) stime: 0.024031999999999998
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) utime: 0.040054
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) wtime: 0.163
2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"
11	1111	"one"
22	2222	"two"
33	3333	"three"
55	5555	"five"
77	7777	"seven"
111	1111	"one"
222	2222	"two"
333	3333	"three"
555	5555	"five"
777	7777	"seven"
1111	1111	"one"
2222	2222	"two"
3333	3333	"three"
5555	5555	"five"
7777	7777	"seven"

2025-10-31 15:33:46,977 - DEBUG - ya.test - _save_outputs: Command (pid 2775290) errors:

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

2025-10-31 15:33:46,979 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:34:08,984 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5082/1
2025-10-31 15:34:08,989 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22898
2025-10-31 15:34:08,989 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24003
2025-10-31 15:34:08,990 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31936
2025-10-31 15:34:08,990 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5082
2025-10-31 15:34:08,990 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18284
2025-10-31 15:34:09,698 - 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 15:34:09,699 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] status: good
