2025-10-31 13:48:07,181 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:07,181 - INFO - ya.test - pytest_runtest_setup: test_write_skew_g2_item
2025-10-31 13:48:07,187 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:07,195 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:07,210 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2025-10-31 13:48:07,216 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3c8566f5-e650-4dcf-beba-63ecd7a648fc, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,216 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3c8566f5-e650-4dcf-beba-63ecd7a648fc, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:07,221 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3c8566f5-e650-4dcf-beba-63ecd7a648fc, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" } } } }
2025-10-31 13:48:07,223 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, ce22759f-d919-43d0-8f5d-34d30fd1dc5b, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,223 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, ce22759f-d919-43d0-8f5d-34d30fd1dc5b, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" 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-10-31 13:48:07,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, ce22759f-d919-43d0-8f5d-34d30fd1dc5b, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:07,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 1e6090d8-e375-443d-9f06-6394888127d9, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 1e6090d8-e375-443d-9f06-6394888127d9, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" 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-10-31 13:48:07,333 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 1e6090d8-e375-443d-9f06-6394888127d9, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 13:48:07,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6aeabccb-9167-4b4d-8c8a-21871d9e53ff, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,338 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6aeabccb-9167-4b4d-8c8a-21871d9e53ff, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" 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-10-31 13:48:07,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6aeabccb-9167-4b4d-8c8a-21871d9e53ff, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8d2tm0b4y5fxytn281jz6" } } } } }
2025-10-31 13:48:07,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cfc19992-9c0d-4773-b4f4-221be6dfce42, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,389 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cfc19992-9c0d-4773-b4f4-221be6dfce42, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" 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-10-31 13:48:07,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cfc19992-9c0d-4773-b4f4-221be6dfce42, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8d2w2cnd0w3df1ebe49ma" } } } } }
2025-10-31 13:48:07,434 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 37886a7c-dd5a-447a-9737-e0261dca6ea9, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,437 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 37886a7c-dd5a-447a-9737-e0261dca6ea9, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" tx_control { tx_id: "01k8x8d2tm0b4y5fxytn281jz6" } 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-10-31 13:48:07,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 37886a7c-dd5a-447a-9737-e0261dca6ea9, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d2tm0b4y5fxytn281jz6" } } } } }
2025-10-31 13:48:07,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b75deca5-c819-4cc9-989e-4be656f9c14f, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,489 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b75deca5-c819-4cc9-989e-4be656f9c14f, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" tx_control { tx_id: "01k8x8d2w2cnd0w3df1ebe49ma" } 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-10-31 13:48:07,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b75deca5-c819-4cc9-989e-4be656f9c14f, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8d2w2cnd0w3df1ebe49ma" } } } } }
2025-10-31 13:48:07,540 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 1373de24-2b39-4673-bcce-8bd45957d602, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,544 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 1373de24-2b39-4673-bcce-8bd45957d602, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" tx_id: "01k8x8d2tm0b4y5fxytn281jz6" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:07,553 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 1373de24-2b39-4673-bcce-8bd45957d602, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:07,554 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, be275d7c-d6e6-4e6c-bec7-3bfed9f60ff4, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:07,558 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, be275d7c-d6e6-4e6c-bec7-3bfed9f60ff4, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=YjM0YWQ1MDMtZGJmZjkwYzctOThjOGVkNGItZjNkYmJiZTQ=" tx_id: "01k8x8d2w2cnd0w3df1ebe49ma" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:07,568 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, be275d7c-d6e6-4e6c-bec7-3bfed9f60ff4, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_write_skew_g2_item`" issue_code: 2001 severity: 1 } } }
2025-10-31 13:48:07,572 - INFO - ya.test - pytest_runtest_teardown: Test teardown
