2025-11-01 04:20:23,267 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:23,268 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-row]
2025-11-01 04:20:23,268 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:20:23,268 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:20:23,271 - 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 04:20:23,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 18911fb6-e6bb-4100-a0b4-227cc124f53e, ghrun-i4gyxlpqdu.auto.internal:10984): creating call state
2025-11-01 04:20:23,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 18911fb6-e6bb-4100-a0b4-227cc124f53e, ghrun-i4gyxlpqdu.auto.internal:10984): request = { session_id: "ydb://session/3?node_id=1&id=NjhmOWYwOGMtZjRiOWFmZjMtZjg2NGYxOWEtNTA4YTIwZjM=" 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 04:20:23,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 18911fb6-e6bb-4100-a0b4-227cc124f53e, ghrun-i4gyxlpqdu.auto.internal:10984): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:20:23,293 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb
2025-11-01 04:20:23,294 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-01 04:20:23,294 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-01 04:20:23,294 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10984', '--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/g81u/004de3/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/g81u/004de3/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-11-01 04:20:23,295 - DEBUG - ya.test - execute: Command pid: 2555830
2025-11-01 04:20:23,420 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) rc: 0
2025-11-01 04:20:23,420 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) elapsed time (sec): 0.12427830696105957
2025-11-01 04:20:23,420 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) idrss: 0
2025-11-01 04:20:23,420 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) inblock: 0
2025-11-01 04:20:23,420 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) isrss: 0
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) ixrss: 0
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) majflt: 0
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) maxrss: 184472
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) minflt: 4417
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) msgrcv: 0
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) msgsnd: 0
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) nivcsw: 3
2025-11-01 04:20:23,421 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) nsignals: 0
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) nswap: 0
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) nvcsw: 975
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) oublock: 48
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) stime: 0.045806
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) utime: 0.045806
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) wtime: 0.125
2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) output:

2025-11-01 04:20:23,422 - DEBUG - ya.test - _save_outputs: Command (pid 2555830) errors:
Elapsed: 0.00669 sec. Total read size: 290B. Average processing speed: 42.3KiB/s.


2025-11-01 04:20:23,423 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:20:23,423 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb
2025-11-01 04:20:23,424 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-01 04:20:23,424 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004de3/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10984', '--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 04:20:23,425 - DEBUG - ya.test - execute: Command pid: 2556107
2025-11-01 04:20:23,552 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) rc: 0
2025-11-01 04:20:23,553 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) elapsed time (sec): 0.1270768642425537
2025-11-01 04:20:23,553 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) idrss: 0
2025-11-01 04:20:23,553 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) inblock: 0
2025-11-01 04:20:23,553 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) isrss: 0
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) ixrss: 0
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) majflt: 0
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) maxrss: 184472
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) minflt: 3443
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) msgrcv: 0
2025-11-01 04:20:23,554 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) msgsnd: 0
2025-11-01 04:20:23,555 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) nivcsw: 1
2025-11-01 04:20:23,555 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) nsignals: 0
2025-11-01 04:20:23,555 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) nswap: 0
2025-11-01 04:20:23,555 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) nvcsw: 187
2025-11-01 04:20:23,555 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) oublock: 8
2025-11-01 04:20:23,556 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) stime: 0.031422
2025-11-01 04:20:23,556 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) utime: 0.026933
2025-11-01 04:20:23,556 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) wtime: 0.128
2025-11-01 04:20:23,556 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) 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:20:23,556 - DEBUG - ya.test - _save_outputs: Command (pid 2556107) errors:

2025-11-01 04:20:23,557 - 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:20:23,559 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:20:45,561 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10984/1
2025-11-01 04:20:45,562 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28168
2025-11-01 04:20:45,562 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8298
2025-11-01 04:20:45,562 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22299
2025-11-01 04:20:45,563 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10984
2025-11-01 04:20:45,563 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27090
2025-11-01 04:20:46,126 - 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 04:20:46,127 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] status: good
