2026-08-19 13:42:12,885 - INFO - ya.test.1621738  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:42:12,885 - INFO - ya.test.1621738  - pytest_runtest_setup: test_session_pool_min_size_feature
2026-08-19 13:42:12,885 - INFO - ya.test.1621738  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:42:12,886 - INFO - ya.test.1621738  - pytest_runtest_setup: Test setup
2026-08-19 13:42:12,887 - INFO - ya.test.1621738  - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_min_size_feature)
2026-08-19 13:42:12,887 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f74531ad710>
2026-08-19 13:42:12,887 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f74531ad710>
2026-08-19 13:42:12,888 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 77a518c0-5e09-46f0-a496-aaa2c632e413, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,888 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 77a518c0-5e09-46f0-a496-aaa2c632e413, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,889 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f74531739e0>
2026-08-19 13:42:12,889 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f74531739e0>
2026-08-19 13:42:12,889 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 11fc11b9-fc48-4c17-bf19-064079bd7436, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,890 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 11fc11b9-fc48-4c17-bf19-064079bd7436, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,890 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 77a518c0-5e09-46f0-a496-aaa2c632e413, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,891 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 77a518c0-5e09-46f0-a496-aaa2c632e413, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MmVmODBiOTItNDFjOGMwMjAtMmUxMGQ5YjMtMjU3N2U1ZWQ=" } } } }
2026-08-19 13:42:12,891 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450136100>
2026-08-19 13:42:12,891 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450136100>
2026-08-19 13:42:12,892 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, a52c9d67-b6e8-4a6e-9f8c-5df23586d963, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,892 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, a52c9d67-b6e8-4a6e-9f8c-5df23586d963, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,892 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f74531ad710>
2026-08-19 13:42:12,893 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 77a518c0-5e09-46f0-a496-aaa2c632e413, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,893 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450136cd0>
2026-08-19 13:42:12,893 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 11fc11b9-fc48-4c17-bf19-064079bd7436, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,893 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450136cd0>
2026-08-19 13:42:12,894 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 11fc11b9-fc48-4c17-bf19-064079bd7436, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODhiNjdkMTQtNTUxNjU5YzAtMTg0YjI3NjQtMzk2OGEzZGU=" } } } }
2026-08-19 13:42:12,894 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 5261c168-58cd-49eb-99cf-17c9a1536b94, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,895 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 5261c168-58cd-49eb-99cf-17c9a1536b94, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,895 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f745319a760>
2026-08-19 13:42:12,895 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f745319a760>
2026-08-19 13:42:12,895 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, c4838d44-5aaa-4481-bda3-174a118d452c, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,896 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, c4838d44-5aaa-4481-bda3-174a118d452c, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,896 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450a3dbe0>
2026-08-19 13:42:12,896 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450a3dbe0>
2026-08-19 13:42:12,896 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 39d8b4dd-7093-4f34-bc17-1da491f35f9a, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,897 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 39d8b4dd-7093-4f34-bc17-1da491f35f9a, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,897 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450775bf0>
2026-08-19 13:42:12,897 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f74531739e0>
2026-08-19 13:42:12,898 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 11fc11b9-fc48-4c17-bf19-064079bd7436, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,898 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450775bf0>
2026-08-19 13:42:12,898 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, a52c9d67-b6e8-4a6e-9f8c-5df23586d963, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,898 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 23b06725-4fc6-45f0-b66b-d3cb10de4909, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,899 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, a52c9d67-b6e8-4a6e-9f8c-5df23586d963, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MWVkNWU5NjktNGFiNTI4ODgtZDkxZDU0MDUtZWY0NmI5YTI=" } } } }
2026-08-19 13:42:12,899 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 23b06725-4fc6-45f0-b66b-d3cb10de4909, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,900 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450775a30>
2026-08-19 13:42:12,900 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450775a30>
2026-08-19 13:42:12,901 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 66920303-75da-487c-8371-e220357f0f3d, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,901 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 66920303-75da-487c-8371-e220357f0f3d, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,901 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450775e90>
2026-08-19 13:42:12,902 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450775e90>
2026-08-19 13:42:12,902 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 634d38c7-6118-4c0f-8c26-42ab5f69c963, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,902 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 634d38c7-6118-4c0f-8c26-42ab5f69c963, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,902 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f7450561f00>
2026-08-19 13:42:12,903 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f7450561f00>
2026-08-19 13:42:12,903 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, b41c2f65-d785-436f-973b-8b7dff1094dd, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,903 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, b41c2f65-d785-436f-973b-8b7dff1094dd, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,903 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450136100>
2026-08-19 13:42:12,904 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, a52c9d67-b6e8-4a6e-9f8c-5df23586d963, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,904 - DEBUG - __tests__.test_session_pool.TestSessionPool.1621738  - wait_for_size: Current size is 10
2026-08-19 13:42:12,904 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 5261c168-58cd-49eb-99cf-17c9a1536b94, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,905 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f74531ad710>
2026-08-19 13:42:12,905 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 5261c168-58cd-49eb-99cf-17c9a1536b94, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTkzY2U3MzEtODg1OWI5MWItOWZkYTdjOTQtNGU5ZjBhYmM=" } } } }
2026-08-19 13:42:12,905 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f74531ad710>, reason: not-initialized
2026-08-19 13:42:12,906 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f74531ad710> is no longer active. Current active count 9.
2026-08-19 13:42:12,906 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Current session pool size is less than 10, actual size 9
2026-08-19 13:42:12,906 - DEBUG - ydb.table.SessionPool.1621738  - _create: Created session <ydb.table.Session object at 0x7f745054d720>
2026-08-19 13:42:12,906 - DEBUG - ydb.table.SessionPool.1621738  - _prepare: Preparing session <ydb.table.Session object at 0x7f745054d720>
2026-08-19 13:42:12,907 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(CreateSession, 495fb0bf-f1df-4e01-983c-7866f5ad342b, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,907 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(CreateSession, 495fb0bf-f1df-4e01-983c-7866f5ad342b, ghrun-4q22hfe2g4.auto.internal:32230): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,908 - DEBUG - __tests__.test_session_pool.TestSessionPool.1621738  - wait_for_size: Current size is 10
2026-08-19 13:42:12,908 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450136cd0>
2026-08-19 13:42:12,908 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 5261c168-58cd-49eb-99cf-17c9a1536b94, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,908 - DEBUG - ydb.table.SessionPool.1621738  - stop: Requested session pool stop.
2026-08-19 13:42:12,909 - DEBUG - ydb.table.SessionPool.1621738  - stop: Session pool is under stop, cancelling all in flight waiters.
2026-08-19 13:42:12,909 - DEBUG - ydb.table.SessionPool.1621738  - stop: Destroying sessions in active queue
2026-08-19 13:42:12,909 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f74531739e0>, reason: session-pool-terminated
2026-08-19 13:42:12,909 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f74531739e0> is no longer active. Current active count 9.
2026-08-19 13:42:12,909 - DEBUG - ydb.table.SessionPool.1621738  - _terminate_event: Terminated session pool.
2026-08-19 13:42:12,910 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, c4838d44-5aaa-4481-bda3-174a118d452c, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,910 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, b62c271f-6e3b-48fc-a106-2cc8929bd8e2, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,911 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, c4838d44-5aaa-4481-bda3-174a118d452c, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OGEwNTQ1MWItOTkzZjRjMjctNDRkNDhkMjctNzAzZjcxNw==" } } } }
2026-08-19 13:42:12,911 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, b62c271f-6e3b-48fc-a106-2cc8929bd8e2, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=ODhiNjdkMTQtNTUxNjU5YzAtMTg0YjI3NjQtMzk2OGEzZGU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,912 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f74531739e0>
2026-08-19 13:42:12,912 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450136100>, reason: session-pool-terminated
2026-08-19 13:42:12,913 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450136100> is no longer active. Current active count 8.
2026-08-19 13:42:12,913 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, f5fdf859-42d4-4720-8b82-b34fe41d0acf, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,913 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, f5fdf859-42d4-4720-8b82-b34fe41d0acf, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=MWVkNWU5NjktNGFiNTI4ODgtZDkxZDU0MDUtZWY0NmI5YTI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,913 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450136100>
2026-08-19 13:42:12,914 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450136cd0>, reason: session-pool-terminated
2026-08-19 13:42:12,914 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450136cd0> is no longer active. Current active count 7.
2026-08-19 13:42:12,914 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 817a2a1d-3496-4534-a83f-2839e398a94f, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,914 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 817a2a1d-3496-4534-a83f-2839e398a94f, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=NTkzY2U3MzEtODg1OWI5MWItOWZkYTdjOTQtNGU5ZjBhYmM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,915 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450136cd0>
2026-08-19 13:42:12,915 - DEBUG - ydb.table.SessionPool.1621738  - stop: Destroyed active sessions
2026-08-19 13:42:12,915 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f745319a760>
2026-08-19 13:42:12,916 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f745319a760>, reason: not-initialized
2026-08-19 13:42:12,916 - INFO - ya.test.1621738  - pytest_runtest_teardown: Test teardown
2026-08-19 13:42:12,916 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f745319a760> is no longer active. Current active count 6.
2026-08-19 13:42:12,917 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 6f2fa928-a86e-4c01-a438-ebb31c83653a, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,917 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 6f2fa928-a86e-4c01-a438-ebb31c83653a, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=OGEwNTQ1MWItOTkzZjRjMjctNDRkNDhkMjctNzAzZjcxNw==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,918 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f745319a760>
2026-08-19 13:42:12,918 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, c4838d44-5aaa-4481-bda3-174a118d452c, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,919 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 39d8b4dd-7093-4f34-bc17-1da491f35f9a, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,919 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 39d8b4dd-7093-4f34-bc17-1da491f35f9a, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MWIxMTMyZTEtYjgxNDhmYWYtOWVjMDk2MTUtM2RlMDExYg==" } } } }
2026-08-19 13:42:12,919 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450a3dbe0>
2026-08-19 13:42:12,920 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450a3dbe0>, reason: not-initialized
2026-08-19 13:42:12,920 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450a3dbe0> is no longer active. Current active count 5.
2026-08-19 13:42:12,920 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, c54c0603-965b-4133-a29a-e606f8baadd5, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,920 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, c54c0603-965b-4133-a29a-e606f8baadd5, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=MWIxMTMyZTEtYjgxNDhmYWYtOWVjMDk2MTUtM2RlMDExYg==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,921 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450a3dbe0>
2026-08-19 13:42:12,921 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 39d8b4dd-7093-4f34-bc17-1da491f35f9a, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,921 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 23b06725-4fc6-45f0-b66b-d3cb10de4909, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,922 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 23b06725-4fc6-45f0-b66b-d3cb10de4909, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTdlMjIwODAtOTQwNGYzMzQtNjUxYzViMWEtMjUxYzE1N2U=" } } } }
2026-08-19 13:42:12,922 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450775bf0>
2026-08-19 13:42:12,922 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450775bf0>, reason: not-initialized
2026-08-19 13:42:12,923 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450775bf0> is no longer active. Current active count 4.
2026-08-19 13:42:12,923 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 91492099-68fe-4cb6-865d-4c4903c23c26, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,923 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 91492099-68fe-4cb6-865d-4c4903c23c26, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=NTdlMjIwODAtOTQwNGYzMzQtNjUxYzViMWEtMjUxYzE1N2U=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,924 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450775bf0>
2026-08-19 13:42:12,924 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 23b06725-4fc6-45f0-b66b-d3cb10de4909, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,924 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 66920303-75da-487c-8371-e220357f0f3d, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,925 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 66920303-75da-487c-8371-e220357f0f3d, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MTM5OTQ4YWItNTcxMzAzYzctMWEyNzdlYzgtZTJmMzQ0YmE=" } } } }
2026-08-19 13:42:12,925 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450775a30>
2026-08-19 13:42:12,925 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450775a30>, reason: not-initialized
2026-08-19 13:42:12,925 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450775a30> is no longer active. Current active count 3.
2026-08-19 13:42:12,926 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, f0f82722-4c52-40ae-bf0d-7124f33161a1, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,926 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, f0f82722-4c52-40ae-bf0d-7124f33161a1, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=MTM5OTQ4YWItNTcxMzAzYzctMWEyNzdlYzgtZTJmMzQ0YmE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,926 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450775a30>
2026-08-19 13:42:12,927 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 66920303-75da-487c-8371-e220357f0f3d, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,927 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 634d38c7-6118-4c0f-8c26-42ab5f69c963, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,927 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 634d38c7-6118-4c0f-8c26-42ab5f69c963, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZGE4YzFmZGQtYjFkNzA5OTItY2EzYWRkNDUtNWVhZDY3OGM=" } } } }
2026-08-19 13:42:12,928 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450775e90>
2026-08-19 13:42:12,929 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450775e90>, reason: not-initialized
2026-08-19 13:42:12,930 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450775e90> is no longer active. Current active count 2.
2026-08-19 13:42:12,930 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 5512e450-b1ac-4246-8355-f3be09e7a2f9, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,930 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 5512e450-b1ac-4246-8355-f3be09e7a2f9, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=ZGE4YzFmZGQtYjFkNzA5OTItY2EzYWRkNDUtNWVhZDY3OGM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,931 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450775e90>
2026-08-19 13:42:12,931 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 634d38c7-6118-4c0f-8c26-42ab5f69c963, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,931 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, b41c2f65-d785-436f-973b-8b7dff1094dd, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,932 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, b41c2f65-d785-436f-973b-8b7dff1094dd, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZGM0YjgzMjQtZDUxMGU1NTEtNWY3MzQ4YWYtZTgxNGQ4YWQ=" } } } }
2026-08-19 13:42:12,932 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f7450561f00>
2026-08-19 13:42:12,932 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7450561f00>, reason: not-initialized
2026-08-19 13:42:12,932 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f7450561f00> is no longer active. Current active count 1.
2026-08-19 13:42:12,933 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 54938d4f-8c3c-43f1-8eee-479098e66122, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,933 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 54938d4f-8c3c-43f1-8eee-479098e66122, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=ZGM0YjgzMjQtZDUxMGU1NTEtNWY3MzQ4YWYtZTgxNGQ4YWQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,933 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7450561f00>
2026-08-19 13:42:12,933 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, b41c2f65-d785-436f-973b-8b7dff1094dd, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,934 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 495fb0bf-f1df-4e01-983c-7866f5ad342b, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,934 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(CreateSession, 495fb0bf-f1df-4e01-983c-7866f5ad342b, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDZkMTFkMzEtYWVhMGUzNC1lZjhhMzgzZi1iZjk5Njky" } } } }
2026-08-19 13:42:12,934 - DEBUG - ydb.table.SessionPool.1621738  - put: Put on session <ydb.table.Session object at 0x7f745054d720>
2026-08-19 13:42:12,935 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f745054d720>, reason: not-initialized
2026-08-19 13:42:12,935 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Session <ydb.table.Session object at 0x7f745054d720> is no longer active. Current active count 0.
2026-08-19 13:42:12,935 - DEBUG - ydb.connection.1621738  - _prepare_call: RpcState(DeleteSession, 2804af0c-61d0-452f-ba63-cbd1f8521c7e, ghrun-4q22hfe2g4.auto.internal:32230): creating call state
2026-08-19 13:42:12,935 - DEBUG - ydb.connection.1621738  - _log_request: RpcState(DeleteSession, 2804af0c-61d0-452f-ba63-cbd1f8521c7e, ghrun-4q22hfe2g4.auto.internal:32230): request = { session_id: "ydb://session/3?node_id=1&id=ZDZkMTFkMzEtYWVhMGUzNC1lZjhhMzgzZi1iZjk5Njky" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-08-19 13:42:12,936 - DEBUG - ydb.table.SessionPool.1621738  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f745054d720>
2026-08-19 13:42:12,936 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(CreateSession, 495fb0bf-f1df-4e01-983c-7866f5ad342b, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,936 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, b62c271f-6e3b-48fc-a106-2cc8929bd8e2, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,937 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, b62c271f-6e3b-48fc-a106-2cc8929bd8e2, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,937 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, b62c271f-6e3b-48fc-a106-2cc8929bd8e2, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,937 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, f5fdf859-42d4-4720-8b82-b34fe41d0acf, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,937 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, f5fdf859-42d4-4720-8b82-b34fe41d0acf, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,938 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, f5fdf859-42d4-4720-8b82-b34fe41d0acf, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,938 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 817a2a1d-3496-4534-a83f-2839e398a94f, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,938 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 817a2a1d-3496-4534-a83f-2839e398a94f, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,938 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 817a2a1d-3496-4534-a83f-2839e398a94f, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,938 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 6f2fa928-a86e-4c01-a438-ebb31c83653a, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,939 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 6f2fa928-a86e-4c01-a438-ebb31c83653a, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,939 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 6f2fa928-a86e-4c01-a438-ebb31c83653a, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,939 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, c54c0603-965b-4133-a29a-e606f8baadd5, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,940 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, c54c0603-965b-4133-a29a-e606f8baadd5, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,940 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, c54c0603-965b-4133-a29a-e606f8baadd5, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,940 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 91492099-68fe-4cb6-865d-4c4903c23c26, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,940 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 91492099-68fe-4cb6-865d-4c4903c23c26, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,941 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 91492099-68fe-4cb6-865d-4c4903c23c26, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,941 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, f0f82722-4c52-40ae-bf0d-7124f33161a1, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,941 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, f0f82722-4c52-40ae-bf0d-7124f33161a1, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,941 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, f0f82722-4c52-40ae-bf0d-7124f33161a1, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,942 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 5512e450-b1ac-4246-8355-f3be09e7a2f9, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,942 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 5512e450-b1ac-4246-8355-f3be09e7a2f9, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,942 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 5512e450-b1ac-4246-8355-f3be09e7a2f9, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,942 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 54938d4f-8c3c-43f1-8eee-479098e66122, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,943 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 54938d4f-8c3c-43f1-8eee-479098e66122, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,943 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 54938d4f-8c3c-43f1-8eee-479098e66122, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:12,943 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 2804af0c-61d0-452f-ba63-cbd1f8521c7e, ghrun-4q22hfe2g4.auto.internal:32230): on response callback started
2026-08-19 13:42:12,943 - DEBUG - ydb.connection.1621738  - _log_response: RpcState(DeleteSession, 2804af0c-61d0-452f-ba63-cbd1f8521c7e, ghrun-4q22hfe2g4.auto.internal:32230): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:42:12,944 - DEBUG - ydb.connection.1621738  - _on_response_callback: RpcState(DeleteSession, 2804af0c-61d0-452f-ba63-cbd1f8521c7e, ghrun-4q22hfe2g4.auto.internal:32230): on response callback success
2026-08-19 13:42:29,918 - INFO - ydb.tests.library.harness.kikimr_runner.1621738  - stop: Stopped node localhost:32230/1
2026-08-19 13:42:29,919 - DEBUG - library.python.filelock.1621738  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5373
2026-08-19 13:42:29,919 - DEBUG - library.python.filelock.1621738  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10599
2026-08-19 13:42:29,920 - DEBUG - library.python.filelock.1621738  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22996
2026-08-19 13:42:29,921 - DEBUG - library.python.filelock.1621738  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32230
2026-08-19 13:42:29,921 - DEBUG - library.python.filelock.1621738  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3586
