2025-12-12 10:05:47,011 - INFO - ya.test.2075013  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:47,012 - INFO - ya.test.2075013  - pytest_runtest_setup: test_session_pool_min_size_feature
2025-12-12 10:05:47,012 - INFO - ya.test.2075013  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:47,013 - INFO - ya.test.2075013  - pytest_runtest_setup: Test setup
2025-12-12 10:05:47,019 - INFO - ya.test.2075013  - pytest_runtest_call: Test call (class_name: test_session_pool.py::TestSessionPool, test_name: test_session_pool_min_size_feature)
2025-12-12 10:05:47,020 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc509356220>
2025-12-12 10:05:47,036 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc509356220>
2025-12-12 10:05:47,037 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 3986b045-0563-4b7a-bc8d-0ec7e541b69f, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,037 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 3986b045-0563-4b7a-bc8d-0ec7e541b69f, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,060 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 3986b045-0563-4b7a-bc8d-0ec7e541b69f, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,064 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 3986b045-0563-4b7a-bc8d-0ec7e541b69f, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=N2FhNzk3YjgtMmY3YTk5ODctN2UzZWIxNmQtNWZjNGYwMzA=" } } } }
2025-12-12 10:05:47,065 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 3986b045-0563-4b7a-bc8d-0ec7e541b69f, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,068 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc509356220>
2025-12-12 10:05:47,074 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc5093562c0>
2025-12-12 10:05:47,074 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc5093562c0>
2025-12-12 10:05:47,075 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 5480944d-62a2-4209-8318-9a34811dc479, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,076 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 5480944d-62a2-4209-8318-9a34811dc479, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,079 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 5480944d-62a2-4209-8318-9a34811dc479, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,088 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 5480944d-62a2-4209-8318-9a34811dc479, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZWE4NzBhZGMtNjJjMDY4NGMtYWY4ZTcyNmYtOTg0MzAwNWU=" } } } }
2025-12-12 10:05:47,088 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 5480944d-62a2-4209-8318-9a34811dc479, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,095 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5093562c0>
2025-12-12 10:05:47,095 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc509355050>
2025-12-12 10:05:47,096 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc509355050>
2025-12-12 10:05:47,097 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 7c25953c-a86f-40ba-bec5-6a995fcbbe6a, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,098 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 7c25953c-a86f-40ba-bec5-6a995fcbbe6a, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,105 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc509354740>
2025-12-12 10:05:47,107 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7c25953c-a86f-40ba-bec5-6a995fcbbe6a, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,109 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc509354740>
2025-12-12 10:05:47,110 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 7c25953c-a86f-40ba-bec5-6a995fcbbe6a, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YmYwMjZiYTUtNmUzZjU5MTAtNTY5Njc1NTgtY2UwZmFkYzE=" } } } }
2025-12-12 10:05:47,112 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 7973d445-1ec8-4c70-8fcc-32f55a8db19d, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,121 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 7973d445-1ec8-4c70-8fcc-32f55a8db19d, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,121 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc5088d4790>
2025-12-12 10:05:47,124 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc5088d4790>
2025-12-12 10:05:47,124 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 4d63d28a-c039-42e2-94c2-40de94443c86, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,148 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 4d63d28a-c039-42e2-94c2-40de94443c86, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,149 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc5088d4470>
2025-12-12 10:05:47,150 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc5088d4470>
2025-12-12 10:05:47,152 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 6a689df1-bc20-4897-aa27-9b9bae830c11, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,168 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 6a689df1-bc20-4897-aa27-9b9bae830c11, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,169 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc508a7a9f0>
2025-12-12 10:05:47,171 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc508a7a9f0>
2025-12-12 10:05:47,172 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 1710d29e-164f-4147-a977-33cb20e2c6e9, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,173 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 1710d29e-164f-4147-a977-33cb20e2c6e9, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,174 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc50abea0e0>
2025-12-12 10:05:47,178 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc509355050>
2025-12-12 10:05:47,180 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7c25953c-a86f-40ba-bec5-6a995fcbbe6a, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,182 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7973d445-1ec8-4c70-8fcc-32f55a8db19d, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,181 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc50abea0e0>
2025-12-12 10:05:47,189 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 7973d445-1ec8-4c70-8fcc-32f55a8db19d, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YzIyNTkzLWMyZWZkMDA2LWRkYTBjNmVkLWRjZWYyY2U1" } } } }
2025-12-12 10:05:47,200 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 7766aa15-62b6-48e4-8c75-1f6174eae840, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,202 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 7766aa15-62b6-48e4-8c75-1f6174eae840, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,207 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc50abea680>
2025-12-12 10:05:47,219 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc50abea680>
2025-12-12 10:05:47,220 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, ae89abe5-69c1-487b-ad62-7a4660635a1a, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,220 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, ae89abe5-69c1-487b-ad62-7a4660635a1a, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,223 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc50abe9d70>
2025-12-12 10:05:47,223 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc50abe9d70>
2025-12-12 10:05:47,223 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 281b9e7e-d287-4c12-8cc4-bd468a880534, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,265 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 281b9e7e-d287-4c12-8cc4-bd468a880534, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,270 - DEBUG - __tests__.test_session_pool.TestSessionPool.2075013  - wait_for_size: Current size is 10
2025-12-12 10:05:47,271 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc509354740>
2025-12-12 10:05:47,330 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7973d445-1ec8-4c70-8fcc-32f55a8db19d, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,331 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 4d63d28a-c039-42e2-94c2-40de94443c86, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,332 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 4d63d28a-c039-42e2-94c2-40de94443c86, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZGY5N2UxM2UtZTE5M2Y5OTgtMWY3ZDM3YmUtNDEyZmJmNzU=" } } } }
2025-12-12 10:05:47,331 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc509356220>
2025-12-12 10:05:47,358 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc509356220>, reason: not-initialized
2025-12-12 10:05:47,359 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc509356220> is no longer active. Current active count 9.
2025-12-12 10:05:47,359 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Current session pool size is less than 10, actual size 9
2025-12-12 10:05:47,359 - DEBUG - ydb.table.SessionPool.2075013  - _create: Created session <ydb.table.Session object at 0x7fc509379640>
2025-12-12 10:05:47,359 - DEBUG - ydb.table.SessionPool.2075013  - _prepare: Preparing session <ydb.table.Session object at 0x7fc509379640>
2025-12-12 10:05:47,360 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(CreateSession, 28e20b83-b352-4790-ba96-d19511136cde, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,360 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(CreateSession, 28e20b83-b352-4790-ba96-d19511136cde, ghrun-llwi4fnhxu.auto.internal:15935): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,361 - DEBUG - __tests__.test_session_pool.TestSessionPool.2075013  - wait_for_size: Current size is 10
2025-12-12 10:05:47,362 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5088d4790>
2025-12-12 10:05:47,371 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 4d63d28a-c039-42e2-94c2-40de94443c86, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,372 - DEBUG - ydb.table.SessionPool.2075013  - stop: Requested session pool stop.
2025-12-12 10:05:47,373 - DEBUG - ydb.table.SessionPool.2075013  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 10:05:47,373 - DEBUG - ydb.table.SessionPool.2075013  - _terminate_event: Terminated session pool.
2025-12-12 10:05:47,373 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 6a689df1-bc20-4897-aa27-9b9bae830c11, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,380 - DEBUG - ydb.table.SessionPool.2075013  - stop: Destroying sessions in active queue
2025-12-12 10:05:47,388 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc5093562c0>, reason: session-pool-terminated
2025-12-12 10:05:47,389 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc5093562c0> is no longer active. Current active count 9.
2025-12-12 10:05:47,389 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 9bce3727-580b-486b-9c39-f12905bd7613, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,389 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 9bce3727-580b-486b-9c39-f12905bd7613, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=ZWE4NzBhZGMtNjJjMDY4NGMtYWY4ZTcyNmYtOTg0MzAwNWU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,386 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 6a689df1-bc20-4897-aa27-9b9bae830c11, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZjczZDkxMTktYTk0M2Q3MzUtMjY0NDUxMC1iNGZjNjExYw==" } } } }
2025-12-12 10:05:47,390 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc5093562c0>
2025-12-12 10:05:47,398 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc509355050>, reason: session-pool-terminated
2025-12-12 10:05:47,400 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc509355050> is no longer active. Current active count 8.
2025-12-12 10:05:47,402 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 9d201726-6217-4091-a2d9-8e1c7f25133d, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,414 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 9d201726-6217-4091-a2d9-8e1c7f25133d, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=YmYwMjZiYTUtNmUzZjU5MTAtNTY5Njc1NTgtY2UwZmFkYzE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,415 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc509355050>
2025-12-12 10:05:47,415 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc509354740>, reason: session-pool-terminated
2025-12-12 10:05:47,417 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc509354740> is no longer active. Current active count 7.
2025-12-12 10:05:47,418 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, a4ad82b8-778a-473c-96d1-22a16cd28646, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,418 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, a4ad82b8-778a-473c-96d1-22a16cd28646, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=YzIyNTkzLWMyZWZkMDA2LWRkYTBjNmVkLWRjZWYyY2U1" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,425 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc509354740>
2025-12-12 10:05:47,426 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc5088d4790>, reason: session-pool-terminated
2025-12-12 10:05:47,426 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc5088d4790> is no longer active. Current active count 6.
2025-12-12 10:05:47,426 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, b590a0d2-253b-43b4-8038-743425ee5f8c, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,426 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, b590a0d2-253b-43b4-8038-743425ee5f8c, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=ZGY5N2UxM2UtZTE5M2Y5OTgtMWY3ZDM3YmUtNDEyZmJmNzU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,431 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc5088d4790>
2025-12-12 10:05:47,433 - DEBUG - ydb.table.SessionPool.2075013  - stop: Destroyed active sessions
2025-12-12 10:05:47,434 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc5088d4470>
2025-12-12 10:05:47,435 - INFO - ya.test.2075013  - pytest_runtest_teardown: Test teardown
2025-12-12 10:05:47,435 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc5088d4470>, reason: not-initialized
2025-12-12 10:05:47,446 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc5088d4470> is no longer active. Current active count 5.
2025-12-12 10:05:47,446 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 6f621308-84ac-422c-b0f7-e593bdc93a8b, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,450 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 6f621308-84ac-422c-b0f7-e593bdc93a8b, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=ZjczZDkxMTktYTk0M2Q3MzUtMjY0NDUxMC1iNGZjNjExYw==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,463 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc5088d4470>
2025-12-12 10:05:47,463 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 6a689df1-bc20-4897-aa27-9b9bae830c11, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,464 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 1710d29e-164f-4147-a977-33cb20e2c6e9, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,471 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 1710d29e-164f-4147-a977-33cb20e2c6e9, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NTZiNWM2MzctODQ0ODViNGUtOGUxNjUwY2YtMWIyZjY1M2M=" } } } }
2025-12-12 10:05:47,473 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc508a7a9f0>
2025-12-12 10:05:47,474 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc508a7a9f0>, reason: not-initialized
2025-12-12 10:05:47,474 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc508a7a9f0> is no longer active. Current active count 4.
2025-12-12 10:05:47,481 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 54c3225a-0328-414b-b133-d94a3417cf61, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,485 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 54c3225a-0328-414b-b133-d94a3417cf61, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=NTZiNWM2MzctODQ0ODViNGUtOGUxNjUwY2YtMWIyZjY1M2M=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,487 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc508a7a9f0>
2025-12-12 10:05:47,500 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 1710d29e-164f-4147-a977-33cb20e2c6e9, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,501 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7766aa15-62b6-48e4-8c75-1f6174eae840, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,502 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 7766aa15-62b6-48e4-8c75-1f6174eae840, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=M2E4NWE5MDQtZmU0M2NmNjktYTEwM2IxMzQtNWMzODlhYjY=" } } } }
2025-12-12 10:05:47,502 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc50abea0e0>
2025-12-12 10:05:47,506 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc50abea0e0>, reason: not-initialized
2025-12-12 10:05:47,506 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc50abea0e0> is no longer active. Current active count 3.
2025-12-12 10:05:47,506 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 4e3ec27a-d59f-4203-a93b-1d50991669c7, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,507 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 4e3ec27a-d59f-4203-a93b-1d50991669c7, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=M2E4NWE5MDQtZmU0M2NmNjktYTEwM2IxMzQtNWMzODlhYjY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,507 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc50abea0e0>
2025-12-12 10:05:47,512 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 7766aa15-62b6-48e4-8c75-1f6174eae840, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,514 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, ae89abe5-69c1-487b-ad62-7a4660635a1a, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,515 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, ae89abe5-69c1-487b-ad62-7a4660635a1a, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OWI0ZGE5YjYtZDE5NjIwNmItMWRiODI3NmYtNGNjNDI3Mjk=" } } } }
2025-12-12 10:05:47,515 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc50abea680>
2025-12-12 10:05:47,522 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc50abea680>, reason: not-initialized
2025-12-12 10:05:47,523 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc50abea680> is no longer active. Current active count 2.
2025-12-12 10:05:47,523 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 56c4230d-0a1a-4442-b8c2-449de5ba7ec3, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,536 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 56c4230d-0a1a-4442-b8c2-449de5ba7ec3, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=OWI0ZGE5YjYtZDE5NjIwNmItMWRiODI3NmYtNGNjNDI3Mjk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,537 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc50abea680>
2025-12-12 10:05:47,537 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, ae89abe5-69c1-487b-ad62-7a4660635a1a, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,538 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 281b9e7e-d287-4c12-8cc4-bd468a880534, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,539 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 281b9e7e-d287-4c12-8cc4-bd468a880534, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=OGM1ZTU4NGMtZWI4OWZjMS03YTJjMWRkZC0zM2NkOGU0NA==" } } } }
2025-12-12 10:05:47,539 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc50abe9d70>
2025-12-12 10:05:47,561 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc50abe9d70>, reason: not-initialized
2025-12-12 10:05:47,561 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc50abe9d70> is no longer active. Current active count 1.
2025-12-12 10:05:47,561 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 7b7b07b5-6eb3-4fa0-a882-d0a8e4576cfe, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,563 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 7b7b07b5-6eb3-4fa0-a882-d0a8e4576cfe, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=OGM1ZTU4NGMtZWI4OWZjMS03YTJjMWRkZC0zM2NkOGU0NA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,564 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc50abe9d70>
2025-12-12 10:05:47,565 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 281b9e7e-d287-4c12-8cc4-bd468a880534, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,588 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 28e20b83-b352-4790-ba96-d19511136cde, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,603 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(CreateSession, 28e20b83-b352-4790-ba96-d19511136cde, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YzU3NDk0NWYtMjQ5ZWUyYjEtYjNkMzM1YzUtODhjMGVkZjk=" } } } }
2025-12-12 10:05:47,603 - DEBUG - ydb.table.SessionPool.2075013  - put: Put on session <ydb.table.Session object at 0x7fc509379640>
2025-12-12 10:05:47,605 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fc509379640>, reason: not-initialized
2025-12-12 10:05:47,612 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Session <ydb.table.Session object at 0x7fc509379640> is no longer active. Current active count 0.
2025-12-12 10:05:47,627 - DEBUG - ydb.connection.2075013  - _prepare_call: RpcState(DeleteSession, 2745d812-fdf2-4c30-b066-2ede98639dbc, ghrun-llwi4fnhxu.auto.internal:15935): creating call state
2025-12-12 10:05:47,628 - DEBUG - ydb.connection.2075013  - _log_request: RpcState(DeleteSession, 2745d812-fdf2-4c30-b066-2ede98639dbc, ghrun-llwi4fnhxu.auto.internal:15935): request = { session_id: "ydb://session/3?node_id=1&id=YzU3NDk0NWYtMjQ5ZWUyYjEtYjNkMzM1YzUtODhjMGVkZjk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 10:05:47,629 - DEBUG - ydb.table.SessionPool.2075013  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fc509379640>
2025-12-12 10:05:47,630 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(CreateSession, 28e20b83-b352-4790-ba96-d19511136cde, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,631 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 9bce3727-580b-486b-9c39-f12905bd7613, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,637 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 9bce3727-580b-486b-9c39-f12905bd7613, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,637 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 9bce3727-580b-486b-9c39-f12905bd7613, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,638 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 9d201726-6217-4091-a2d9-8e1c7f25133d, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,638 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 9d201726-6217-4091-a2d9-8e1c7f25133d, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,638 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 9d201726-6217-4091-a2d9-8e1c7f25133d, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,639 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, a4ad82b8-778a-473c-96d1-22a16cd28646, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,642 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, a4ad82b8-778a-473c-96d1-22a16cd28646, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,644 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, a4ad82b8-778a-473c-96d1-22a16cd28646, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,645 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, b590a0d2-253b-43b4-8038-743425ee5f8c, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,653 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, b590a0d2-253b-43b4-8038-743425ee5f8c, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,653 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, b590a0d2-253b-43b4-8038-743425ee5f8c, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,657 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 6f621308-84ac-422c-b0f7-e593bdc93a8b, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,658 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 6f621308-84ac-422c-b0f7-e593bdc93a8b, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,670 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 6f621308-84ac-422c-b0f7-e593bdc93a8b, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,681 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 54c3225a-0328-414b-b133-d94a3417cf61, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,681 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 54c3225a-0328-414b-b133-d94a3417cf61, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,687 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 54c3225a-0328-414b-b133-d94a3417cf61, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,715 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 4e3ec27a-d59f-4203-a93b-1d50991669c7, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,716 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 4e3ec27a-d59f-4203-a93b-1d50991669c7, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,771 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 4e3ec27a-d59f-4203-a93b-1d50991669c7, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,772 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 56c4230d-0a1a-4442-b8c2-449de5ba7ec3, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,772 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 56c4230d-0a1a-4442-b8c2-449de5ba7ec3, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,781 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 56c4230d-0a1a-4442-b8c2-449de5ba7ec3, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,806 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 7b7b07b5-6eb3-4fa0-a882-d0a8e4576cfe, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,808 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 7b7b07b5-6eb3-4fa0-a882-d0a8e4576cfe, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,810 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 7b7b07b5-6eb3-4fa0-a882-d0a8e4576cfe, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:05:47,811 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 2745d812-fdf2-4c30-b066-2ede98639dbc, ghrun-llwi4fnhxu.auto.internal:15935): on response callback started
2025-12-12 10:05:47,811 - DEBUG - ydb.connection.2075013  - _log_response: RpcState(DeleteSession, 2745d812-fdf2-4c30-b066-2ede98639dbc, ghrun-llwi4fnhxu.auto.internal:15935): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:47,811 - DEBUG - ydb.connection.2075013  - _on_response_callback: RpcState(DeleteSession, 2745d812-fdf2-4c30-b066-2ede98639dbc, ghrun-llwi4fnhxu.auto.internal:15935): on response callback success
2025-12-12 10:06:04,443 - INFO - ydb.tests.library.harness.kikimr_runner.2075013  - stop: Stopped node localhost:15935/1
2025-12-12 10:06:04,445 - DEBUG - library.python.filelock.2075013  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29027
2025-12-12 10:06:04,445 - DEBUG - library.python.filelock.2075013  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12329
2025-12-12 10:06:04,499 - DEBUG - library.python.filelock.2075013  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26181
2025-12-12 10:06:04,505 - DEBUG - library.python.filelock.2075013  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15935
2025-12-12 10:06:04,541 - DEBUG - library.python.filelock.2075013  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31820
