2026-03-25 17:14:52,059 - INFO - ya.test.2498679  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:52,059 - INFO - ya.test.2498679  - pytest_runtest_setup: test_session_pool_no_race_after_future_cancel_case_2
2026-03-25 17:14:52,059 - INFO - ya.test.2498679  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:52,059 - INFO - ya.test.2498679  - pytest_runtest_setup: Test setup
2026-03-25 17:14:52,060 - INFO - ya.test.2498679  - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_no_race_after_future_cancel_case_2)
2026-03-25 17:14:52,061 - DEBUG - ydb.table.SessionPool.2498679  - subscribe: Active session queue is empty, subscribe waiter for a session
2026-03-25 17:14:52,061 - DEBUG - ydb.table.SessionPool.2498679  - subscribe: Subscribe waiter <Future at 0x7fa7bdd8d3e0 state=pending>
2026-03-25 17:14:52,062 - DEBUG - ydb.table.SessionPool.2498679  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2026-03-25 17:14:52,062 - DEBUG - ydb.table.SessionPool.2498679  - _create: Created session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,062 - DEBUG - ydb.table.SessionPool.2498679  - _prepare: Preparing session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,062 - DEBUG - ydb.connection.2498679  - _prepare_call: RpcState(CreateSession, db705fdc-f324-4da6-bfb6-9d1fb6e88630, ghrun-ve2ie6ihly.auto.internal:27034): creating call state
2026-03-25 17:14:52,063 - DEBUG - ydb.connection.2498679  - _log_request: RpcState(CreateSession, db705fdc-f324-4da6-bfb6-9d1fb6e88630, ghrun-ve2ie6ihly.auto.internal:27034): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-03-25 17:14:52,064 - DEBUG - ydb.connection.2498679  - _on_response_callback: RpcState(CreateSession, db705fdc-f324-4da6-bfb6-9d1fb6e88630, ghrun-ve2ie6ihly.auto.internal:27034): on response callback started
2026-03-25 17:14:52,065 - DEBUG - ydb.connection.2498679  - _log_response: RpcState(CreateSession, db705fdc-f324-4da6-bfb6-9d1fb6e88630, ghrun-ve2ie6ihly.auto.internal:27034): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjBjNDZiNTMtOGEzY2MzMTItMmEwYjM2NzAtNjVmNmNjMGM%3D" } } } }
2026-03-25 17:14:52,065 - DEBUG - ydb.table.SessionPool.2498679  - put: Put on session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,065 - DEBUG - ydb.table.SessionPool.2498679  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,065 - DEBUG - ydb.connection.2498679  - _on_response_callback: RpcState(CreateSession, db705fdc-f324-4da6-bfb6-9d1fb6e88630, ghrun-ve2ie6ihly.auto.internal:27034): on response callback success
2026-03-25 17:14:52,066 - DEBUG - ydb.table.SessionPool.2498679  - subscribe: Active session queue is empty, subscribe waiter for a session
2026-03-25 17:14:52,066 - DEBUG - ydb.table.SessionPool.2498679  - subscribe: Subscribe waiter <Future at 0x7fa7bdd64ed0 state=pending>
2026-03-25 17:14:52,066 - DEBUG - ydb.table.SessionPool.2498679  - put: Put on session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,067 - DEBUG - ydb.table.SessionPool.2498679  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,067 - DEBUG - ydb.table.SessionPool.2498679  - unsubscribe: Unsubscribe on waiter <Future at 0x7fa7bdd64ed0 state=finished returned Session>
2026-03-25 17:14:52,067 - DEBUG - ydb.table.SessionPool.2498679  - put: Put on session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,068 - DEBUG - ydb.table.SessionPool.2498679  - stop: Requested session pool stop.
2026-03-25 17:14:52,068 - DEBUG - ydb.table.SessionPool.2498679  - stop: Session pool is under stop, cancelling all in flight waiters.
2026-03-25 17:14:52,068 - DEBUG - ydb.table.SessionPool.2498679  - _terminate_event: Terminated session pool.
2026-03-25 17:14:52,068 - DEBUG - ydb.table.SessionPool.2498679  - stop: Destroying sessions in active queue
2026-03-25 17:14:52,069 - DEBUG - ydb.table.SessionPool.2498679  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fa7bdd56a60>, reason: session-pool-terminated
2026-03-25 17:14:52,069 - DEBUG - ydb.table.SessionPool.2498679  - _destroy: Session <ydb.table.Session object at 0x7fa7bdd56a60> is no longer active. Current active count 0.
2026-03-25 17:14:52,070 - DEBUG - ydb.connection.2498679  - _prepare_call: RpcState(DeleteSession, 104a0eef-462d-4360-a347-1a760179e15d, ghrun-ve2ie6ihly.auto.internal:27034): creating call state
2026-03-25 17:14:52,070 - DEBUG - ydb.connection.2498679  - _log_request: RpcState(DeleteSession, 104a0eef-462d-4360-a347-1a760179e15d, ghrun-ve2ie6ihly.auto.internal:27034): request = { session_id: "ydb://session/3?node_id=1&id=YjBjNDZiNTMtOGEzY2MzMTItMmEwYjM2NzAtNjVmNmNjMGM%3D" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-03-25 17:14:52,071 - DEBUG - ydb.table.SessionPool.2498679  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fa7bdd56a60>
2026-03-25 17:14:52,072 - DEBUG - ydb.connection.2498679  - _on_response_callback: RpcState(DeleteSession, 104a0eef-462d-4360-a347-1a760179e15d, ghrun-ve2ie6ihly.auto.internal:27034): on response callback started
2026-03-25 17:14:52,072 - DEBUG - ydb.table.SessionPool.2498679  - stop: Destroyed active sessions
2026-03-25 17:14:52,072 - DEBUG - ydb.connection.2498679  - _log_response: RpcState(DeleteSession, 104a0eef-462d-4360-a347-1a760179e15d, ghrun-ve2ie6ihly.auto.internal:27034): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:52,073 - DEBUG - ydb.connection.2498679  - _on_response_callback: RpcState(DeleteSession, 104a0eef-462d-4360-a347-1a760179e15d, ghrun-ve2ie6ihly.auto.internal:27034): on response callback success
2026-03-25 17:14:52,074 - INFO - ya.test.2498679  - pytest_runtest_teardown: Test teardown
