2025-10-27 12:37:59,618 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:37:59,639 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-10-27 12:37:59,653 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:37:59,654 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-27 12:37:59,659 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-10-27 12:37:59,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=
2025-10-27 12:37:59,665 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 709e7e13-490f-42c4-9df0-bf2a4b0287e1, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:37:59,667 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 709e7e13-490f-42c4-9df0-bf2a4b0287e1, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table61179` (\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-10-27 12:37:59,678 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 709e7e13-490f-42c4-9df0-bf2a4b0287e1, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:00,418 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=
2025-10-27 12:38:00,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=
2025-10-27 12:38:00,443 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, e0027552-9661-4a4b-90dd-e40d31e1ae21, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,443 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, e0027552-9661-4a4b-90dd-e40d31e1ae21, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_settings { serializable_read_write { } } }
2025-10-27 12:38:00,446 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, e0027552-9661-4a4b-90dd-e40d31e1ae21, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS tx_meta { id: "01k8jtstfx7w6fwkyv9ztdnxw4" } }
2025-10-27 12:38:00,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 970bfc42-37bb-48cc-ae17-d7c324e24992, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 970bfc42-37bb-48cc-ae17-d7c324e24992, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k8jtstfx7w6fwkyv9ztdnxw4" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table61179` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:00,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 970bfc42-37bb-48cc-ae17-d7c324e24992, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:00,486 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 458b85e5-7ab6-4237-8782-3268acb4bc51, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,510 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 458b85e5-7ab6-4237-8782-3268acb4bc51, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_id: "01k8jtstfx7w6fwkyv9ztdnxw4" }
2025-10-27 12:38:00,513 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 458b85e5-7ab6-4237-8782-3268acb4bc51, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS }
2025-10-27 12:38:00,514 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, cb27e49e-a33a-4731-a17c-81c3771c28c6, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,532 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, cb27e49e-a33a-4731-a17c-81c3771c28c6, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_settings { serializable_read_write { } } }
2025-10-27 12:38:00,538 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, cb27e49e-a33a-4731-a17c-81c3771c28c6, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS tx_meta { id: "01k8jtstjpd5qqs1casxrb17xa" } }
2025-10-27 12:38:00,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be71198b-ddc8-4a38-9cc3-4c40dcef6b7e, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,541 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be71198b-ddc8-4a38-9cc3-4c40dcef6b7e, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k8jtstjpd5qqs1casxrb17xa" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table61179` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:00,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be71198b-ddc8-4a38-9cc3-4c40dcef6b7e, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:00,569 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 199b6ab8-c59d-4d0e-a233-9b8e52525b88, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,570 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 199b6ab8-c59d-4d0e-a233-9b8e52525b88, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_id: "01k8jtstjpd5qqs1casxrb17xa" }
2025-10-27 12:38:00,573 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 199b6ab8-c59d-4d0e-a233-9b8e52525b88, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS }
2025-10-27 12:38:00,574 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, ad575a00-98c3-4da3-9cf3-a78a48ab239a, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,574 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, ad575a00-98c3-4da3-9cf3-a78a48ab239a, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_settings { serializable_read_write { } } }
2025-10-27 12:38:00,578 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, ad575a00-98c3-4da3-9cf3-a78a48ab239a, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS tx_meta { id: "01k8jtstm01m4fb1cec7j5dk0b" } }
2025-10-27 12:38:00,579 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0fbc672a-a875-4658-9b25-fd7ee9a24760, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,579 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0fbc672a-a875-4658-9b25-fd7ee9a24760, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k8jtstm01m4fb1cec7j5dk0b" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table61179` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:00,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0fbc672a-a875-4658-9b25-fd7ee9a24760, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:00,608 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, b2df9473-a063-42ec-bca3-f6a1edbc10a9, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,633 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, b2df9473-a063-42ec-bca3-f6a1edbc10a9, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_id: "01k8jtstm01m4fb1cec7j5dk0b" }
2025-10-27 12:38:00,636 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, b2df9473-a063-42ec-bca3-f6a1edbc10a9, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS }
2025-10-27 12:38:00,674 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 1cb61ca7-c7d2-4634-94a5-250ff48c2522, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,675 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 1cb61ca7-c7d2-4634-94a5-250ff48c2522, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_settings { serializable_read_write { } } }
2025-10-27 12:38:00,711 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 1cb61ca7-c7d2-4634-94a5-250ff48c2522, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS tx_meta { id: "01k8jtstr54b2dzpxw2wzcvy2c" } }
2025-10-27 12:38:00,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f64da2f-bd90-430b-bb11-762015f03da6, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f64da2f-bd90-430b-bb11-762015f03da6, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k8jtstr54b2dzpxw2wzcvy2c" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table61179` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:00,815 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f64da2f-bd90-430b-bb11-762015f03da6, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:00,835 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 7699dd87-fd71-4b1d-b476-5a39fbbaf6a8, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,845 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 7699dd87-fd71-4b1d-b476-5a39fbbaf6a8, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_id: "01k8jtstr54b2dzpxw2wzcvy2c" }
2025-10-27 12:38:00,850 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 7699dd87-fd71-4b1d-b476-5a39fbbaf6a8, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS }
2025-10-27 12:38:00,877 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 46dce388-6d0d-4993-a1b6-ec36f8d1d926, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,878 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 46dce388-6d0d-4993-a1b6-ec36f8d1d926, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_settings { serializable_read_write { } } }
2025-10-27 12:38:00,880 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 46dce388-6d0d-4993-a1b6-ec36f8d1d926, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS tx_meta { id: "01k8jtstxf2kbpmtr24xn4q78y" } }
2025-10-27 12:38:00,895 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 666f5f83-ea9c-407a-82de-83c7cbf4240b, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:00,919 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 666f5f83-ea9c-407a-82de-83c7cbf4240b, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k8jtstxf2kbpmtr24xn4q78y" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table61179` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:00,921 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 666f5f83-ea9c-407a-82de-83c7cbf4240b, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:01,063 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 344ba649-140c-4213-818f-9be5f6d8c54a, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:01,065 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 344ba649-140c-4213-818f-9be5f6d8c54a, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=" tx_id: "01k8jtstxf2kbpmtr24xn4q78y" }
2025-10-27 12:38:01,099 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 344ba649-140c-4213-818f-9be5f6d8c54a, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS }
2025-10-27 12:38:01,127 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-10-27 12:38:01,127 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6bcc3311-f45c-436c-8a21-100762a525e5, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:01,128 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6bcc3311-f45c-436c-8a21-100762a525e5, ghrun-5rmqn7spre.auto.internal:18995): request = {  }
2025-10-27 12:38:01,130 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6bcc3311-f45c-436c-8a21-100762a525e5, ghrun-5rmqn7spre.auto.internal:18995): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWNmZjdjYTAtOTQyNGJhMGEtODBjYjNiNzQtMjg2ZDIwNDI=" node_id: 1 }
2025-10-27 12:38:01,131 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 547cdf93-88e7-4ead-8623-5848a29189b5, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:01,131 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 547cdf93-88e7-4ead-8623-5848a29189b5, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmZjdjYTAtOTQyNGJhMGEtODBjYjNiNzQtMjg2ZDIwNDI=" }
2025-10-27 12:38:01,133 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 547cdf93-88e7-4ead-8623-5848a29189b5, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:01,167 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWNmZjdjYTAtOTQyNGJhMGEtODBjYjNiNzQtMjg2ZDIwNDI=
2025-10-27 12:38:01,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f8f22d2-e8b2-421a-9465-9b25f8251b27, ghrun-5rmqn7spre.auto.internal:18995): creating call state
2025-10-27 12:38:01,168 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f8f22d2-e8b2-421a-9465-9b25f8251b27, ghrun-5rmqn7spre.auto.internal:18995): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmZjdjYTAtOTQyNGJhMGEtODBjYjNiNzQtMjg2ZDIwNDI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table61179`;" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:01,169 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f8f22d2-e8b2-421a-9465-9b25f8251b27, ghrun-5rmqn7spre.auto.internal:18995): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:01,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmZjdjYTAtOTQyNGJhMGEtODBjYjNiNzQtMjg2ZDIwNDI=
2025-10-27 12:38:01,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmZjYTZiZTAtZWQxOGU2MTktMzRlYjE4NzktYjAyMDgzYmI=
2025-10-27 12:38:01,317 - INFO - ya.test - pytest_runtest_teardown: Test teardown
