2025-10-31 15:33:33,058 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:33,058 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-column]
2025-10-31 15:33:33,058 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:33:33,058 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:33:33,060 - 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_args2-column])
2025-10-31 15:33:33,061 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 5368b25d-a38d-435b-b216-bb602fd13efa, ghrun-upzbhs6y6e.auto.internal:26539): creating call state
2025-10-31 15:33:33,061 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 5368b25d-a38d-435b-b216-bb602fd13efa, ghrun-upzbhs6y6e.auto.internal:26539): request = { session_id: "ydb://session/3?node_id=1&id=NThmZmEzNTQtYWU3NGNiMWItOWQyMjkxOWUtNjYzNDVlNzI=" 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 } } }
2025-10-31 15:33:33,321 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 5368b25d-a38d-435b-b216-bb602fd13efa, ghrun-upzbhs6y6e.auto.internal:26539): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:33:33,322 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002ded/ydb/apps/ydb/ydb
2025-10-31 15:33:33,325 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:33:33,326 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:33:33,326 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002ded/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:26539', '--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/stm2/002ded/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/002ded/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/002ded/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/002ded/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:33:33,327 - DEBUG - ya.test - execute: Command pid: 2762125
2025-10-31 15:33:33,472 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) rc: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) elapsed time (sec): 0.1435563564300537
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) idrss: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) inblock: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) isrss: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) ixrss: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) majflt: 0
2025-10-31 15:33:33,473 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) maxrss: 184472
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) minflt: 4155
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) msgrcv: 0
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) msgsnd: 0
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) nivcsw: 0
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) nsignals: 0
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) nswap: 0
2025-10-31 15:33:33,474 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) nvcsw: 542
2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) oublock: 80
2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) stime: 0.039527
2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) utime: 0.046714
2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) wtime: 0.145
2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) output:

2025-10-31 15:33:33,475 - DEBUG - ya.test - _save_outputs: Command (pid 2762125) errors:
Elapsed: 0.0157 sec. Total read size: 290B. Average processing speed: 18.1KiB/s.


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

2025-10-31 15:33:33,476 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002ded/ydb/apps/ydb/ydb
2025-10-31 15:33:33,477 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:33:33,477 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002ded/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:26539', '--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)
2025-10-31 15:33:33,478 - DEBUG - ya.test - execute: Command pid: 2762378
2025-10-31 15:33:33,642 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) rc: 0
2025-10-31 15:33:33,642 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) elapsed time (sec): 0.16285419464111328
2025-10-31 15:33:33,642 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) idrss: 0
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) inblock: 0
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) isrss: 0
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) ixrss: 0
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) majflt: 0
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) maxrss: 184472
2025-10-31 15:33:33,643 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) minflt: 3450
2025-10-31 15:33:33,644 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) msgrcv: 0
2025-10-31 15:33:33,644 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) msgsnd: 0
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) nivcsw: 0
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) nsignals: 0
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) nswap: 0
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) nvcsw: 194
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) oublock: 8
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) stime: 0.023396999999999998
2025-10-31 15:33:33,645 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) utime: 0.040109
2025-10-31 15:33:33,646 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) wtime: 0.164
2025-10-31 15:33:33,646 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) 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:33,646 - DEBUG - ya.test - _save_outputs: Command (pid 2762378) errors:

2025-10-31 15:33:33,646 - 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:33,648 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:33:55,650 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:26539/1
2025-10-31 15:33:55,651 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16000
2025-10-31 15:33:55,651 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5305
2025-10-31 15:33:55,651 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10580
2025-10-31 15:33:55,652 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26539
2025-10-31 15:33:55,652 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8078
2025-10-31 15:33:56,470 - 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
2025-10-31 15:33:56,471 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] status: good
