2026-01-08 02:56:42,898 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:42,898 - INFO - ya.test.2397631  - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-column]
2026-01-08 02:56:42,899 - INFO - ya.test.2397631  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:42,899 - INFO - ya.test.2397631  - pytest_runtest_setup: Test setup
2026-01-08 02:56:42,900 - 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-column])
2026-01-08 02:56:42,901 - DEBUG - ydb.connection.2397631  - _prepare_call: RpcState(ExecuteSchemeQuery, baba8707-e783-48e4-b7b8-1c058b243573, ghrun-paw7opzmb4.auto.internal:13093): creating call state
2026-01-08 02:56:42,902 - DEBUG - ydb.connection.2397631  - _log_request: RpcState(ExecuteSchemeQuery, baba8707-e783-48e4-b7b8-1c058b243573, 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-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:56:43,109 - DEBUG - ydb.connection.2397631  - _log_response: RpcState(ExecuteSchemeQuery, baba8707-e783-48e4-b7b8-1c058b243573, ghrun-paw7opzmb4.auto.internal:13093): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:56:43,111 - 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,112 - DEBUG - ya.test.2397631  - _get_command_output_file: Command out will be placed to ydb.out.0
2026-01-08 02:56:43,112 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err.1
2026-01-08 02:56:43,113 - 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-column]', '--header', '--columns', 'key\tid\tvalue\n', '--newline-delimited', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column1/tempinput0.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column1/tempinput1.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column1/tempinput2.tsv', '/home/runner/.ya/build/build_root/pp6n/0033f3/tmp/test_multiple_files_and_column1/tempinput3.tsv']' in 'None' (waiting)
2026-01-08 02:56:43,113 - DEBUG - ya.test.2397631  - execute: Command pid: 2402360
2026-01-08 02:56:43,272 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) rc: 0
2026-01-08 02:56:43,273 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) elapsed time (sec): 0.15830445289611816
2026-01-08 02:56:43,273 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) idrss: 0
2026-01-08 02:56:43,273 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) inblock: 0
2026-01-08 02:56:43,273 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) isrss: 0
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) ixrss: 0
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) majflt: 0
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) maxrss: 183884
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) minflt: 4525
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) msgrcv: 0
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) msgsnd: 0
2026-01-08 02:56:43,274 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) nivcsw: 0
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) nsignals: 0
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) nswap: 0
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) nvcsw: 863
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) oublock: 48
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) stime: 0.025419999999999998
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) utime: 0.059315
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) wtime: 0.159
2026-01-08 02:56:43,275 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402360) output:

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


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

2026-01-08 02:56:43,276 - 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,277 - DEBUG - ya.test.2397631  - _get_command_output_file: Command err will be placed to ydb.err.2
2026-01-08 02:56:43,277 - 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-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2026-01-08 02:56:43,278 - DEBUG - ya.test.2397631  - execute: Command pid: 2402734
2026-01-08 02:56:43,435 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) rc: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) elapsed time (sec): 0.15686511993408203
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) idrss: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) inblock: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) isrss: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) ixrss: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) majflt: 0
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) maxrss: 183884
2026-01-08 02:56:43,436 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) minflt: 3567
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) msgrcv: 0
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) msgsnd: 0
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) nivcsw: 0
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) nsignals: 0
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) nswap: 0
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) nvcsw: 205
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) oublock: 8
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) stime: 0.014117999999999999
2026-01-08 02:56:43,437 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) utime: 0.042355
2026-01-08 02:56:43,438 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) wtime: 0.158
2026-01-08 02:56:43,438 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) 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,438 - DEBUG - ya.test.2397631  - _save_outputs: Command (pid 2402734) errors:

2026-01-08 02:56:43,438 - 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,439 - INFO - ya.test.2397631  - pytest_runtest_teardown: Test teardown
2026-01-08 02:57:06,032 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-column] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:57:06,033 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-column] status: good
