2025-10-31 08:42:59,540 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:59,540 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args3-row]
2025-10-31 08:42:59,540 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:42:59,540 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:42:59,542 - 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-10-31 08:42:59,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, e9749c94-0685-46ec-a68e-565341b21aef, ghrun-uabpkrvm6i.auto.internal:6662): creating call state
2025-10-31 08:42:59,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, e9749c94-0685-46ec-a68e-565341b21aef, ghrun-uabpkrvm6i.auto.internal:6662): request = { session_id: "ydb://session/3?node_id=1&id=NTFkOTVmZTktMmVlNWIyMWMtZjliM2U1YWMtZWM3ZDkxNjY=" 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-10-31 08:42:59,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, e9749c94-0685-46ec-a68e-565341b21aef, ghrun-uabpkrvm6i.auto.internal:6662): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:42:59,568 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:59,569 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 08:42:59,569 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 08:42:59,570 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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/pogg/001612/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001612/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:42:59,571 - DEBUG - ya.test - execute: Command pid: 3308581
2025-10-31 08:42:59,719 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) rc: 0
2025-10-31 08:42:59,719 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) elapsed time (sec): 0.1476459503173828
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) idrss: 0
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) inblock: 0
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) isrss: 0
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) ixrss: 0
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) majflt: 0
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) maxrss: 184392
2025-10-31 08:42:59,720 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) minflt: 4422
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) msgrcv: 0
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) msgsnd: 0
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) nivcsw: 19
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) nsignals: 0
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) nswap: 0
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) nvcsw: 969
2025-10-31 08:42:59,721 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) oublock: 48
2025-10-31 08:42:59,722 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) stime: 0.054624
2025-10-31 08:42:59,722 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) utime: 0.054624
2025-10-31 08:42:59,722 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) wtime: 0.149
2025-10-31 08:42:59,722 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) output:

2025-10-31 08:42:59,722 - DEBUG - ya.test - _save_outputs: Command (pid 3308581) errors:
Elapsed: 0.00737 sec. Total read size: 290B. Average processing speed: 38.4KiB/s.


2025-10-31 08:42:59,722 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:42:59,723 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb
2025-10-31 08:42:59,723 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 08:42:59,724 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001612/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:6662', '--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-10-31 08:42:59,725 - DEBUG - ya.test - execute: Command pid: 3308939
2025-10-31 08:42:59,864 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) rc: 0
2025-10-31 08:42:59,865 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) elapsed time (sec): 0.13918137550354004
2025-10-31 08:42:59,865 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) idrss: 0
2025-10-31 08:42:59,865 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) inblock: 0
2025-10-31 08:42:59,866 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) isrss: 0
2025-10-31 08:42:59,867 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) ixrss: 0
2025-10-31 08:42:59,867 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) majflt: 0
2025-10-31 08:42:59,867 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) maxrss: 184392
2025-10-31 08:42:59,868 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) minflt: 3430
2025-10-31 08:42:59,868 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) msgrcv: 0
2025-10-31 08:42:59,868 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) msgsnd: 0
2025-10-31 08:42:59,868 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) nivcsw: 3
2025-10-31 08:42:59,868 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) nsignals: 0
2025-10-31 08:42:59,869 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) nswap: 0
2025-10-31 08:42:59,869 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) nvcsw: 209
2025-10-31 08:42:59,869 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) oublock: 8
2025-10-31 08:42:59,870 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) stime: 0.025869
2025-10-31 08:42:59,870 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) utime: 0.044347
2025-10-31 08:42:59,870 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) wtime: 0.14
2025-10-31 08:42:59,871 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) 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-10-31 08:42:59,871 - DEBUG - ya.test - _save_outputs: Command (pid 3308939) errors:

2025-10-31 08:42:59,871 - 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-10-31 08:42:59,876 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:43:21,879 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:6662/1
2025-10-31 08:43:21,880 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14684
2025-10-31 08:43:21,880 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25187
2025-10-31 08:43:21,880 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4824
2025-10-31 08:43:21,880 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6662
2025-10-31 08:43:21,880 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27953
2025-10-31 08:43:22,409 - 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-10-31 08:43:22,410 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args3-row] status: good
