2025-12-12 10:09:12,875 - INFO - ya.test.2068729  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:09:12,876 - INFO - ya.test.2068729  - pytest_runtest_setup: test_json_success
2025-12-12 10:09:12,876 - INFO - ya.test.2068729  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:09:12,876 - INFO - ya.test.2068729  - pytest_runtest_setup: Test setup
2025-12-12 10:09:12,879 - INFO - ya.test.2068729  - pytest_runtest_call: Test call (class_name: test_public_api.py::TestJsonExample, test_name: test_json_success)
2025-12-12 10:09:12,879 - DEBUG - ydb.connection.2068729  - _prepare_call: RpcState(CreateSession, 07cf7a68-2468-4937-b427-813c51388448, ghrun-llwi4fnhxu.auto.internal:17602): creating call state
2025-12-12 10:09:12,880 - DEBUG - ydb.connection.2068729  - _log_request: RpcState(CreateSession, 07cf7a68-2468-4937-b427-813c51388448, ghrun-llwi4fnhxu.auto.internal:17602): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:09:12,886 - DEBUG - ydb.connection.2068729  - _log_response: RpcState(CreateSession, 07cf7a68-2468-4937-b427-813c51388448, ghrun-llwi4fnhxu.auto.internal:17602): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NGU2MWQ2MjEtMWJhNDQ0Mi0xOTEwMjQyNS1iNWNlNWI1Yg==" } } } }
2025-12-12 10:09:12,887 - DEBUG - ydb.connection.2068729  - _prepare_call: RpcState(CreateTable, 56be1585-fc97-4df1-a767-0b8a4102eed4, ghrun-llwi4fnhxu.auto.internal:17602): creating call state
2025-12-12 10:09:12,888 - DEBUG - ydb.connection.2068729  - _log_request: RpcState(CreateTable, 56be1585-fc97-4df1-a767-0b8a4102eed4, ghrun-llwi4fnhxu.auto.internal:17602): request = { session_id: "ydb://session/3?node_id=1&id=NGU2MWQ2MjEtMWJhNDQ0Mi0xOTEwMjQyNS1iNWNlNWI1Yg==" path: "/Root/test_json_success" columns { name: "key" type { optional_type { item { type_id: UTF8 } } } } columns { name: "value" type { optional_type { item { type_id: JSON } } } } primary_key: "key" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:09:12,921 - DEBUG - ydb.connection.2068729  - _log_response: RpcState(CreateTable, 56be1585-fc97-4df1-a767-0b8a4102eed4, ghrun-llwi4fnhxu.auto.internal:17602): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:09:12,922 - DEBUG - ydb.connection.2068729  - _prepare_call: RpcState(PrepareDataQuery, d659ff2e-33e7-47ff-923b-ee609ad4b30f, ghrun-llwi4fnhxu.auto.internal:17602): creating call state
2025-12-12 10:09:12,922 - DEBUG - ydb.connection.2068729  - _log_request: RpcState(PrepareDataQuery, d659ff2e-33e7-47ff-923b-ee609ad4b30f, ghrun-llwi4fnhxu.auto.internal:17602): request = { session_id: "ydb://session/3?node_id=1&id=NGU2MWQ2MjEtMWJhNDQ0Mi0xOTEwMjQyNS1iNWNlNWI1Yg==" yql_text: "DECLARE $key as Utf8; \nDECLARE $value as Json; \nUPSERT INTO `/Root/test_json_success` (key, value) VALUES ($key, $value)" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:09:12,938 - DEBUG - ydb.connection.2068729  - _log_response: RpcState(PrepareDataQuery, d659ff2e-33e7-47ff-923b-ee609ad4b30f, ghrun-llwi4fnhxu.auto.internal:17602): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=6ff4963c-309b0226-6b7e5e80-308b8d4d" parameters_types { key: "$key" value { type_id: UTF8 } } parameters_types { key: "$value" value { type_id: JSON } } } } } }
2025-12-12 10:09:12,939 - DEBUG - ydb.connection.2068729  - _prepare_call: RpcState(ExecuteDataQuery, 9c986eaf-b939-4710-be6f-88daed715d5c, ghrun-llwi4fnhxu.auto.internal:17602): creating call state
2025-12-12 10:09:12,940 - DEBUG - ydb.connection.2068729  - _log_request: RpcState(ExecuteDataQuery, 9c986eaf-b939-4710-be6f-88daed715d5c, ghrun-llwi4fnhxu.auto.internal:17602): request = { session_id: "ydb://session/3?node_id=1&id=NGU2MWQ2MjEtMWJhNDQ0Mi0xOTEwMjQyNS1iNWNlNWI1Yg==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "DECLARE $key as Utf8; \nDECLARE $value as Json; \nUPSERT INTO `/Root/test_json_success` (key, value) VALUES ($key, $value)" } parameters { key: "$key" value { type { type_id: UTF8 } value { text_value: "one" } } } parameters { key: "$value" value { type { type_id: JSON } value { text_value: "{\"one\": 1}" } } } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:09:12,947 - DEBUG - ydb.connection.2068729  - _log_response: RpcState(ExecuteDataQuery, 9c986eaf-b939-4710-be6f-88daed715d5c, ghrun-llwi4fnhxu.auto.internal:17602): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } query_meta { id: "ydb://preparedqueryid/4?id=b2591cde-51a69624-88539b56-5f0fdbd1" parameters_types { key: "$key" value { type_id: UTF8 } } parameters_types { key: "$value" value { type_id: JSON } } } } } } }
2025-12-12 10:09:12,948 - INFO - ya.test.2068729  - pytest_runtest_teardown: Test teardown
2025-12-12 10:09:16,979 - DEBUG - ydb.connection.2068729  - close: Closing channel for endpoint localhost:12193
2025-12-12 10:09:17,149 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2068729  - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:12193". Check endpoint correctness.
2025-12-12 10:09:18,232 - DEBUG - ydb.connection.2068729  - close: Closing channel for endpoint localhost:64557
2025-12-12 10:09:18,371 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2068729  - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:64557". Check endpoint correctness.
2025-12-12 10:09:19,008 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2068729  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 10:09:19,009 - DEBUG - ydb.connection.2068729  - 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-12 10:09:19,636 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2068729  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 10:09:19,637 - DEBUG - ydb.connection.2068729  - 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-12 10:09:24,965 - INFO - ydb.tests.library.harness.kikimr_runner.2068729  - stop: Stopped node localhost:17602/1
2025-12-12 10:09:24,973 - DEBUG - library.python.filelock.2068729  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7457
2025-12-12 10:09:24,973 - DEBUG - library.python.filelock.2068729  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24301
2025-12-12 10:09:24,973 - DEBUG - library.python.filelock.2068729  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12382
2025-12-12 10:09:24,973 - DEBUG - library.python.filelock.2068729  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17602
2025-12-12 10:09:24,974 - DEBUG - library.python.filelock.2068729  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32710
2025-12-12 10:09:24,974 - DEBUG - ydb.topic.2068729  - close: Close topic client
2025-12-12 10:09:24,974 - DEBUG - ydb.topic.2068729  - close: Topic client was closed
2025-12-12 10:09:24,974 - DEBUG - ydb.connection.2068729  - close: Closing channel for endpoint localhost:17602
2025-12-12 10:09:24,975 - DEBUG - ydb.connection.2068729  - close: Closing channel for endpoint ghrun-llwi4fnhxu.auto.internal:17602
2025-12-12 10:09:24,979 - INFO - ydb.pool.Discovery.2068729  - run: Successfully terminated discovery process
