2025-12-08 23:28:04,542 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:04,543 - INFO - ya.test - pytest_runtest_setup: test_session_pool_close_basic_logic_case_1
2025-12-08 23:28:04,543 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:04,544 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:28:04,546 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_close_basic_logic_case_1)
2025-12-08 23:28:04,547 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:28:04,553 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f082557b490 state=pending>
2025-12-08 23:28:04,558 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-08 23:28:04,562 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,563 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,565 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8310496a-db72-490c-8921-3e405507f5fd, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:04,575 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8310496a-db72-490c-8921-3e405507f5fd, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:04,579 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8310496a-db72-490c-8921-3e405507f5fd, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:04,580 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8310496a-db72-490c-8921-3e405507f5fd, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWY5YTlhMDItY2FiZjU2ODUtOGY4YzE4NDgtNTc5Y2ViMzk=" } } } }
2025-12-08 23:28:04,581 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,592 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,598 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8310496a-db72-490c-8921-3e405507f5fd, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:04,601 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-08 23:28:04,601 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f082557b300 state=pending>
2025-12-08 23:28:04,602 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-12-08 23:28:04,604 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-08 23:28:04,604 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f0824dba040>
2025-12-08 23:28:04,604 - DEBUG - ydb.table.SessionPool - stop: Waiter <Future at 0x7f082557b300 state=finished returned Session> has been replied with empty session info. Session details: <ydb.table.Session object at 0x7f0824dba040>.
2025-12-08 23:28:04,604 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-12-08 23:28:04,613 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-12-08 23:28:04,628 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-12-08 23:28:04,629 - ERROR - ydb.table.SessionPool - acquire: Take session from closed session pool
2025-12-08 23:28:04,633 - DEBUG - ydb.table.SessionPool - unsubscribe: Unsubscribe on waiter <Future at 0x7f082557b300 state=finished returned Session>
2025-12-08 23:28:04,635 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0824dba040>
2025-12-08 23:28:04,638 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0824dba040>, reason: not-initialized
2025-12-08 23:28:04,641 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f0824dba040> is no longer active. Current active count 1.
2025-12-08 23:28:04,644 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,644 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082557a720>, reason: not-initialized
2025-12-08 23:28:04,645 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082557a720> is no longer active. Current active count 0.
2025-12-08 23:28:04,645 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b25cd031-9d72-4b46-ba12-a0b7eb5af668, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:04,646 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b25cd031-9d72-4b46-ba12-a0b7eb5af668, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=OWY5YTlhMDItY2FiZjU2ODUtOGY4YzE4NDgtNTc5Y2ViMzk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:04,654 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b25cd031-9d72-4b46-ba12-a0b7eb5af668, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:04,654 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b25cd031-9d72-4b46-ba12-a0b7eb5af668, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:04,655 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b25cd031-9d72-4b46-ba12-a0b7eb5af668, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:04,662 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082557a720>
2025-12-08 23:28:04,666 - INFO - ya.test - pytest_runtest_teardown: Test teardown
