2025-11-01 16:45:18,589 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:45:18,597 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-01 16:45:18,607 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:45:18,607 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:45:18,609 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-01 16:45:18,610 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=
2025-11-01 16:45:18,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a183a5f-befa-41c1-a183-d5ddc1c41572, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:18,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a183a5f-befa-41c1-a183-d5ddc1c41572, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table76680` (\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 16:45:18,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a183a5f-befa-41c1-a183-d5ddc1c41572, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:18,999 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=
2025-11-01 16:45:19,004 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=
2025-11-01 16:45:19,005 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, a58cbab6-f15c-4486-a4e0-292744b92eeb, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,005 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, a58cbab6-f15c-4486-a4e0-292744b92eeb, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_settings { serializable_read_write { } } }
2025-11-01 16:45:19,007 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, a58cbab6-f15c-4486-a4e0-292744b92eeb, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS tx_meta { id: "01k904y88z1ajxeg2xbw5nkz7n" } }
2025-11-01 16:45:19,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c774403e-b49a-4f8d-ad98-accd780bebcd, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,016 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c774403e-b49a-4f8d-ad98-accd780bebcd, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k904y88z1ajxeg2xbw5nkz7n" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table76680` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,017 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c774403e-b49a-4f8d-ad98-accd780bebcd, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,046 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, ef703092-9793-4825-ad57-ddfe97db23b5, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,052 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, ef703092-9793-4825-ad57-ddfe97db23b5, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_id: "01k904y88z1ajxeg2xbw5nkz7n" }
2025-11-01 16:45:19,054 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, ef703092-9793-4825-ad57-ddfe97db23b5, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS }
2025-11-01 16:45:19,054 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 269ebcd7-d20b-40ed-a552-6516a82b9d41, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,055 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 269ebcd7-d20b-40ed-a552-6516a82b9d41, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_settings { serializable_read_write { } } }
2025-11-01 16:45:19,057 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 269ebcd7-d20b-40ed-a552-6516a82b9d41, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS tx_meta { id: "01k904y8ag9j73cr85sa2515z7" } }
2025-11-01 16:45:19,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 129d3bda-7614-4106-b952-0fc9d0fe97f8, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,060 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 129d3bda-7614-4106-b952-0fc9d0fe97f8, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k904y8ag9j73cr85sa2515z7" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table76680` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,060 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 129d3bda-7614-4106-b952-0fc9d0fe97f8, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,080 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, f9334e12-3a62-4560-b2a1-69b62a736577, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,081 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, f9334e12-3a62-4560-b2a1-69b62a736577, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_id: "01k904y8ag9j73cr85sa2515z7" }
2025-11-01 16:45:19,082 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, f9334e12-3a62-4560-b2a1-69b62a736577, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS }
2025-11-01 16:45:19,091 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, a3dfdf46-6886-4e53-9b13-41c53a5c1b26, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,091 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, a3dfdf46-6886-4e53-9b13-41c53a5c1b26, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_settings { serializable_read_write { } } }
2025-11-01 16:45:19,095 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, a3dfdf46-6886-4e53-9b13-41c53a5c1b26, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS tx_meta { id: "01k904y8bpdvh78ecevd9dyp2a" } }
2025-11-01 16:45:19,096 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a65dda0e-5693-4347-9ed3-0fb4852b951c, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,097 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a65dda0e-5693-4347-9ed3-0fb4852b951c, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k904y8bpdvh78ecevd9dyp2a" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table76680` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,098 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a65dda0e-5693-4347-9ed3-0fb4852b951c, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,120 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 9ae48179-465e-4e84-832e-7f6d729686c6, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,121 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 9ae48179-465e-4e84-832e-7f6d729686c6, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_id: "01k904y8bpdvh78ecevd9dyp2a" }
2025-11-01 16:45:19,123 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 9ae48179-465e-4e84-832e-7f6d729686c6, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS }
2025-11-01 16:45:19,123 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, f8108f26-6678-4214-b9bc-6d10da457bab, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,126 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, f8108f26-6678-4214-b9bc-6d10da457bab, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_settings { serializable_read_write { } } }
2025-11-01 16:45:19,129 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, f8108f26-6678-4214-b9bc-6d10da457bab, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS tx_meta { id: "01k904y8crebfft3mbwm3da174" } }
2025-11-01 16:45:19,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9caa48b8-94e9-4194-9780-3e19b99ebfa5, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9caa48b8-94e9-4194-9780-3e19b99ebfa5, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k904y8crebfft3mbwm3da174" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table76680` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,143 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9caa48b8-94e9-4194-9780-3e19b99ebfa5, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,169 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 0ae27e68-9e74-4365-8f04-67af85dae8a3, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,175 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 0ae27e68-9e74-4365-8f04-67af85dae8a3, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_id: "01k904y8crebfft3mbwm3da174" }
2025-11-01 16:45:19,178 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 0ae27e68-9e74-4365-8f04-67af85dae8a3, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS }
2025-11-01 16:45:19,178 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 672acb13-1ae7-48f9-a5c5-49303ccc8b62, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,179 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 672acb13-1ae7-48f9-a5c5-49303ccc8b62, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_settings { serializable_read_write { } } }
2025-11-01 16:45:19,182 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 672acb13-1ae7-48f9-a5c5-49303ccc8b62, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS tx_meta { id: "01k904y8edf4wfynd8s7ghr88g" } }
2025-11-01 16:45:19,183 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c83e04a6-f514-4f3c-821d-51dceb7cab6a, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,183 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c83e04a6-f514-4f3c-821d-51dceb7cab6a, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k904y8edf4wfynd8s7ghr88g" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table76680` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c83e04a6-f514-4f3c-821d-51dceb7cab6a, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,204 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 5167ea4c-fcd8-4dff-ada2-afa866744f73, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,205 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 5167ea4c-fcd8-4dff-ada2-afa866744f73, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=" tx_id: "01k904y8edf4wfynd8s7ghr88g" }
2025-11-01 16:45:19,207 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 5167ea4c-fcd8-4dff-ada2-afa866744f73, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS }
2025-11-01 16:45:19,207 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-01 16:45:19,211 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 487e3493-0dc6-4e34-9755-272ee1d5f2cd, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,212 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 487e3493-0dc6-4e34-9755-272ee1d5f2cd, ghrun-tvlw7frnpa.auto.internal:63107): request = {  }
2025-11-01 16:45:19,214 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 487e3493-0dc6-4e34-9755-272ee1d5f2cd, ghrun-tvlw7frnpa.auto.internal:63107): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MjgwNTc5YzQtM2ExN2I5ZWQtNjAwOTg5OTktMzdhY2RiMTM=" node_id: 1 }
2025-11-01 16:45:19,215 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2fe2061c-54b0-4c4c-899e-1b40aa044e75, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,215 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2fe2061c-54b0-4c4c-899e-1b40aa044e75, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=MjgwNTc5YzQtM2ExN2I5ZWQtNjAwOTg5OTktMzdhY2RiMTM=" }
2025-11-01 16:45:19,217 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2fe2061c-54b0-4c4c-899e-1b40aa044e75, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,218 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MjgwNTc5YzQtM2ExN2I5ZWQtNjAwOTg5OTktMzdhY2RiMTM=
2025-11-01 16:45:19,220 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f489574e-54c9-4f3c-a485-64e4c87b5281, ghrun-tvlw7frnpa.auto.internal:63107): creating call state
2025-11-01 16:45:19,221 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f489574e-54c9-4f3c-a485-64e4c87b5281, ghrun-tvlw7frnpa.auto.internal:63107): request = { session_id: "ydb://session/3?node_id=1&id=MjgwNTc5YzQtM2ExN2I5ZWQtNjAwOTg5OTktMzdhY2RiMTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table76680`;" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:45:19,222 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f489574e-54c9-4f3c-a485-64e4c87b5281, ghrun-tvlw7frnpa.auto.internal:63107): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:45:19,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwNTc5YzQtM2ExN2I5ZWQtNjAwOTg5OTktMzdhY2RiMTM=
2025-11-01 16:45:19,311 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmI4Yzk5MWItZDMzNTc1ZGUtNjk2NDAxMGEtYTRiOGYyNDk=
2025-11-01 16:45:19,312 - INFO - ya.test - pytest_runtest_teardown: Test teardown
