2025-10-31 13:46:49,803 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:46:49,804 - INFO - ya.test - pytest_runtest_setup: test_session_pool_keep_alive
2025-10-31 13:46:49,804 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:46:49,804 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:46:49,805 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_keep_alive)
2025-10-31 13:46:49,807 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 33a29113-d49b-4f1e-bfc1-367c7d86fdc9, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:46:49,807 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 33a29113-d49b-4f1e-bfc1-367c7d86fdc9, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:46:49,807 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-31 13:46:49,808 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f7a3a2b2270 state=pending>
2025-10-31 13:46:49,808 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-31 13:46:49,808 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,808 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,809 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, df52de9d-5a1c-4f05-a3b2-25fd06fa0da7, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:46:49,809 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, df52de9d-5a1c-4f05-a3b2-25fd06fa0da7, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:46:49,811 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, df52de9d-5a1c-4f05-a3b2-25fd06fa0da7, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:46:49,812 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, df52de9d-5a1c-4f05-a3b2-25fd06fa0da7, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODc4YzYxMDUtZWY5MjJhMGQtOGI2Nzc5YmMtMjVhZGY4YzM=" } } } }
2025-10-31 13:46:49,812 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,812 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,813 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, df52de9d-5a1c-4f05-a3b2-25fd06fa0da7, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:46:49,813 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,814 - DEBUG - ydb.connection - _prepare_call: RpcState(KeepAlive, 92583f78-0ba4-4fb7-9572-612709caadc5, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:46:49,814 - DEBUG - ydb.connection - _log_request: RpcState(KeepAlive, 92583f78-0ba4-4fb7-9572-612709caadc5, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=ODc4YzYxMDUtZWY5MjJhMGQtOGI2Nzc5YmMtMjVhZGY4YzM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:46:49,815 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-31 13:46:49,816 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f7a3a2b0650 state=pending>
2025-10-31 13:46:49,816 - DEBUG - ydb.connection - _on_response_callback: RpcState(KeepAlive, 92583f78-0ba4-4fb7-9572-612709caadc5, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:46:49,817 - DEBUG - ydb.connection - _log_response: RpcState(KeepAlive, 92583f78-0ba4-4fb7-9572-612709caadc5, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.KeepAliveResult] { session_status: SESSION_STATUS_READY } } } }
2025-10-31 13:46:49,817 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,817 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f7a3a2b3f80>
2025-10-31 13:46:49,818 - DEBUG - ydb.connection - _on_response_callback: RpcState(KeepAlive, 92583f78-0ba4-4fb7-9572-612709caadc5, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:46:49,818 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-31 13:46:49,818 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-31 13:46:49,819 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-31 13:46:49,819 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-31 13:46:49,819 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-31 13:46:49,821 - INFO - ya.test - pytest_runtest_teardown: Test teardown
