2025-11-01 20:00:21,977 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:00:21,983 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-01 20:00:21,984 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:00:21,984 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 20:00:21,986 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-01 20:00:21,987 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=
2025-11-01 20:00:21,987 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd5ec40b-3779-4e40-9bac-4ff3eb35c24b, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:21,988 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd5ec40b-3779-4e40-9bac-4ff3eb35c24b, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table80577` (\n                id Uint64 NOT NULL,\n                vn Uint64,\n                vs Utf8,\n                PRIMARY KEY(id),\n            )\n            WITH (\n                STORE = COLUMN\n            )\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:21,989 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd5ec40b-3779-4e40-9bac-4ff3eb35c24b, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,344 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=
2025-11-01 20:00:22,345 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=
2025-11-01 20:00:22,345 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 360a16f3-baa0-47bc-b7e3-dd9c610c98c3, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,346 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 360a16f3-baa0-47bc-b7e3-dd9c610c98c3, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_settings { serializable_read_write { } } }
2025-11-01 20:00:22,348 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 360a16f3-baa0-47bc-b7e3-dd9c610c98c3, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS tx_meta { id: "01k90g3dacf3s9h043xv8q7yzs" } }
2025-11-01 20:00:22,349 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14051b40-2599-4b43-81ba-4cf228d7bb57, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,350 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14051b40-2599-4b43-81ba-4cf228d7bb57, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k90g3dacf3s9h043xv8q7yzs" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table80577` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,351 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14051b40-2599-4b43-81ba-4cf228d7bb57, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,370 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 3aebd704-1877-4c61-accc-de54e733fc3f, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,371 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 3aebd704-1877-4c61-accc-de54e733fc3f, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_id: "01k90g3dacf3s9h043xv8q7yzs" }
2025-11-01 20:00:22,373 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 3aebd704-1877-4c61-accc-de54e733fc3f, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS }
2025-11-01 20:00:22,373 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, fd049597-3fcb-4f29-b4ef-042e4188b461, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,374 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, fd049597-3fcb-4f29-b4ef-042e4188b461, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_settings { serializable_read_write { } } }
2025-11-01 20:00:22,375 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, fd049597-3fcb-4f29-b4ef-042e4188b461, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS tx_meta { id: "01k90g3db77ww1btaq7c0hbjfc" } }
2025-11-01 20:00:22,376 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 139934bb-65a7-4e55-a588-800778636333, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,376 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 139934bb-65a7-4e55-a588-800778636333, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k90g3db77ww1btaq7c0hbjfc" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table80577` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 139934bb-65a7-4e55-a588-800778636333, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,395 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 70d97693-d2a1-4193-8407-c9e6b5ec308c, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,395 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 70d97693-d2a1-4193-8407-c9e6b5ec308c, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_id: "01k90g3db77ww1btaq7c0hbjfc" }
2025-11-01 20:00:22,397 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 70d97693-d2a1-4193-8407-c9e6b5ec308c, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS }
2025-11-01 20:00:22,398 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 4fef3feb-612b-490d-b628-c0a882cad51c, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,398 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 4fef3feb-612b-490d-b628-c0a882cad51c, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_settings { serializable_read_write { } } }
2025-11-01 20:00:22,400 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 4fef3feb-612b-490d-b628-c0a882cad51c, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS tx_meta { id: "01k90g3dbz9n9m92y8mdebxnfz" } }
2025-11-01 20:00:22,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf8cf1e3-434c-4c07-94c1-b20a07970bf3, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,401 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf8cf1e3-434c-4c07-94c1-b20a07970bf3, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k90g3dbz9n9m92y8mdebxnfz" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table80577` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,402 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf8cf1e3-434c-4c07-94c1-b20a07970bf3, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,420 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 6e2548cc-6935-4997-a032-8aae39bab540, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,421 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 6e2548cc-6935-4997-a032-8aae39bab540, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_id: "01k90g3dbz9n9m92y8mdebxnfz" }
2025-11-01 20:00:22,423 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 6e2548cc-6935-4997-a032-8aae39bab540, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS }
2025-11-01 20:00:22,423 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 29e7a305-5b35-462e-8a7e-c1fedb74de3b, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,425 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 29e7a305-5b35-462e-8a7e-c1fedb74de3b, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_settings { serializable_read_write { } } }
2025-11-01 20:00:22,428 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 29e7a305-5b35-462e-8a7e-c1fedb74de3b, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS tx_meta { id: "01k90g3dcvdccstdknzbbpck5s" } }
2025-11-01 20:00:22,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7fe22d3-5ee2-4658-8700-15bdf90a89b3, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7fe22d3-5ee2-4658-8700-15bdf90a89b3, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k90g3dcvdccstdknzbbpck5s" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table80577` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7fe22d3-5ee2-4658-8700-15bdf90a89b3, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,450 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 1b11ccc3-5266-4db6-bce7-439ab513f82f, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,450 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 1b11ccc3-5266-4db6-bce7-439ab513f82f, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_id: "01k90g3dcvdccstdknzbbpck5s" }
2025-11-01 20:00:22,452 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 1b11ccc3-5266-4db6-bce7-439ab513f82f, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS }
2025-11-01 20:00:22,454 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 2a74db02-6b0f-499d-a5c7-be984968d0c0, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,455 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 2a74db02-6b0f-499d-a5c7-be984968d0c0, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_settings { serializable_read_write { } } }
2025-11-01 20:00:22,457 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 2a74db02-6b0f-499d-a5c7-be984968d0c0, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS tx_meta { id: "01k90g3ddscwz70z3bk2sy8bnz" } }
2025-11-01 20:00:22,458 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c396bc4f-7b4a-4940-948e-6b36cb4f2789, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,460 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c396bc4f-7b4a-4940-948e-6b36cb4f2789, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k90g3ddscwz70z3bk2sy8bnz" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table80577` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,460 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c396bc4f-7b4a-4940-948e-6b36cb4f2789, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,478 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 14716c87-9f96-4986-b51e-868b3b9defc0, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,478 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 14716c87-9f96-4986-b51e-868b3b9defc0, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=" tx_id: "01k90g3ddscwz70z3bk2sy8bnz" }
2025-11-01 20:00:22,480 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 14716c87-9f96-4986-b51e-868b3b9defc0, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS }
2025-11-01 20:00:22,480 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-01 20:00:22,481 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a16cf2ec-8bc5-4bd8-9e56-05b02f788f30, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,481 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a16cf2ec-8bc5-4bd8-9e56-05b02f788f30, ghrun-mjpjznvske.auto.internal:7640): request = {  }
2025-11-01 20:00:22,483 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a16cf2ec-8bc5-4bd8-9e56-05b02f788f30, ghrun-mjpjznvske.auto.internal:7640): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YWI1NGRiZjAtZTJkMTFkZGItNmEzMmU3ZDctYTRjZDg1NTE=" node_id: 1 }
2025-11-01 20:00:22,484 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, cdc39ae9-f795-4c77-a258-ecae1831cfc2, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,484 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, cdc39ae9-f795-4c77-a258-ecae1831cfc2, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=YWI1NGRiZjAtZTJkMTFkZGItNmEzMmU3ZDctYTRjZDg1NTE=" }
2025-11-01 20:00:22,485 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, cdc39ae9-f795-4c77-a258-ecae1831cfc2, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,489 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YWI1NGRiZjAtZTJkMTFkZGItNmEzMmU3ZDctYTRjZDg1NTE=
2025-11-01 20:00:22,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5db7d16c-3a5f-46af-8572-0d8568976ceb, ghrun-mjpjznvske.auto.internal:7640): creating call state
2025-11-01 20:00:22,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5db7d16c-3a5f-46af-8572-0d8568976ceb, ghrun-mjpjznvske.auto.internal:7640): request = { session_id: "ydb://session/3?node_id=1&id=YWI1NGRiZjAtZTJkMTFkZGItNmEzMmU3ZDctYTRjZDg1NTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table80577`;" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:00:22,491 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5db7d16c-3a5f-46af-8572-0d8568976ceb, ghrun-mjpjznvske.auto.internal:7640): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:00:22,578 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWI1NGRiZjAtZTJkMTFkZGItNmEzMmU3ZDctYTRjZDg1NTE=
2025-11-01 20:00:22,581 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJlZWUyYzctMTkyNjNlYmUtMTdhNDA1MTktYmExOTc5ZDg=
2025-11-01 20:00:22,584 - INFO - ya.test - pytest_runtest_teardown: Test teardown
