2025-11-11 14:33:40,384 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:33:40,384 - INFO - ya.test - pytest_runtest_setup: test_insert_rollback
2025-11-11 14:33:40,384 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:33:40,384 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 14:33:40,385 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_insert.py::TestInsertStatement, test_name: test_insert_rollback)
2025-11-11 14:33:40,385 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=
2025-11-11 14:33:40,386 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29d7ff8d-8abc-4a40-8e84-b3ea3d2793eb, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,386 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29d7ff8d-8abc-4a40-8e84-b3ea3d2793eb, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            CREATE TABLE `/Root/insert/table44806` (\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-11 14:33:40,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29d7ff8d-8abc-4a40-8e84-b3ea3d2793eb, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=
2025-11-11 14:33:40,772 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=
2025-11-11 14:33:40,773 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 6682a22e-8273-4c5f-9ff5-04bb96cc8ca7, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,773 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 6682a22e-8273-4c5f-9ff5-04bb96cc8ca7, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_settings { serializable_read_write { } } }
2025-11-11 14:33:40,780 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 6682a22e-8273-4c5f-9ff5-04bb96cc8ca7, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS tx_meta { id: "01k9sncd56fqsemefqkr9hy646" } }
2025-11-11 14:33:40,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74bb7d0f-e96a-4a6c-a5ec-063f7b04cbab, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74bb7d0f-e96a-4a6c-a5ec-063f7b04cbab, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sncd56fqsemefqkr9hy646" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table44806` (id, vn, vs) VALUES (0, 0, \'0\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74bb7d0f-e96a-4a6c-a5ec-063f7b04cbab, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,800 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, f2a66cd0-3b81-4194-9e8c-5531edbf9aa9, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,801 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, f2a66cd0-3b81-4194-9e8c-5531edbf9aa9, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_id: "01k9sncd56fqsemefqkr9hy646" }
2025-11-11 14:33:40,803 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, f2a66cd0-3b81-4194-9e8c-5531edbf9aa9, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS }
2025-11-11 14:33:40,803 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, ec2ec64f-789e-48f3-88ad-5d48e9d646bd, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,804 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, ec2ec64f-789e-48f3-88ad-5d48e9d646bd, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_settings { serializable_read_write { } } }
2025-11-11 14:33:40,806 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, ec2ec64f-789e-48f3-88ad-5d48e9d646bd, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS tx_meta { id: "01k9sncd654x7qhy4ktqsg9vgq" } }
2025-11-11 14:33:40,806 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a693926-3c62-4ff6-97cf-cd04a1bfaefb, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a693926-3c62-4ff6-97cf-cd04a1bfaefb, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sncd654x7qhy4ktqsg9vgq" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table44806` (id, vn, vs) VALUES (1, 1, \'1\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,807 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a693926-3c62-4ff6-97cf-cd04a1bfaefb, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,828 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 9e43fd00-361e-4dbb-87db-4dafcba57651, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,830 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 9e43fd00-361e-4dbb-87db-4dafcba57651, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_id: "01k9sncd654x7qhy4ktqsg9vgq" }
2025-11-11 14:33:40,832 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 9e43fd00-361e-4dbb-87db-4dafcba57651, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS }
2025-11-11 14:33:40,833 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 7ca19b18-ae52-4bc2-a9c5-8934ed6d7dba, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,833 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 7ca19b18-ae52-4bc2-a9c5-8934ed6d7dba, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_settings { serializable_read_write { } } }
2025-11-11 14:33:40,835 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 7ca19b18-ae52-4bc2-a9c5-8934ed6d7dba, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS tx_meta { id: "01k9sncd72ap2kgknxca0x1k5y" } }
2025-11-11 14:33:40,835 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1f5ff2a0-0181-4069-ac0b-8089d59f1f31, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,836 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1f5ff2a0-0181-4069-ac0b-8089d59f1f31, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sncd72ap2kgknxca0x1k5y" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table44806` (id, vn, vs) VALUES (2, 2, \'2\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1f5ff2a0-0181-4069-ac0b-8089d59f1f31, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,856 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 1a971975-f156-4f71-aab9-46383be37177, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,857 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 1a971975-f156-4f71-aab9-46383be37177, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_id: "01k9sncd72ap2kgknxca0x1k5y" }
2025-11-11 14:33:40,859 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 1a971975-f156-4f71-aab9-46383be37177, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS }
2025-11-11 14:33:40,860 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 32f26cc5-d202-4f7b-b63c-9d9aefc5e6e3, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,860 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 32f26cc5-d202-4f7b-b63c-9d9aefc5e6e3, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_settings { serializable_read_write { } } }
2025-11-11 14:33:40,862 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 32f26cc5-d202-4f7b-b63c-9d9aefc5e6e3, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS tx_meta { id: "01k9sncd7x7ycwgzdpzzqgkhe7" } }
2025-11-11 14:33:40,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 520a9830-c185-47b5-a148-36609abee266, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 520a9830-c185-47b5-a148-36609abee266, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sncd7x7ycwgzdpzzqgkhe7" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table44806` (id, vn, vs) VALUES (3, 3, \'3\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,865 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 520a9830-c185-47b5-a148-36609abee266, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,884 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 3eca6e56-41fe-43c5-8db5-3f4c0ce146f2, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,885 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 3eca6e56-41fe-43c5-8db5-3f4c0ce146f2, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_id: "01k9sncd7x7ycwgzdpzzqgkhe7" }
2025-11-11 14:33:40,887 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 3eca6e56-41fe-43c5-8db5-3f4c0ce146f2, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS }
2025-11-11 14:33:40,887 - DEBUG - ydb.connection - _prepare_call: RpcState(BeginTransaction, 4c98e97d-b0d7-4922-9e6e-51ed58f9471f, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,888 - DEBUG - ydb.connection - _log_request: RpcState(BeginTransaction, 4c98e97d-b0d7-4922-9e6e-51ed58f9471f, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_settings { serializable_read_write { } } }
2025-11-11 14:33:40,890 - DEBUG - ydb.connection - _log_response: RpcState(BeginTransaction, 4c98e97d-b0d7-4922-9e6e-51ed58f9471f, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS tx_meta { id: "01k9sncd8sbja9n93eqwn0ntvc" } }
2025-11-11 14:33:40,890 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 026dcfd9-a1cd-4dc2-9634-bbdffd28205f, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 026dcfd9-a1cd-4dc2-9634-bbdffd28205f, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" exec_mode: EXEC_MODE_EXECUTE tx_control { tx_id: "01k9sncd8sbja9n93eqwn0ntvc" } query_content { syntax: SYNTAX_YQL_V1 text: "INSERT INTO `/Root/insert/table44806` (id, vn, vs) VALUES (4, 4, \'4\');" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,891 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 026dcfd9-a1cd-4dc2-9634-bbdffd28205f, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,913 - DEBUG - ydb.connection - _prepare_call: RpcState(RollbackTransaction, 40976c84-fd2b-4d04-b919-aa323590cf89, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,913 - DEBUG - ydb.connection - _log_request: RpcState(RollbackTransaction, 40976c84-fd2b-4d04-b919-aa323590cf89, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=" tx_id: "01k9sncd8sbja9n93eqwn0ntvc" }
2025-11-11 14:33:40,915 - DEBUG - ydb.connection - _log_response: RpcState(RollbackTransaction, 40976c84-fd2b-4d04-b919-aa323590cf89, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS }
2025-11-11 14:33:40,916 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 14:33:40,916 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f759c4df-53f8-473d-a35b-a8adc15846d1, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,917 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f759c4df-53f8-473d-a35b-a8adc15846d1, ghrun-3ynj4chmly.auto.internal:20039): request = {  }
2025-11-11 14:33:40,919 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f759c4df-53f8-473d-a35b-a8adc15846d1, ghrun-3ynj4chmly.auto.internal:20039): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjNiMzkwNi01YTEyMTRlZi05YzcyNmM4LWIwZDdhY2M1" node_id: 1 }
2025-11-11 14:33:40,920 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c7882773-4689-437b-b05d-8c9bae0bf92d, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,920 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c7882773-4689-437b-b05d-8c9bae0bf92d, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=ZjNiMzkwNi01YTEyMTRlZi05YzcyNmM4LWIwZDdhY2M1" }
2025-11-11 14:33:40,921 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c7882773-4689-437b-b05d-8c9bae0bf92d, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:40,922 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjNiMzkwNi01YTEyMTRlZi05YzcyNmM4LWIwZDdhY2M1
2025-11-11 14:33:40,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b006b51-49f7-4ca3-ade4-b34dd6fa0d54, ghrun-3ynj4chmly.auto.internal:20039): creating call state
2025-11-11 14:33:40,927 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b006b51-49f7-4ca3-ade4-b34dd6fa0d54, ghrun-3ynj4chmly.auto.internal:20039): request = { session_id: "ydb://session/3?node_id=1&id=ZjNiMzkwNi01YTEyMTRlZi05YzcyNmM4LWIwZDdhY2M1" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT count(*) AS cnt FROM `/Root/insert/table44806`;" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:33:40,928 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b006b51-49f7-4ca3-ade4-b34dd6fa0d54, ghrun-3ynj4chmly.auto.internal:20039): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:33:41,015 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjNiMzkwNi01YTEyMTRlZi05YzcyNmM4LWIwZDdhY2M1
2025-11-11 14:33:41,016 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTJiYmVlZDYtYjMxZjI3ZjctYTBiMjczMDItNzJkNDdiNWY=
2025-11-11 14:33:41,017 - INFO - ya.test - pytest_runtest_teardown: Test teardown
