2025-11-10 10:00:34,415 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:00:34,415 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-10 10:00:34,415 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:00:34,416 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-10 10:00:34,417 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-10 10:00:34,417 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=
2025-11-10 10:00:34,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1eb479b9-d9e0-4a06-b784-ea1b28c0e55c, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,418 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1eb479b9-d9e0-4a06-b784-ea1b28c0e55c, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table21717` (\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-10 10:00:34,419 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1eb479b9-d9e0-4a06-b784-ea1b28c0e55c, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=
2025-11-10 10:00:34,736 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=
2025-11-10 10:00:34,736 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, ad8b6504-79fd-438f-bd6a-8353da687d70, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,737 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, ad8b6504-79fd-438f-bd6a-8353da687d70, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_settings { serializable_read_write { } } }
2025-11-10 10:00:34,739 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, ad8b6504-79fd-438f-bd6a-8353da687d70, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS tx_meta { id: "01k9pkbm5j4f5yzaej500hrmay" } }
2025-11-10 10:00:34,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8ca83b9-68ce-440a-87b4-06e491a02c1e, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,740 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8ca83b9-68ce-440a-87b4-06e491a02c1e, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9pkbm5j4f5yzaej500hrmay" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table21717` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8ca83b9-68ce-440a-87b4-06e491a02c1e, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,763 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, adb08cf5-34e0-403c-9df4-0537468f977d, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,764 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, adb08cf5-34e0-403c-9df4-0537468f977d, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_id: "01k9pkbm5j4f5yzaej500hrmay" }
2025-11-10 10:00:34,767 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, adb08cf5-34e0-403c-9df4-0537468f977d, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS }
2025-11-10 10:00:34,767 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 1185d790-d718-40e3-993a-19a1d38cd272, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,768 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 1185d790-d718-40e3-993a-19a1d38cd272, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_settings { serializable_read_write { } } }
2025-11-10 10:00:34,770 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 1185d790-d718-40e3-993a-19a1d38cd272, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS tx_meta { id: "01k9pkbm6hbggyftdvt1a9cj3q" } }
2025-11-10 10:00:34,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c54e0b1f-ed8b-4407-acf1-5f63d0f16d0a, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,771 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c54e0b1f-ed8b-4407-acf1-5f63d0f16d0a, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9pkbm6hbggyftdvt1a9cj3q" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table21717` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c54e0b1f-ed8b-4407-acf1-5f63d0f16d0a, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,794 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 26732bd8-88e1-45d2-bbad-06e19375ac45, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,795 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 26732bd8-88e1-45d2-bbad-06e19375ac45, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_id: "01k9pkbm6hbggyftdvt1a9cj3q" }
2025-11-10 10:00:34,797 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 26732bd8-88e1-45d2-bbad-06e19375ac45, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS }
2025-11-10 10:00:34,798 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 102d612e-1af4-4d2f-8bdd-6099d0fb6a98, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,799 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 102d612e-1af4-4d2f-8bdd-6099d0fb6a98, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_settings { serializable_read_write { } } }
2025-11-10 10:00:34,801 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 102d612e-1af4-4d2f-8bdd-6099d0fb6a98, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS tx_meta { id: "01k9pkbm7g8tbyqfc6h1cxm50m" } }
2025-11-10 10:00:34,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7ac594a-257f-447f-b9a3-2f9b75a124c8, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,803 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7ac594a-257f-447f-b9a3-2f9b75a124c8, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9pkbm7g8tbyqfc6h1cxm50m" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table21717` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,803 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7ac594a-257f-447f-b9a3-2f9b75a124c8, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,825 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 1ac15815-5c96-4fa9-bfce-23d7b977e3c3, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,826 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 1ac15815-5c96-4fa9-bfce-23d7b977e3c3, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_id: "01k9pkbm7g8tbyqfc6h1cxm50m" }
2025-11-10 10:00:34,828 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 1ac15815-5c96-4fa9-bfce-23d7b977e3c3, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS }
2025-11-10 10:00:34,829 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 4b816516-f37e-45e7-a334-d967350ec135, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,829 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 4b816516-f37e-45e7-a334-d967350ec135, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_settings { serializable_read_write { } } }
2025-11-10 10:00:34,831 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 4b816516-f37e-45e7-a334-d967350ec135, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS tx_meta { id: "01k9pkbm8effeh0ry9195ggvt7" } }
2025-11-10 10:00:34,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 924d9eb2-31bc-438b-a466-98c660447882, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,833 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 924d9eb2-31bc-438b-a466-98c660447882, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9pkbm8effeh0ry9195ggvt7" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table21717` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 924d9eb2-31bc-438b-a466-98c660447882, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,857 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 593a640b-8906-46e0-98a7-1fb01b62e592, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,857 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 593a640b-8906-46e0-98a7-1fb01b62e592, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_id: "01k9pkbm8effeh0ry9195ggvt7" }
2025-11-10 10:00:34,859 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 593a640b-8906-46e0-98a7-1fb01b62e592, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS }
2025-11-10 10:00:34,860 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, faa547ab-3e88-4a6b-94cd-d6dba7cb1a71, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,860 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, faa547ab-3e88-4a6b-94cd-d6dba7cb1a71, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_settings { serializable_read_write { } } }
2025-11-10 10:00:34,862 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, faa547ab-3e88-4a6b-94cd-d6dba7cb1a71, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS tx_meta { id: "01k9pkbm9ddbqwydyn2wvn0h2q" } }
2025-11-10 10:00:34,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78840914-afcb-471b-842d-cd22473e055f, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,863 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78840914-afcb-471b-842d-cd22473e055f, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9pkbm9ddbqwydyn2wvn0h2q" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table21717` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,864 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78840914-afcb-471b-842d-cd22473e055f, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,884 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, ede80c64-39e4-4d8f-886e-c234c76ae9d4, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,884 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, ede80c64-39e4-4d8f-886e-c234c76ae9d4, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=" tx_id: "01k9pkbm9ddbqwydyn2wvn0h2q" }
2025-11-10 10:00:34,886 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, ede80c64-39e4-4d8f-886e-c234c76ae9d4, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS }
2025-11-10 10:00:34,887 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-10 10:00:34,887 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 17c02e49-09c2-4ddb-b82b-073f3ad995a0, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,887 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 17c02e49-09c2-4ddb-b82b-073f3ad995a0, ghrun-kipqlopw2q.auto.internal:62476): request = {  }
2025-11-10 10:00:34,890 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 17c02e49-09c2-4ddb-b82b-073f3ad995a0, ghrun-kipqlopw2q.auto.internal:62476): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NDE2MjA1MTQtOGVmYzRkYWUtMjdkNTRiNTgtODgyMGUzNzg=" node_id: 1 }
2025-11-10 10:00:34,890 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 439267b7-b206-495f-99ab-776c11d07973, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,890 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 439267b7-b206-495f-99ab-776c11d07973, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=NDE2MjA1MTQtOGVmYzRkYWUtMjdkNTRiNTgtODgyMGUzNzg=" }
2025-11-10 10:00:34,891 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 439267b7-b206-495f-99ab-776c11d07973, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,893 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NDE2MjA1MTQtOGVmYzRkYWUtMjdkNTRiNTgtODgyMGUzNzg=
2025-11-10 10:00:34,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74a3d12d-792f-4574-92e2-05b142f345f0, ghrun-kipqlopw2q.auto.internal:62476): creating call state
2025-11-10 10:00:34,894 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74a3d12d-792f-4574-92e2-05b142f345f0, ghrun-kipqlopw2q.auto.internal:62476): request = { session_id: "ydb://session/3?node_id=1&id=NDE2MjA1MTQtOGVmYzRkYWUtMjdkNTRiNTgtODgyMGUzNzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table21717`;" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:00:34,895 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74a3d12d-792f-4574-92e2-05b142f345f0, ghrun-kipqlopw2q.auto.internal:62476): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:00:34,980 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDE2MjA1MTQtOGVmYzRkYWUtMjdkNTRiNTgtODgyMGUzNzg=
2025-11-10 10:00:34,980 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjM1MzQ0YjYtODJmM2M0YzMtOGUzZmY5YzUtNTdmMzQ2ZTk=
2025-11-10 10:00:34,981 - INFO - ya.test - pytest_runtest_teardown: Test teardown
