2025-11-01 04:29:56,900 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:29:56,904 - INFO - ya.test - pytest_runtest_setup: test_multiple_files[tsv-additional_args3-column]
2025-11-01 04:29:56,911 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:29:56,919 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:29:56,923 - 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 04:29:56,924 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, fe97fde2-2787-4493-843d-7f593b0b63c3, ghrun-i4gyxlpqdu.auto.internal:28853): creating call state
2025-11-01 04:29:56,925 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, fe97fde2-2787-4493-843d-7f593b0b63c3, ghrun-i4gyxlpqdu.auto.internal:28853): request = { session_id: "ydb://session/3?node_id=1&id=MzZlN2M5ZTAtZTRkNWVmZmQtOTAyNGQ4MzEtM2QxY2VhNGY=" 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 04:29:57,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, fe97fde2-2787-4493-843d-7f593b0b63c3, ghrun-i4gyxlpqdu.auto.internal:28853): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:29:57,604 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb
2025-11-01 04:29:57,605 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 04:29:57,606 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 04:29:57,617 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28853', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files[tsv-additional_args3-column]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi2/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi2/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi2/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004d80/tmp/test_multiple_files_tsv_additi2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:29:57,621 - DEBUG - ya.test - execute: Command pid: 2901759
2025-11-01 04:29:57,891 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) rc: 0
2025-11-01 04:29:57,891 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) elapsed time (sec): 0.2686777114868164
2025-11-01 04:29:57,891 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) idrss: 0
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) inblock: 0
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) isrss: 0
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) ixrss: 0
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) majflt: 0
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) maxrss: 187756
2025-11-01 04:29:57,892 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) minflt: 4393
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) msgrcv: 0
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) msgsnd: 0
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) nivcsw: 111
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) nsignals: 0
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) nswap: 0
2025-11-01 04:29:57,893 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) nvcsw: 746
2025-11-01 04:29:57,894 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) oublock: 48
2025-11-01 04:29:57,894 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) stime: 0.059225999999999994
2025-11-01 04:29:57,894 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) utime: 0.055415
2025-11-01 04:29:57,895 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) wtime: 0.272
2025-11-01 04:29:57,895 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) output:

2025-11-01 04:29:57,895 - DEBUG - ya.test - _save_outputs: Command (pid 2901759) errors:
Elapsed: 0.0434 sec. Total read size: 290B. Average processing speed: 6.53KiB/s.


2025-11-01 04:29:57,895 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:29:57,896 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb
2025-11-01 04:29:57,897 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 04:29:57,897 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004d80/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28853', '--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 04:29:57,908 - DEBUG - ya.test - execute: Command pid: 2902266
2025-11-01 04:29:58,202 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) rc: 0
2025-11-01 04:29:58,203 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) elapsed time (sec): 0.2940795421600342
2025-11-01 04:29:58,210 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) idrss: 0
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) inblock: 0
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) isrss: 0
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) ixrss: 0
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) majflt: 0
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) maxrss: 187756
2025-11-01 04:29:58,211 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) minflt: 3451
2025-11-01 04:29:58,212 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) msgrcv: 0
2025-11-01 04:29:58,212 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) msgsnd: 0
2025-11-01 04:29:58,217 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) nivcsw: 30
2025-11-01 04:29:58,217 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) nsignals: 0
2025-11-01 04:29:58,218 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) nswap: 0
2025-11-01 04:29:58,218 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) nvcsw: 133
2025-11-01 04:29:58,218 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) oublock: 8
2025-11-01 04:29:58,218 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) stime: 0.023611999999999998
2025-11-01 04:29:58,218 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) utime: 0.051947
2025-11-01 04:29:58,219 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) wtime: 0.298
2025-11-01 04:29:58,219 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) 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:29:58,219 - DEBUG - ya.test - _save_outputs: Command (pid 2902266) errors:

2025-11-01 04:29:58,219 - 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:29:58,221 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:30:20,227 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:28853/1
2025-11-01 04:30:20,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26477
2025-11-01 04:30:20,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61235
2025-11-01 04:30:20,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27591
2025-11-01 04:30:20,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28853
2025-11-01 04:30:20,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21291
2025-11-01 04:30:20,996 - 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 04:30:20,997 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files[tsv-additional_args3-column] status: good
