2026-01-08 02:56:29,333 - INFO - ya.test.2389659  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:29,333 - INFO - ya.test.2389659  - pytest_runtest_setup: test_import_file_with_bom[tsv-additional_args3-column]
2026-01-08 02:56:29,334 - INFO - ya.test.2389659  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:56:29,334 - INFO - ya.test.2389659  - pytest_runtest_setup: Test setup
2026-01-08 02:56:29,335 - INFO - ya.test.2389659  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_import_file_with_bom[tsv-additional_args3-column])
2026-01-08 02:56:29,336 - DEBUG - ydb.connection.2389659  - _prepare_call: RpcState(ExecuteSchemeQuery, e5679d36-1a52-4394-8c65-17a071e35693, ghrun-paw7opzmb4.auto.internal:24054): creating call state
2026-01-08 02:56:29,336 - DEBUG - ydb.connection.2389659  - _log_request: RpcState(ExecuteSchemeQuery, e5679d36-1a52-4394-8c65-17a071e35693, ghrun-paw7opzmb4.auto.internal:24054): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhOTc2ZTMtYTUxN2FiYy04NTM4ZTNmMS00ZGJlZTA0Yw==" yql_text: "\n        CREATE TABLE `/Root/test_import_file_with_bom[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 } } }
2026-01-08 02:56:29,542 - DEBUG - ydb.connection.2389659  - _log_response: RpcState(ExecuteSchemeQuery, e5679d36-1a52-4394-8c65-17a071e35693, ghrun-paw7opzmb4.auto.internal:24054): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:56:29,543 - DEBUG - ya.test.2389659  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/00341e/ydb/apps/ydb/ydb
2026-01-08 02:56:29,544 - DEBUG - ya.test.2389659  - _get_command_output_file: Command out will be placed to ydb.out.1
2026-01-08 02:56:29,544 - DEBUG - ya.test.2389659  - _get_command_output_file: Command err will be placed to ydb.err.3
2026-01-08 02:56:29,544 - DEBUG - ya.test.2389659  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/00341e/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:24054', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_import_file_with_bom[tsv-additional_args3-column]', '-i', '/home/runner/.ya/build/build_root/pp6n/00341e/tmp/test_import_file_with_bom_tsv_2/tempinput.tsv', '--header', '--newline-delimited']' in 'None' (waiting)
2026-01-08 02:56:29,545 - DEBUG - ya.test.2389659  - execute: Command pid: 2392437
2026-01-08 02:56:29,687 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) rc: 0
2026-01-08 02:56:29,687 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) elapsed time (sec): 0.1412515640258789
2026-01-08 02:56:29,687 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) idrss: 0
2026-01-08 02:56:29,687 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) inblock: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) isrss: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) ixrss: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) majflt: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) maxrss: 184056
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) minflt: 4469
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) msgrcv: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) msgsnd: 0
2026-01-08 02:56:29,688 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) nivcsw: 0
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) nsignals: 0
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) nswap: 0
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) nvcsw: 659
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) oublock: 24
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) stime: 0.035806
2026-01-08 02:56:29,689 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) utime: 0.051718
2026-01-08 02:56:29,690 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) wtime: 0.142
2026-01-08 02:56:29,690 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) output:

2026-01-08 02:56:29,690 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392437) errors:
Elapsed: 0.0161 sec. Total read size: 65B. Average processing speed: 3.94KiB/s.


2026-01-08 02:56:29,690 - DEBUG - __tests__.test_ydb_impex.2389659  - execute_ydb_cli_command: std_out:

2026-01-08 02:56:29,691 - DEBUG - ya.test.2389659  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/00341e/ydb/apps/ydb/ydb
2026-01-08 02:56:29,692 - DEBUG - ya.test.2389659  - _get_command_output_file: Command err will be placed to ydb.err.4
2026-01-08 02:56:29,692 - DEBUG - ya.test.2389659  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/00341e/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:24054', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_import_file_with_bom[tsv-additional_args3-column]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2026-01-08 02:56:29,693 - DEBUG - ya.test.2389659  - execute: Command pid: 2392747
2026-01-08 02:56:29,835 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) rc: 0
2026-01-08 02:56:29,835 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) elapsed time (sec): 0.14161157608032227
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) idrss: 0
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) inblock: 0
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) isrss: 0
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) ixrss: 0
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) majflt: 0
2026-01-08 02:56:29,836 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) maxrss: 184056
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) minflt: 3559
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) msgrcv: 0
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) msgsnd: 0
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) nivcsw: 2
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) nsignals: 0
2026-01-08 02:56:29,837 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) nswap: 0
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) nvcsw: 202
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) oublock: 8
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) stime: 0.027736
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) utime: 0.031698
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) wtime: 0.143
2026-01-08 02:56:29,838 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"

2026-01-08 02:56:29,839 - DEBUG - ya.test.2389659  - _save_outputs: Command (pid 2392747) errors:

2026-01-08 02:56:29,839 - DEBUG - __tests__.test_ydb_impex.2389659  - 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:29,841 - INFO - ya.test.2389659  - pytest_runtest_teardown: Test teardown
2026-01-08 02:56:52,537 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_import_file_with_bom[tsv-additional_args3-column] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:56:52,538 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_import_file_with_bom[tsv-additional_args3-column] status: good
