2026-03-25 17:14:54,696 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,697 - INFO - ya.test.2499227  - pytest_runtest_setup: test_write_skew_g2_item
2026-03-25 17:14:54,697 - INFO - ya.test.2499227  - pytest_runtest_setup: ####################################################################################################
2026-03-25 17:14:54,697 - INFO - ya.test.2499227  - pytest_runtest_setup: Test setup
2026-03-25 17:14:54,698 - INFO - ya.test.2499227  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2026-03-25 17:14:54,698 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CreateSession, ae8a475e-da04-4dad-8b3c-6d72ce721401, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,698 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CreateSession, ae8a475e-da04-4dad-8b3c-6d72ce721401, ghrun-ve2ie6ihly.auto.internal:65362): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,700 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CreateSession, ae8a475e-da04-4dad-8b3c-6d72ce721401, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" } } } }
2026-03-25 17:14:54,700 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteSchemeQuery, 3ad47e3f-357d-467a-85bf-4ee5f291d6f0, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,701 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteSchemeQuery, 3ad47e3f-357d-467a-85bf-4ee5f291d6f0, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" yql_text: "create table test_write_skew_g2_item (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,719 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteSchemeQuery, 3ad47e3f-357d-467a-85bf-4ee5f291d6f0, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS } }
2026-03-25 17:14:54,720 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 0d3c4f32-50b9-4ade-8088-b0f6c68e6a4d, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,721 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 0d3c4f32-50b9-4ade-8088-b0f6c68e6a4d, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_write_skew_g2_item (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,733 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 0d3c4f32-50b9-4ade-8088-b0f6c68e6a4d, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-03-25 17:14:54,733 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, b541d94e-7b08-447f-8fe5-99ee4d1e671a, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,734 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, b541d94e-7b08-447f-8fe5-99ee4d1e671a, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select value from test_write_skew_g2_item where id in (1,2);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,769 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, b541d94e-7b08-447f-8fe5-99ee4d1e671a, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS issues { message: "Type annotation" issue_code: 1030 severity: 2 issues { position { row: 1 column: 2 } message: "At function: RemovePrefixMembers, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 2 } severity: 2 issues { position { row: 1 column: 20 } message: "At function: Filter, At lambda, At function: Coalesce" end_position { row: 1 column: 20 } severity: 2 issues { position { row: 1 column: 50 } message: "At function: SqlIn" end_position { row: 1 column: 50 } severity: 2 issues { position { row: 1 column: 50 } message: "IN may produce unexpected result when used with nullable arguments. Consider adding \'PRAGMA AnsiInForEmptyOrNullableItemsCollections;\'" end_position { row: 1 column: 50 } issue_code: 1108 severity: 2 } } } } } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 10 } } rows { items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01kmjzvydda1mcf23mqk0297bv" } } } } }
2026-03-25 17:14:54,770 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, c054b750-370a-4fa4-a1aa-bf84eacb8a0e, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,770 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, c054b750-370a-4fa4-a1aa-bf84eacb8a0e, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select value from test_write_skew_g2_item where id in (1,2);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,802 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, c054b750-370a-4fa4-a1aa-bf84eacb8a0e, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS issues { message: "Type annotation" issue_code: 1030 severity: 2 issues { position { row: 1 column: 2 } message: "At function: RemovePrefixMembers, At function: PersistableRepr, At function: SqlProject" end_position { row: 1 column: 2 } severity: 2 issues { position { row: 1 column: 20 } message: "At function: Filter, At lambda, At function: Coalesce" end_position { row: 1 column: 20 } severity: 2 issues { position { row: 1 column: 50 } message: "At function: SqlIn" end_position { row: 1 column: 50 } severity: 2 issues { position { row: 1 column: 50 } message: "IN may produce unexpected result when used with nullable arguments. Consider adding \'PRAGMA AnsiInForEmptyOrNullableItemsCollections;\'" end_position { row: 1 column: 50 } issue_code: 1108 severity: 2 } } } } } result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 10 } } rows { items { int32_value: 20 } } format: FORMAT_VALUE } tx_meta { id: "01kmjzvyeech5q9bd1wwjjq1nc" } } } } }
2026-03-25 17:14:54,803 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 8359c35b-e747-4529-b1c9-89a256293bda, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,804 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 8359c35b-e747-4529-b1c9-89a256293bda, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_control { tx_id: "01kmjzvydda1mcf23mqk0297bv" } query { yql_text: " update test_write_skew_g2_item set value = 11 where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,822 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 8359c35b-e747-4529-b1c9-89a256293bda, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvydda1mcf23mqk0297bv" } } } } }
2026-03-25 17:14:54,823 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(ExecuteDataQuery, 29690a8f-281a-4545-a3a7-77e7303f5ec3, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,823 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(ExecuteDataQuery, 29690a8f-281a-4545-a3a7-77e7303f5ec3, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_control { tx_id: "01kmjzvyeech5q9bd1wwjjq1nc" } query { yql_text: " update test_write_skew_g2_item set value = 21 where id = 2;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,841 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(ExecuteDataQuery, 29690a8f-281a-4545-a3a7-77e7303f5ec3, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kmjzvyeech5q9bd1wwjjq1nc" } } } } }
2026-03-25 17:14:54,842 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 4e15cf2c-22f9-4752-b3d0-d038e740e384, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,842 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 4e15cf2c-22f9-4752-b3d0-d038e740e384, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_id: "01kmjzvydda1mcf23mqk0297bv" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,846 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 4e15cf2c-22f9-4752-b3d0-d038e740e384, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-03-25 17:14:54,846 - DEBUG - ydb.connection.2499227  - _prepare_call: RpcState(CommitTransaction, 568e7d67-3660-4305-87ab-b9bf01bee847, ghrun-ve2ie6ihly.auto.internal:65362): creating call state
2026-03-25 17:14:54,847 - DEBUG - ydb.connection.2499227  - _log_request: RpcState(CommitTransaction, 568e7d67-3660-4305-87ab-b9bf01bee847, ghrun-ve2ie6ihly.auto.internal:65362): request = { session_id: "ydb://session/3?node_id=1&id=ZDU1NGU5ZmUtYWYwNzc4YTctMjlmOWNlMTUtNDVmMjlmMWQ%3D" tx_id: "01kmjzvyeech5q9bd1wwjjq1nc" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-03-25 17:14:54,850 - DEBUG - ydb.connection.2499227  - _log_response: RpcState(CommitTransaction, 568e7d67-3660-4305-87ab-b9bf01bee847, ghrun-ve2ie6ihly.auto.internal:65362): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_write_skew_g2_item`." issue_code: 2001 severity: 1 } } }
2026-03-25 17:14:54,851 - INFO - ya.test.2499227  - pytest_runtest_teardown: Test teardown
