2026-01-08 02:56:43,442 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:43,442 - INFO - ya.test.2397631  - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-row]
2026-01-08 02:56:43,442 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:43,442 - INFO - ya.test.2397631  - pytest_runtest_setup: Test setup
2026-01-08 02:56:43,444 - INFO - ya.test.2397631  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_columns_opt[tsv-additional_args3-row])
2026-01-08 02:56:43,444 - DEBUG - ydb.connection.2397631  - _prepare_call: RpcState(ExecuteSchemeQuery, af2a730b-a568-42bd-a246-ef3b88f0ee10, ghrun-paw7opzmb4.auto.internal:13093): creating call state
2026-01-08 02:56:43,444 - DEBUG - ydb.connection.2397631  - _log_request: RpcState(ExecuteSchemeQuery, af2a730b-a568-42bd-a246-ef3b88f0ee10, ghrun-paw7opzmb4.auto.internal:13093): request = { session_id: "ydb://session/3?node_id=1&id=ZWJhZTExNTItZTUwNzkxM2ItNWFjMDc3MS1jODUyY2I0OQ==" 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 } } }
2026-01-08 02:56:43,461 - DEBUG - ydb.connection.2397631  - _log_response: RpcState(ExecuteSchemeQuery, af2a730b-a568-42bd-a246-ef3b88f0ee10, ghrun-paw7opzmb4.auto.internal:13093): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:56:43,462 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb
2026-01-08 02:56:43,463 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydb.out.1
2026-01-08 02:56:43,463 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err.3
2026-01-08 02:56:43,463 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13093', '--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/pp6n/0033f3/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2026-01-08 02:56:43,464 - DEBUG - ya.test.2397631  - execute: Command pid: 2402825
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) rc: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) elapsed time (sec): 0.12106108665466309
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) idrss: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) inblock: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) isrss: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) ixrss: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) majflt: 0
2026-01-08 02:56:43,586 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) maxrss: 183884
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) minflt: 4532
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) msgrcv: 0
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) msgsnd: 0
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) nivcsw: 1
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) nsignals: 0
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) nswap: 0
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) nvcsw: 901
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) oublock: 48
2026-01-08 02:56:43,587 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) stime: 0.056091999999999996
2026-01-08 02:56:43,588 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) utime: 0.029917
2026-01-08 02:56:43,588 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) wtime: 0.122
2026-01-08 02:56:43,588 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) output:

2026-01-08 02:56:43,588 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402825) errors:
Elapsed: 0.00611 sec. Total read size: 290B. Average processing speed: 46.3KiB/s.


2026-01-08 02:56:43,588 - DEBUG - __tests__.test_ydb_impex.2397631  - execute_ydb_cli_command: std_out:

2026-01-08 02:56:43,588 - DEBUG - ya.test.2397631  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb
2026-01-08 02:56:43,589 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err.4
2026-01-08 02:56:43,589 - DEBUG - ya.test.2397631  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033f3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13093', '--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)
2026-01-08 02:56:43,590 - DEBUG - ya.test.2397631  - execute: Command pid: 2403150
2026-01-08 02:56:43,709 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) rc: 0
2026-01-08 02:56:43,709 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) elapsed time (sec): 0.11823606491088867
2026-01-08 02:56:43,709 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) idrss: 0
2026-01-08 02:56:43,709 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) inblock: 0
2026-01-08 02:56:43,709 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) isrss: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) ixrss: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) majflt: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) maxrss: 183884
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) minflt: 3565
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) msgrcv: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) msgsnd: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) nivcsw: 2
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) nsignals: 0
2026-01-08 02:56:43,710 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) nswap: 0
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) nvcsw: 192
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) oublock: 8
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) stime: 0.025671
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) utime: 0.030843
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) wtime: 0.119
2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) 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"

2026-01-08 02:56:43,711 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2403150) errors:

2026-01-08 02:56:43,711 - DEBUG - __tests__.test_ydb_impex.2397631  - 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"

2026-01-08 02:56:43,713 - INFO - ya.test.2397631  - pytest_runtest_teardown: Test teardown
2026-01-08 02:57:05,715 - INFO - ydb.tests.library.harness.kikimr_runner.2397631  - stop: Stopped node localhost:13093/1
2026-01-08 02:57:05,715 - DEBUG - library.python.filelock.2397631  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24815
2026-01-08 02:57:05,716 - DEBUG - library.python.filelock.2397631  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4465
2026-01-08 02:57:05,716 - DEBUG - library.python.filelock.2397631  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10937
2026-01-08 02:57:05,716 - DEBUG - library.python.filelock.2397631  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13093
2026-01-08 02:57:05,716 - DEBUG - library.python.filelock.2397631  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26630
2026-01-08 02:57:06,034 - 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
2026-01-08 02:57:06,035 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] status: good
