2025-11-26 18:39:46,121 - INFO - ya.test.2254636  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:39:46,122 - INFO - ya.test.2254636  - pytest_runtest_setup: test_session_pool_close_basic_logic_case_2
2025-11-26 18:39:46,122 - INFO - ya.test.2254636  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:39:46,122 - INFO - ya.test.2254636  - pytest_runtest_setup: Test setup
2025-11-26 18:39:46,124 - INFO - ya.test.2254636  - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_close_basic_logic_case_2)
2025-11-26 18:39:46,126 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,126 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c8104a3b0 state=pending>
2025-11-26 18:39:46,126 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 0 < 10). will create a new session.
2025-11-26 18:39:46,127 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,127 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,127 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 06902385-39b9-42e3-88fd-ee10dbcb394f, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,128 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 06902385-39b9-42e3-88fd-ee10dbcb394f, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,130 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 06902385-39b9-42e3-88fd-ee10dbcb394f, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,131 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 06902385-39b9-42e3-88fd-ee10dbcb394f, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZGE5MTUzMDktZWU5NWRiYjctZDdkOTQwYWQtNDk2YWU3M2I=" } } } }
2025-11-26 18:39:46,131 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,132 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,133 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 06902385-39b9-42e3-88fd-ee10dbcb394f, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,134 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,134 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7f6e3cb0 state=pending>
2025-11-26 18:39:46,134 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 1 < 10). will create a new session.
2025-11-26 18:39:46,134 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,134 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,135 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 12671b6e-3edf-43a9-802e-36c61973630c, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,135 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 12671b6e-3edf-43a9-802e-36c61973630c, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,138 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 12671b6e-3edf-43a9-802e-36c61973630c, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,139 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 12671b6e-3edf-43a9-802e-36c61973630c, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTlhMTA4NGYtZjEyZTIwMTgtY2Y5ZWFiNDItYjc1ZGY2NmE=" } } } }
2025-11-26 18:39:46,139 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,141 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,141 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 12671b6e-3edf-43a9-802e-36c61973630c, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,142 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,144 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7f6e32b0 state=pending>
2025-11-26 18:39:46,145 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 2 < 10). will create a new session.
2025-11-26 18:39:46,145 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,145 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,148 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 2e68cd9a-c695-4387-a36c-d6e3a4456745, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,148 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 2e68cd9a-c695-4387-a36c-d6e3a4456745, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,151 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 2e68cd9a-c695-4387-a36c-d6e3a4456745, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,151 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 2e68cd9a-c695-4387-a36c-d6e3a4456745, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTA3OTYwZjctZmNjZTY2NDYtYjdiOGZkNDYtZDAxYTNhNmY=" } } } }
2025-11-26 18:39:46,152 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,152 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,152 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 2e68cd9a-c695-4387-a36c-d6e3a4456745, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,153 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,155 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7f5095f0 state=pending>
2025-11-26 18:39:46,155 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 3 < 10). will create a new session.
2025-11-26 18:39:46,155 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,156 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,157 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 47a2ca7f-981b-4033-9780-56259e0e8807, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,157 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 47a2ca7f-981b-4033-9780-56259e0e8807, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,160 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 47a2ca7f-981b-4033-9780-56259e0e8807, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,160 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 47a2ca7f-981b-4033-9780-56259e0e8807, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTNjMzU5YmEtYjA0MjJiNDEtZWFhYWQ3NjEtM2YxYjc0M2I=" } } } }
2025-11-26 18:39:46,161 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,161 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,162 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 47a2ca7f-981b-4033-9780-56259e0e8807, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,163 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,164 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c8102a2c0 state=pending>
2025-11-26 18:39:46,164 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 4 < 10). will create a new session.
2025-11-26 18:39:46,164 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,164 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,165 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 8a4f7a9b-0043-4a85-a41a-0c833ded0f34, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,165 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 8a4f7a9b-0043-4a85-a41a-0c833ded0f34, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,168 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 8a4f7a9b-0043-4a85-a41a-0c833ded0f34, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,168 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 8a4f7a9b-0043-4a85-a41a-0c833ded0f34, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjdjZTY1YTUtNGNiYjRlYzUtNjE5YWQ2OWQtNGYzMjlmOGQ=" } } } }
2025-11-26 18:39:46,169 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,170 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,170 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 8a4f7a9b-0043-4a85-a41a-0c833ded0f34, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,175 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,176 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c8102a5e0 state=pending>
2025-11-26 18:39:46,176 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 5 < 10). will create a new session.
2025-11-26 18:39:46,176 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,177 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,177 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, b0b0db69-34d5-4030-8e64-741c616e4466, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,178 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, b0b0db69-34d5-4030-8e64-741c616e4466, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,180 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, b0b0db69-34d5-4030-8e64-741c616e4466, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,181 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, b0b0db69-34d5-4030-8e64-741c616e4466, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NzgzOGE0NzEtOTU1MGQxNTQtNGU1ZDcwMDItOTU4ZmMzMzM=" } } } }
2025-11-26 18:39:46,181 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,182 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,182 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, b0b0db69-34d5-4030-8e64-741c616e4466, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,183 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,183 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7ec318c0 state=pending>
2025-11-26 18:39:46,183 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 6 < 10). will create a new session.
2025-11-26 18:39:46,183 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,184 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,184 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 371b6188-c2d2-41ca-acbe-e9c3a9d80bca, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,184 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 371b6188-c2d2-41ca-acbe-e9c3a9d80bca, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,187 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 371b6188-c2d2-41ca-acbe-e9c3a9d80bca, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,188 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 371b6188-c2d2-41ca-acbe-e9c3a9d80bca, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OTU2NmFkZmItZWU0M2FjYmItOWE4ZmZiNGEtYTM5MmNjMzI=" } } } }
2025-11-26 18:39:46,189 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,190 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,191 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 371b6188-c2d2-41ca-acbe-e9c3a9d80bca, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,191 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,193 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c81029870 state=pending>
2025-11-26 18:39:46,194 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 7 < 10). will create a new session.
2025-11-26 18:39:46,194 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,195 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,196 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 4328bac9-9689-46a8-a436-a107c536f27d, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,197 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 4328bac9-9689-46a8-a436-a107c536f27d, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,204 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 4328bac9-9689-46a8-a436-a107c536f27d, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,205 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 4328bac9-9689-46a8-a436-a107c536f27d, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NjQxMjBhNmQtYWFkNjdhZGQtMTQ4ZTE4N2UtNWVkZjg3OQ==" } } } }
2025-11-26 18:39:46,205 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,205 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,206 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 4328bac9-9689-46a8-a436-a107c536f27d, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,206 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,207 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7f6dfbc0 state=pending>
2025-11-26 18:39:46,207 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 8 < 10). will create a new session.
2025-11-26 18:39:46,207 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,208 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,208 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 71ca07a9-7fc2-4758-b9a9-322f26829d9e, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,208 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 71ca07a9-7fc2-4758-b9a9-322f26829d9e, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,211 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 71ca07a9-7fc2-4758-b9a9-322f26829d9e, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,212 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 71ca07a9-7fc2-4758-b9a9-322f26829d9e, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjNjZjAxZGYtZTQxZTRjNWMtYWU2NjcxZDctNDhiNWY1ZmQ=" } } } }
2025-11-26 18:39:46,212 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,213 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,213 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 71ca07a9-7fc2-4758-b9a9-322f26829d9e, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,213 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 18:39:46,215 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Subscribe waiter <Future at 0x7f2c7f6dc6a0 state=pending>
2025-11-26 18:39:46,215 - DEBUG - ydb.table.SessionPool.2254636  - subscribe: Session pool is not large enough (active_count < size: 9 < 10). will create a new session.
2025-11-26 18:39:46,217 - DEBUG - ydb.table.SessionPool.2254636  - _create: Created session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,217 - DEBUG - ydb.table.SessionPool.2254636  - _prepare: Preparing session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,217 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(CreateSession, 96d9037a-426e-43c3-b8d6-9a0e2d6994bf, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,218 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(CreateSession, 96d9037a-426e-43c3-b8d6-9a0e2d6994bf, ghrun-ihttfokzrq.auto.internal:12792): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,220 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 96d9037a-426e-43c3-b8d6-9a0e2d6994bf, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,221 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(CreateSession, 96d9037a-426e-43c3-b8d6-9a0e2d6994bf, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZTNiMjBlOTktMjkwN2E5NGMtZWVlNDVhY2EtMjVlMzYwNjU=" } } } }
2025-11-26 18:39:46,222 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,222 - DEBUG - ydb.table.SessionPool.2254636  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,222 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(CreateSession, 96d9037a-426e-43c3-b8d6-9a0e2d6994bf, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,224 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,224 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,224 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,229 - DEBUG - ydb.table.SessionPool.2254636  - stop: Requested session pool stop.
2025-11-26 18:39:46,230 - DEBUG - ydb.table.SessionPool.2254636  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-26 18:39:46,231 - DEBUG - ydb.table.SessionPool.2254636  - stop: Destroying sessions in active queue
2025-11-26 18:39:46,231 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c81023b70>, reason: session-pool-terminated
2025-11-26 18:39:46,231 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c81023b70> is no longer active. Current active count 9.
2025-11-26 18:39:46,232 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 3e09f55f-261f-49a2-adb3-43ccf3322dc8, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,233 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 3e09f55f-261f-49a2-adb3-43ccf3322dc8, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiMjBlOTktMjkwN2E5NGMtZWVlNDVhY2EtMjVlMzYwNjU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,231 - DEBUG - ydb.table.SessionPool.2254636  - _terminate_event: Terminated session pool.
2025-11-26 18:39:46,235 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c81023b70>
2025-11-26 18:39:46,235 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7f6dd370>, reason: session-pool-terminated
2025-11-26 18:39:46,235 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7f6dd370> is no longer active. Current active count 8.
2025-11-26 18:39:46,236 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, e8451563-0d10-4702-9cb4-edbbf0a8d68e, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,236 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, e8451563-0d10-4702-9cb4-edbbf0a8d68e, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=YjNjZjAxZGYtZTQxZTRjNWMtYWU2NjcxZDctNDhiNWY1ZmQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,239 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 3e09f55f-261f-49a2-adb3-43ccf3322dc8, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,239 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 3e09f55f-261f-49a2-adb3-43ccf3322dc8, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,240 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 3e09f55f-261f-49a2-adb3-43ccf3322dc8, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,241 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7f6dd370>
2025-11-26 18:39:46,241 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7f6de6d0>, reason: session-pool-terminated
2025-11-26 18:39:46,241 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7f6de6d0> is no longer active. Current active count 7.
2025-11-26 18:39:46,241 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, bbba6b03-739f-4c74-9bb4-23b62663d0c5, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,242 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, e8451563-0d10-4702-9cb4-edbbf0a8d68e, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,243 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, bbba6b03-739f-4c74-9bb4-23b62663d0c5, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=NjQxMjBhNmQtYWFkNjdhZGQtMTQ4ZTE4N2UtNWVkZjg3OQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,248 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7f6de6d0>
2025-11-26 18:39:46,247 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, e8451563-0d10-4702-9cb4-edbbf0a8d68e, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,249 - DEBUG - ydb.table.SessionPool.2254636  - stop: Destroyed active sessions
2025-11-26 18:39:46,250 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, e8451563-0d10-4702-9cb4-edbbf0a8d68e, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,251 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,252 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, bbba6b03-739f-4c74-9bb4-23b62663d0c5, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,253 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7f673c60>, reason: not-initialized
2025-11-26 18:39:46,254 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, bbba6b03-739f-4c74-9bb4-23b62663d0c5, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,254 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7f673c60> is no longer active. Current active count 6.
2025-11-26 18:39:46,255 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, bbba6b03-739f-4c74-9bb4-23b62663d0c5, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,256 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, e33b9421-cf68-4747-87cc-fbbb207af2e2, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,259 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, e33b9421-cf68-4747-87cc-fbbb207af2e2, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=OTU2NmFkZmItZWU0M2FjYmItOWE4ZmZiNGEtYTM5MmNjMzI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,262 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7f673c60>
2025-11-26 18:39:46,263 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,263 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7ec32950>, reason: not-initialized
2025-11-26 18:39:46,262 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, e33b9421-cf68-4747-87cc-fbbb207af2e2, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,265 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7ec32950> is no longer active. Current active count 5.
2025-11-26 18:39:46,265 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, e33b9421-cf68-4747-87cc-fbbb207af2e2, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,267 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 51b870f6-81a3-4d60-8988-f287e8380a15, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,268 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, e33b9421-cf68-4747-87cc-fbbb207af2e2, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,269 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 51b870f6-81a3-4d60-8988-f287e8380a15, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=NzgzOGE0NzEtOTU1MGQxNTQtNGU1ZDcwMDItOTU4ZmMzMzM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,272 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7ec32950>
2025-11-26 18:39:46,273 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,273 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c81028470>, reason: not-initialized
2025-11-26 18:39:46,273 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c81028470> is no longer active. Current active count 4.
2025-11-26 18:39:46,274 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 189ad7c5-7a04-4492-8765-635ec1f34240, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,274 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 189ad7c5-7a04-4492-8765-635ec1f34240, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=ZjdjZTY1YTUtNGNiYjRlYzUtNjE5YWQ2OWQtNGYzMjlmOGQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,275 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 51b870f6-81a3-4d60-8988-f287e8380a15, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,276 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 51b870f6-81a3-4d60-8988-f287e8380a15, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,276 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 51b870f6-81a3-4d60-8988-f287e8380a15, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,277 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c81028470>
2025-11-26 18:39:46,278 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,278 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c81029050>, reason: not-initialized
2025-11-26 18:39:46,278 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c81029050> is no longer active. Current active count 3.
2025-11-26 18:39:46,279 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 55bb40de-72cb-4e60-8eb1-21f79dc92167, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,279 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 189ad7c5-7a04-4492-8765-635ec1f34240, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,280 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 55bb40de-72cb-4e60-8eb1-21f79dc92167, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=NTNjMzU5YmEtYjA0MjJiNDEtZWFhYWQ3NjEtM2YxYjc0M2I=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,282 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 189ad7c5-7a04-4492-8765-635ec1f34240, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,283 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c81029050>
2025-11-26 18:39:46,287 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,285 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 189ad7c5-7a04-4492-8765-635ec1f34240, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,287 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7f6e3e40>, reason: not-initialized
2025-11-26 18:39:46,289 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7f6e3e40> is no longer active. Current active count 2.
2025-11-26 18:39:46,289 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 55bb40de-72cb-4e60-8eb1-21f79dc92167, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,290 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 2d32d5b0-2b5b-420e-9495-37236e404109, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,295 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 2d32d5b0-2b5b-420e-9495-37236e404109, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=YTA3OTYwZjctZmNjZTY2NDYtYjdiOGZkNDYtZDAxYTNhNmY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,296 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7f6e3e40>
2025-11-26 18:39:46,291 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 55bb40de-72cb-4e60-8eb1-21f79dc92167, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,297 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,301 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 55bb40de-72cb-4e60-8eb1-21f79dc92167, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,302 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c7f6e2680>, reason: not-initialized
2025-11-26 18:39:46,303 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c7f6e2680> is no longer active. Current active count 1.
2025-11-26 18:39:46,304 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 2d32d5b0-2b5b-420e-9495-37236e404109, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,305 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, 21e8f8f3-f8fa-4e61-851e-44cc94edc063, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,306 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 2d32d5b0-2b5b-420e-9495-37236e404109, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,307 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, 21e8f8f3-f8fa-4e61-851e-44cc94edc063, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=NTlhMTA4NGYtZjEyZTIwMTgtY2Y5ZWFiNDItYjc1ZGY2NmE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,308 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 2d32d5b0-2b5b-420e-9495-37236e404109, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,309 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c7f6e2680>
2025-11-26 18:39:46,310 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 21e8f8f3-f8fa-4e61-851e-44cc94edc063, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,318 - DEBUG - ydb.table.SessionPool.2254636  - put: Put on session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,320 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, 21e8f8f3-f8fa-4e61-851e-44cc94edc063, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,321 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2c8104adb0>, reason: not-initialized
2025-11-26 18:39:46,324 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, 21e8f8f3-f8fa-4e61-851e-44cc94edc063, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,325 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Session <ydb.table.Session object at 0x7f2c8104adb0> is no longer active. Current active count 0.
2025-11-26 18:39:46,327 - DEBUG - ydb.connection.2254636  - _prepare_call: RpcState(DeleteSession, aac5075a-67c1-43a8-9e5d-140b5fb1c8b2, ghrun-ihttfokzrq.auto.internal:12792): creating call state
2025-11-26 18:39:46,328 - DEBUG - ydb.connection.2254636  - _log_request: RpcState(DeleteSession, aac5075a-67c1-43a8-9e5d-140b5fb1c8b2, ghrun-ihttfokzrq.auto.internal:12792): request = { session_id: "ydb://session/3?node_id=1&id=ZGE5MTUzMDktZWU5NWRiYjctZDdkOTQwYWQtNDk2YWU3M2I=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 18:39:46,330 - DEBUG - ydb.table.SessionPool.2254636  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2c8104adb0>
2025-11-26 18:39:46,332 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, aac5075a-67c1-43a8-9e5d-140b5fb1c8b2, ghrun-ihttfokzrq.auto.internal:12792): on response callback started
2025-11-26 18:39:46,336 - ERROR - ydb.table.SessionPool.2254636  - acquire: Take session from closed session pool
2025-11-26 18:39:46,340 - DEBUG - ydb.connection.2254636  - _log_response: RpcState(DeleteSession, aac5075a-67c1-43a8-9e5d-140b5fb1c8b2, ghrun-ihttfokzrq.auto.internal:12792): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:39:46,344 - DEBUG - ydb.connection.2254636  - _on_response_callback: RpcState(DeleteSession, aac5075a-67c1-43a8-9e5d-140b5fb1c8b2, ghrun-ihttfokzrq.auto.internal:12792): on response callback success
2025-11-26 18:39:46,345 - INFO - ya.test.2254636  - pytest_runtest_teardown: Test teardown
