2025-10-31 08:42:58,890 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:58,890 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-column]
2025-10-31 08:42:58,890 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:58,890 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:42:58,893 - 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-column])
2025-10-31 08:42:58,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, edce9da0-6cb3-4f11-9d71-8b2762ac9f0d, ghrun-uabpkrvm6i.auto.internal:6662): creating call state
2025-10-31 08:42:58,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, edce9da0-6cb3-4f11-9d71-8b2762ac9f0d, ghrun-uabpkrvm6i.auto.internal:6662): request = { session_id: "ydb://session/3?node_id=1&id=NTFkOTVmZTktMmVlNWIyMWMtZjliM2U1YWMtZWM3ZDkxNjY=" 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 } } }
2025-10-31 08:42:59,177 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, edce9da0-6cb3-4f11-9d71-8b2762ac9f0d, ghrun-uabpkrvm6i.auto.internal:6662): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:42:59,179 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:59,181 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-31 08:42:59,181 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-31 08:42:59,181 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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/pogg/001612/tmp/test_multiple_files_and_column1/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column1/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column1/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column1/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:42:59,182 - DEBUG - ya.test - execute: Command pid: 3308140
2025-10-31 08:42:59,342 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) rc: 0
2025-10-31 08:42:59,343 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) elapsed time (sec): 0.15929055213928223
2025-10-31 08:42:59,344 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) idrss: 0
2025-10-31 08:42:59,344 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) inblock: 0
2025-10-31 08:42:59,344 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) isrss: 0
2025-10-31 08:42:59,344 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) ixrss: 0
2025-10-31 08:42:59,345 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) majflt: 0
2025-10-31 08:42:59,345 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) maxrss: 184392
2025-10-31 08:42:59,345 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) minflt: 4392
2025-10-31 08:42:59,345 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) msgrcv: 0
2025-10-31 08:42:59,346 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) msgsnd: 0
2025-10-31 08:42:59,346 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) nivcsw: 11
2025-10-31 08:42:59,346 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) nsignals: 0
2025-10-31 08:42:59,346 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) nswap: 0
2025-10-31 08:42:59,346 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) nvcsw: 900
2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) oublock: 48
2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) stime: 0.059601999999999995
2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) utime: 0.047681
2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) wtime: 0.161
2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) output:

2025-10-31 08:42:59,347 - DEBUG - ya.test - _save_outputs: Command (pid 3308140) errors:
Elapsed: 0.0217 sec. Total read size: 290B. Average processing speed: 13.1KiB/s.


2025-10-31 08:42:59,348 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:42:59,348 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:59,349 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-31 08:42:59,349 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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)
2025-10-31 08:42:59,350 - DEBUG - ya.test - execute: Command pid: 3308491
2025-10-31 08:42:59,530 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) rc: 0
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) elapsed time (sec): 0.1794111728668213
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) idrss: 0
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) inblock: 0
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) isrss: 0
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) ixrss: 0
2025-10-31 08:42:59,531 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) majflt: 0
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) maxrss: 184392
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) minflt: 3433
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) msgrcv: 0
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) msgsnd: 0
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) nivcsw: 0
2025-10-31 08:42:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) nsignals: 0
2025-10-31 08:42:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) nswap: 0
2025-10-31 08:42:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) nvcsw: 192
2025-10-31 08:42:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) oublock: 8
2025-10-31 08:42:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) stime: 0.024464999999999997
2025-10-31 08:42:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) utime: 0.044851999999999996
2025-10-31 08:42:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) wtime: 0.18
2025-10-31 08:42:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) 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 08:42:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 3308491) errors:

2025-10-31 08:42:59,535 - 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 08:42:59,537 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:43:22,407 - 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
2025-10-31 08:43:22,408 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-column] status: good
