2025-12-09 03:43:10,604 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-09 03:43:10,605 - INFO - ya.test - pytest_runtest_setup: test_recovery
2025-12-09 03:43:10,605 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-09 03:43:10,605 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-09 03:43:10,607 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28046
2025-12-09 03:43:10,609 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30042
2025-12-09 03:43:10,665 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd
2025-12-09 03:43:10,666 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21373
2025-12-09 03:43:10,707 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd
2025-12-09 03:43:10,708 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10645
2025-12-09 03:43:10,709 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8281
2025-12-09 03:43:10,709 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22637
2025-12-09 03:43:10,710 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 03:43:10,715 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 03:43:10,717 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 03:43:10,724 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-12-09 03:43:10,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 21f0ed3a-19cc-44b5-a854-136defdeb55f, localhost:8635): creating call state
2025-12-09 03:43:10,725 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 21f0ed3a-19cc-44b5-a854-136defdeb55f, localhost:8635): request = { database: "local" }
2025-12-09 03:43:10,728 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 21f0ed3a-19cc-44b5-a854-136defdeb55f, localhost:8635): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-syjwtgwtdu.auto.internal" port: 8635 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-09 03:43:10,728 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-syjwtgwtdu.auto.internal:8635, location 1, ssl: False>]>
2025-12-09 03:43:10,729 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 03:43:10,736 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8635
2025-12-09 03:43:10,737 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 03:43:10,737 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f708645d8c0 state=pending>
2025-12-09 03:43:10,738 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-09 03:43:10,738 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,739 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,739 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6fefad83-1131-460d-91e0-5a11c85b4226, ghrun-syjwtgwtdu.auto.internal:8635): creating call state
2025-12-09 03:43:10,740 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6fefad83-1131-460d-91e0-5a11c85b4226, ghrun-syjwtgwtdu.auto.internal:8635): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 03:43:10,742 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6fefad83-1131-460d-91e0-5a11c85b4226, ghrun-syjwtgwtdu.auto.internal:8635): on response callback started
2025-12-09 03:43:10,743 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6fefad83-1131-460d-91e0-5a11c85b4226, ghrun-syjwtgwtdu.auto.internal:8635): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZTM2NjRhNmItN2ZkNTdiMzAtNWQ1ZTUyMTQtZWYxNzNjYTA=" } } } }
2025-12-09 03:43:10,743 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,743 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,743 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6fefad83-1131-460d-91e0-5a11c85b4226, ghrun-syjwtgwtdu.auto.internal:8635): on response callback success
2025-12-09 03:43:10,744 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 77b13283-931c-4050-b60a-870c3e4c6c22, ghrun-syjwtgwtdu.auto.internal:8635): creating call state
2025-12-09 03:43:10,745 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 77b13283-931c-4050-b60a-870c3e4c6c22, ghrun-syjwtgwtdu.auto.internal:8635): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2NjRhNmItN2ZkNTdiMzAtNWQ1ZTUyMTQtZWYxNzNjYTA=" path: "local/DbPoolStorage_24dcb7ce-0b3c-41fa-8ad1-607ba812a645/tenants" columns { name: "tenant" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } columns { name: "common" type { optional_type { item { type_id: BOOL } } } } columns { name: "state" type { optional_type { item { type_id: UINT32 } } } } columns { name: "state_time" type { optional_type { item { type_id: TIMESTAMP } } } } primary_key: "tenant" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 03:43:10,770 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 77b13283-931c-4050-b60a-870c3e4c6c22, ghrun-syjwtgwtdu.auto.internal:8635): response = { operation { ready: true status: SUCCESS } }
2025-12-09 03:43:10,770 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,771 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f08f4018-37cf-4413-9ee1-80cc484b6f92, ghrun-syjwtgwtdu.auto.internal:8635): creating call state
2025-12-09 03:43:10,772 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f08f4018-37cf-4413-9ee1-80cc484b6f92, ghrun-syjwtgwtdu.auto.internal:8635): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2NjRhNmItN2ZkNTdiMzAtNWQ1ZTUyMTQtZWYxNzNjYTA=" path: "local/DbPoolStorage_24dcb7ce-0b3c-41fa-8ad1-607ba812a645/mappings" columns { name: "subject_type" type { optional_type { item { type_id: STRING } } } } columns { name: "subject_id" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } columns { name: "node" type { optional_type { item { type_id: STRING } } } } primary_key: "subject_type" primary_key: "subject_id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 03:43:10,788 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f08f4018-37cf-4413-9ee1-80cc484b6f92, ghrun-syjwtgwtdu.auto.internal:8635): response = { operation { ready: true status: SUCCESS } }
2025-12-09 03:43:10,788 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, daf03898-81cd-4fe7-8bfb-91130b3d6294, ghrun-syjwtgwtdu.auto.internal:8635): creating call state
2025-12-09 03:43:10,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, daf03898-81cd-4fe7-8bfb-91130b3d6294, ghrun-syjwtgwtdu.auto.internal:8635): request = { session_id: "ydb://session/3?node_id=1&id=ZTM2NjRhNmItN2ZkNTdiMzAtNWQ1ZTUyMTQtZWYxNzNjYTA=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_24dcb7ce-0b3c-41fa-8ad1-607ba812a645\");\n            UPSERT INTO tenants (tenant, vtenant, common, state, state_time) values(\"/compute\", \"/compute\", true, 0, CurrentUtcTimestamp());\n                " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 03:43:10,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, daf03898-81cd-4fe7-8bfb-91130b3d6294, ghrun-syjwtgwtdu.auto.internal:8635): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 03:43:10,852 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f708645cb50>
2025-12-09 03:43:10,911 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd
2025-12-09 03:43:10,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11464
2025-12-09 03:43:10,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2172
2025-12-09 03:43:10,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10209
2025-12-09 03:43:10,914 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/kikimr_configs"
2025-12-09 03:43:10,915 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/cp --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/node_1/logfile_8xs5xm2z.log --grpc-port=22637 --mon-port=11464 --ic-port=21373
2025-12-09 03:43:10,915 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/kikimr_configs"
2025-12-09 03:43:10,916 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/cp --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/node_1/logfile_8xs5xm2z.log --grpc-port=22637 --mon-port=11464 --ic-port=21373
2025-12-09 03:43:10,917 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/kikimr_configs/config.yaml', '--node=1', '--tenant=/cp', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/node_1/logfile_8xs5xm2z.log', '--grpc-port=22637', '--mon-port=11464', '--ic-port=21373')' in '/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/cp/node_1' (no wait)
2025-12-09 03:43:10,919 - DEBUG - ya.test - execute: Command pid: 1634171
2025-12-09 03:43:10,921 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:22637/1
2025-12-09 03:43:10,922 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-09 03:43:10,923 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-09 03:43:10,923 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 03:43:10,924 - DEBUG - ya.test - execute: Command pid: 1634172
2025-12-09 03:43:11,593 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) rc: 0
2025-12-09 03:43:11,593 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) elapsed time (sec): 0.6681709289550781
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) idrss: 0
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) inblock: 0
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) isrss: 0
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) ixrss: 0
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) majflt: 0
2025-12-09 03:43:11,594 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) maxrss: 543848
2025-12-09 03:43:11,595 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) minflt: 19313
2025-12-09 03:43:11,595 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) msgrcv: 0
2025-12-09 03:43:11,595 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) msgsnd: 0
2025-12-09 03:43:11,595 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) nivcsw: 18
2025-12-09 03:43:11,595 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) nsignals: 0
2025-12-09 03:43:11,596 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) nswap: 0
2025-12-09 03:43:11,596 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) nvcsw: 18
2025-12-09 03:43:11,596 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) oublock: 8
2025-12-09 03:43:11,596 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) stime: 0.507919
2025-12-09 03:43:11,596 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) utime: 0.111107
2025-12-09 03:43:11,597 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) wtime: 0.669
2025-12-09 03:43:11,597 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) output:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-syjwtgwtdu
    Host information: 
        Linux ghrun-syjwtgwtdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-09 03:43:11,597 - DEBUG - ya.test - _save_outputs: Command (pid 1634172) errors:

2025-12-09 03:43:11,598 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6
2025-12-09 03:43:11,598 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-09 03:43:11,650 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd
2025-12-09 03:43:11,651 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1223
2025-12-09 03:43:11,651 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7972
2025-12-09 03:43:11,652 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18524
2025-12-09 03:43:11,652 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19219
2025-12-09 03:43:11,653 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:11,653 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1/logfile_6r7j90x3.log --grpc-port=1223 --mon-port=7972 --ic-port=10645
2025-12-09 03:43:11,653 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd
2025-12-09 03:43:11,653 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21983
2025-12-09 03:43:11,654 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26245
2025-12-09 03:43:11,654 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9452
2025-12-09 03:43:11,654 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21582
2025-12-09 03:43:11,655 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:11,655 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=2 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2/logfile_p1uqxj8p.log --grpc-port=21983 --mon-port=26245 --ic-port=8281
2025-12-09 03:43:11,656 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:11,656 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1/logfile_6r7j90x3.log --grpc-port=1223 --mon-port=7972 --ic-port=10645
2025-12-09 03:43:11,657 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs/config.yaml', '--node=1', '--tenant=/compute', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1/logfile_6r7j90x3.log', '--grpc-port=1223', '--mon-port=7972', '--ic-port=10645')' in '/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1' (no wait)
2025-12-09 03:43:11,658 - DEBUG - ya.test - execute: Command pid: 1634571
2025-12-09 03:43:11,659 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:1223/1
2025-12-09 03:43:11,660 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-09 03:43:11,660 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-09 03:43:11,660 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 03:43:11,661 - DEBUG - ya.test - execute: Command pid: 1634572
2025-12-09 03:43:12,141 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) rc: 0
2025-12-09 03:43:12,142 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) elapsed time (sec): 0.4791700839996338
2025-12-09 03:43:12,142 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) idrss: 0
2025-12-09 03:43:12,142 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) inblock: 0
2025-12-09 03:43:12,142 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) isrss: 0
2025-12-09 03:43:12,142 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) ixrss: 0
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) majflt: 0
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) maxrss: 543848
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) minflt: 17478
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) msgrcv: 0
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) msgsnd: 0
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) nivcsw: 5
2025-12-09 03:43:12,143 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) nsignals: 0
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) nswap: 0
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) nvcsw: 18
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) oublock: 8
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) stime: 0.31920499999999996
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) utime: 0.111721
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) wtime: 0.48
2025-12-09 03:43:12,144 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) output:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-syjwtgwtdu
    Host information: 
        Linux ghrun-syjwtgwtdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-09 03:43:12,145 - DEBUG - ya.test - _save_outputs: Command (pid 1634572) errors:

2025-12-09 03:43:12,145 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6
2025-12-09 03:43:12,145 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:12,146 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=2 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2/logfile_p1uqxj8p.log --grpc-port=21983 --mon-port=26245 --ic-port=8281
2025-12-09 03:43:12,146 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs/config.yaml', '--node=2', '--tenant=/compute', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2/logfile_p1uqxj8p.log', '--grpc-port=21983', '--mon-port=26245', '--ic-port=8281')' in '/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2' (no wait)
2025-12-09 03:43:12,148 - DEBUG - ya.test - execute: Command pid: 1634831
2025-12-09 03:43:12,148 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21983/2
2025-12-09 03:43:12,149 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-09 03:43:12,149 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-09 03:43:12,149 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 03:43:12,150 - DEBUG - ya.test - execute: Command pid: 1634832
2025-12-09 03:43:12,579 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) rc: 0
2025-12-09 03:43:12,579 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) elapsed time (sec): 0.42796945571899414
2025-12-09 03:43:12,579 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) idrss: 0
2025-12-09 03:43:12,579 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) inblock: 0
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) isrss: 0
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) ixrss: 0
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) majflt: 0
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) maxrss: 543848
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) minflt: 16867
2025-12-09 03:43:12,580 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) msgrcv: 0
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) msgsnd: 0
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) nivcsw: 12
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) nsignals: 0
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) nswap: 0
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) nvcsw: 15
2025-12-09 03:43:12,581 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) oublock: 8
2025-12-09 03:43:12,582 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) stime: 0.275329
2025-12-09 03:43:12,582 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) utime: 0.103752
2025-12-09 03:43:12,582 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) wtime: 0.429
2025-12-09 03:43:12,582 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) output:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-syjwtgwtdu
    Host information: 
        Linux ghrun-syjwtgwtdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-09 03:43:12,583 - DEBUG - ya.test - _save_outputs: Command (pid 1634832) errors:

2025-12-09 03:43:12,583 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6
2025-12-09 03:43:12,583 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-09 03:43:12,589 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_recovery.py::TestRecovery, test_name: test_recovery)
2025-12-09 03:43:12,590 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-12-09 03:43:12,625 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-12-09 03:43:12,625 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-12-09 03:43:13,400 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-12-09 03:43:13,400 - DEBUG - root - wait_bootstrap: Wait for node 2 to bootstrap
2025-12-09 03:43:13,885 - DEBUG - root - wait_bootstrap: Node 2 has been bootstrapped
2025-12-09 03:43:13,886 - DEBUG - root - wait_discovery: Wait for node 1 peer discovery
2025-12-09 03:43:17,374 - DEBUG - root - wait_discovery: Node 1 discovery finished
2025-12-09 03:43:17,374 - DEBUG - root - wait_discovery: Wait for node 2 peer discovery
2025-12-09 03:43:17,380 - DEBUG - root - wait_discovery: Node 2 discovery finished
2025-12-09 03:43:17,382 - DEBUG - root - create_stream: Requesting CreateStream.
Database: "local".
Request:
stream_name: "/local/recovery_input"
shard_count: 2
write_quota_kb_per_sec: 1024
retention_period_hours: 1

2025-12-09 03:43:17,427 - DEBUG - root - _get_and_check_result: Response: operation {
  ready: true
  status: SUCCESS
}

2025-12-09 03:43:17,428 - DEBUG - root - _get_and_check_result: Result: 
2025-12-09 03:43:17,430 - DEBUG - root - create_stream: Requesting CreateStream.
Database: "local".
Request:
stream_name: "/local/recovery_output"
shard_count: 2
write_quota_kb_per_sec: 1024
retention_period_hours: 1

2025-12-09 03:43:17,459 - DEBUG - root - _get_and_check_result: Response: operation {
  ready: true
  status: SUCCESS
}

2025-12-09 03:43:17,459 - DEBUG - root - _get_and_check_result: Result: 
2025-12-09 03:43:17,477 - DEBUG - root - _get_and_check_result: Response: operation {
  ready: true
  status: SUCCESS
}

2025-12-09 03:43:17,477 - DEBUG - root - _get_and_check_result: Result: 
2025-12-09 03:43:17,865 - DEBUG - root - create_query_impl: Request: content {
  type: STREAMING
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            PRAGMA dq.MaxTasksPerStage=\"2\";\n\n            INSERT INTO myyds.`recovery_output`\n            SELECT STREAM\n                *\n            FROM myyds.`recovery_input`;"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "cad68a9d-1d22-4a47-8223-207a2ea6c406"

2025-12-09 03:43:18,072 - DEBUG - root - create_query_impl: Result: query_id: "ptqudqgd4qc3uprnc222"

2025-12-09 03:43:18,135 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudqgd4qc3uprnc222"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1765251797
        nanos: 872582000
      }
      modified_at {
        seconds: 1765251797
        nanos: 872582000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudqgd4qc3u2sci2dn-ptqudqgd4qc3uprnc222"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1765251797
      nanos: 872582000
    }
  }
  content {
    type: STREAMING
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            PRAGMA dq.MaxTasksPerStage=\"2\";\n\n            INSERT INTO myyds.`recovery_output`\n            SELECT STREAM\n                *\n            FROM myyds.`recovery_input`;"
    syntax: YQL_V1
  }
}

2025-12-09 03:43:18,647 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-12-09 03:43:19,169 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudqgd4qc3uprnc222"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1765251797
        nanos: 872582000
      }
      modified_at {
        seconds: 1765251797
        nanos: 872582000
      }
      revision: 1
    }
    started_at {
      seconds: 1765251798
      nanos: 473000000
    }
    execute_mode: RUN
    status: RUNNING
    last_job_query_revision: 1
    last_job_id: "ptjudqgd4qc3u2sci2dn-ptqudqgd4qc3uprnc222"
    started_by: "root@builtin"
    has_saved_checkpoints: true
    submitted_at {
      seconds: 1765251797
      nanos: 872582000
    }
  }
  content {
    type: STREAMING
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            PRAGMA dq.MaxTasksPerStage=\"2\";\n\n            INSERT INTO myyds.`recovery_output`\n            SELECT STREAM\n                *\n            FROM myyds.`recovery_input`;"
    syntax: YQL_V1
  }
  plan {
    json: "{\"Detailed\":{\"Operations\":[{\"Id\":3,\"Name\":\"DqStage\",\"Streams\":{\"Program\":[{\"Name\":\"DqSourceWideWrap\"},{\"Name\":\"NarrowMap\"}]}},{\"Id\":2,\"Name\":\"DqQuery!\",\"DependsOn\":[3]},{\"Id\":1,\"Name\":\"Commit!\",\"DependsOn\":[2]}],\"OperationRoot\":1,\"Providers\":[],\"OperationStats\":{\"Commit!\":1,\"DqQuery!\":1,\"DqStage\":1}},\"Basic\":{\"nodes\":[{\"id\":3,\"level\":1,\"name\":\"DqStage #3\",\"type\":\"op\"},{\"id\":2,\"level\":2,\"name\":\"DqQuery!\",\"type\":\"op\"},{\"id\":1,\"level\":3,\"name\":\"Commit!\",\"type\":\"op\"}],\"links\":[{\"source\":3,\"target\":2},{\"source\":2,\"target\":1}]}}"
  }
  ast {
    data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (Configure! $1 (DataSource \'\"dq\" \'\"$all\") \'\"Attr\" \'\"maxtasksperstage\" \'\"2\"))\n(let $3 (DataSource \'\"pq\" \'\"myyds\"))\n(let $4 \'(\'(\'\"PartitionsCount\" \'\"2\")))\n(let $5 (StructType \'(\'\"Data\" (DataType \'String))))\n(let $6 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_input\" $4 \'() $5))\n(let $7 \'(\'\"Endpoint\" \'\"localhost:8635\"))\n(let $8 \'(\'\"SharedReading\" \'\"0\"))\n(let $9 \'(\'\"AddBearerToToken\" \'\"1\"))\n(let $10 \'($7 $8 \'(\'\"ReconnectPeriod\" \'\"\") \'(\'\"Format\" \'\"raw\") \'(\'\"ReadGroup\" \'\"ptcudqgd4qlmshu89mtp\") $9))\n(let $11 (SecureParam \'\"cluster:default_myyds\"))\n(let $12 (DqPqTopicSource $2 $6 \'(\'\"Data\") $10 $11 \'\"\" $5 \'\"\"))\n(let $13 (DataSink \'\"pq\" \'\"myyds\"))\n(let $14 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_output\" $4 \'() $5))\n(let $15 (DqPqTopicSink $14 \'($7 $9) $11))\n(return (Commit! (DqQuery! $2 \'((DqStage \'((DqSource $3 $12)) (lambda \'($16) (block \'(\n  (let $17 \'(\'(\'\"format\" \'\"raw\") \'(\'\"formatSettings\" \'(\'(\'\"data.datetime.formatname\" \'\"POSIX\") \'(\'\"data.timestamp.formatname\" \'\"POSIX\"))) \'(\'\"settings\" \'($8))))\n  (let $18 (DqSourceWideWrap $16 $3 $5 $17))\n  (return (NarrowMap $18 (lambda \'($19) (AsStruct \'(\'\"Data\" $19)))))\n))) \'(\'(\'\"_logical_id\" \'200208)) \'((DqSink \'\"0\" $13 $15))))) $13))\n)\n"
  }
}

2025-12-09 03:43:19,692 - DEBUG - root - test_recovery: Uuid = 24dcb7ce-0b3c-41fa-8ad1-607ba812a645
2025-12-09 03:43:19,698 - DEBUG - root - test_recovery: Master node 1
2025-12-09 03:43:19,762 - DEBUG - root - write_stream: Write topic recovery_input. Response: operation {
  ready: true
  status: SUCCESS
  result {
    type_url: "type.googleapis.com/Ydb.DataStreams.V1.PutRecordsResult"
    value: "\010\001\032\021\"\0010*\014shard-000001\032\021\"\0011*\014shard-000001\032\021\"\0012*\014shard-000001\032\021\"\0013*\014shard-000001\032\021\"\0014*\014shard-000001\032\021\"\0010*\014shard-000000\032\021\"\0015*\014shard-000001\032\021\"\0016*\014shard-000001\032\021\"\0011*\014shard-000000\032\021\"\0017*\014shard-000001"
  }
}

2025-12-09 03:43:19,762 - INFO - root - write_stream: Data was written to recovery_input: ['1', '2', '3', '4', '5', '6', '7', '8', '9', '10']
2025-12-09 03:43:19,763 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read
2025-12-09 03:43:19,764 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to pq_read.err
2025-12-09 03:43:19,765 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read', '--endpoint', 'localhost:8635', '--database', 'local', '--topic-path', 'recovery_output', '--consumer-name', 'recovery_consumer', '--disable-cluster-discovery', '--messages-count', '10', '--timeout', '30000ms', '--commit-after-processing']' in 'None' (waiting)
2025-12-09 03:43:19,765 - DEBUG - ya.test - execute: Command pid: 1638438
2025-12-09 03:43:19,866 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-09 03:43:19,867 - DEBUG - ya.test - _save_outputs: Command (pid 1638438) rc: 0
2025-12-09 03:43:19,867 - DEBUG - ya.test - _save_outputs: Command (pid 1638438) elapsed time (sec): 0.10094237327575684
2025-12-09 03:43:19,868 - DEBUG - ya.test - _save_outputs: Command (pid 1638438) wtime: 0.102
2025-12-09 03:43:19,868 - DEBUG - ya.test - _save_outputs: Command (pid 1638438) output:
None
2025-12-09 03:43:19,868 - DEBUG - ya.test - _save_outputs: Command (pid 1638438) errors:
2025-12-09T03:43:19.786598Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Starting read session
2025-12-09T03:43:19.786684Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Starting session to cluster null (localhost:8635)
2025-12-09T03:43:19.787274Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] In Reconnect, ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:19.787289Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] New values: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:19.787296Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Reconnecting session to cluster null in 0.000000s
2025-12-09T03:43:19.792552Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Successfully connected. Initializing session
2025-12-09T03:43:19.793649Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Server session id: recovery_consumer_1_3_1024398709398485169_v1
2025-12-09T03:43:19.793727Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:19.795182Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Confirm partition stream create. Partition stream id: 1. Cluster: "". Topic: "recovery_output". Partition: 1. Read offset: (NULL)
2025-12-09T03:43:19.795304Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Confirm partition stream create. Partition stream id: 2. Cluster: "". Topic: "recovery_output". Partition: 0. Read offset: (NULL)
2025-12-09T03:43:19.796524Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:19.796594Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (0-7)
2025-12-09T03:43:19.796650Z :DEBUG: [local] Take Data. Partition 1. Read: {0, 0} (0-0)
2025-12-09T03:43:19.796669Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 0} (1-1)
2025-12-09T03:43:19.796676Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 1} (2-2)
2025-12-09T03:43:19.796688Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 2} (3-3)
2025-12-09T03:43:19.796700Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 3} (4-4)
2025-12-09T03:43:19.796705Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 4} (5-5)
2025-12-09T03:43:19.796710Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 5} (6-6)
2025-12-09T03:43:19.796715Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 6} (7-7)
2025-12-09T03:43:19.796853Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Commit offsets [0, 8). Partition stream id: 1
2025-12-09T03:43:19.796995Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] The application data is transferred to the client. Number of messages 8, size 9 bytes
2025-12-09T03:43:19.797191Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:19.797273Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 0 (0-1)
2025-12-09T03:43:19.797315Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (0-0)
2025-12-09T03:43:19.797325Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 0} (1-1)
2025-12-09T03:43:19.797396Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Commit offsets [0, 2). Partition stream id: 2
2025-12-09T03:43:19.797524Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] The application data is transferred to the client. Number of messages 2, size 2 bytes
2025-12-09T03:43:19.799475Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Committed response: cookies { assign_id: 1 partition_cookie: 1 }
2025-12-09T03:43:19.799554Z :DEBUG: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] [null] Committed response: cookies { assign_id: 2 partition_cookie: 1 }
Closing session. Got 10 messages
2025-12-09T03:43:19.799669Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Closing read session. Close timeout: 5.000000s
2025-12-09T03:43:19.799717Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:0:2:1:2 null:recovery_output:1:1:7:8
2025-12-09T03:43:19.799728Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Counters: { Errors: 0 CurrentSessionLifetimeMs: 13 BytesRead: 11 MessagesRead: 10 BytesReadCompressed: 11 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
Session closed
2025-12-09T03:43:19.799934Z :NOTICE: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
Stopping driver...
Driver stopped. Exit
Session closed event: SessionClosed { Status: SUCCESS Issues: "<main>: Error: Session was gracefully closed " }
2025-12-09T03:43:19.800047Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Closing read session. Close timeout: 0.000000s
2025-12-09T03:43:19.800058Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:0:2:1:2 null:recovery_output:1:1:7:8
2025-12-09T03:43:19.800072Z :INFO: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Counters: { Errors: 0 CurrentSessionLifetimeMs: 13 BytesRead: 11 MessagesRead: 10 BytesReadCompressed: 11 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-12-09T03:43:19.800082Z :NOTICE: [local] [local] [56bcc051-651bff2-3e6fe9db-63889199] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }

2025-12-09 03:43:19,869 - INFO - root - read_stream: Data was read from recovery_output: ['1', '2', '3', '4', '5', '7', '8', '10', '6', '9']
2025-12-09 03:43:19,931 - DEBUG - root - dump_workers: Node 1, workers 1, ca 2
2025-12-09 03:43:19,931 - DEBUG - root - dump_workers: Node 2, workers 1, ca 2
2025-12-09 03:43:19,943 - DEBUG - root - test_recovery: Restart non-master node 2
2025-12-09 03:43:20,944 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:21983/2
2025-12-09 03:43:20,944 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:20,944 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=2 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2/logfile_p1uqxj8p.log --grpc-port=21983 --mon-port=26245 --ic-port=8281
2025-12-09 03:43:20,945 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs/config.yaml', '--node=2', '--tenant=/compute', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2/logfile_p1uqxj8p.log', '--grpc-port=21983', '--mon-port=26245', '--ic-port=8281')' in '/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_2' (no wait)
2025-12-09 03:43:20,946 - DEBUG - ya.test - execute: Command pid: 1639108
2025-12-09 03:43:20,946 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21983/2
2025-12-09 03:43:20,947 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-09 03:43:20,947 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-09 03:43:20,947 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 03:43:20,948 - DEBUG - ya.test - execute: Command pid: 1639120
2025-12-09 03:43:21,389 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) rc: 0
2025-12-09 03:43:21,389 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) elapsed time (sec): 0.43971872329711914
2025-12-09 03:43:21,389 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) idrss: 0
2025-12-09 03:43:21,389 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) inblock: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) isrss: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) ixrss: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) majflt: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) maxrss: 543848
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) minflt: 16448
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) msgrcv: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) msgsnd: 0
2025-12-09 03:43:21,390 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) nivcsw: 3
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) nsignals: 0
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) nswap: 0
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) nvcsw: 14
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) oublock: 8
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) stime: 0.292631
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) utime: 0.090943
2025-12-09 03:43:21,391 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) wtime: 0.441
2025-12-09 03:43:21,392 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) output:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-syjwtgwtdu
    Host information: 
        Linux ghrun-syjwtgwtdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-09 03:43:21,392 - DEBUG - ya.test - _save_outputs: Command (pid 1639120) errors:

2025-12-09 03:43:21,392 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6
2025-12-09 03:43:21,392 - DEBUG - root - wait_bootstrap: Wait for node 2 to bootstrap
2025-12-09 03:43:22,682 - DEBUG - root - wait_bootstrap: Node 2 has been bootstrapped
2025-12-09 03:43:23,697 - DEBUG - root - dump_workers: Node 1, workers 1, ca 2
2025-12-09 03:43:23,697 - DEBUG - root - dump_workers: Node 2, workers 1, ca 2
2025-12-09 03:43:23,712 - DEBUG - root - write_stream: Write topic recovery_input. Response: operation {
  ready: true
  status: SUCCESS
  result {
    type_url: "type.googleapis.com/Ydb.DataStreams.V1.PutRecordsResult"
    value: "\010\001\032\021\"\0012*\014shard-000000\032\021\"\0018*\014shard-000001\032\021\"\0019*\014shard-000001\032\022\"\00210*\014shard-000001\032\022\"\00211*\014shard-000001\032\022\"\00212*\014shard-000001\032\021\"\0013*\014shard-000000\032\021\"\0014*\014shard-000000\032\021\"\0015*\014shard-000000\032\022\"\00213*\014shard-000001"
  }
}

2025-12-09 03:43:23,712 - INFO - root - write_stream: Data was written to recovery_input: ['11', '12', '13', '14', '15', '16', '17', '18', '19', '20']
2025-12-09 03:43:23,713 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read
2025-12-09 03:43:23,714 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to pq_read.err.0
2025-12-09 03:43:23,715 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read', '--endpoint', 'localhost:8635', '--database', 'local', '--topic-path', 'recovery_output', '--consumer-name', 'recovery_consumer', '--disable-cluster-discovery', '--messages-count', '10', '--timeout', '30000ms', '--commit-after-processing']' in 'None' (waiting)
2025-12-09 03:43:23,716 - DEBUG - ya.test - execute: Command pid: 1640646
2025-12-09 03:43:23,816 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-09 03:43:23,817 - DEBUG - ya.test - _save_outputs: Command (pid 1640646) rc: 0
2025-12-09 03:43:23,817 - DEBUG - ya.test - _save_outputs: Command (pid 1640646) elapsed time (sec): 0.10081624984741211
2025-12-09 03:43:23,818 - DEBUG - ya.test - _save_outputs: Command (pid 1640646) wtime: 0.102
2025-12-09 03:43:23,818 - DEBUG - ya.test - _save_outputs: Command (pid 1640646) output:
None
2025-12-09 03:43:23,818 - DEBUG - ya.test - _save_outputs: Command (pid 1640646) errors:
2025-12-09T03:43:23.734731Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Starting read session
2025-12-09T03:43:23.734771Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Starting session to cluster null (localhost:8635)
2025-12-09T03:43:23.735197Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] In Reconnect, ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:23.735212Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] New values: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:23.735218Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Reconnecting session to cluster null in 0.000000s
2025-12-09T03:43:23.739962Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Successfully connected. Initializing session
2025-12-09T03:43:23.741017Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Server session id: recovery_consumer_1_6_10708869645824557001_v1
2025-12-09T03:43:23.741060Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:23.742722Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Confirm partition stream create. Partition stream id: 1. Cluster: "". Topic: "recovery_output". Partition: 0. Read offset: (NULL)
2025-12-09T03:43:23.742895Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Confirm partition stream create. Partition stream id: 2. Cluster: "". Topic: "recovery_output". Partition: 1. Read offset: (NULL)
2025-12-09T03:43:23.743616Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:23.743670Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 0 (2-5)
2025-12-09T03:43:23.743724Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (2-2)
2025-12-09T03:43:23.743739Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 0} (3-3)
2025-12-09T03:43:23.743744Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 1} (4-4)
2025-12-09T03:43:23.743750Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 2} (5-5)
2025-12-09T03:43:23.743857Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Commit offsets [2, 6). Partition stream id: 1
2025-12-09T03:43:23.743939Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] The application data is transferred to the client. Number of messages 4, size 8 bytes
2025-12-09T03:43:23.744259Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:23.744311Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (8-13)
2025-12-09T03:43:23.744351Z :DEBUG: [local] Take Data. Partition 1. Read: {0, 0} (8-8)
2025-12-09T03:43:23.744360Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 0} (9-9)
2025-12-09T03:43:23.744367Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 1} (10-10)
2025-12-09T03:43:23.744372Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 2} (11-11)
2025-12-09T03:43:23.744381Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 3} (12-12)
2025-12-09T03:43:23.744386Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 4} (13-13)
2025-12-09T03:43:23.744451Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Commit offsets [8, 14). Partition stream id: 2
2025-12-09T03:43:23.744540Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] The application data is transferred to the client. Number of messages 6, size 12 bytes
2025-12-09T03:43:23.746890Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Committed response: cookies { assign_id: 2 partition_cookie: 1 }
2025-12-09T03:43:23.746974Z :DEBUG: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] [null] Committed response: cookies { assign_id: 1 partition_cookie: 1 }
Closing session. Got 10 messages
2025-12-09T03:43:23.747080Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Closing read session. Close timeout: 5.000000s
2025-12-09T03:43:23.747108Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:1:2:13:14 null:recovery_output:0:1:5:6
2025-12-09T03:43:23.747116Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Counters: { Errors: 0 CurrentSessionLifetimeMs: 12 BytesRead: 20 MessagesRead: 10 BytesReadCompressed: 20 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
Session closed
2025-12-09T03:43:23.747305Z :NOTICE: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
Stopping driver...
Driver stopped. Exit
Session closed event: SessionClosed { Status: SUCCESS Issues: "<main>: Error: Session was gracefully closed " }
2025-12-09T03:43:23.747442Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Closing read session. Close timeout: 0.000000s
2025-12-09T03:43:23.747453Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:1:2:13:14 null:recovery_output:0:1:5:6
2025-12-09T03:43:23.747460Z :INFO: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Counters: { Errors: 0 CurrentSessionLifetimeMs: 12 BytesRead: 20 MessagesRead: 10 BytesReadCompressed: 20 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-12-09T03:43:23.747470Z :NOTICE: [local] [local] [f1ab27b7-3a9d4e16-1b46ee36-15670ae1] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }

2025-12-09 03:43:23,819 - INFO - root - read_stream: Data was read from recovery_output: ['11', '17', '18', '19', '12', '13', '14', '15', '16', '20']
2025-12-09 03:43:23,819 - DEBUG - root - test_recovery: Restart Master node 1
2025-12-09 03:43:24,820 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1223/1
2025-12-09 03:43:24,820 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs"
2025-12-09 03:43:24,820 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/compute --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1/logfile_6r7j90x3.log --grpc-port=1223 --mon-port=7972 --ic-port=10645
2025-12-09 03:43:24,822 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/kikimr_configs/config.yaml', '--node=1', '--tenant=/compute', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1/logfile_6r7j90x3.log', '--grpc-port=1223', '--mon-port=7972', '--ic-port=10645')' in '/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/fq/yds/test-results/py3test/testing_out_stuff/chunk14/testing_out_stuff/test_recovery.py.TestRecovery.test_recovery/compute/node_1' (no wait)
2025-12-09 03:43:24,823 - DEBUG - ya.test - execute: Command pid: 1641324
2025-12-09 03:43:24,823 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:1223/1
2025-12-09 03:43:24,824 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-12-09 03:43:24,825 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-12-09 03:43:24,825 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 03:43:24,826 - DEBUG - ya.test - execute: Command pid: 1641325
2025-12-09 03:43:25,207 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) rc: 0
2025-12-09 03:43:25,207 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) elapsed time (sec): 0.38085150718688965
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) idrss: 0
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) inblock: 0
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) isrss: 0
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) ixrss: 0
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) majflt: 0
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) maxrss: 543848
2025-12-09 03:43:25,208 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) minflt: 16421
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) msgrcv: 0
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) msgsnd: 0
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) nivcsw: 1
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) nsignals: 0
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) nswap: 0
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) nvcsw: 19
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) oublock: 8
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) stime: 0.21651199999999998
2025-12-09 03:43:25,209 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) utime: 0.11627499999999999
2025-12-09 03:43:25,210 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) wtime: 0.382
2025-12-09 03:43:25,210 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) output:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-syjwtgwtdu
    Host information: 
        Linux ghrun-syjwtgwtdu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-09 03:43:25,210 - DEBUG - ya.test - _save_outputs: Command (pid 1641325) errors:

2025-12-09 03:43:25,210 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2ad8a0198ac871ab3cd73b3e9d43a8d7c87aa707
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge 59f28a396f698b38a7f7341b2c06d7104ff2cbd4 into 0ab8ad45a21edd60c6548fe63ce9b19ce58e1cd6
2025-12-09 03:43:25,211 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-12-09 03:43:26,589 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-12-09 03:43:26,707 - DEBUG - root - test_recovery: New master node 1
2025-12-09 03:43:27,635 - DEBUG - root - dump_workers: Node 1, workers 1, ca 2
2025-12-09 03:43:27,635 - DEBUG - root - dump_workers: Node 2, workers 1, ca 2
2025-12-09 03:43:27,640 - DEBUG - root - write_stream: Write topic recovery_input. Response: operation {
  ready: true
  status: SUCCESS
  result {
    type_url: "type.googleapis.com/Ydb.DataStreams.V1.PutRecordsResult"
    value: "\010\001\032\021\"\0016*\014shard-000000\032\022\"\00214*\014shard-000001\032\021\"\0017*\014shard-000000\032\021\"\0018*\014shard-000000\032\022\"\00215*\014shard-000001\032\021\"\0019*\014shard-000000\032\022\"\00210*\014shard-000000\032\022\"\00211*\014shard-000000\032\022\"\00212*\014shard-000000\032\022\"\00213*\014shard-000000"
  }
}

2025-12-09 03:43:27,641 - INFO - root - write_stream: Data was written to recovery_input: ['21', '22', '23', '24', '25', '26', '27', '28', '29', '30']
2025-12-09 03:43:27,641 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read
2025-12-09 03:43:27,642 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to pq_read.err.1
2025-12-09 03:43:27,643 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/av6y/0029ae/ydb/tests/tools/pq_read/pq_read', '--endpoint', 'localhost:8635', '--database', 'local', '--topic-path', 'recovery_output', '--consumer-name', 'recovery_consumer', '--disable-cluster-discovery', '--messages-count', '10', '--timeout', '30000ms', '--commit-after-processing']' in 'None' (waiting)
2025-12-09 03:43:27,644 - DEBUG - ya.test - execute: Command pid: 1642653
2025-12-09 03:43:27,745 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-09 03:43:27,746 - DEBUG - ya.test - _save_outputs: Command (pid 1642653) rc: 0
2025-12-09 03:43:27,746 - DEBUG - ya.test - _save_outputs: Command (pid 1642653) elapsed time (sec): 0.10080265998840332
2025-12-09 03:43:27,746 - DEBUG - ya.test - _save_outputs: Command (pid 1642653) wtime: 0.102
2025-12-09 03:43:27,746 - DEBUG - ya.test - _save_outputs: Command (pid 1642653) output:
None
2025-12-09 03:43:27,746 - DEBUG - ya.test - _save_outputs: Command (pid 1642653) errors:
2025-12-09T03:43:27.662783Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Starting read session
2025-12-09T03:43:27.662828Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Starting session to cluster null (localhost:8635)
2025-12-09T03:43:27.663276Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] In Reconnect, ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:27.663291Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] New values: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:27.663297Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Reconnecting session to cluster null in 0.000000s
2025-12-09T03:43:27.668553Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Successfully connected. Initializing session
2025-12-09T03:43:27.669467Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Server session id: recovery_consumer_1_9_9208474043698581454_v1
2025-12-09T03:43:27.669526Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:27.670682Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Confirm partition stream create. Partition stream id: 1. Cluster: "". Topic: "recovery_output". Partition: 0. Read offset: (NULL)
2025-12-09T03:43:27.670860Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Confirm partition stream create. Partition stream id: 2. Cluster: "". Topic: "recovery_output". Partition: 1. Read offset: (NULL)
2025-12-09T03:43:27.671532Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:27.671617Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 0 (6-13)
2025-12-09T03:43:27.671680Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (6-6)
2025-12-09T03:43:27.671703Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 0} (7-7)
2025-12-09T03:43:27.671711Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 1} (8-8)
2025-12-09T03:43:27.671717Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 2} (9-9)
2025-12-09T03:43:27.671726Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 3} (10-10)
2025-12-09T03:43:27.671731Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 4} (11-11)
2025-12-09T03:43:27.671737Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 5} (12-12)
2025-12-09T03:43:27.671742Z :DEBUG: [local] Take Data. Partition 0. Read: {1, 6} (13-13)
2025-12-09T03:43:27.671872Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Commit offsets [6, 14). Partition stream id: 1
2025-12-09T03:43:27.671945Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] The application data is transferred to the client. Number of messages 8, size 16 bytes
2025-12-09T03:43:27.672038Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] After sending read request: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-09T03:43:27.672108Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (14-15)
2025-12-09T03:43:27.672148Z :DEBUG: [local] Take Data. Partition 1. Read: {0, 0} (14-14)
2025-12-09T03:43:27.672158Z :DEBUG: [local] Take Data. Partition 1. Read: {1, 0} (15-15)
2025-12-09T03:43:27.672196Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Commit offsets [14, 16). Partition stream id: 2
2025-12-09T03:43:27.672314Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] The application data is transferred to the client. Number of messages 2, size 4 bytes
2025-12-09T03:43:27.674098Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Committed response: cookies { assign_id: 2 partition_cookie: 1 }
2025-12-09T03:43:27.674177Z :DEBUG: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] [null] Committed response: cookies { assign_id: 1 partition_cookie: 1 }
Closing session. Got 10 messages
2025-12-09T03:43:27.674250Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Closing read session. Close timeout: 5.000000s
2025-12-09T03:43:27.674296Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:1:2:15:16 null:recovery_output:0:1:13:14
2025-12-09T03:43:27.674307Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Counters: { Errors: 0 CurrentSessionLifetimeMs: 11 BytesRead: 20 MessagesRead: 10 BytesReadCompressed: 20 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
Session closed
2025-12-09T03:43:27.674538Z :NOTICE: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
Stopping driver...
Driver stopped. Exit
Session closed event: SessionClosed { Status: SUCCESS Issues: "<main>: Error: Session was gracefully closed " }
2025-12-09T03:43:27.677855Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Closing read session. Close timeout: 0.000000s
2025-12-09T03:43:27.677868Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): null:recovery_output:1:2:15:16 null:recovery_output:0:1:13:14
2025-12-09T03:43:27.677876Z :INFO: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Counters: { Errors: 0 CurrentSessionLifetimeMs: 15 BytesRead: 20 MessagesRead: 10 BytesReadCompressed: 20 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-12-09T03:43:27.677888Z :NOTICE: [local] [local] [860907b4-e80edc7f-a00cb48f-7cf576e1] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }

2025-12-09 03:43:27,747 - INFO - root - read_stream: Data was read from recovery_output: ['21', '23', '24', '26', '27', '28', '29', '30', '22', '25']
2025-12-09 03:43:27,930 - DEBUG - root - control_query: Result: 
2025-12-09 03:43:27,939 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudqgd4qc3uprnc222"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1765251797
        nanos: 872582000
      }
      modified_at {
        seconds: 1765251807
        nanos: 858868000
      }
      revision: 2
    }
    started_at {
      seconds: 1765251798
      nanos: 473000000
    }
    execute_mode: RUN
    status: ABORTING_BY_USER
    last_job_query_revision: 1
    last_job_id: "ptjudqgd4qc3u2sci2dn-ptqudqgd4qc3uprnc222"
    started_by: "root@builtin"
    aborted_by: "root@builtin"
    has_saved_checkpoints: true
    submitted_at {
      seconds: 1765251797
      nanos: 872582000
    }
  }
  content {
    type: STREAMING
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            PRAGMA dq.MaxTasksPerStage=\"2\";\n\n            INSERT INTO myyds.`recovery_output`\n            SELECT STREAM\n                *\n            FROM myyds.`recovery_input`;"
    syntax: YQL_V1
  }
  plan {
    json: "{\"Detailed\":{\"Operations\":[{\"Id\":3,\"Name\":\"DqStage\",\"Streams\":{\"Program\":[{\"Name\":\"DqSourceWideWrap\"},{\"Name\":\"NarrowMap\"}]}},{\"Id\":2,\"Name\":\"DqQuery!\",\"DependsOn\":[3]},{\"Id\":1,\"Name\":\"Commit!\",\"DependsOn\":[2]}],\"OperationRoot\":1,\"Providers\":[],\"OperationStats\":{\"Commit!\":1,\"DqQuery!\":1,\"DqStage\":1}},\"Basic\":{\"nodes\":[{\"id\":3,\"level\":1,\"name\":\"DqStage #3\",\"type\":\"op\"},{\"id\":2,\"level\":2,\"name\":\"DqQuery!\",\"type\":\"op\"},{\"id\":1,\"level\":3,\"name\":\"Commit!\",\"type\":\"op\"}],\"links\":[{\"source\":3,\"target\":2},{\"source\":2,\"target\":1}]}}"
  }
  transient_issue {
    message: "Undelivered Event 276037632 from [2:7581698762651453429:2266] (Self) to [1:7581698759001213580:3836] Reason: ActorUnknown Cookie: 0"
    severity: 1
  }
  transient_issue {
    message: "Query failed with code UNAVAILABLE and will be restarted (RetryCount: 2) at 2025-12-09T03:43:25.964338Z"
    severity: 1
  }
  transient_issue {
    message: "Undelivered Event 276037634 from [1:7581698741821344323:3820] (Self) to [2:7581698742835296694:2185] Reason: ActorUnknown Cookie: 0"
    severity: 1
  }
  transient_issue {
    message: "Query failed with code UNAVAILABLE and will be restarted (RetryCount: 1) at 2025-12-09T03:43:22.444266Z"
    severity: 1
  }
  statistics {
    json: "{\"Graph=0\":{\"Tasks\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}"
  }
  ast {
    data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (Configure! $1 (DataSource \'\"dq\" \'\"$all\") \'\"Attr\" \'\"maxtasksperstage\" \'\"2\"))\n(let $3 (DataSource \'\"pq\" \'\"myyds\"))\n(let $4 \'(\'(\'\"PartitionsCount\" \'\"2\")))\n(let $5 (StructType \'(\'\"Data\" (DataType \'String))))\n(let $6 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_input\" $4 \'() $5))\n(let $7 \'(\'\"Endpoint\" \'\"localhost:8635\"))\n(let $8 \'(\'\"SharedReading\" \'\"0\"))\n(let $9 \'(\'\"AddBearerToToken\" \'\"1\"))\n(let $10 \'($7 $8 \'(\'\"ReconnectPeriod\" \'\"\") \'(\'\"Format\" \'\"raw\") \'(\'\"ReadGroup\" \'\"ptcudqgd4qlmshu89mtp\") $9))\n(let $11 (SecureParam \'\"cluster:default_myyds\"))\n(let $12 (DqPqTopicSource $2 $6 \'(\'\"Data\") $10 $11 \'\"\" $5 \'\"\"))\n(let $13 (DataSink \'\"pq\" \'\"myyds\"))\n(let $14 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_output\" $4 \'() $5))\n(let $15 (DqPqTopicSink $14 \'($7 $9) $11))\n(return (Commit! (DqQuery! $2 \'((DqStage \'((DqSource $3 $12)) (lambda \'($16) (block \'(\n  (let $17 \'(\'(\'\"format\" \'\"raw\") \'(\'\"formatSettings\" \'(\'(\'\"data.datetime.formatname\" \'\"POSIX\") \'(\'\"data.timestamp.formatname\" \'\"POSIX\"))) \'(\'\"settings\" \'($8))))\n  (let $18 (DqSourceWideWrap $16 $3 $5 $17))\n  (return (NarrowMap $18 (lambda \'($19) (AsStruct \'(\'\"Data\" $19)))))\n))) \'(\'(\'\"_logical_id\" \'200208)) \'((DqSink \'\"0\" $13 $15))))) $13))\n)\n"
  }
}

2025-12-09 03:43:28,449 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-12-09 03:43:28,957 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudqgd4qc3uprnc222"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1765251797
        nanos: 872582000
      }
      modified_at {
        seconds: 1765251807
        nanos: 858868000
      }
      revision: 2
    }
    started_at {
      seconds: 1765251798
      nanos: 473000000
    }
    finished_at {
      seconds: 1765251808
      nanos: 613000000
    }
    execute_mode: RUN
    status: ABORTED_BY_USER
    last_job_query_revision: 1
    last_job_id: "ptjudqgd4qc3u2sci2dn-ptqudqgd4qc3uprnc222"
    started_by: "root@builtin"
    aborted_by: "root@builtin"
    has_saved_checkpoints: true
    submitted_at {
      seconds: 1765251797
      nanos: 872582000
    }
  }
  content {
    type: STREAMING
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            PRAGMA dq.MaxTasksPerStage=\"2\";\n\n            INSERT INTO myyds.`recovery_output`\n            SELECT STREAM\n                *\n            FROM myyds.`recovery_input`;"
    syntax: YQL_V1
  }
  plan {
    json: "{\"Detailed\":{\"Operations\":[{\"Id\":3,\"Name\":\"DqStage\",\"Streams\":{\"Program\":[{\"Name\":\"DqSourceWideWrap\"},{\"Name\":\"NarrowMap\"}]}},{\"Id\":2,\"Name\":\"DqQuery!\",\"DependsOn\":[3]},{\"Id\":1,\"Name\":\"Commit!\",\"DependsOn\":[2]}],\"OperationRoot\":1,\"Providers\":[],\"OperationStats\":{\"Commit!\":1,\"DqQuery!\":1,\"DqStage\":1}},\"Basic\":{\"nodes\":[{\"id\":3,\"level\":1,\"name\":\"DqStage #3\",\"type\":\"op\"},{\"id\":2,\"level\":2,\"name\":\"DqQuery!\",\"type\":\"op\"},{\"id\":1,\"level\":3,\"name\":\"Commit!\",\"type\":\"op\"}],\"links\":[{\"source\":3,\"target\":2},{\"source\":2,\"target\":1}]}}"
  }
  issue {
    message: "ABORTED_BY_USER"
    severity: 1
  }
  transient_issue {
    message: "Undelivered Event 276037632 from [2:7581698762651453429:2266] (Self) to [1:7581698759001213580:3836] Reason: ActorUnknown Cookie: 0"
    severity: 1
  }
  transient_issue {
    message: "Query failed with code UNAVAILABLE and will be restarted (RetryCount: 2) at 2025-12-09T03:43:25.964338Z"
    severity: 1
  }
  transient_issue {
    message: "Undelivered Event 276037634 from [1:7581698741821344323:3820] (Self) to [2:7581698742835296694:2185] Reason: ActorUnknown Cookie: 0"
    severity: 1
  }
  transient_issue {
    message: "Query failed with code UNAVAILABLE and will be restarted (RetryCount: 1) at 2025-12-09T03:43:22.444266Z"
    severity: 1
  }
  statistics {
    json: "{\"Graph=0\":{\"Tasks\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"CpuTimeUs\":{\"sum\":\"0.00s\",\"count\":2,\"avg\":\"0.00s\",\"max\":\"0.00s\",\"min\":\"0.00s\"}}}"
  }
  ast {
    data: "(\n(let $1 (Configure! world (DataSource \'\"config\") \'\"DqEngine\" \'\"force\"))\n(let $2 (Configure! $1 (DataSource \'\"dq\" \'\"$all\") \'\"Attr\" \'\"maxtasksperstage\" \'\"2\"))\n(let $3 (DataSource \'\"pq\" \'\"myyds\"))\n(let $4 \'(\'(\'\"PartitionsCount\" \'\"2\")))\n(let $5 (StructType \'(\'\"Data\" (DataType \'String))))\n(let $6 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_input\" $4 \'() $5))\n(let $7 \'(\'\"Endpoint\" \'\"localhost:8635\"))\n(let $8 \'(\'\"SharedReading\" \'\"0\"))\n(let $9 \'(\'\"AddBearerToToken\" \'\"1\"))\n(let $10 \'($7 $8 \'(\'\"ReconnectPeriod\" \'\"\") \'(\'\"Format\" \'\"raw\") \'(\'\"ReadGroup\" \'\"ptcudqgd4qlmshu89mtp\") $9))\n(let $11 (SecureParam \'\"cluster:default_myyds\"))\n(let $12 (DqPqTopicSource $2 $6 \'(\'\"Data\") $10 $11 \'\"\" $5 \'\"\"))\n(let $13 (DataSink \'\"pq\" \'\"myyds\"))\n(let $14 (PqTopic \'\"myyds\" \'\"local\" \'\"recovery_output\" $4 \'() $5))\n(let $15 (DqPqTopicSink $14 \'($7 $9) $11))\n(return (Commit! (DqQuery! $2 \'((DqStage \'((DqSource $3 $12)) (lambda \'($16) (block \'(\n  (let $17 \'(\'(\'\"format\" \'\"raw\") \'(\'\"formatSettings\" \'(\'(\'\"data.datetime.formatname\" \'\"POSIX\") \'(\'\"data.timestamp.formatname\" \'\"POSIX\"))) \'(\'\"settings\" \'($8))))\n  (let $18 (DqSourceWideWrap $16 $3 $5 $17))\n  (return (NarrowMap $18 (lambda \'($19) (AsStruct \'(\'\"Data\" $19)))))\n))) \'(\'(\'\"_logical_id\" \'200208)) \'((DqSink \'\"0\" $13 $15))))) $13))\n)\n"
  }
}

2025-12-09 03:43:28,960 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-12-09 03:43:31,962 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:22637/1
2025-12-09 03:43:31,963 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10209
2025-12-09 03:43:31,964 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2172
2025-12-09 03:43:31,964 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11464
2025-12-09 03:43:31,964 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22637
2025-12-09 03:43:31,964 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21373
2025-12-09 03:43:31,965 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30042
2025-12-09 03:43:32,966 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1223/1
2025-12-09 03:43:32,990 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:21983/2
2025-12-09 03:43:32,991 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21582
2025-12-09 03:43:32,992 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9452
2025-12-09 03:43:32,992 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26245
2025-12-09 03:43:32,992 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21983
2025-12-09 03:43:32,992 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19219
2025-12-09 03:43:32,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18524
2025-12-09 03:43:32,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7972
2025-12-09 03:43:32,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1223
2025-12-09 03:43:32,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8281
2025-12-09 03:43:32,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10645
