2025-11-01 16:50:01,224 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:50:01,225 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-column]
2025-11-01 16:50:01,225 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:50:01,225 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:50:01,236 - 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-11-01 16:50:01,237 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 49ddb19c-1fda-4237-9b83-f75228004409, ghrun-tvlw7frnpa.auto.internal:13499): creating call state
2025-11-01 16:50:01,237 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 49ddb19c-1fda-4237-9b83-f75228004409, ghrun-tvlw7frnpa.auto.internal:13499): request = { session_id: "ydb://session/3?node_id=1&id=OWU3ZThlMi0zOGM3YmY1Yy1kMTBiNGQzYi1jNzBhMWI2MA==" 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-11-01 16:50:01,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 49ddb19c-1fda-4237-9b83-f75228004409, ghrun-tvlw7frnpa.auto.internal:13499): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:50:01,691 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb
2025-11-01 16:50:01,694 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-01 16:50:01,695 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-01 16:50:01,696 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13499', '--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/q6a6/001987/tmp/test_multiple_files_and_column1/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column1/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column1/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column1/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 16:50:01,697 - DEBUG - ya.test - execute: Command pid: 3207352
2025-11-01 16:50:01,919 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) rc: 0
2025-11-01 16:50:01,919 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) elapsed time (sec): 0.2209014892578125
2025-11-01 16:50:01,919 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) idrss: 0
2025-11-01 16:50:01,919 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) inblock: 0
2025-11-01 16:50:01,919 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) isrss: 0
2025-11-01 16:50:01,920 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) ixrss: 0
2025-11-01 16:50:01,920 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) majflt: 0
2025-11-01 16:50:01,921 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) maxrss: 195640
2025-11-01 16:50:01,921 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) minflt: 4415
2025-11-01 16:50:01,922 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) msgrcv: 0
2025-11-01 16:50:01,922 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) msgsnd: 0
2025-11-01 16:50:01,923 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) nivcsw: 54
2025-11-01 16:50:01,923 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) nsignals: 0
2025-11-01 16:50:01,923 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) nswap: 0
2025-11-01 16:50:01,926 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) nvcsw: 744
2025-11-01 16:50:01,926 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) oublock: 48
2025-11-01 16:50:01,926 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) stime: 0.059105
2025-11-01 16:50:01,927 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) utime: 0.050661
2025-11-01 16:50:01,927 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) wtime: 0.222
2025-11-01 16:50:01,928 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) output:

2025-11-01 16:50:01,929 - DEBUG - ya.test - _save_outputs: Command (pid 3207352) errors:
Elapsed: 0.0257 sec. Total read size: 290B. Average processing speed: 11KiB/s.


2025-11-01 16:50:01,930 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 16:50:01,931 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb
2025-11-01 16:50:01,948 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-01 16:50:01,948 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001987/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:13499', '--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-11-01 16:50:01,952 - DEBUG - ya.test - execute: Command pid: 3207891
2025-11-01 16:50:02,175 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) rc: 0
2025-11-01 16:50:02,176 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) elapsed time (sec): 0.22095084190368652
2025-11-01 16:50:02,176 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) idrss: 0
2025-11-01 16:50:02,177 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) inblock: 0
2025-11-01 16:50:02,177 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) isrss: 0
2025-11-01 16:50:02,178 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) ixrss: 0
2025-11-01 16:50:02,178 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) majflt: 0
2025-11-01 16:50:02,179 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) maxrss: 195640
2025-11-01 16:50:02,179 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) minflt: 3430
2025-11-01 16:50:02,183 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) msgrcv: 0
2025-11-01 16:50:02,183 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) msgsnd: 0
2025-11-01 16:50:02,184 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) nivcsw: 23
2025-11-01 16:50:02,188 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) nsignals: 0
2025-11-01 16:50:02,188 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) nswap: 0
2025-11-01 16:50:02,188 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) nvcsw: 171
2025-11-01 16:50:02,190 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) oublock: 8
2025-11-01 16:50:02,190 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) stime: 0.026921
2025-11-01 16:50:02,190 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) utime: 0.040382
2025-11-01 16:50:02,190 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) wtime: 0.226
2025-11-01 16:50:02,191 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) 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-11-01 16:50:02,192 - DEBUG - ya.test - _save_outputs: Command (pid 3207891) errors:

2025-11-01 16:50:02,192 - 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-11-01 16:50:02,195 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:50:25,268 - 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-11-01 16:50:25,269 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-column] status: good
