2026-08-19 13:41:25,423 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:25,423 - INFO - ya.test.1603699  - pytest_runtest_setup: test_write_skew_g2_item
2026-08-19 13:41:25,423 - INFO - ya.test.1603699  - pytest_runtest_setup: ####################################################################################################
2026-08-19 13:41:25,423 - INFO - ya.test.1603699  - pytest_runtest_setup: Test setup
2026-08-19 13:41:25,424 - INFO - ya.test.1603699  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2026-08-19 13:41:25,424 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CreateSession, f2c10d3b-9044-4128-a294-34d508bc79c9, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,425 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CreateSession, f2c10d3b-9044-4128-a294-34d508bc79c9, ghrun-4q22hfe2g4.auto.internal:22374): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:25,426 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CreateSession, f2c10d3b-9044-4128-a294-34d508bc79c9, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" } } } }
2026-08-19 13:41:25,427 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteSchemeQuery, 1395e8fc-5113-479c-919f-68531a05629b, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,427 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteSchemeQuery, 1395e8fc-5113-479c-919f-68531a05629b, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" 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-08-19 13:41:25,442 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteSchemeQuery, 1395e8fc-5113-479c-919f-68531a05629b, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS } }
2026-08-19 13:41:25,443 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 2b394249-9c7c-4b44-a85a-155f41db6405, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,443 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 2b394249-9c7c-4b44-a85a-155f41db6405, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" 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-08-19 13:41:25,452 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 2b394249-9c7c-4b44-a85a-155f41db6405, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-08-19 13:41:25,453 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, f3685922-241e-4b73-b754-c111a60f4eb7, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,453 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, f3685922-241e-4b73-b754-c111a60f4eb7, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" 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-08-19 13:41:25,491 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, f3685922-241e-4b73-b754-c111a60f4eb7, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42pbg5dpswxysc1zb8m24" } } } } }
2026-08-19 13:41:25,492 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 3a0e5609-256e-4327-a87b-2a1bd3472688, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,493 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 3a0e5609-256e-4327-a87b-2a1bd3472688, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" 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-08-19 13:41:25,526 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 3a0e5609-256e-4327-a87b-2a1bd3472688, ghrun-4q22hfe2g4.auto.internal:22374): 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: "01m0d42pcj433hj2799h6s8xvd" } } } } }
2026-08-19 13:41:25,527 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, edf0e33f-c09c-41b7-bde0-3e0371a29172, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,527 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, edf0e33f-c09c-41b7-bde0-3e0371a29172, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" tx_control { tx_id: "01m0d42pbg5dpswxysc1zb8m24" } 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-08-19 13:41:25,544 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, edf0e33f-c09c-41b7-bde0-3e0371a29172, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42pbg5dpswxysc1zb8m24" } } } } }
2026-08-19 13:41:25,545 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(ExecuteDataQuery, 462f3fed-9c79-4703-bac5-7e270403cfdf, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,545 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(ExecuteDataQuery, 462f3fed-9c79-4703-bac5-7e270403cfdf, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" tx_control { tx_id: "01m0d42pcj433hj2799h6s8xvd" } 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-08-19 13:41:25,562 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(ExecuteDataQuery, 462f3fed-9c79-4703-bac5-7e270403cfdf, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01m0d42pcj433hj2799h6s8xvd" } } } } }
2026-08-19 13:41:25,562 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 494beba3-948f-4192-952d-92c5ae9c0ba9, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,563 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 494beba3-948f-4192-952d-92c5ae9c0ba9, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" tx_id: "01m0d42pbg5dpswxysc1zb8m24" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:25,567 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 494beba3-948f-4192-952d-92c5ae9c0ba9, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2026-08-19 13:41:25,568 - DEBUG - ydb.connection.1603699  - _prepare_call: RpcState(CommitTransaction, 8efc94d1-b918-403f-9b11-7ba66f216b91, ghrun-4q22hfe2g4.auto.internal:22374): creating call state
2026-08-19 13:41:25,568 - DEBUG - ydb.connection.1603699  - _log_request: RpcState(CommitTransaction, 8efc94d1-b918-403f-9b11-7ba66f216b91, ghrun-4q22hfe2g4.auto.internal:22374): request = { session_id: "ydb://session/3?node_id=1&id=ZmM2NTU0NS1lYjU1OTQwYi0yYmFlNWE4OC03MDkwNDAxZQ==" tx_id: "01m0d42pcj433hj2799h6s8xvd" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-08-19 13:41:25,571 - DEBUG - ydb.connection.1603699  - _log_response: RpcState(CommitTransaction, 8efc94d1-b918-403f-9b11-7ba66f216b91, ghrun-4q22hfe2g4.auto.internal:22374): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_write_skew_g2_item`." issue_code: 2001 severity: 1 } } }
2026-08-19 13:41:25,572 - INFO - ya.test.1603699  - pytest_runtest_teardown: Test teardown
