2025-10-31 15:41:29,780 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:29,780 - INFO - ya.test - pytest_runtest_setup: test_session_pool_min_size_feature
2025-10-31 15:41:29,780 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:29,780 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:29,781 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_min_size_feature)
2025-10-31 15:41:29,782 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36ed8f260>
2025-10-31 15:41:29,783 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36ed8f260>
2025-10-31 15:41:29,783 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, eb15a35b-7c30-46ca-8dd4-46be5667be94, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,783 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, eb15a35b-7c30-46ca-8dd4-46be5667be94, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,784 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f647b70>
2025-10-31 15:41:29,786 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, eb15a35b-7c30-46ca-8dd4-46be5667be94, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:29,788 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f647b70>
2025-10-31 15:41:29,788 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, eb15a35b-7c30-46ca-8dd4-46be5667be94, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YWM5YTk4N2ItNjAyNWMyM2EtZWE0OWJhNjktOTY4OGZlOGI=" } } } }
2025-10-31 15:41:29,795 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2be4737d-cca5-46de-b0c3-109fe8015838, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,796 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2be4737d-cca5-46de-b0c3-109fe8015838, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,801 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f646cc0>
2025-10-31 15:41:29,804 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f646cc0>
2025-10-31 15:41:29,805 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 656a74a6-3479-46bf-9037-d2298e677f2b, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,811 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 656a74a6-3479-46bf-9037-d2298e677f2b, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,812 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f646950>
2025-10-31 15:41:29,831 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f646950>
2025-10-31 15:41:29,831 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 391cd399-13ed-4cb1-9dcd-c9e4aca3673f, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,831 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 391cd399-13ed-4cb1-9dcd-c9e4aca3673f, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,832 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f645e60>
2025-10-31 15:41:29,832 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f645e60>
2025-10-31 15:41:29,833 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2a77f91f-ec8b-4f83-b842-ffa31fb8d7ac, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,834 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2a77f91f-ec8b-4f83-b842-ffa31fb8d7ac, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,834 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f6455f0>
2025-10-31 15:41:29,835 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f6455f0>
2025-10-31 15:41:29,835 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, e72ff648-ee55-4976-8671-330bc01d1f2a, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,835 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, e72ff648-ee55-4976-8671-330bc01d1f2a, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,836 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f645000>
2025-10-31 15:41:29,837 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f645000>
2025-10-31 15:41:29,842 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 470273bb-f0b8-4a22-8191-cd8ea55f0702, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,844 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 470273bb-f0b8-4a22-8191-cd8ea55f0702, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,845 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36ed8f260>
2025-10-31 15:41:29,846 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, eb15a35b-7c30-46ca-8dd4-46be5667be94, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:29,847 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2be4737d-cca5-46de-b0c3-109fe8015838, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:29,847 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2be4737d-cca5-46de-b0c3-109fe8015838, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NmVmNTUyMzItMmJlNmRlMS1iOWM0YjI3NC1iNjJjZWViOA==" } } } }
2025-10-31 15:41:29,848 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36f6456e0>
2025-10-31 15:41:29,849 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36f6456e0>
2025-10-31 15:41:29,850 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 57a0d096-d81b-490c-8c90-2682f57d4a8c, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,851 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 57a0d096-d81b-490c-8c90-2682f57d4a8c, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,852 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36ed44740>
2025-10-31 15:41:29,856 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36ed44740>
2025-10-31 15:41:29,856 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fec2ec8c-917d-4ec3-a657-20c772adf578, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,857 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fec2ec8c-917d-4ec3-a657-20c772adf578, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,857 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36ed44d80>
2025-10-31 15:41:29,860 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36ed44d80>
2025-10-31 15:41:29,860 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7fc14053-4458-44a2-8a4c-f143e5f69b9e, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,861 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7fc14053-4458-44a2-8a4c-f143e5f69b9e, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,863 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-10-31 15:41:29,863 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f647b70>
2025-10-31 15:41:29,865 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2be4737d-cca5-46de-b0c3-109fe8015838, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:29,866 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36ed8f260>
2025-10-31 15:41:29,867 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 656a74a6-3479-46bf-9037-d2298e677f2b, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:29,867 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36ed8f260>, reason: not-initialized
2025-10-31 15:41:29,868 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 656a74a6-3479-46bf-9037-d2298e677f2b, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=M2JkNGIxODgtNmExNzcwODEtZmU5YTc5NzgtMzY4MTYzZGM=" } } } }
2025-10-31 15:41:29,868 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36ed8f260> is no longer active. Current active count 9.
2025-10-31 15:41:29,869 - DEBUG - ydb.table.SessionPool - _destroy: Current session pool size is less than 10, actual size 9
2025-10-31 15:41:29,869 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fc36ed44330>
2025-10-31 15:41:29,869 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fc36ed44330>
2025-10-31 15:41:29,870 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a674683c-fd73-401f-9bec-468e42de2ea5, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,870 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a674683c-fd73-401f-9bec-468e42de2ea5, ghrun-xldpwfo7ey.auto.internal:2526): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,871 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f646cc0>
2025-10-31 15:41:29,871 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 656a74a6-3479-46bf-9037-d2298e677f2b, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:29,871 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-10-31 15:41:29,872 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 391cd399-13ed-4cb1-9dcd-c9e4aca3673f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:29,872 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-31 15:41:29,873 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 391cd399-13ed-4cb1-9dcd-c9e4aca3673f, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NWYxMzUyYzEtYWFlMDk2ZDktMjA1MDIxMjEtOGE2NWQxNGQ=" } } } }
2025-10-31 15:41:29,873 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-31 15:41:29,873 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-31 15:41:29,874 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-31 15:41:29,875 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f647b70>, reason: session-pool-terminated
2025-10-31 15:41:29,876 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f647b70> is no longer active. Current active count 9.
2025-10-31 15:41:29,887 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b0ed64b6-d3cb-477e-b90c-bd794fc58478, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,887 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b0ed64b6-d3cb-477e-b90c-bd794fc58478, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=NmVmNTUyMzItMmJlNmRlMS1iOWM0YjI3NC1iNjJjZWViOA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,888 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f647b70>
2025-10-31 15:41:29,927 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f646cc0>, reason: session-pool-terminated
2025-10-31 15:41:29,927 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f646cc0> is no longer active. Current active count 8.
2025-10-31 15:41:29,927 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 96c5ddfe-51ff-497a-97b6-f1b49450047f, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,928 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 96c5ddfe-51ff-497a-97b6-f1b49450047f, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=M2JkNGIxODgtNmExNzcwODEtZmU5YTc5NzgtMzY4MTYzZGM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,934 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f646cc0>
2025-10-31 15:41:29,966 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-31 15:41:29,967 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f646950>
2025-10-31 15:41:29,983 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 15:41:29,983 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f646950>, reason: not-initialized
2025-10-31 15:41:29,995 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f646950> is no longer active. Current active count 7.
2025-10-31 15:41:29,995 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c1fd0c89-6555-464d-87cf-e4f489c54bf2, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:29,995 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c1fd0c89-6555-464d-87cf-e4f489c54bf2, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=NWYxMzUyYzEtYWFlMDk2ZDktMjA1MDIxMjEtOGE2NWQxNGQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:29,996 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f646950>
2025-10-31 15:41:29,996 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 391cd399-13ed-4cb1-9dcd-c9e4aca3673f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:29,997 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2a77f91f-ec8b-4f83-b842-ffa31fb8d7ac, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,029 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2a77f91f-ec8b-4f83-b842-ffa31fb8d7ac, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YWJkYzZhNmUtY2UxZGQ1ZjQtZWUwMWE5OGMtNGQyNDg4NzA=" } } } }
2025-10-31 15:41:30,031 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f645e60>
2025-10-31 15:41:30,031 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f645e60>, reason: not-initialized
2025-10-31 15:41:30,066 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f645e60> is no longer active. Current active count 6.
2025-10-31 15:41:30,067 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 145273df-ae1e-45b5-afc6-36407f23b1c9, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,068 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 145273df-ae1e-45b5-afc6-36407f23b1c9, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=YWJkYzZhNmUtY2UxZGQ1ZjQtZWUwMWE5OGMtNGQyNDg4NzA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,069 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f645e60>
2025-10-31 15:41:30,069 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2a77f91f-ec8b-4f83-b842-ffa31fb8d7ac, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,070 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e72ff648-ee55-4976-8671-330bc01d1f2a, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,070 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, e72ff648-ee55-4976-8671-330bc01d1f2a, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MTg0NWY4MGQtYTg5YTM0YjItMWJhMDMxOS0yNGZmZDU4" } } } }
2025-10-31 15:41:30,071 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f6455f0>
2025-10-31 15:41:30,071 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f6455f0>, reason: not-initialized
2025-10-31 15:41:30,071 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f6455f0> is no longer active. Current active count 5.
2025-10-31 15:41:30,071 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a9992de9-e271-4cfe-afb4-9d34a05987a9, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,072 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a9992de9-e271-4cfe-afb4-9d34a05987a9, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=MTg0NWY4MGQtYTg5YTM0YjItMWJhMDMxOS0yNGZmZDU4" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,072 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f6455f0>
2025-10-31 15:41:30,073 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e72ff648-ee55-4976-8671-330bc01d1f2a, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,073 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 470273bb-f0b8-4a22-8191-cd8ea55f0702, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,074 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 470273bb-f0b8-4a22-8191-cd8ea55f0702, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NWMyZmM5NTUtZGRkNmE3OTYtNjQzNzMxNGYtMjRmY2ZlYmM=" } } } }
2025-10-31 15:41:30,074 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f645000>
2025-10-31 15:41:30,075 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f645000>, reason: not-initialized
2025-10-31 15:41:30,075 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f645000> is no longer active. Current active count 4.
2025-10-31 15:41:30,075 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 4a5ac876-37b9-4099-8926-8ea9c28f1103, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,075 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 4a5ac876-37b9-4099-8926-8ea9c28f1103, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=NWMyZmM5NTUtZGRkNmE3OTYtNjQzNzMxNGYtMjRmY2ZlYmM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,076 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f645000>
2025-10-31 15:41:30,076 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 470273bb-f0b8-4a22-8191-cd8ea55f0702, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,077 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 57a0d096-d81b-490c-8c90-2682f57d4a8c, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,077 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 57a0d096-d81b-490c-8c90-2682f57d4a8c, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NzBlMWJlODUtYTYwYWUwY2EtZWIwNTc4Y2ItYWRiNjg4Zg==" } } } }
2025-10-31 15:41:30,078 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36f6456e0>
2025-10-31 15:41:30,079 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36f6456e0>, reason: not-initialized
2025-10-31 15:41:30,079 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36f6456e0> is no longer active. Current active count 3.
2025-10-31 15:41:30,079 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, f6452039-7fa3-44a6-8cce-69f4f15c2988, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,079 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, f6452039-7fa3-44a6-8cce-69f4f15c2988, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=NzBlMWJlODUtYTYwYWUwY2EtZWIwNTc4Y2ItYWRiNjg4Zg==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,080 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36f6456e0>
2025-10-31 15:41:30,080 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 57a0d096-d81b-490c-8c90-2682f57d4a8c, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,081 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fec2ec8c-917d-4ec3-a657-20c772adf578, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,081 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fec2ec8c-917d-4ec3-a657-20c772adf578, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Y2I3NzE3ZmYtYzZjMmFjMjYtODBiYWUzNGUtOGVkNjJkZQ==" } } } }
2025-10-31 15:41:30,081 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36ed44740>
2025-10-31 15:41:30,081 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36ed44740>, reason: not-initialized
2025-10-31 15:41:30,082 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36ed44740> is no longer active. Current active count 2.
2025-10-31 15:41:30,082 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9aa1b106-bb5b-4141-8e97-a4f5b9f8a0bf, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,082 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9aa1b106-bb5b-4141-8e97-a4f5b9f8a0bf, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=Y2I3NzE3ZmYtYzZjMmFjMjYtODBiYWUzNGUtOGVkNjJkZQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,083 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36ed44740>
2025-10-31 15:41:30,083 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fec2ec8c-917d-4ec3-a657-20c772adf578, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,083 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7fc14053-4458-44a2-8a4c-f143e5f69b9e, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,084 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7fc14053-4458-44a2-8a4c-f143e5f69b9e, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODE0ZmFiYjktZDJjNjYxOTEtYmYyNzQ2NTQtZmJkZDNjNzU=" } } } }
2025-10-31 15:41:30,084 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36ed44d80>
2025-10-31 15:41:30,084 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36ed44d80>, reason: not-initialized
2025-10-31 15:41:30,084 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36ed44d80> is no longer active. Current active count 1.
2025-10-31 15:41:30,085 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3b0750dd-3cc3-4c3c-bfb6-9e3c02459d3a, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,085 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3b0750dd-3cc3-4c3c-bfb6-9e3c02459d3a, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=ODE0ZmFiYjktZDJjNjYxOTEtYmYyNzQ2NTQtZmJkZDNjNzU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,086 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36ed44d80>
2025-10-31 15:41:30,086 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7fc14053-4458-44a2-8a4c-f143e5f69b9e, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,087 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a674683c-fd73-401f-9bec-468e42de2ea5, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,087 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a674683c-fd73-401f-9bec-468e42de2ea5, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MTU2ZTcxZjUtYWRiYjY3YTktOGYyMDZhMmYtYWRkNGI2M2Q=" } } } }
2025-10-31 15:41:30,088 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fc36ed44330>
2025-10-31 15:41:30,088 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc36ed44330>, reason: not-initialized
2025-10-31 15:41:30,088 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fc36ed44330> is no longer active. Current active count 0.
2025-10-31 15:41:30,088 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 93542f25-d639-475c-9437-a608e4179f2f, ghrun-xldpwfo7ey.auto.internal:2526): creating call state
2025-10-31 15:41:30,089 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 93542f25-d639-475c-9437-a608e4179f2f, ghrun-xldpwfo7ey.auto.internal:2526): request = { session_id: "ydb://session/3?node_id=1&id=MTU2ZTcxZjUtYWRiYjY3YTktOGYyMDZhMmYtYWRkNGI2M2Q=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 15:41:30,089 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc36ed44330>
2025-10-31 15:41:30,089 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a674683c-fd73-401f-9bec-468e42de2ea5, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,090 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b0ed64b6-d3cb-477e-b90c-bd794fc58478, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,091 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b0ed64b6-d3cb-477e-b90c-bd794fc58478, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,091 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b0ed64b6-d3cb-477e-b90c-bd794fc58478, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,091 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 96c5ddfe-51ff-497a-97b6-f1b49450047f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,092 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 96c5ddfe-51ff-497a-97b6-f1b49450047f, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,092 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 96c5ddfe-51ff-497a-97b6-f1b49450047f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,092 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c1fd0c89-6555-464d-87cf-e4f489c54bf2, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,092 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c1fd0c89-6555-464d-87cf-e4f489c54bf2, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,093 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c1fd0c89-6555-464d-87cf-e4f489c54bf2, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,093 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 145273df-ae1e-45b5-afc6-36407f23b1c9, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,093 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 145273df-ae1e-45b5-afc6-36407f23b1c9, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,094 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 145273df-ae1e-45b5-afc6-36407f23b1c9, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,094 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a9992de9-e271-4cfe-afb4-9d34a05987a9, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,094 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a9992de9-e271-4cfe-afb4-9d34a05987a9, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,095 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a9992de9-e271-4cfe-afb4-9d34a05987a9, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,095 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 4a5ac876-37b9-4099-8926-8ea9c28f1103, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,096 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 4a5ac876-37b9-4099-8926-8ea9c28f1103, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,096 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 4a5ac876-37b9-4099-8926-8ea9c28f1103, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,096 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, f6452039-7fa3-44a6-8cce-69f4f15c2988, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,096 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, f6452039-7fa3-44a6-8cce-69f4f15c2988, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,097 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, f6452039-7fa3-44a6-8cce-69f4f15c2988, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,097 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 9aa1b106-bb5b-4141-8e97-a4f5b9f8a0bf, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,097 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 9aa1b106-bb5b-4141-8e97-a4f5b9f8a0bf, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,097 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 9aa1b106-bb5b-4141-8e97-a4f5b9f8a0bf, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,098 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3b0750dd-3cc3-4c3c-bfb6-9e3c02459d3a, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,098 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3b0750dd-3cc3-4c3c-bfb6-9e3c02459d3a, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,099 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3b0750dd-3cc3-4c3c-bfb6-9e3c02459d3a, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:30,099 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 93542f25-d639-475c-9437-a608e4179f2f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback started
2025-10-31 15:41:30,099 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 93542f25-d639-475c-9437-a608e4179f2f, ghrun-xldpwfo7ey.auto.internal:2526): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:30,099 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 93542f25-d639-475c-9437-a608e4179f2f, ghrun-xldpwfo7ey.auto.internal:2526): on response callback success
2025-10-31 15:41:46,990 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:2526/1
2025-10-31 15:41:46,991 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22998
2025-10-31 15:41:46,991 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3304
2025-10-31 15:41:46,992 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30718
2025-10-31 15:41:46,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2526
2025-10-31 15:41:46,993 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64958
