2025-10-31 15:31:00,746 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:00,746 - INFO - ya.test - pytest_runtest_setup: test_excess_columns[tsv-additional_args3-row]
2025-10-31 15:31:00,747 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:31:00,747 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:31:00,749 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_excess_columns[tsv-additional_args3-row])
2025-10-31 15:31:00,749 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, abb4dd53-05c6-47de-86f7-0a1125681c29, ghrun-upzbhs6y6e.auto.internal:10642): creating call state
2025-10-31 15:31:00,750 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, abb4dd53-05c6-47de-86f7-0a1125681c29, ghrun-upzbhs6y6e.auto.internal:10642): request = { session_id: "ydb://session/3?node_id=1&id=YTQyZmM5YWQtZWI0YjEyYzQtOTY0ODAxYzEtMmIxNTgwMzQ=" yql_text: "\n        CREATE TABLE `/Root/test_excess_columns[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 15:31:00,779 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, abb4dd53-05c6-47de-86f7-0a1125681c29, ghrun-upzbhs6y6e.auto.internal:10642): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:31:00,783 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b4/ydb/apps/ydb/ydb
2025-10-31 15:31:00,784 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-10-31 15:31:00,785 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-10-31 15:31:00,785 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b4/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10642', '--database', '/Root', 'import', 'file', 'tsv', '-p', '/Root/test_excess_columns[tsv-additional_args3-row]', '-i', '/home/runner/.ya/build/build_root/stm2/0030b4/tmp/test_excess_columns_tsv_additi3/tempinput.tsv', '--header', '--newline-delimited']' in 'None' (waiting)
2025-10-31 15:31:00,787 - DEBUG - ya.test - execute: Command pid: 2645518
2025-10-31 15:31:00,946 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) rc: 0
2025-10-31 15:31:00,946 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) elapsed time (sec): 0.15882539749145508
2025-10-31 15:31:00,947 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) idrss: 0
2025-10-31 15:31:00,947 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) inblock: 0
2025-10-31 15:31:00,947 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) isrss: 0
2025-10-31 15:31:00,947 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) ixrss: 0
2025-10-31 15:31:00,947 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) majflt: 0
2025-10-31 15:31:00,948 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) maxrss: 187504
2025-10-31 15:31:00,948 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) minflt: 4359
2025-10-31 15:31:00,948 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) msgrcv: 0
2025-10-31 15:31:00,948 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) msgsnd: 0
2025-10-31 15:31:00,948 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) nivcsw: 28
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) nsignals: 0
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) nswap: 0
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) nvcsw: 757
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) oublock: 24
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) stime: 0.039685
2025-10-31 15:31:00,949 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) utime: 0.059518999999999996
2025-10-31 15:31:00,950 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) wtime: 0.16
2025-10-31 15:31:00,950 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) output:

2025-10-31 15:31:00,950 - DEBUG - ya.test - _save_outputs: Command (pid 2645518) errors:
Elapsed: 0.0112 sec. Total read size: 75B. Average processing speed: 6.57KiB/s.


2025-10-31 15:31:00,950 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-10-31 15:31:00,951 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/stm2/0030b4/ydb/apps/ydb/ydb
2025-10-31 15:31:00,952 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-10-31 15:31:00,955 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/stm2/0030b4/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:10642', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_excess_columns[tsv-additional_args3-row]` ORDER BY `key`', '-t', 'scan', '--format', 'tsv']' in 'None' (waiting)
2025-10-31 15:31:00,976 - DEBUG - ya.test - execute: Command pid: 2645895
2025-10-31 15:31:01,122 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) rc: 0
2025-10-31 15:31:01,122 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) elapsed time (sec): 0.14474105834960938
2025-10-31 15:31:01,122 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) idrss: 0
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) inblock: 0
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) isrss: 0
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) ixrss: 0
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) majflt: 0
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) maxrss: 187504
2025-10-31 15:31:01,123 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) minflt: 3434
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) msgrcv: 0
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) msgsnd: 0
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) nivcsw: 6
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) nsignals: 0
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) nswap: 0
2025-10-31 15:31:01,124 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) nvcsw: 176
2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) oublock: 8
2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) stime: 0.036739
2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) utime: 0.033042
2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) wtime: 0.166
2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) output:
1	1111	"one"
2	2222	"two"
3	3333	"three"
5	5555	"five"
7	7777	"seven"

2025-10-31 15:31:01,125 - DEBUG - ya.test - _save_outputs: Command (pid 2645895) errors:

2025-10-31 15:31:01,126 - 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"

2025-10-31 15:31:01,128 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:31:23,130 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10642/1
2025-10-31 15:31:23,131 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19715
2025-10-31 15:31:23,131 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11213
2025-10-31 15:31:23,131 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19943
2025-10-31 15:31:23,131 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10642
2025-10-31 15:31:23,132 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7879
2025-10-31 15:31:23,629 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_excess_columns[tsv-additional_args3-row] in ydb/tests/functional/ydb_cli] canonical results
2025-10-31 15:31:23,631 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_excess_columns[tsv-additional_args3-row] status: good
