2025-11-01 16:48:57,191 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:48:57,191 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-column]
2025-11-01 16:48:57,191 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:48:57,192 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:48:57,195 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files[tsv-additional_args3-column])
2025-11-01 16:48:57,196 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 00f871c5-2d2c-448b-b23a-3c6b179202b9, ghrun-tvlw7frnpa.auto.internal:3812): creating call state
2025-11-01 16:48:57,199 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 00f871c5-2d2c-448b-b23a-3c6b179202b9, ghrun-tvlw7frnpa.auto.internal:3812): request = { session_id: "ydb://session/3?node_id=1&id=MmUyYmUzNi0zYmY1Y2IzNi1jYjYyYzI2NC0xNTExYjNhYQ==" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files[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:48:57,665 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 00f871c5-2d2c-448b-b23a-3c6b179202b9, ghrun-tvlw7frnpa.auto.internal:3812): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:48:57,667 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb
2025-11-01 16:48:57,668 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 16:48:57,668 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 16:48:57,669 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:3812', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-column]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi2/tempinput0.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi2/tempinput1.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi2/tempinput2.tsv', '/home/runner/.ya/build/build_root/q6a6/001b47/tmp/test_multiple_files_tsv_additi2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 16:48:57,670 - DEBUG - ya.test - execute: Command pid: 3157817
2025-11-01 16:48:57,882 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) rc: 0
2025-11-01 16:48:57,882 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) elapsed time (sec): 0.21061325073242188
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) idrss: 0
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) inblock: 0
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) isrss: 0
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) ixrss: 0
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) majflt: 0
2025-11-01 16:48:57,883 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) maxrss: 187444
2025-11-01 16:48:57,884 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) minflt: 4406
2025-11-01 16:48:57,884 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) msgrcv: 0
2025-11-01 16:48:57,885 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) msgsnd: 0
2025-11-01 16:48:57,885 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) nivcsw: 48
2025-11-01 16:48:57,885 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) nsignals: 0
2025-11-01 16:48:57,885 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) nswap: 0
2025-11-01 16:48:57,885 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) nvcsw: 876
2025-11-01 16:48:57,886 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) oublock: 48
2025-11-01 16:48:57,887 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) stime: 0.03683
2025-11-01 16:48:57,888 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) utime: 0.068399
2025-11-01 16:48:57,888 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) wtime: 0.212
2025-11-01 16:48:57,889 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) output:

2025-11-01 16:48:57,890 - DEBUG - ya.test - _save_outputs: Command (pid 3157817) errors:
Elapsed: 0.031 sec. Total read size: 290B. Average processing speed: 9.14KiB/s.


2025-11-01 16:48:57,890 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 16:48:57,891 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb
2025-11-01 16:48:57,893 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 16:48:57,894 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/001b47/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:3812', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files[tsv-additional_args3-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-11-01 16:48:57,895 - DEBUG - ya.test - execute: Command pid: 3158211
2025-11-01 16:48:58,117 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) rc: 0
2025-11-01 16:48:58,118 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) elapsed time (sec): 0.22152233123779297
2025-11-01 16:48:58,118 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) idrss: 0
2025-11-01 16:48:58,119 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) inblock: 0
2025-11-01 16:48:58,119 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) isrss: 0
2025-11-01 16:48:58,119 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) ixrss: 0
2025-11-01 16:48:58,119 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) majflt: 0
2025-11-01 16:48:58,120 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) maxrss: 187444
2025-11-01 16:48:58,120 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) minflt: 3431
2025-11-01 16:48:58,120 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) msgrcv: 0
2025-11-01 16:48:58,120 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) msgsnd: 0
2025-11-01 16:48:58,121 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) nivcsw: 17
2025-11-01 16:48:58,121 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) nsignals: 0
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) nswap: 0
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) nvcsw: 185
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) oublock: 8
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) stime: 0.014369
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) utime: 0.057477
2025-11-01 16:48:58,122 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) wtime: 0.223
2025-11-01 16:48:58,123 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) 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:48:58,123 - DEBUG - ya.test - _save_outputs: Command (pid 3158211) errors:

2025-11-01 16:48:58,123 - 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:48:58,125 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 16:49:20,131 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:3812/1
2025-11-01 16:49:20,131 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1137
2025-11-01 16:49:20,132 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22702
2025-11-01 16:49:20,132 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21329
2025-11-01 16:49:20,132 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3812
2025-11-01 16:49:20,132 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18782
2025-11-01 16:49:20,703 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 16:49:20,704 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] status: good
