2025-10-31 15:39:50,073 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:39:50,073 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_stdin[tsv-additional_args3-column]
2025-10-31 15:39:50,073 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:39:50,074 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:39:50,076 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_multiple_files_and_stdin[tsv-additional_args3-column])
2025-10-31 15:39:50,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, e756de4d-de56-45e9-8609-dc29f64bee25, ghrun-upzbhs6y6e.auto.internal:28790): creating call state
2025-10-31 15:39:50,086 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, e756de4d-de56-45e9-8609-dc29f64bee25, ghrun-upzbhs6y6e.auto.internal:28790): request = { session_id: "ydb://session/3?node_id=1&id=YTVjY2IxNmUtZTAzNWQyMzEtMmZiZTRlMTYtOWUxY2RiNzA=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_stdin[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-10-31 15:39:50,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, e756de4d-de56-45e9-8609-dc29f64bee25, ghrun-upzbhs6y6e.auto.internal:28790): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:39:50,686 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002faa/ydb/apps/ydb/ydb
2025-10-31 15:39:50,687 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 15:39:50,688 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 15:39:50,688 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002faa/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28790', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_stdin[tsv-additional_args3-column]', '--header', '--newline-delimited', '/home/runner/.ya/build/build_root/stm2/002faa/tmp/test_multiple_files_and_stdin_2/tempinput0.tsv', '/home/runner/.ya/build/build_root/stm2/002faa/tmp/test_multiple_files_and_stdin_2/tempinput1.tsv', '/home/runner/.ya/build/build_root/stm2/002faa/tmp/test_multiple_files_and_stdin_2/tempinput2.tsv', '/home/runner/.ya/build/build_root/stm2/002faa/tmp/test_multiple_files_and_stdin_2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 15:39:50,696 - DEBUG - ya.test - execute: Command pid: 3016508
2025-10-31 15:39:50,917 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) rc: 0
2025-10-31 15:39:50,917 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) elapsed time (sec): 0.22010564804077148
2025-10-31 15:39:50,917 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) idrss: 0
2025-10-31 15:39:50,917 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) inblock: 0
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) isrss: 0
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) ixrss: 0
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) majflt: 0
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) maxrss: 184216
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) minflt: 4425
2025-10-31 15:39:50,918 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) msgrcv: 0
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) msgsnd: 0
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) nivcsw: 58
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) nsignals: 0
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) nswap: 0
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) nvcsw: 806
2025-10-31 15:39:50,921 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) oublock: 48
2025-10-31 15:39:50,922 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) stime: 0.040234
2025-10-31 15:39:50,922 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) utime: 0.060350999999999995
2025-10-31 15:39:50,923 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) wtime: 0.228
2025-10-31 15:39:50,923 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) output:

2025-10-31 15:39:50,923 - DEBUG - ya.test - _save_outputs: Command (pid 3016508) errors:
Elapsed: 0.0432 sec. Total read size: 290B. Average processing speed: 6.56KiB/s.


2025-10-31 15:39:50,923 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:39:50,924 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/002faa/ydb/apps/ydb/ydb
2025-10-31 15:39:50,932 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 15:39:50,933 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/002faa/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:28790', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_stdin[tsv-additional_args3-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 15:39:50,934 - DEBUG - ya.test - execute: Command pid: 3016977
2025-10-31 15:39:51,175 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) rc: 0
2025-10-31 15:39:51,175 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) elapsed time (sec): 0.23771381378173828
2025-10-31 15:39:51,176 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) idrss: 0
2025-10-31 15:39:51,177 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) inblock: 0
2025-10-31 15:39:51,177 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) isrss: 0
2025-10-31 15:39:51,177 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) ixrss: 0
2025-10-31 15:39:51,177 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) majflt: 0
2025-10-31 15:39:51,178 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) maxrss: 184216
2025-10-31 15:39:51,179 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) minflt: 3440
2025-10-31 15:39:51,179 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) msgrcv: 0
2025-10-31 15:39:51,179 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) msgsnd: 0
2025-10-31 15:39:51,179 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) nivcsw: 19
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) nsignals: 0
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) nswap: 0
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) nvcsw: 149
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) oublock: 8
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) stime: 0.030226
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) utime: 0.038862
2025-10-31 15:39:51,180 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) wtime: 0.242
2025-10-31 15:39:51,181 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) 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 15:39:51,181 - DEBUG - ya.test - _save_outputs: Command (pid 3016977) errors:

2025-10-31 15:39:51,181 - 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 15:39:51,183 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:40:13,204 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:28790/1
2025-10-31 15:40:13,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9913
2025-10-31 15:40:13,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6934
2025-10-31 15:40:13,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8179
2025-10-31 15:40:13,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28790
2025-10-31 15:40:13,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16334
2025-10-31 15:40:13,831 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_stdin[tsv-additional_args3-column] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 15:40:13,833 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_stdin[tsv-additional_args3-column] status: good
