2025-11-01 04:21:04,201 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:21:04,201 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-column]
2025-11-01 04:21:04,201 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:21:04,202 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:21:04,204 - 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-11-01 04:21:04,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 13a95f25-ffde-4ea7-8a19-906e44cbc8f9, ghrun-i4gyxlpqdu.auto.internal:19287): creating call state
2025-11-01 04:21:04,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 13a95f25-ffde-4ea7-8a19-906e44cbc8f9, ghrun-i4gyxlpqdu.auto.internal:19287): request = { session_id: "ydb://session/3?node_id=1&id=Y2QzOTA5ZTctNjgzZTA4ODQtNjFlY2YwNy0yMDkwYWM5" 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-11-01 04:21:04,417 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 13a95f25-ffde-4ea7-8a19-906e44cbc8f9, ghrun-i4gyxlpqdu.auto.internal:19287): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:21:04,419 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d6c/ydb/apps/ydb/ydb
2025-11-01 04:21:04,419 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 04:21:04,420 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 04:21:04,420 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d6c/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:19287', '--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/g81u/004d6c/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004d6c/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004d6c/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004d6c/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:21:04,421 - DEBUG - ya.test - execute: Command pid: 2595308
2025-11-01 04:21:04,555 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) rc: 0
2025-11-01 04:21:04,555 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) elapsed time (sec): 0.13368511199951172
2025-11-01 04:21:04,555 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) idrss: 0
2025-11-01 04:21:04,555 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) inblock: 0
2025-11-01 04:21:04,555 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) isrss: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) ixrss: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) majflt: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) maxrss: 190304
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) minflt: 4151
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) msgrcv: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) msgsnd: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) nivcsw: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) nsignals: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) nswap: 0
2025-11-01 04:21:04,556 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) nvcsw: 531
2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) oublock: 80
2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) stime: 0.044756
2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) utime: 0.040278
2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) wtime: 0.134
2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) output:

2025-11-01 04:21:04,557 - DEBUG - ya.test - _save_outputs: Command (pid 2595308) errors:
Elapsed: 0.0142 sec. Total read size: 290B. Average processing speed: 19.9KiB/s.


2025-11-01 04:21:04,557 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:21:04,558 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d6c/ydb/apps/ydb/ydb
2025-11-01 04:21:04,558 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 04:21:04,558 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d6c/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:19287', '--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-11-01 04:21:04,559 - DEBUG - ya.test - execute: Command pid: 2595584
2025-11-01 04:21:04,706 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) rc: 0
2025-11-01 04:21:04,707 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) elapsed time (sec): 0.14664816856384277
2025-11-01 04:21:04,707 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) idrss: 0
2025-11-01 04:21:04,707 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) inblock: 0
2025-11-01 04:21:04,707 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) isrss: 0
2025-11-01 04:21:04,707 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) ixrss: 0
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) majflt: 0
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) maxrss: 190304
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) minflt: 3435
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) msgrcv: 0
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) msgsnd: 0
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) nivcsw: 1
2025-11-01 04:21:04,708 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) nsignals: 0
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) nswap: 0
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) nvcsw: 185
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) oublock: 8
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) stime: 0.025248
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) utime: 0.029456
2025-11-01 04:21:04,709 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) wtime: 0.147
2025-11-01 04:21:04,710 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) 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 04:21:04,710 - DEBUG - ya.test - _save_outputs: Command (pid 2595584) errors:

2025-11-01 04:21:04,710 - 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 04:21:04,712 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:21:26,714 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:19287/1
2025-11-01 04:21:26,718 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18366
2025-11-01 04:21:26,719 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21476
2025-11-01 04:21:26,719 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20779
2025-11-01 04:21:26,719 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19287
2025-11-01 04:21:26,719 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19029
2025-11-01 04:21:27,551 - 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-11-01 04:21:27,553 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] status: good
