2025-10-31 08:41:52,846 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:52,846 - INFO - ya.test - pytest_runtest_setup: test_multiple_files_and_columns_opt[tsv-additional_args2-column]
2025-10-31 08:41:52,846 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 08:41:52,847 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 08:41:52,849 - 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_args2-column])
2025-10-31 08:41:52,849 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 4365fe08-3ac6-4d7b-bfb7-8687f2a27c6c, ghrun-uabpkrvm6i.auto.internal:63217): creating call state
2025-10-31 08:41:52,850 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 4365fe08-3ac6-4d7b-bfb7-8687f2a27c6c, ghrun-uabpkrvm6i.auto.internal:63217): request = { session_id: "ydb://session/3?node_id=1&id=YWNhYjVkNWItNjIyMWQ2ZmMtNGYxMmE4ZTMtN2NlYzdjNTM=" yql_text: "\n        CREATE TABLE `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-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 08:41:53,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 4365fe08-3ac6-4d7b-bfb7-8687f2a27c6c, ghrun-uabpkrvm6i.auto.internal:63217): response = { operation { ready: true status: SUCCESS } }
2025-10-31 08:41:53,153 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001d34/ydb/apps/ydb/ydb
2025-10-31 08:41:53,154 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-31 08:41:53,155 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-31 08:41:53,155 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001d34/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:63217', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-column]', '--header', '--columns', 'key\tid\tvalue\n', '/home/runner/.ya/build/build_root/pogg/001d34/tmp/test_multiple_files_and_column2/tempinput0.tsv', '/home/runner/.ya/build/build_root/pogg/001d34/tmp/test_multiple_files_and_column2/tempinput1.tsv', '/home/runner/.ya/build/build_root/pogg/001d34/tmp/test_multiple_files_and_column2/tempinput2.tsv', '/home/runner/.ya/build/build_root/pogg/001d34/tmp/test_multiple_files_and_column2/tempinput3.tsv']' in 'None' (waiting)
2025-10-31 08:41:53,156 - DEBUG - ya.test - execute: Command pid: 3252462
2025-10-31 08:41:53,323 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) rc: 0
2025-10-31 08:41:53,323 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) elapsed time (sec): 0.16569089889526367
2025-10-31 08:41:53,323 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) idrss: 0
2025-10-31 08:41:53,324 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) inblock: 0
2025-10-31 08:41:53,324 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) isrss: 0
2025-10-31 08:41:53,325 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) ixrss: 0
2025-10-31 08:41:53,325 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) majflt: 0
2025-10-31 08:41:53,326 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) maxrss: 184260
2025-10-31 08:41:53,327 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) minflt: 4158
2025-10-31 08:41:53,327 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) msgrcv: 0
2025-10-31 08:41:53,328 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) msgsnd: 0
2025-10-31 08:41:53,328 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) nivcsw: 2
2025-10-31 08:41:53,329 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) nsignals: 0
2025-10-31 08:41:53,329 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) nswap: 0
2025-10-31 08:41:53,330 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) nvcsw: 548
2025-10-31 08:41:53,330 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) oublock: 80
2025-10-31 08:41:53,331 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) stime: 0.033753
2025-10-31 08:41:53,332 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) utime: 0.071256
2025-10-31 08:41:53,332 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) wtime: 0.167
2025-10-31 08:41:53,332 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) output:

2025-10-31 08:41:53,332 - DEBUG - ya.test - _save_outputs: Command (pid 3252462) errors:
Elapsed: 0.0248 sec. Total read size: 290B. Average processing speed: 11.4KiB/s.


2025-10-31 08:41:53,333 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 08:41:53,334 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/pogg/001d34/ydb/apps/ydb/ydb
2025-10-31 08:41:53,335 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-31 08:41:53,335 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/pogg/001d34/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:63217', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_multiple_files_and_columns_opt[tsv-additional_args2-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 08:41:53,336 - DEBUG - ya.test - execute: Command pid: 3252640
2025-10-31 08:41:53,520 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) rc: 0
2025-10-31 08:41:53,520 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) elapsed time (sec): 0.18288969993591309
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) idrss: 0
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) inblock: 0
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) isrss: 0
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) ixrss: 0
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) majflt: 0
2025-10-31 08:41:53,521 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) maxrss: 184260
2025-10-31 08:41:53,522 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) minflt: 3434
2025-10-31 08:41:53,522 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) msgrcv: 0
2025-10-31 08:41:53,523 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) msgsnd: 0
2025-10-31 08:41:53,523 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) nivcsw: 7
2025-10-31 08:41:53,523 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) nsignals: 0
2025-10-31 08:41:53,523 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) nswap: 0
2025-10-31 08:41:53,524 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) nvcsw: 200
2025-10-31 08:41:53,524 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) oublock: 8
2025-10-31 08:41:53,524 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) stime: 0.020180999999999998
2025-10-31 08:41:53,524 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) utime: 0.056506999999999995
2025-10-31 08:41:53,524 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) wtime: 0.184
2025-10-31 08:41:53,525 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) 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:41:53,525 - DEBUG - ya.test - _save_outputs: Command (pid 3252640) errors:

2025-10-31 08:41:53,525 - 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:41:53,527 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 08:42:15,531 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:63217/1
2025-10-31 08:42:15,532 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21510
2025-10-31 08:42:15,533 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29255
2025-10-31 08:42:15,533 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11045
2025-10-31 08:42:15,533 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63217
2025-10-31 08:42:15,533 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17430
2025-10-31 08:42:16,277 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 08:42:16,283 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_multiple_files_and_columns_opt[tsv-additional_args2-column] status: good
