2025-10-31 15:31:07,327 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:07,327 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-column]
2025-10-31 15:31:07,327 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:07,328 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:31:07,330 - 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 15:31:07,330 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, bfa79280-aeb9-43ab-a76f-32b763ddb341, ghrun-upzbhs6y6e.auto.internal:30865): creating call state
2025-10-31 15:31:07,331 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, bfa79280-aeb9-43ab-a76f-32b763ddb341, ghrun-upzbhs6y6e.auto.internal:30865): request = { session_id: "ydb://session/3?node_id=1&id=NGU3MDAxYzEtNmUwNWUxZWMtYzMxNjlmNTktMzYzYWM0Y2E=" 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 15:31:07,600 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, bfa79280-aeb9-43ab-a76f-32b763ddb341, ghrun-upzbhs6y6e.auto.internal:30865): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:31:07,602 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:07,603 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-31 15:31:07,604 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-31 15:31:07,604 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--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/stm2/00309a/tmp/test_multiple_files_and_column1/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column1/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column1/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/00309a/tmp/test_multiple_files_and_column1/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:31:07,605 - DEBUG - ya.test - execute: Command pid: 2651344
2025-10-31 15:31:07,767 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) rc: 0
2025-10-31 15:31:07,768 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) elapsed time (sec): 0.1619095802307129
2025-10-31 15:31:07,768 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) idrss: 0
2025-10-31 15:31:07,768 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) inblock: 0
2025-10-31 15:31:07,768 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) isrss: 0
2025-10-31 15:31:07,769 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) ixrss: 0
2025-10-31 15:31:07,769 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) majflt: 0
2025-10-31 15:31:07,769 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) maxrss: 184424
2025-10-31 15:31:07,769 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) minflt: 4413
2025-10-31 15:31:07,769 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) msgrcv: 0
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) msgsnd: 0
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) nivcsw: 6
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) nsignals: 0
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) nswap: 0
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) nvcsw: 917
2025-10-31 15:31:07,770 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) oublock: 48
2025-10-31 15:31:07,771 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) stime: 0.040712
2025-10-31 15:31:07,771 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) utime: 0.058807
2025-10-31 15:31:07,771 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) wtime: 0.163
2025-10-31 15:31:07,771 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) output:

2025-10-31 15:31:07,771 - DEBUG - ya.test - _save_outputs: Command (pid 2651344) errors:
Elapsed: 0.0275 sec. Total read size: 290B. Average processing speed: 10.3KiB/s.


2025-10-31 15:31:07,772 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:31:07,772 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb
2025-10-31 15:31:07,773 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-31 15:31:07,773 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/00309a/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:30865', '--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 15:31:07,774 - DEBUG - ya.test - execute: Command pid: 2651654
2025-10-31 15:31:07,948 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) rc: 0
2025-10-31 15:31:07,949 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) elapsed time (sec): 0.1733689308166504
2025-10-31 15:31:07,949 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) idrss: 0
2025-10-31 15:31:07,951 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) inblock: 0
2025-10-31 15:31:07,952 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) isrss: 0
2025-10-31 15:31:07,952 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) ixrss: 0
2025-10-31 15:31:07,953 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) majflt: 0
2025-10-31 15:31:07,954 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) maxrss: 184424
2025-10-31 15:31:07,954 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) minflt: 3439
2025-10-31 15:31:07,954 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) msgrcv: 0
2025-10-31 15:31:07,955 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) msgsnd: 0
2025-10-31 15:31:07,955 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) nivcsw: 2
2025-10-31 15:31:07,956 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) nsignals: 0
2025-10-31 15:31:07,956 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) nswap: 0
2025-10-31 15:31:07,957 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) nvcsw: 196
2025-10-31 15:31:07,957 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) oublock: 8
2025-10-31 15:31:07,957 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) stime: 0.016748
2025-10-31 15:31:07,957 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) utime: 0.046057999999999995
2025-10-31 15:31:07,958 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) wtime: 0.174
2025-10-31 15:31:07,958 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) 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:31:07,958 - DEBUG - ya.test - _save_outputs: Command (pid 2651654) errors:

2025-10-31 15:31:07,959 - 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:31:07,960 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:31:30,772 - 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 15:31:30,773 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-column] status: good
