2025-12-08 23:27:59,261 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:59,268 - INFO - ya.test - pytest_runtest_setup: test_write_skew_g2_item
2025-12-08 23:27:59,306 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-08 23:27:59,306 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-08 23:27:59,331 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2025-12-08 23:27:59,333 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 852713aa-79a9-400e-92ed-5ab0776b806f, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,338 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 852713aa-79a9-400e-92ed-5ab0776b806f, ghrun-sfoiezpm3a.auto.internal:1948): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:59,341 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 852713aa-79a9-400e-92ed-5ab0776b806f, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" } } } }
2025-12-08 23:27:59,354 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 92a016ce-f0e4-49b5-b83b-b45e81f06059, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,355 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 92a016ce-f0e4-49b5-b83b-b45e81f06059, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" 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 } } }
2025-12-08 23:27:59,430 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 92a016ce-f0e4-49b5-b83b-b45e81f06059, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS } }
2025-12-08 23:27:59,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 04de1d8b-022b-416e-81b8-41292d41ee87, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,442 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 04de1d8b-022b-416e-81b8-41292d41ee87, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" 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 } } }
2025-12-08 23:27:59,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 04de1d8b-022b-416e-81b8-41292d41ee87, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-08 23:27:59,502 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7d48bee1-86c2-49da-bed2-31f57cee1c31, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7d48bee1-86c2-49da-bed2-31f57cee1c31, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" 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 } } }
2025-12-08 23:27:59,582 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7d48bee1-86c2-49da-bed2-31f57cee1c31, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p5js2ppx1r0e4093ypqa" } } } } }
2025-12-08 23:27:59,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 95259555-f200-4322-80ba-49919116c91e, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,593 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 95259555-f200-4322-80ba-49919116c91e, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" 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 } } }
2025-12-08 23:27:59,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 95259555-f200-4322-80ba-49919116c91e, ghrun-sfoiezpm3a.auto.internal:1948): 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: "01kc04p5ngc7pex640qyptaxrk" } } } } }
2025-12-08 23:27:59,672 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c57a6125-f24c-4a3a-80b0-e9466db5839b, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c57a6125-f24c-4a3a-80b0-e9466db5839b, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" tx_control { tx_id: "01kc04p5js2ppx1r0e4093ypqa" } 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 } } }
2025-12-08 23:27:59,722 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c57a6125-f24c-4a3a-80b0-e9466db5839b, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p5js2ppx1r0e4093ypqa" } } } } }
2025-12-08 23:27:59,726 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 43f632d2-9e4e-441f-b603-7d19f6aea6e2, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 43f632d2-9e4e-441f-b603-7d19f6aea6e2, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" tx_control { tx_id: "01kc04p5ngc7pex640qyptaxrk" } 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 } } }
2025-12-08 23:27:59,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 43f632d2-9e4e-441f-b603-7d19f6aea6e2, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc04p5ngc7pex640qyptaxrk" } } } } }
2025-12-08 23:27:59,794 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 2610751e-e1c4-4135-80ee-70b685437b48, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,798 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 2610751e-e1c4-4135-80ee-70b685437b48, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" tx_id: "01kc04p5js2ppx1r0e4093ypqa" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:59,815 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 2610751e-e1c4-4135-80ee-70b685437b48, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-08 23:27:59,820 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 57fb04e0-820d-433c-8e9f-63a221c910e4, ghrun-sfoiezpm3a.auto.internal:1948): creating call state
2025-12-08 23:27:59,820 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 57fb04e0-820d-433c-8e9f-63a221c910e4, ghrun-sfoiezpm3a.auto.internal:1948): request = { session_id: "ydb://session/3?node_id=1&id=NWZjOTgyOGQtZWY1NTkxNTItYzIxYjJmNmQtZmQxMGY1MTY=" tx_id: "01kc04p5ngc7pex640qyptaxrk" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-08 23:27:59,835 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 57fb04e0-820d-433c-8e9f-63a221c910e4, ghrun-sfoiezpm3a.auto.internal:1948): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_write_skew_g2_item`" issue_code: 2001 severity: 1 } } }
2025-12-08 23:27:59,837 - INFO - ya.test - pytest_runtest_teardown: Test teardown
