2025-11-01 04:19:45,646 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:19:45,646 - INFO - ya.test - pytest_runtest_setup: test_excess_columns[csv-additional_args1-row]
2025-11-01 04:19:45,646 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 04:19:45,646 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 04:19:45,649 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_excess_columns[csv-additional_args1-row])
2025-11-01 04:19:45,650 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 11c53fc8-0bc6-492d-a01d-ff1e4c0c5edd, ghrun-i4gyxlpqdu.auto.internal:23267): creating call state
2025-11-01 04:19:45,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 11c53fc8-0bc6-492d-a01d-ff1e4c0c5edd, ghrun-i4gyxlpqdu.auto.internal:23267): request = { session_id: "ydb://session/3?node_id=1&id=ZTFjOTlhZC0xZjJhMzM0Ni0xY2MwMTNkOC04ZWU0ZDdlMw==" yql_text: "\n        CREATE TABLE `/Root/test_excess_columns[csv-additional_args1-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-11-01 04:19:45,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 11c53fc8-0bc6-492d-a01d-ff1e4c0c5edd, ghrun-i4gyxlpqdu.auto.internal:23267): response = { operation { ready: true status: SUCCESS } }
2025-11-01 04:19:45,676 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e34/ydb/apps/ydb/ydb
2025-11-01 04:19:45,677 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-01 04:19:45,677 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-01 04:19:45,678 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e34/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:23267', '--database', '/Root', 'import', 'file', 'csv', '-p', '/Root/test_excess_columns[csv-additional_args1-row]', '-i', '/home/runner/.ya/build/build_root/g81u/004e34/tmp/test_excess_columns_csv_additi1/tempinput.csv', '--header', '--newline-delimited']' in 'None' (waiting)
2025-11-01 04:19:45,678 - DEBUG - ya.test - execute: Command pid: 2525235
2025-11-01 04:19:45,837 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) rc: 0
2025-11-01 04:19:45,837 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) elapsed time (sec): 0.14503145217895508
2025-11-01 04:19:45,841 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) idrss: 0
2025-11-01 04:19:45,842 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) inblock: 0
2025-11-01 04:19:45,842 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) isrss: 0
2025-11-01 04:19:45,842 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) ixrss: 0
2025-11-01 04:19:45,842 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) majflt: 0
2025-11-01 04:19:45,842 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) maxrss: 184432
2025-11-01 04:19:45,845 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) minflt: 4358
2025-11-01 04:19:45,845 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) msgrcv: 0
2025-11-01 04:19:45,849 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) msgsnd: 0
2025-11-01 04:19:45,849 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) nivcsw: 13
2025-11-01 04:19:45,849 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) nsignals: 0
2025-11-01 04:19:45,849 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) nswap: 0
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) nvcsw: 710
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) oublock: 24
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) stime: 0.047858
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) utime: 0.052209
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) wtime: 0.146
2025-11-01 04:19:45,850 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) output:

2025-11-01 04:19:45,851 - DEBUG - ya.test - _save_outputs: Command (pid 2525235) errors:
Elapsed: 0.0104 sec. Total read size: 75B. Average processing speed: 7.06KiB/s.


2025-11-01 04:19:45,851 - DEBUG - __tests__.test_ydb_impex - execute_ydb_cli_command: std_out:

2025-11-01 04:19:45,851 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g81u/004e34/ydb/apps/ydb/ydb
2025-11-01 04:19:45,852 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-01 04:19:45,853 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g81u/004e34/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:23267', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_excess_columns[csv-additional_args1-row]` ORDER BY `key`', '-t', 'scan', '--format', 'csv']' in 'None' (waiting)
2025-11-01 04:19:45,854 - DEBUG - ya.test - execute: Command pid: 2525522
2025-11-01 04:19:46,009 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) rc: 0
2025-11-01 04:19:46,010 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) elapsed time (sec): 0.15468668937683105
2025-11-01 04:19:46,010 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) idrss: 0
2025-11-01 04:19:46,010 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) inblock: 0
2025-11-01 04:19:46,010 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) isrss: 0
2025-11-01 04:19:46,011 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) ixrss: 0
2025-11-01 04:19:46,011 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) majflt: 0
2025-11-01 04:19:46,011 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) maxrss: 184432
2025-11-01 04:19:46,011 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) minflt: 3439
2025-11-01 04:19:46,011 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) msgrcv: 0
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) msgsnd: 0
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) nivcsw: 0
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) nsignals: 0
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) nswap: 0
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) nvcsw: 205
2025-11-01 04:19:46,012 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) oublock: 8
2025-11-01 04:19:46,013 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) stime: 0.028357999999999998
2025-11-01 04:19:46,013 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) utime: 0.047264
2025-11-01 04:19:46,013 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) wtime: 0.156
2025-11-01 04:19:46,013 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) output:
1,1111,"one"
2,2222,"two"
3,3333,"three"
5,5555,"five"
7,7777,"seven"

2025-11-01 04:19:46,013 - DEBUG - ya.test - _save_outputs: Command (pid 2525522) errors:

2025-11-01 04:19:46,014 - 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-11-01 04:19:46,015 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 04:20:09,589 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_excess_columns[csv-additional_args1-row] in ydb/tests/functional/ydb_cli] canonical results
2025-11-01 04:20:09,590 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_excess_columns[csv-additional_args1-row] status: good
