2025-11-01 15:58:44,235 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 15:58:44,235 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-column]
2025-11-01 15:58:44,255 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 15:58:44,255 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 15:58:44,258 - 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 15:58:44,258 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 0d561162-2f27-4007-89da-45f26c96cfd8, ghrun-tvlw7frnpa.auto.internal:18424): creating call state
2025-11-01 15:58:44,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 0d561162-2f27-4007-89da-45f26c96cfd8, ghrun-tvlw7frnpa.auto.internal:18424): request = { session_id: "ydb://session/3?node_id=1&id=NWQyYWNjNDgtOTI0ZmFlN2YtZjQ3YjM3NmEtMjU0NmE0Mjk=" 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 15:58:44,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 0d561162-2f27-4007-89da-45f26c96cfd8, ghrun-tvlw7frnpa.auto.internal:18424): response = { operation { ready: true status: SUCCESS } }
2025-11-01 15:58:44,995 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001c63/ydb/apps/ydb/ydb
2025-11-01 15:58:44,996 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 15:58:44,997 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 15:58:44,997 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001c63/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:18424', '--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/q6a6/001c63/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001c63/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001c63/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001c63/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 15:58:44,998 - DEBUG - ya.test - execute: Command pid: 26186
2025-11-01 15:58:45,410 - DEBUG - ya.test - _save_outputs: Command (pid 26186) rc: 0
2025-11-01 15:58:45,410 - DEBUG - ya.test - _save_outputs: Command (pid 26186) elapsed time (sec): 0.41105151176452637
2025-11-01 15:58:45,410 - DEBUG - ya.test - _save_outputs: Command (pid 26186) idrss: 0
2025-11-01 15:58:45,410 - DEBUG - ya.test - _save_outputs: Command (pid 26186) inblock: 0
2025-11-01 15:58:45,411 - DEBUG - ya.test - _save_outputs: Command (pid 26186) isrss: 0
2025-11-01 15:58:45,411 - DEBUG - ya.test - _save_outputs: Command (pid 26186) ixrss: 0
2025-11-01 15:58:45,411 - DEBUG - ya.test - _save_outputs: Command (pid 26186) majflt: 0
2025-11-01 15:58:45,411 - DEBUG - ya.test - _save_outputs: Command (pid 26186) maxrss: 197524
2025-11-01 15:58:45,411 - DEBUG - ya.test - _save_outputs: Command (pid 26186) minflt: 4148
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) msgrcv: 0
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) msgsnd: 0
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) nivcsw: 123
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) nsignals: 0
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) nswap: 0
2025-11-01 15:58:45,412 - DEBUG - ya.test - _save_outputs: Command (pid 26186) nvcsw: 569
2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) oublock: 80
2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) stime: 0.126846
2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) utime: 0.05078
2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) wtime: 0.412
2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) output:

2025-11-01 15:58:45,413 - DEBUG - ya.test - _save_outputs: Command (pid 26186) errors:
Elapsed: 0.0523 sec. Total read size: 290B. Average processing speed: 5.42KiB/s.


2025-11-01 15:58:45,414 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 15:58:45,414 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001c63/ydb/apps/ydb/ydb
2025-11-01 15:58:45,467 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 15:58:45,468 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001c63/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:18424', '--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 15:58:45,471 - DEBUG - ya.test - execute: Command pid: 26758
2025-11-01 15:58:45,949 - DEBUG - ya.test - _save_outputs: Command (pid 26758) rc: 0
2025-11-01 15:58:45,949 - DEBUG - ya.test - _save_outputs: Command (pid 26758) elapsed time (sec): 0.47738122940063477
2025-11-01 15:58:45,949 - DEBUG - ya.test - _save_outputs: Command (pid 26758) idrss: 0
2025-11-01 15:58:45,949 - DEBUG - ya.test - _save_outputs: Command (pid 26758) inblock: 0
2025-11-01 15:58:45,949 - DEBUG - ya.test - _save_outputs: Command (pid 26758) isrss: 0
2025-11-01 15:58:45,950 - DEBUG - ya.test - _save_outputs: Command (pid 26758) ixrss: 0
2025-11-01 15:58:45,950 - DEBUG - ya.test - _save_outputs: Command (pid 26758) majflt: 0
2025-11-01 15:58:45,950 - DEBUG - ya.test - _save_outputs: Command (pid 26758) maxrss: 197524
2025-11-01 15:58:45,950 - DEBUG - ya.test - _save_outputs: Command (pid 26758) minflt: 3435
2025-11-01 15:58:45,950 - DEBUG - ya.test - _save_outputs: Command (pid 26758) msgrcv: 0
2025-11-01 15:58:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 26758) msgsnd: 0
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) nivcsw: 70
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) nsignals: 0
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) nswap: 0
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) nvcsw: 147
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) oublock: 8
2025-11-01 15:58:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 26758) stime: 0.206334
2025-11-01 15:58:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 26758) utime: 0.036411
2025-11-01 15:58:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 26758) wtime: 0.48
2025-11-01 15:58:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 26758) 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 15:58:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 26758) errors:

2025-11-01 15:58:46,000 - 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 15:58:46,006 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 15:59:08,118 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18424/1
2025-11-01 15:59:08,123 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4950
2025-11-01 15:59:08,123 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1510
2025-11-01 15:59:08,123 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1961
2025-11-01 15:59:08,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18424
2025-11-01 15:59:08,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27742
2025-11-01 15:59:08,767 - 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 15:59:08,768 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] status: good
