2025-12-12 10:05:20,196 - INFO - ya.test.2075013  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:20,197 - INFO - ya.test.2075013  - pytest_runtest_setup: test_session_pool_keep_alive
2025-12-12 10:05:20,198 - INFO - ya.test.2075013  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:20,200 - INFO - ya.test.2075013  - pytest_runtest_setup: Test setup
2025-12-12 10:05:20,213 - INFO - ya.test.2075013  - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_keep_alive)
2025-12-12 10:05:20,219 - DEBUG - ydb.table.SessionPool.2075013  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 10:05:20,219 - DEBUG - ydb.table.SessionPool.2075013  - subscribe: Subscribe waiter <Future at 0x7fc5088d4790 state=pending>
2025-12-12 10:05:20,219 - DEBUG - ydb.table.SessionPool.2075013  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-12 10:05:20,220 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,221 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,222 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, bd513c09-cd95-40df-a432-03392aa6c58b, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:20,222 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, bd513c09-cd95-40df-a432-03392aa6c58b, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:20,225 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, bd513c09-cd95-40df-a432-03392aa6c58b, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:20,256 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, bd513c09-cd95-40df-a432-03392aa6c58b, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MTUyMmIwOGEtNTBkOTY0M2UtZTI2MDM0NzctMmVhYjk0OTU=" } } } }
2025-12-12 10:05:20,261 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,262 - DEBUG - ydb.table.SessionPool.2075013  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,263 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, bd513c09-cd95-40df-a432-03392aa6c58b, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:20,264 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,265 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(KeepAlive, 74d9e05f-4e25-4294-9603-d7fb6a318f33, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:20,270 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(KeepAlive, 74d9e05f-4e25-4294-9603-d7fb6a318f33, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=MTUyMmIwOGEtNTBkOTY0M2UtZTI2MDM0NzctMmVhYjk0OTU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:20,275 - DEBUG - ydb.table.SessionPool.2075013  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 10:05:20,285 - DEBUG - ydb.table.SessionPool.2075013  - subscribe: Subscribe waiter <Future at 0x7fc50937a950 state=pending>
2025-12-12 10:05:20,276 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(KeepAlive, 74d9e05f-4e25-4294-9603-d7fb6a318f33, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:20,304 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(KeepAlive, 74d9e05f-4e25-4294-9603-d7fb6a318f33, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.KeepAliveResult] { session_status: SESSION_STATUS_READY } } } }
2025-12-12 10:05:20,305 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,321 - DEBUG - ydb.table.SessionPool.2075013  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fc5088d71c0>
2025-12-12 10:05:20,326 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(KeepAlive, 74d9e05f-4e25-4294-9603-d7fb6a318f33, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:20,327 - DEBUG - ydb.table.SessionPool.2075013  - stop: Requested session pool stop.
2025-12-12 10:05:20,354 - DEBUG - ydb.table.SessionPool.2075013  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 10:05:20,355 - DEBUG - ydb.table.SessionPool.2075013  - stop: Destroying sessions in active queue
2025-12-12 10:05:20,355 - DEBUG - ydb.table.SessionPool.2075013  - stop: Destroyed active sessions
2025-12-12 10:05:20,357 - DEBUG - ydb.table.SessionPool.2075013  - _terminate_event: Terminated session pool.
2025-12-12 10:05:20,359 - INFO - ya.test.2075013  - pytest_runtest_teardown: Test teardown
