2025-11-01 16:50:02,200 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:50:02,200 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-row]
2025-11-01 16:50:02,200 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:50:02,200 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:50:02,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_args3-row])
2025-11-01 16:50:02,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 01df78b2-2b59-4e7b-81e2-b105e4eeca1a, ghrun-tvlw7frnpa.auto.internal:13499): creating call state
2025-11-01 16:50:02,206 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 01df78b2-2b59-4e7b-81e2-b105e4eeca1a, 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-row]` (\n            key Uint32 NOT NULL,\n            id Uint64,\n            value Utf8,\n            PRIMARY KEY(key)\n        )\n        \n        WITH (\n            STORE = row\n        )\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:50:02,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 01df78b2-2b59-4e7b-81e2-b105e4eeca1a, ghrun-tvlw7frnpa.auto.internal:13499): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:50:02,245 - 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:02,246 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 16:50:02,247 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 16:50:02,248 - 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-row]', '--header', '--columns', 'key\tid\tvalue\n', '--newline-delimited', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001987/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 16:50:02,249 - DEBUG - ya.test - execute: Command pid: 3208491
2025-11-01 16:50:02,426 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) rc: 0
2025-11-01 16:50:02,427 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) elapsed time (sec): 0.17441463470458984
2025-11-01 16:50:02,428 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) idrss: 0
2025-11-01 16:50:02,428 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) inblock: 0
2025-11-01 16:50:02,428 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) isrss: 0
2025-11-01 16:50:02,429 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) ixrss: 0
2025-11-01 16:50:02,429 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) majflt: 0
2025-11-01 16:50:02,429 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) maxrss: 195640
2025-11-01 16:50:02,430 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) minflt: 4396
2025-11-01 16:50:02,430 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) msgrcv: 0
2025-11-01 16:50:02,431 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) msgsnd: 0
2025-11-01 16:50:02,432 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) nivcsw: 50
2025-11-01 16:50:02,432 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) nsignals: 0
2025-11-01 16:50:02,432 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) nswap: 0
2025-11-01 16:50:02,433 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) nvcsw: 814
2025-11-01 16:50:02,433 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) oublock: 48
2025-11-01 16:50:02,433 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) stime: 0.047453999999999996
2025-11-01 16:50:02,433 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) utime: 0.058405
2025-11-01 16:50:02,433 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) wtime: 0.175
2025-11-01 16:50:02,434 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) output:

2025-11-01 16:50:02,434 - DEBUG - ya.test - _save_outputs: Command (pid 3208491) errors:
Elapsed: 0.0142 sec. Total read size: 290B. Average processing speed: 19.9KiB/s.


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

2025-11-01 16:50:02,434 - 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:02,435 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 16:50:02,436 - 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-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-11-01 16:50:02,437 - DEBUG - ya.test - execute: Command pid: 3209015
2025-11-01 16:50:02,613 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) rc: 0
2025-11-01 16:50:02,614 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) elapsed time (sec): 0.17413663864135742
2025-11-01 16:50:02,615 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) idrss: 0
2025-11-01 16:50:02,615 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) inblock: 0
2025-11-01 16:50:02,615 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) isrss: 0
2025-11-01 16:50:02,615 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) ixrss: 0
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) majflt: 0
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) maxrss: 195640
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) minflt: 3431
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) msgrcv: 0
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) msgsnd: 0
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) nivcsw: 25
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) nsignals: 0
2025-11-01 16:50:02,616 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) nswap: 0
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) nvcsw: 150
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) oublock: 8
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) stime: 0.012329
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) utime: 0.057538
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) wtime: 0.177
2025-11-01 16:50:02,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) 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,617 - DEBUG - ya.test - _save_outputs: Command (pid 3209015) errors:

2025-11-01 16:50:02,618 - 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,624 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:50:24,626 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:13499/1
2025-11-01 16:50:24,627 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4429
2025-11-01 16:50:24,628 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10055
2025-11-01 16:50:24,628 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25419
2025-11-01 16:50:24,628 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13499
2025-11-01 16:50:24,628 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64865
2025-11-01 16:50:25,270 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 16:50:25,271 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] status: good
