2026-01-08 02:56:22,820 - INFO - ya.test.2384930  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:22,820 - INFO - ya.test.2384930  - pytest_runtest_setup: test_delimeter_at_end_of_lines[tsv-additional_args2-row]
2026-01-08 02:56:22,820 - INFO - ya.test.2384930  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:22,820 - INFO - ya.test.2384930  - pytest_runtest_setup: Test setup
2026-01-08 02:56:22,822 - INFO - ya.test.2384930  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_delimeter_at_end_of_lines[tsv-additional_args2-row])
2026-01-08 02:56:22,822 - DEBUG - ydb.connection.2384930  - _prepare_call: RpcState(ExecuteSchemeQuery, 544c224c-ad1a-4739-ac95-8ff639bc9826, ghrun-paw7opzmb4.auto.internal:21158): creating call state
2026-01-08 02:56:22,822 - DEBUG - ydb.connection.2384930  - _log_request: RpcState(ExecuteSchemeQuery, 544c224c-ad1a-4739-ac95-8ff639bc9826, ghrun-paw7opzmb4.auto.internal:21158): request = { session_id: "ydb://session/3?node_id=1&id=MzhjMzQ3ZDUtZWUxZjExMWEtMWUxOWY5ZWQtNDU1NDE3NTg=" yql_text: "\n        CREATE TABLE `/Root/test_delimeter_at_end_of_lines[tsv-additional_args2-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 } } }
2026-01-08 02:56:22,840 - DEBUG - ydb.connection.2384930  - _log_response: RpcState(ExecuteSchemeQuery, 544c224c-ad1a-4739-ac95-8ff639bc9826, ghrun-paw7opzmb4.auto.internal:21158): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:56:22,841 - DEBUG - ya.test.2384930  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/003428/ydb/apps/ydb/ydb
2026-01-08 02:56:22,841 - DEBUG - ya.test.2384930  - _get_command_output_file: Command out will be placed to ydb.out.2
2026-01-08 02:56:22,842 - DEBUG - ya.test.2384930  - _get_command_output_file: Command err will be placed to ydb.err.5
2026-01-08 02:56:22,842 - DEBUG - ya.test.2384930  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/003428/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:21158', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_delimeter_at_end_of_lines[tsv-additional_args2-row]', '-i', '/home/runner/.ya/build/build_root/pp6n/003428/tmp/test_delimeter_at_end_of_lines3/tempinput.tsv', '--header']' in 'None' (waiting)
2026-01-08 02:56:22,843 - DEBUG - ya.test.2384930  - execute: Command pid: 2388321
2026-01-08 02:56:22,968 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) rc: 0
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) elapsed time (sec): 0.12493467330932617
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) idrss: 0
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) inblock: 0
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) isrss: 0
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) ixrss: 0
2026-01-08 02:56:22,969 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) majflt: 0
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) maxrss: 183880
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) minflt: 4194
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) msgrcv: 0
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) msgsnd: 0
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) nivcsw: 3
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) nsignals: 0
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) nswap: 0
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) nvcsw: 483
2026-01-08 02:56:22,970 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) oublock: 32
2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) stime: 0.034464999999999996
2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) utime: 0.042123999999999995
2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) wtime: 0.126
2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) output:

2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388321) errors:
Elapsed: 0.00303 sec. Total read size: 70B. Average processing speed: 22.6KiB/s.


2026-01-08 02:56:22,971 - DEBUG - __tests__.test_ydb_impex.2384930  - execute_ydb_cli_command: std_out:

2026-01-08 02:56:22,971 - DEBUG - ya.test.2384930  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/003428/ydb/apps/ydb/ydb
2026-01-08 02:56:22,972 - DEBUG - ya.test.2384930  - _get_command_output_file: Command err will be placed to ydb.err.6
2026-01-08 02:56:22,972 - DEBUG - ya.test.2384930  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/003428/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:21158', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_delimeter_at_end_of_lines[tsv-additional_args2-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2026-01-08 02:56:22,973 - DEBUG - ya.test.2384930  - execute: Command pid: 2388525
2026-01-08 02:56:23,094 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) rc: 0
2026-01-08 02:56:23,094 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) elapsed time (sec): 0.1204984188079834
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) idrss: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) inblock: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) isrss: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) ixrss: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) majflt: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) maxrss: 183880
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) minflt: 3557
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) msgrcv: 0
2026-01-08 02:56:23,095 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) msgsnd: 0
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) nivcsw: 1
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) nsignals: 0
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) nswap: 0
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) nvcsw: 193
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) oublock: 8
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) stime: 0.01723
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) utime: 0.038769
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) wtime: 0.121
2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"

2026-01-08 02:56:23,096 - DEBUG - ya.test.2384930  - _save_outputs: Command (pid 2388525) errors:

2026-01-08 02:56:23,097 - DEBUG - __tests__.test_ydb_impex.2384930  - execute_ydb_cli_command: std_out:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"

2026-01-08 02:56:23,098 - INFO - ya.test.2384930  - pytest_runtest_teardown: Test teardown
2026-01-08 02:56:45,100 - INFO - ydb.tests.library.harness.kikimr_runner.2384930  - stop: Stopped node localhost:21158/1
2026-01-08 02:56:45,101 - DEBUG - library.python.filelock.2384930  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11541
2026-01-08 02:56:45,101 - DEBUG - library.python.filelock.2384930  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9826
2026-01-08 02:56:45,102 - DEBUG - library.python.filelock.2384930  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20961
2026-01-08 02:56:45,102 - DEBUG - library.python.filelock.2384930  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21158
2026-01-08 02:56:45,102 - DEBUG - library.python.filelock.2384930  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10265
2026-01-08 02:56:45,559 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_delimeter_at_end_of_lines[tsv-additional_args2-row] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:56:45,560 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_delimeter_at_end_of_lines[tsv-additional_args2-row] status: good
