2025-10-31 13:47:14,728 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8c6cfc8d-0865-45c8-8d00-613f4153709d, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,732 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:47:14,733 - INFO - ya.test - pytest_runtest_setup: test_session_pool_min_size_feature
2025-10-31 13:47:14,734 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:47:14,734 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:47:14,736 - 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 13:47:14,737 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a45c920>
2025-10-31 13:47:14,737 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a45c920>
2025-10-31 13:47:14,738 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a0708ee4-d1ea-46ce-aff7-a820626d4834, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,738 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a0708ee4-d1ea-46ce-aff7-a820626d4834, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,739 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a45d2d0>
2025-10-31 13:47:14,739 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a45d2d0>
2025-10-31 13:47:14,742 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a0708ee4-d1ea-46ce-aff7-a820626d4834, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,744 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 325790f6-4e9f-408c-bbf4-c2b8ae39f09f, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,745 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a0708ee4-d1ea-46ce-aff7-a820626d4834, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODNlNjhhYzctNjcwYzgzYmItYzJjZDNhMWMtMTMwMTU2OTk=" } } } }
2025-10-31 13:47:14,746 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 325790f6-4e9f-408c-bbf4-c2b8ae39f09f, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,748 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a45f990>
2025-10-31 13:47:14,748 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a45c920>
2025-10-31 13:47:14,749 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a0708ee4-d1ea-46ce-aff7-a820626d4834, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,751 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 325790f6-4e9f-408c-bbf4-c2b8ae39f09f, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,751 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 325790f6-4e9f-408c-bbf4-c2b8ae39f09f, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Y2E3MjQxZjUtMTJiMmYxODUtYmZiOGMyNmMtYjMzZTVjZWI=" } } } }
2025-10-31 13:47:14,749 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a45f990>
2025-10-31 13:47:14,752 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a65abeba-c101-432c-b16a-2f7b78ee35f3, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,752 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a65abeba-c101-432c-b16a-2f7b78ee35f3, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,753 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a459e10>
2025-10-31 13:47:14,753 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a459e10>
2025-10-31 13:47:14,753 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8332d01d-0dfd-43ae-bc64-9b1e836816ee, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,754 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8332d01d-0dfd-43ae-bc64-9b1e836816ee, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,754 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a45b8f0>
2025-10-31 13:47:14,755 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a45b8f0>
2025-10-31 13:47:14,755 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 49cdd9af-4183-46f5-808f-1562682bbe48, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,756 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 49cdd9af-4183-46f5-808f-1562682bbe48, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,757 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a458830>
2025-10-31 13:47:14,757 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a458830>
2025-10-31 13:47:14,757 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6e6a0acb-9820-4004-b1c5-60d89f7579ce, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,758 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6e6a0acb-9820-4004-b1c5-60d89f7579ce, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,758 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a459320>
2025-10-31 13:47:14,758 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a459320>
2025-10-31 13:47:14,759 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1b525358-e7dc-4653-a9f2-1b8aa6740f00, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,759 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1b525358-e7dc-4653-a9f2-1b8aa6740f00, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,760 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a4598c0>
2025-10-31 13:47:14,760 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a4598c0>
2025-10-31 13:47:14,761 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8ce35796-ba3b-42c0-824f-aa6e6a037fd4, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,763 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8ce35796-ba3b-42c0-824f-aa6e6a037fd4, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,764 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a459eb0>
2025-10-31 13:47:14,768 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a459eb0>
2025-10-31 13:47:14,768 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 445508fc-2829-44f7-8e00-f2d8ed167e5d, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,772 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 445508fc-2829-44f7-8e00-f2d8ed167e5d, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,773 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a463d50>
2025-10-31 13:47:14,773 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a463d50>
2025-10-31 13:47:14,774 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4266f499-be7d-41a1-ac9b-f49d27291d0d, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,777 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4266f499-be7d-41a1-ac9b-f49d27291d0d, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,778 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-10-31 13:47:14,779 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a45d2d0>
2025-10-31 13:47:14,780 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 325790f6-4e9f-408c-bbf4-c2b8ae39f09f, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,780 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a45c920>
2025-10-31 13:47:14,781 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a45c920>, reason: not-initialized
2025-10-31 13:47:14,781 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a45c920> is no longer active. Current active count 9.
2025-10-31 13:47:14,782 - DEBUG - ydb.table.SessionPool - _destroy: Current session pool size is less than 10, actual size 9
2025-10-31 13:47:14,782 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a65abeba-c101-432c-b16a-2f7b78ee35f3, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,783 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a65abeba-c101-432c-b16a-2f7b78ee35f3, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=Y2M3ZmM4ZDUtMWQ2MjIxYTItODY2Y2IwZmYtOWIyNjQ3NTY=" } } } }
2025-10-31 13:47:14,782 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f7a3a28a810>
2025-10-31 13:47:14,784 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f7a3a28a810>
2025-10-31 13:47:14,784 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 35c91485-be42-4ae3-8731-d5df9456485f, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,785 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 35c91485-be42-4ae3-8731-d5df9456485f, ghrun-e45ihybpyu.auto.internal:15180): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,786 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-10-31 13:47:14,786 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-31 13:47:14,786 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-31 13:47:14,787 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-31 13:47:14,787 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-31 13:47:14,792 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a45d2d0>, reason: session-pool-terminated
2025-10-31 13:47:14,792 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a45d2d0> is no longer active. Current active count 9.
2025-10-31 13:47:14,793 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 29ff608e-b14c-464e-9788-b8a5083a71cf, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,793 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 29ff608e-b14c-464e-9788-b8a5083a71cf, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=Y2E3MjQxZjUtMTJiMmYxODUtYmZiOGMyNmMtYjMzZTVjZWI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,794 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a45d2d0>
2025-10-31 13:47:14,795 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-31 13:47:14,796 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 13:47:14,796 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a45f990>
2025-10-31 13:47:14,801 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a45f990>, reason: not-initialized
2025-10-31 13:47:14,803 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a45f990> is no longer active. Current active count 8.
2025-10-31 13:47:14,804 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 79c687ce-b121-4074-836e-8eaf6829e975, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,808 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 79c687ce-b121-4074-836e-8eaf6829e975, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=Y2M3ZmM4ZDUtMWQ2MjIxYTItODY2Y2IwZmYtOWIyNjQ3NTY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,809 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a45f990>
2025-10-31 13:47:14,809 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, a65abeba-c101-432c-b16a-2f7b78ee35f3, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,809 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8332d01d-0dfd-43ae-bc64-9b1e836816ee, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,810 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8332d01d-0dfd-43ae-bc64-9b1e836816ee, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWRhODY4YTYtMmUwNmNlYmQtMTE1ODI4YzQtOWMwYWQ3NDg=" } } } }
2025-10-31 13:47:14,814 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a459e10>
2025-10-31 13:47:14,831 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a459e10>, reason: not-initialized
2025-10-31 13:47:14,831 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a459e10> is no longer active. Current active count 7.
2025-10-31 13:47:14,833 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 55800bbe-0638-41e9-8f93-1b9f022c0be7, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,833 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 55800bbe-0638-41e9-8f93-1b9f022c0be7, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=OWRhODY4YTYtMmUwNmNlYmQtMTE1ODI4YzQtOWMwYWQ3NDg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,840 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a459e10>
2025-10-31 13:47:14,841 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8332d01d-0dfd-43ae-bc64-9b1e836816ee, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,843 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6e6a0acb-9820-4004-b1c5-60d89f7579ce, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,844 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6e6a0acb-9820-4004-b1c5-60d89f7579ce, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTViMmQ1Ny1hOTk2YTIwZi03YzA5ZWYzNC1kZTFhOGVjOQ==" } } } }
2025-10-31 13:47:14,845 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a458830>
2025-10-31 13:47:14,845 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a458830>, reason: not-initialized
2025-10-31 13:47:14,846 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a458830> is no longer active. Current active count 6.
2025-10-31 13:47:14,847 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 5e1d4308-1c4a-4a55-a938-7403cba8aba4, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,847 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 5e1d4308-1c4a-4a55-a938-7403cba8aba4, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=YTViMmQ1Ny1hOTk2YTIwZi03YzA5ZWYzNC1kZTFhOGVjOQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,853 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a458830>
2025-10-31 13:47:14,853 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6e6a0acb-9820-4004-b1c5-60d89f7579ce, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,854 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 49cdd9af-4183-46f5-808f-1562682bbe48, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,855 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 49cdd9af-4183-46f5-808f-1562682bbe48, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MWVhYzQ3YzMtNjI5NzRjNGYtNmIyM2RlLTlmMmZjYWQ4" } } } }
2025-10-31 13:47:14,855 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a45b8f0>
2025-10-31 13:47:14,860 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a45b8f0>, reason: not-initialized
2025-10-31 13:47:14,867 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a45b8f0> is no longer active. Current active count 5.
2025-10-31 13:47:14,868 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 221dec03-5e35-4a83-9ff4-1501c83f1621, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,869 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 221dec03-5e35-4a83-9ff4-1501c83f1621, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=MWVhYzQ3YzMtNjI5NzRjNGYtNmIyM2RlLTlmMmZjYWQ4" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,870 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a45b8f0>
2025-10-31 13:47:14,870 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 49cdd9af-4183-46f5-808f-1562682bbe48, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,871 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1b525358-e7dc-4653-a9f2-1b8aa6740f00, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,872 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1b525358-e7dc-4653-a9f2-1b8aa6740f00, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTBiNjBlZTktMTk2MTljMTYtNGU4MGMwZTctZWRkOGM5MDE=" } } } }
2025-10-31 13:47:14,879 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a459320>
2025-10-31 13:47:14,880 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a459320>, reason: not-initialized
2025-10-31 13:47:14,880 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a459320> is no longer active. Current active count 4.
2025-10-31 13:47:14,880 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1c471104-76ae-470d-a300-7d418c38feb9, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,885 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1c471104-76ae-470d-a300-7d418c38feb9, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=YTBiNjBlZTktMTk2MTljMTYtNGU4MGMwZTctZWRkOGM5MDE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,886 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a459320>
2025-10-31 13:47:14,886 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1b525358-e7dc-4653-a9f2-1b8aa6740f00, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,886 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8ce35796-ba3b-42c0-824f-aa6e6a037fd4, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,888 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8ce35796-ba3b-42c0-824f-aa6e6a037fd4, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ODA0OTA2MWYtYjVlNTQ4MjUtYjkzYzFlMTgtZThmNmU0MGQ=" } } } }
2025-10-31 13:47:14,888 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a4598c0>
2025-10-31 13:47:14,889 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a4598c0>, reason: not-initialized
2025-10-31 13:47:14,889 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a4598c0> is no longer active. Current active count 3.
2025-10-31 13:47:14,890 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 7a97ddfa-02d1-4172-a647-e7fffc819da0, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,890 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 7a97ddfa-02d1-4172-a647-e7fffc819da0, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=ODA0OTA2MWYtYjVlNTQ4MjUtYjkzYzFlMTgtZThmNmU0MGQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,891 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a4598c0>
2025-10-31 13:47:14,892 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 8ce35796-ba3b-42c0-824f-aa6e6a037fd4, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,892 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 445508fc-2829-44f7-8e00-f2d8ed167e5d, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,896 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 445508fc-2829-44f7-8e00-f2d8ed167e5d, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=M2MzYTY4NzktZjg0OTIxOWUtYzIzODRkZWUtMjMwYWZlMGQ=" } } } }
2025-10-31 13:47:14,900 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a459eb0>
2025-10-31 13:47:14,901 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a459eb0>, reason: not-initialized
2025-10-31 13:47:14,902 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a459eb0> is no longer active. Current active count 2.
2025-10-31 13:47:14,902 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ababc28d-87c1-418f-ae4d-1a9e8fc6bf8b, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,903 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ababc28d-87c1-418f-ae4d-1a9e8fc6bf8b, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=M2MzYTY4NzktZjg0OTIxOWUtYzIzODRkZWUtMjMwYWZlMGQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,908 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a459eb0>
2025-10-31 13:47:14,908 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 445508fc-2829-44f7-8e00-f2d8ed167e5d, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,909 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4266f499-be7d-41a1-ac9b-f49d27291d0d, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,910 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4266f499-be7d-41a1-ac9b-f49d27291d0d, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MmQ2OGYwMDktZjJjYWY2MGUtYTZkNjcyYzgtYjAyNjQzOTg=" } } } }
2025-10-31 13:47:14,911 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a463d50>
2025-10-31 13:47:14,911 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a463d50>, reason: not-initialized
2025-10-31 13:47:14,911 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a463d50> is no longer active. Current active count 1.
2025-10-31 13:47:14,912 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, cad30bbf-950a-4c9f-a07d-cf6fd9892fa1, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,912 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, cad30bbf-950a-4c9f-a07d-cf6fd9892fa1, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=MmQ2OGYwMDktZjJjYWY2MGUtYTZkNjcyYzgtYjAyNjQzOTg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,913 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a463d50>
2025-10-31 13:47:14,913 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4266f499-be7d-41a1-ac9b-f49d27291d0d, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,913 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 35c91485-be42-4ae3-8731-d5df9456485f, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,914 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 35c91485-be42-4ae3-8731-d5df9456485f, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjIxYzY0Y2UtNzZjN2JiNjktNjFkYjNlYTQtNzY2OWFlNA==" } } } }
2025-10-31 13:47:14,914 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f7a3a28a810>
2025-10-31 13:47:14,915 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f7a3a28a810>, reason: not-initialized
2025-10-31 13:47:14,915 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f7a3a28a810> is no longer active. Current active count 0.
2025-10-31 13:47:14,915 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 91b4a446-da16-48aa-b7c4-5cb7e3fc01a8, ghrun-e45ihybpyu.auto.internal:15180): creating call state
2025-10-31 13:47:14,920 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 91b4a446-da16-48aa-b7c4-5cb7e3fc01a8, ghrun-e45ihybpyu.auto.internal:15180): request = { session_id: "ydb://session/3?node_id=1&id=YjIxYzY0Y2UtNzZjN2JiNjktNjFkYjNlYTQtNzY2OWFlNA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-31 13:47:14,920 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f7a3a28a810>
2025-10-31 13:47:14,921 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 35c91485-be42-4ae3-8731-d5df9456485f, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,921 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 29ff608e-b14c-464e-9788-b8a5083a71cf, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,921 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 29ff608e-b14c-464e-9788-b8a5083a71cf, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,921 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 29ff608e-b14c-464e-9788-b8a5083a71cf, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,922 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 79c687ce-b121-4074-836e-8eaf6829e975, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,922 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 79c687ce-b121-4074-836e-8eaf6829e975, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,923 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 79c687ce-b121-4074-836e-8eaf6829e975, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,924 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 55800bbe-0638-41e9-8f93-1b9f022c0be7, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,924 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 55800bbe-0638-41e9-8f93-1b9f022c0be7, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,925 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 55800bbe-0638-41e9-8f93-1b9f022c0be7, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,925 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 5e1d4308-1c4a-4a55-a938-7403cba8aba4, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,926 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 5e1d4308-1c4a-4a55-a938-7403cba8aba4, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,926 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 5e1d4308-1c4a-4a55-a938-7403cba8aba4, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,927 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 221dec03-5e35-4a83-9ff4-1501c83f1621, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,927 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 221dec03-5e35-4a83-9ff4-1501c83f1621, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,927 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 221dec03-5e35-4a83-9ff4-1501c83f1621, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,928 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1c471104-76ae-470d-a300-7d418c38feb9, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,929 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1c471104-76ae-470d-a300-7d418c38feb9, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,929 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1c471104-76ae-470d-a300-7d418c38feb9, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,929 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 7a97ddfa-02d1-4172-a647-e7fffc819da0, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,929 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 7a97ddfa-02d1-4172-a647-e7fffc819da0, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,929 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 7a97ddfa-02d1-4172-a647-e7fffc819da0, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,932 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ababc28d-87c1-418f-ae4d-1a9e8fc6bf8b, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,933 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ababc28d-87c1-418f-ae4d-1a9e8fc6bf8b, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,934 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ababc28d-87c1-418f-ae4d-1a9e8fc6bf8b, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,935 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cad30bbf-950a-4c9f-a07d-cf6fd9892fa1, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,935 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, cad30bbf-950a-4c9f-a07d-cf6fd9892fa1, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,940 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cad30bbf-950a-4c9f-a07d-cf6fd9892fa1, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:14,944 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 91b4a446-da16-48aa-b7c4-5cb7e3fc01a8, ghrun-e45ihybpyu.auto.internal:15180): on response callback started
2025-10-31 13:47:14,945 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 91b4a446-da16-48aa-b7c4-5cb7e3fc01a8, ghrun-e45ihybpyu.auto.internal:15180): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:47:14,945 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 91b4a446-da16-48aa-b7c4-5cb7e3fc01a8, ghrun-e45ihybpyu.auto.internal:15180): on response callback success
2025-10-31 13:47:31,811 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15180/1
2025-10-31 13:47:31,820 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17573
2025-10-31 13:47:31,821 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64115
2025-10-31 13:47:31,824 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11206
2025-10-31 13:47:31,831 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15180
2025-10-31 13:47:31,831 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3556
