2026-01-08 02:57:13,331 - INFO - ya.test.2419910  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:57:13,331 - INFO - ya.test.2419910  - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-column]
2026-01-08 02:57:13,331 - INFO - ya.test.2419910  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:57:13,331 - INFO - ya.test.2419910  - pytest_runtest_setup: Test setup
2026-01-08 02:57:13,333 - INFO - ya.test.2419910  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_columns_opt[tsv-additional_args2-column])
2026-01-08 02:57:13,333 - DEBUG - ydb.connection.2419910  - _prepare_call: RpcState(ExecuteSchemeQuery, a04b9f23-02aa-4ca3-a985-461b9b780614, ghrun-paw7opzmb4.auto.internal:5042): creating call state
2026-01-08 02:57:13,334 - DEBUG - ydb.connection.2419910  - _log_request: RpcState(ExecuteSchemeQuery, a04b9f23-02aa-4ca3-a985-461b9b780614, ghrun-paw7opzmb4.auto.internal:5042): request = { session_id: "ydb://session/3?node_id=1&id=NjcyMTA4YmMtZDA3YWRhNmEtYjMwNGQ4NGEtOTA5Y2E5YjU=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_columns_opt[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 } } }
2026-01-08 02:57:13,539 - DEBUG - ydb.connection.2419910  - _log_response: RpcState(ExecuteSchemeQuery, a04b9f23-02aa-4ca3-a985-461b9b780614, ghrun-paw7opzmb4.auto.internal:5042): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:57:13,540 - DEBUG - ya.test.2419910  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/003395/ydb/apps/ydb/ydb
2026-01-08 02:57:13,541 - DEBUG - ya.test.2419910  - _get_command_output_file: Command out will be placed to ydb.out.1
2026-01-08 02:57:13,541 - DEBUG - ya.test.2419910  - _get_command_output_file: Command err will be placed to ydb.err.3
2026-01-08 02:57:13,541 - DEBUG - ya.test.2419910  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/003395/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5042', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-column]', '--header', '--columns', 'key\tid\tvalue\n', '/home/runner/.ya/build/build_root/pp6n/003395/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/pp6n/003395/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/pp6n/003395/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/pp6n/003395/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2026-01-08 02:57:13,542 - DEBUG - ya.test.2419910  - execute: Command pid: 2428215
2026-01-08 02:57:13,697 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) rc: 0
2026-01-08 02:57:13,697 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) elapsed time (sec): 0.15452075004577637
2026-01-08 02:57:13,697 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) idrss: 0
2026-01-08 02:57:13,697 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) inblock: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) isrss: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) ixrss: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) majflt: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) maxrss: 183800
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) minflt: 4269
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) msgrcv: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) msgsnd: 0
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) nivcsw: 1
2026-01-08 02:57:13,698 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) nsignals: 0
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) nswap: 0
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) nvcsw: 532
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) oublock: 80
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) stime: 0.037738
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) utime: 0.037739999999999996
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) wtime: 0.155
2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) output:

2026-01-08 02:57:13,699 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428215) errors:
Elapsed: 0.0415 sec. Total read size: 290B. Average processing speed: 6.82KiB/s.


2026-01-08 02:57:13,699 - DEBUG - __tests__.test_ydb_impex.2419910  - execute_ydb_cli_command: std_out:

2026-01-08 02:57:13,700 - DEBUG - ya.test.2419910  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/003395/ydb/apps/ydb/ydb
2026-01-08 02:57:13,700 - DEBUG - ya.test.2419910  - _get_command_output_file: Command err will be placed to ydb.err.4
2026-01-08 02:57:13,700 - DEBUG - ya.test.2419910  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/003395/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:5042', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2026-01-08 02:57:13,701 - DEBUG - ya.test.2419910  - execute: Command pid: 2428461
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) rc: 0
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) elapsed time (sec): 0.1401517391204834
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) idrss: 0
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) inblock: 0
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) isrss: 0
2026-01-08 02:57:13,842 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) ixrss: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) majflt: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) maxrss: 183800
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) minflt: 3568
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) msgrcv: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) msgsnd: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) nivcsw: 1
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) nsignals: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) nswap: 0
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) nvcsw: 212
2026-01-08 02:57:13,843 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) oublock: 8
2026-01-08 02:57:13,844 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) stime: 0.018966
2026-01-08 02:57:13,844 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) utime: 0.034138
2026-01-08 02:57:13,844 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) wtime: 0.141
2026-01-08 02:57:13,844 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) 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:57:13,844 - DEBUG - ya.test.2419910  - _save_outputs: Command (pid 2428461) errors:

2026-01-08 02:57:13,844 - DEBUG - __tests__.test_ydb_impex.2419910  - 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:57:13,845 - INFO - ya.test.2419910  - pytest_runtest_teardown: Test teardown
2026-01-08 02:57:35,847 - INFO - ydb.tests.library.harness.kikimr_runner.2419910  - stop: Stopped node localhost:5042/1
2026-01-08 02:57:35,848 - DEBUG - library.python.filelock.2419910  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6247
2026-01-08 02:57:35,848 - DEBUG - library.python.filelock.2419910  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63122
2026-01-08 02:57:35,849 - DEBUG - library.python.filelock.2419910  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10561
2026-01-08 02:57:35,849 - DEBUG - library.python.filelock.2419910  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5042
2026-01-08 02:57:35,849 - DEBUG - library.python.filelock.2419910  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19686
2026-01-08 02:57:36,200 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:57:36,201 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] status: good
