2025-10-31 15:31:07,965 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:07,966 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-row]
2025-10-31 15:31:07,966 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:07,966 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:31:07,968 - 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_args3-row])
2025-10-31 15:31:07,969 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 606fcb54-faa4-45a7-8a51-aa9bb412d401, ghrun-upzbhs6y6e.auto.internal:30865): creating call state
2025-10-31 15:31:07,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 606fcb54-faa4-45a7-8a51-aa9bb412d401, ghrun-upzbhs6y6e.auto.internal:30865): request = { session_id: "ydb://session/3?node_id=1&id=NGU3MDAxYzEtNmUwNWUxZWMtYzMxNjlmNTktMzYzYWM0Y2E=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_columns_opt[tsv-additional_args3-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 15:31:07,991 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 606fcb54-faa4-45a7-8a51-aa9bb412d401, ghrun-upzbhs6y6e.auto.internal:30865): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:31:07,992 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:07,993 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:31:07,994 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:31:07,994 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_columns_opt[tsv-additional_args3-row]', '--header', '--columns', 'key\tid\tvalue\n', '--newline-delimited', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:31:07,995 - DEBUG - ya.test - execute: Command pid: 2651849
2025-10-31 15:31:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) rc: 0
2025-10-31 15:31:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) elapsed time (sec): 0.1441946029663086
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) idrss: 0
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) inblock: 0
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) isrss: 0
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) ixrss: 0
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) majflt: 0
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) maxrss: 184424
2025-10-31 15:31:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) minflt: 4413
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) msgrcv: 0
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) msgsnd: 0
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) nivcsw: 9
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) nsignals: 0
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) nswap: 0
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) nvcsw: 921
2025-10-31 15:31:08,142 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) oublock: 48
2025-10-31 15:31:08,143 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) stime: 0.047296
2025-10-31 15:31:08,143 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) utime: 0.055895999999999994
2025-10-31 15:31:08,143 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) wtime: 0.145
2025-10-31 15:31:08,143 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) output:

2025-10-31 15:31:08,143 - DEBUG - ya.test - _save_outputs: Command (pid 2651849) errors:
Elapsed: 0.011 sec. Total read size: 290B. Average processing speed: 25.7KiB/s.


2025-10-31 15:31:08,144 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:31:08,144 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:08,145 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:31:08,146 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_columns_opt[tsv-additional_args3-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 15:31:08,147 - DEBUG - ya.test - execute: Command pid: 2652170
2025-10-31 15:31:08,282 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) rc: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) elapsed time (sec): 0.1347968578338623
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) idrss: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) inblock: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) isrss: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) ixrss: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) majflt: 0
2025-10-31 15:31:08,283 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) maxrss: 184424
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) minflt: 3439
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) msgrcv: 0
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) msgsnd: 0
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) nivcsw: 2
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) nsignals: 0
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) nswap: 0
2025-10-31 15:31:08,284 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) nvcsw: 189
2025-10-31 15:31:08,285 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) oublock: 8
2025-10-31 15:31:08,285 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) stime: 0.016843999999999998
2025-10-31 15:31:08,285 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) utime: 0.046321999999999995
2025-10-31 15:31:08,285 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) wtime: 0.136
2025-10-31 15:31:08,286 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) 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:31:08,286 - DEBUG - ya.test - _save_outputs: Command (pid 2652170) errors:

2025-10-31 15:31:08,286 - 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:31:08,289 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:31:30,291 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:30865/1
2025-10-31 15:31:30,292 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1636
2025-10-31 15:31:30,292 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6085
2025-10-31 15:31:30,292 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1464
2025-10-31 15:31:30,292 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30865
2025-10-31 15:31:30,292 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3710
2025-10-31 15:31:30,774 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 15:31:30,776 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] status: good
