2026-01-08 02:57:06,758 - INFO - ya.test.2413828  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:57:06,758 - INFO - ya.test.2413828  - pytest_runtest_setup: test_simple[json-additional_args4-column]
2026-01-08 02:57:06,758 - INFO - ya.test.2413828  - pytest_runtest_setup: ####################################################################################################
2026-01-08 02:57:06,758 - INFO - ya.test.2413828  - pytest_runtest_setup: Test setup
2026-01-08 02:57:06,760 - INFO - ya.test.2413828  - pytest_runtest_call: Test call (class_name: test_ydb_impex.py::TestImpex, test_name: test_simple[json-additional_args4-column])
2026-01-08 02:57:06,761 - DEBUG - ydb.connection.2413828  - _prepare_call: RpcState(ExecuteSchemeQuery, 869e36a6-d100-4a9f-9d6b-175af049ac0d, ghrun-paw7opzmb4.auto.internal:64445): creating call state
2026-01-08 02:57:06,761 - DEBUG - ydb.connection.2413828  - _log_request: RpcState(ExecuteSchemeQuery, 869e36a6-d100-4a9f-9d6b-175af049ac0d, ghrun-paw7opzmb4.auto.internal:64445): request = { session_id: "ydb://session/3?node_id=1&id=ZmY3ZGE3OGQtYjEyNjE2NDUtNzBlYWFhOGYtZTJjZmQ4MGE=" yql_text: "\n        CREATE TABLE `/Root/test_simple[json-additional_args4-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:57:07,032 - DEBUG - ydb.connection.2413828  - _log_response: RpcState(ExecuteSchemeQuery, 869e36a6-d100-4a9f-9d6b-175af049ac0d, ghrun-paw7opzmb4.auto.internal:64445): response = { operation { ready: true status: SUCCESS } }
2026-01-08 02:57:07,033 - DEBUG - ya.test.2413828  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033b4/ydb/apps/ydb/ydb
2026-01-08 02:57:07,034 - DEBUG - ya.test.2413828  - _get_command_output_file: Command out will be placed to ydb.out.1
2026-01-08 02:57:07,034 - DEBUG - ya.test.2413828  - _get_command_output_file: Command err will be placed to ydb.err.3
2026-01-08 02:57:07,034 - DEBUG - ya.test.2413828  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033b4/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:64445', '--database', '/Root', 'import', 'file', 'json', '-p', '/Root/test_simple[json-additional_args4-column]', '-i', '/home/runner/.ya/build/build_root/pp6n/0033b4/tmp/test_simple_json_additional_ar0/tempinput.json']' in 'None' (waiting)
2026-01-08 02:57:07,035 - DEBUG - ya.test.2413828  - execute: Command pid: 2419425
2026-01-08 02:57:07,161 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) rc: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) elapsed time (sec): 0.1255934238433838
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) idrss: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) inblock: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) isrss: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) ixrss: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) majflt: 0
2026-01-08 02:57:07,162 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) maxrss: 183876
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) minflt: 3942
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) msgrcv: 0
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) msgsnd: 0
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) nivcsw: 0
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) nsignals: 0
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) nswap: 0
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) nvcsw: 356
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) oublock: 8
2026-01-08 02:57:07,163 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) stime: 0.046617
2026-01-08 02:57:07,164 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) utime: 0.020395
2026-01-08 02:57:07,164 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) wtime: 0.126
2026-01-08 02:57:07,164 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) output:

2026-01-08 02:57:07,164 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419425) errors:
Elapsed: 0.0133 sec. Total read size: 0B. Average processing speed: 0B/s.


2026-01-08 02:57:07,164 - DEBUG - __tests__.test_ydb_impex.2413828  - execute_ydb_cli_command: std_out:

2026-01-08 02:57:07,164 - DEBUG - ya.test.2413828  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pp6n/0033b4/ydb/apps/ydb/ydb
2026-01-08 02:57:07,165 - DEBUG - ya.test.2413828  - _get_command_output_file: Command err will be placed to ydb.err.4
2026-01-08 02:57:07,165 - DEBUG - ya.test.2413828  - execute: Executing '['/home/runner/.ya/build/build_root/pp6n/0033b4/ydb/apps/ydb/ydb', '--endpoint', 'grpc://localhost:64445', '--database', '/Root', 'table', 'query', 'execute', '-q', 'SELECT `key`, `id`, `value` FROM `/Root/test_simple[json-additional_args4-column]` ORDER BY `key`', '-t', 'scan', '--format', 'json-unicode']' in 'None' (waiting)
2026-01-08 02:57:07,166 - DEBUG - ya.test.2413828  - execute: Command pid: 2419623
2026-01-08 02:57:07,312 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) rc: 0
2026-01-08 02:57:07,312 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) elapsed time (sec): 0.14532184600830078
2026-01-08 02:57:07,312 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) idrss: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) inblock: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) isrss: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) ixrss: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) majflt: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) maxrss: 183876
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) minflt: 3566
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) msgrcv: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) msgsnd: 0
2026-01-08 02:57:07,313 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) nivcsw: 0
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) nsignals: 0
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) nswap: 0
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) nvcsw: 208
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) oublock: 8
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) stime: 0.023534
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) utime: 0.039223999999999995
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) wtime: 0.146
2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) output:
{"key":1,"id":1111,"value":"one"}
{"key":2,"id":2222,"value":"two"}
{"key":3,"id":3333,"value":"three"}
{"key":5,"id":5555,"value":"five"}
{"key":7,"id":7777,"value":"seven"}

2026-01-08 02:57:07,314 - DEBUG - ya.test.2413828  - _save_outputs: Command (pid 2419623) errors:

2026-01-08 02:57:07,315 - DEBUG - __tests__.test_ydb_impex.2413828  - execute_ydb_cli_command: std_out:
{"key":1,"id":1111,"value":"one"}
{"key":2,"id":2222,"value":"two"}
{"key":3,"id":3333,"value":"three"}
{"key":5,"id":5555,"value":"five"}
{"key":7,"id":7777,"value":"seven"}

2026-01-08 02:57:07,316 - INFO - ya.test.2413828  - pytest_runtest_teardown: Test teardown
2026-01-08 02:57:29,318 - INFO - ydb.tests.library.harness.kikimr_runner.2413828  - stop: Stopped node localhost:64445/1
2026-01-08 02:57:29,319 - DEBUG - library.python.filelock.2413828  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15418
2026-01-08 02:57:29,319 - DEBUG - library.python.filelock.2413828  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9668
2026-01-08 02:57:29,319 - DEBUG - library.python.filelock.2413828  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14375
2026-01-08 02:57:29,320 - DEBUG - library.python.filelock.2413828  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64445
2026-01-08 02:57:29,320 - DEBUG - library.python.filelock.2413828  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22577
2026-01-08 02:57:29,849 - DEBUG - ya.test - _verify_test: Verifying [test_ydb_impex.TestImpex.test_simple[json-additional_args4-column] in ydb/tests/functional/ydb_cli] canonical results
2026-01-08 02:57:29,860 - DEBUG - ya.test - verify: Test test_ydb_impex.TestImpex.test_simple[json-additional_args4-column] status: good
