2025-12-08 23:28:31,601 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:31,602 - INFO - ya.test - pytest_runtest_setup: test_session_pool_min_size_feature
2025-12-08 23:28:31,603 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:28:31,603 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:28:31,609 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_min_size_feature)
2025-12-08 23:28:31,614 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f0823219140>
2025-12-08 23:28:31,673 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f0823219140>
2025-12-08 23:28:31,679 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ba2c1eba-1076-4045-8d7c-b58a96821c92, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,683 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ba2c1eba-1076-4045-8d7c-b58a96821c92, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,688 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f0823218060>
2025-12-08 23:28:31,690 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ba2c1eba-1076-4045-8d7c-b58a96821c92, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,714 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f0823218060>
2025-12-08 23:28:31,716 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ba2c1eba-1076-4045-8d7c-b58a96821c92, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=N2FlZjRiZjktZDQ2ZDhjYzQtZmNiMTgwN2ItYzkxNDljODg=" } } } }
2025-12-08 23:28:31,720 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 64d63fb6-690c-46cf-a023-ed65488f1dac, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,722 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 64d63fb6-690c-46cf-a023-ed65488f1dac, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,754 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f08257998c0>
2025-12-08 23:28:31,759 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0823219140>
2025-12-08 23:28:31,760 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ba2c1eba-1076-4045-8d7c-b58a96821c92, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:31,760 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f08257998c0>
2025-12-08 23:28:31,761 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 64d63fb6-690c-46cf-a023-ed65488f1dac, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,765 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7659729f-5e23-47cf-9f8a-03b5b55ffd90, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,767 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7659729f-5e23-47cf-9f8a-03b5b55ffd90, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,768 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082579a9a0>
2025-12-08 23:28:31,770 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082579a9a0>
2025-12-08 23:28:31,771 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4f3f115e-ca6c-47a0-a58a-401950184aa1, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,772 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4f3f115e-ca6c-47a0-a58a-401950184aa1, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,765 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 64d63fb6-690c-46cf-a023-ed65488f1dac, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWQ5NjA2OWMtNTg1ZmZmZTAtMjQzMzQzOTQtOWQ1MGU0ZjY=" } } } }
2025-12-08 23:28:31,773 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082557a5e0>
2025-12-08 23:28:31,790 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0823218060>
2025-12-08 23:28:31,790 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 64d63fb6-690c-46cf-a023-ed65488f1dac, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:31,791 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082557a5e0>
2025-12-08 23:28:31,809 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7659729f-5e23-47cf-9f8a-03b5b55ffd90, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,809 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 20256ff5-f24a-4429-9f8d-4bf08e8b11b5, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,810 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7659729f-5e23-47cf-9f8a-03b5b55ffd90, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YzNjZDU5M2EtNTkxZTUwMTUtNjM2MTgxODQtNGY4MzQwMjE=" } } } }
2025-12-08 23:28:31,811 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 20256ff5-f24a-4429-9f8d-4bf08e8b11b5, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,813 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082577bc10>
2025-12-08 23:28:31,815 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f08257998c0>
2025-12-08 23:28:31,815 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7659729f-5e23-47cf-9f8a-03b5b55ffd90, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:31,815 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082577bc10>
2025-12-08 23:28:31,816 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4f3f115e-ca6c-47a0-a58a-401950184aa1, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,818 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d90ebe97-f387-496f-a1ea-968920eab0ca, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,820 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4f3f115e-ca6c-47a0-a58a-401950184aa1, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NmFmMWU2OTYtNTg5ZTc1M2QtYTljZmJhMDktZWQwNDJlMmI=" } } } }
2025-12-08 23:28:31,826 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d90ebe97-f387-496f-a1ea-968920eab0ca, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,828 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082577ae50>
2025-12-08 23:28:31,850 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082577ae50>
2025-12-08 23:28:31,855 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 939ad1fb-0081-4a5b-88b4-67bb9ab2e936, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,862 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 939ad1fb-0081-4a5b-88b4-67bb9ab2e936, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,876 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f082577a9f0>
2025-12-08 23:28:31,890 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082579a9a0>
2025-12-08 23:28:31,890 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4f3f115e-ca6c-47a0-a58a-401950184aa1, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:31,892 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f082577a9f0>
2025-12-08 23:28:31,899 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 20256ff5-f24a-4429-9f8d-4bf08e8b11b5, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,903 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 756bed8c-6a18-4a6c-93a9-1c10597bc4e2, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,917 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 20256ff5-f24a-4429-9f8d-4bf08e8b11b5, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NjgxZTZhYzEtZjllZjkzNWItOTFhNThiNDgtOGRhYWU1N2I=" } } } }
2025-12-08 23:28:31,920 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 756bed8c-6a18-4a6c-93a9-1c10597bc4e2, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,934 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f0825779fa0>
2025-12-08 23:28:31,936 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f0825779fa0>
2025-12-08 23:28:31,937 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 65083409-3092-4ac6-8722-10ed55c4b9e7, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,938 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 65083409-3092-4ac6-8722-10ed55c4b9e7, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,939 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f0825779640>
2025-12-08 23:28:31,950 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f0825779640>
2025-12-08 23:28:31,961 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f8d05906-6735-4a42-babc-a438cef18210, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:31,965 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f8d05906-6735-4a42-babc-a438cef18210, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:31,972 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-12-08 23:28:31,973 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082557a5e0>
2025-12-08 23:28:31,980 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 20256ff5-f24a-4429-9f8d-4bf08e8b11b5, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:31,986 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d90ebe97-f387-496f-a1ea-968920eab0ca, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:31,981 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0823219140>
2025-12-08 23:28:31,995 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0823219140>, reason: not-initialized
2025-12-08 23:28:31,994 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d90ebe97-f387-496f-a1ea-968920eab0ca, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NDhmMjQ3OTYtYzBmNDM1NGUtZmQ4MjYwYmEtNjE5NTMwOGM=" } } } }
2025-12-08 23:28:31,996 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f0823219140> is no longer active. Current active count 9.
2025-12-08 23:28:31,999 - DEBUG - ydb.table.SessionPool - _destroy: Current session pool size is less than 10, actual size 9
2025-12-08 23:28:31,999 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f08257788d0>
2025-12-08 23:28:32,000 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f08257788d0>
2025-12-08 23:28:32,001 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d4a30e2b-2377-4fe1-871d-dd9de0cba769, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,002 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d4a30e2b-2377-4fe1-871d-dd9de0cba769, ghrun-sfoiezpm3a.auto.internal:1785): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,008 - DEBUG - __tests__.test_session_pool.TestSessionPool - wait_for_size: Current size is 10
2025-12-08 23:28:32,009 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-12-08 23:28:32,022 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-08 23:28:32,022 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-12-08 23:28:32,022 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0823218060>, reason: session-pool-terminated
2025-12-08 23:28:32,030 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f0823218060> is no longer active. Current active count 9.
2025-12-08 23:28:32,033 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 851d6b88-341c-4b96-985d-98eb0b074bb4, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,033 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 851d6b88-341c-4b96-985d-98eb0b074bb4, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=OWQ5NjA2OWMtNTg1ZmZmZTAtMjQzMzQzOTQtOWQ1MGU0ZjY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,022 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-12-08 23:28:32,035 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0823218060>
2025-12-08 23:28:32,049 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f08257998c0>, reason: session-pool-terminated
2025-12-08 23:28:32,050 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f08257998c0> is no longer active. Current active count 8.
2025-12-08 23:28:32,050 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d64a715a-8c9c-4b41-8742-634182196064, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,051 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d64a715a-8c9c-4b41-8742-634182196064, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=YzNjZDU5M2EtNTkxZTUwMTUtNjM2MTgxODQtNGY4MzQwMjE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,054 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f08257998c0>
2025-12-08 23:28:32,065 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082579a9a0>, reason: session-pool-terminated
2025-12-08 23:28:32,065 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082579a9a0> is no longer active. Current active count 7.
2025-12-08 23:28:32,066 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d5157a7b-b67f-4bb1-9470-222c59a0323f, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,077 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d5157a7b-b67f-4bb1-9470-222c59a0323f, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=NmFmMWU2OTYtNTg5ZTc1M2QtYTljZmJhMDktZWQwNDJlMmI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,078 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082579a9a0>
2025-12-08 23:28:32,121 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082557a5e0>, reason: session-pool-terminated
2025-12-08 23:28:32,121 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082557a5e0> is no longer active. Current active count 6.
2025-12-08 23:28:32,122 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1235e39b-d2d3-40e8-89d9-40171dc0f9ef, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,122 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1235e39b-d2d3-40e8-89d9-40171dc0f9ef, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=NjgxZTZhYzEtZjllZjkzNWItOTFhNThiNDgtOGRhYWU1N2I=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,123 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082557a5e0>
2025-12-08 23:28:32,123 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-12-08 23:28:32,138 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082577bc10>
2025-12-08 23:28:32,138 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082577bc10>, reason: not-initialized
2025-12-08 23:28:32,139 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082577bc10> is no longer active. Current active count 5.
2025-12-08 23:28:32,139 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 81f9c3e9-ab16-4c83-87f9-fd79767fa5b7, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,142 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 81f9c3e9-ab16-4c83-87f9-fd79767fa5b7, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=NDhmMjQ3OTYtYzBmNDM1NGUtZmQ4MjYwYmEtNjE5NTMwOGM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,125 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-12-08 23:28:32,186 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082577bc10>
2025-12-08 23:28:32,194 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d90ebe97-f387-496f-a1ea-968920eab0ca, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,195 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 939ad1fb-0081-4a5b-88b4-67bb9ab2e936, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,199 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 939ad1fb-0081-4a5b-88b4-67bb9ab2e936, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=M2E2OGY3NjUtMTA2MWVjODctMzJjOWNlZTUtMTUxODk1ZDc=" } } } }
2025-12-08 23:28:32,207 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082577ae50>
2025-12-08 23:28:32,208 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082577ae50>, reason: not-initialized
2025-12-08 23:28:32,208 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082577ae50> is no longer active. Current active count 4.
2025-12-08 23:28:32,239 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3bf4e5d1-d24b-41bf-91d9-02e04b808340, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,266 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3bf4e5d1-d24b-41bf-91d9-02e04b808340, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=M2E2OGY3NjUtMTA2MWVjODctMzJjOWNlZTUtMTUxODk1ZDc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,267 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082577ae50>
2025-12-08 23:28:32,271 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 939ad1fb-0081-4a5b-88b4-67bb9ab2e936, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,272 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 756bed8c-6a18-4a6c-93a9-1c10597bc4e2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,275 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 756bed8c-6a18-4a6c-93a9-1c10597bc4e2, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjhhNTVkNzktNmVlYmYxNGEtNDQxNzZkNzItZmRkOWY5NTc=" } } } }
2025-12-08 23:28:32,275 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f082577a9f0>
2025-12-08 23:28:32,276 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f082577a9f0>, reason: not-initialized
2025-12-08 23:28:32,276 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f082577a9f0> is no longer active. Current active count 3.
2025-12-08 23:28:32,276 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fbb76c22-0bd3-43ec-9eef-73df6b6f5fb2, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,277 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fbb76c22-0bd3-43ec-9eef-73df6b6f5fb2, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=YjhhNTVkNzktNmVlYmYxNGEtNDQxNzZkNzItZmRkOWY5NTc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,300 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f082577a9f0>
2025-12-08 23:28:32,301 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 756bed8c-6a18-4a6c-93a9-1c10597bc4e2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,304 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 65083409-3092-4ac6-8722-10ed55c4b9e7, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,305 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 65083409-3092-4ac6-8722-10ed55c4b9e7, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTZjNjg3YmMtNzY0MGZhZjUtMWJhNjhmZjYtNjdjNWRiMjM=" } } } }
2025-12-08 23:28:32,322 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0825779fa0>
2025-12-08 23:28:32,323 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0825779fa0>, reason: not-initialized
2025-12-08 23:28:32,323 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f0825779fa0> is no longer active. Current active count 2.
2025-12-08 23:28:32,330 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, cc4b311f-7414-442c-bfef-62013dd7bf97, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,344 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, cc4b311f-7414-442c-bfef-62013dd7bf97, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=NTZjNjg3YmMtNzY0MGZhZjUtMWJhNjhmZjYtNjdjNWRiMjM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,346 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0825779fa0>
2025-12-08 23:28:32,347 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 65083409-3092-4ac6-8722-10ed55c4b9e7, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,347 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f8d05906-6735-4a42-babc-a438cef18210, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,352 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f8d05906-6735-4a42-babc-a438cef18210, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDc3MzVlNzUtZjBjOWQ5MDgtZTExOTNiNDctMmU2ZWNiNmI=" } } } }
2025-12-08 23:28:32,398 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f0825779640>
2025-12-08 23:28:32,398 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0825779640>, reason: not-initialized
2025-12-08 23:28:32,407 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f0825779640> is no longer active. Current active count 1.
2025-12-08 23:28:32,422 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8e20a451-d1f5-4cd8-b87b-b5062892f7e2, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,493 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8e20a451-d1f5-4cd8-b87b-b5062892f7e2, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=ZDc3MzVlNzUtZjBjOWQ5MDgtZTExOTNiNDctMmU2ZWNiNmI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,517 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0825779640>
2025-12-08 23:28:32,531 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f8d05906-6735-4a42-babc-a438cef18210, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,534 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d4a30e2b-2377-4fe1-871d-dd9de0cba769, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,540 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d4a30e2b-2377-4fe1-871d-dd9de0cba769, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NmFjZjc0OGYtYTRkODc1ZDMtZThlYjhjYjgtYmE2M2NjODA=" } } } }
2025-12-08 23:28:32,562 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f08257788d0>
2025-12-08 23:28:32,567 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f08257788d0>, reason: not-initialized
2025-12-08 23:28:32,610 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f08257788d0> is no longer active. Current active count 0.
2025-12-08 23:28:32,615 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 464edd5b-5ead-44e5-81e7-0c3ab9028496, ghrun-sfoiezpm3a.auto.internal:1785): creating call state
2025-12-08 23:28:32,620 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 464edd5b-5ead-44e5-81e7-0c3ab9028496, ghrun-sfoiezpm3a.auto.internal:1785): request = { session_id: "ydb://session/3?node_id=1&id=NmFjZjc0OGYtYTRkODc1ZDMtZThlYjhjYjgtYmE2M2NjODA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-08 23:28:32,622 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f08257788d0>
2025-12-08 23:28:32,626 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d4a30e2b-2377-4fe1-871d-dd9de0cba769, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,644 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 851d6b88-341c-4b96-985d-98eb0b074bb4, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,686 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 851d6b88-341c-4b96-985d-98eb0b074bb4, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,694 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 851d6b88-341c-4b96-985d-98eb0b074bb4, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,695 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d64a715a-8c9c-4b41-8742-634182196064, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,700 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d64a715a-8c9c-4b41-8742-634182196064, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,701 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d64a715a-8c9c-4b41-8742-634182196064, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,726 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d5157a7b-b67f-4bb1-9470-222c59a0323f, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,726 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d5157a7b-b67f-4bb1-9470-222c59a0323f, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,742 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d5157a7b-b67f-4bb1-9470-222c59a0323f, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,746 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1235e39b-d2d3-40e8-89d9-40171dc0f9ef, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,801 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1235e39b-d2d3-40e8-89d9-40171dc0f9ef, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,801 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1235e39b-d2d3-40e8-89d9-40171dc0f9ef, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,874 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 81f9c3e9-ab16-4c83-87f9-fd79767fa5b7, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,876 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 81f9c3e9-ab16-4c83-87f9-fd79767fa5b7, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,877 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 81f9c3e9-ab16-4c83-87f9-fd79767fa5b7, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,878 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3bf4e5d1-d24b-41bf-91d9-02e04b808340, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,879 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3bf4e5d1-d24b-41bf-91d9-02e04b808340, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,880 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3bf4e5d1-d24b-41bf-91d9-02e04b808340, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,881 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, fbb76c22-0bd3-43ec-9eef-73df6b6f5fb2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,888 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fbb76c22-0bd3-43ec-9eef-73df6b6f5fb2, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,889 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, fbb76c22-0bd3-43ec-9eef-73df6b6f5fb2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,895 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cc4b311f-7414-442c-bfef-62013dd7bf97, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,917 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, cc4b311f-7414-442c-bfef-62013dd7bf97, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:32,918 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cc4b311f-7414-442c-bfef-62013dd7bf97, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:32,922 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e20a451-d1f5-4cd8-b87b-b5062892f7e2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:32,962 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8e20a451-d1f5-4cd8-b87b-b5062892f7e2, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:33,048 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e20a451-d1f5-4cd8-b87b-b5062892f7e2, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:33,048 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 464edd5b-5ead-44e5-81e7-0c3ab9028496, ghrun-sfoiezpm3a.auto.internal:1785): on response callback started
2025-12-08 23:28:33,057 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 464edd5b-5ead-44e5-81e7-0c3ab9028496, ghrun-sfoiezpm3a.auto.internal:1785): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:28:33,060 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 464edd5b-5ead-44e5-81e7-0c3ab9028496, ghrun-sfoiezpm3a.auto.internal:1785): on response callback success
2025-12-08 23:28:49,202 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1785/1
2025-12-08 23:28:49,216 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15878
2025-12-08 23:28:49,317 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9310
2025-12-08 23:28:49,397 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27837
2025-12-08 23:28:49,397 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1785
2025-12-08 23:28:49,398 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17028
