2025-12-12 10:05:21,371 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:21,372 - INFO - ya.test.2060683  - pytest_runtest_setup: test_write_skew_g2_item
2025-12-12 10:05:21,372 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:21,372 - INFO - ya.test.2060683  - pytest_runtest_setup: Test setup
2025-12-12 10:05:21,383 - INFO - ya.test.2060683  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2025-12-12 10:05:21,385 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CreateSession, 8e9b203b-8ca3-4372-90eb-aa5410463a3e, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,397 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CreateSession, 8e9b203b-8ca3-4372-90eb-aa5410463a3e, ghrun-llwi4fnhxu.auto.internal:27863): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:21,404 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CreateSession, 8e9b203b-8ca3-4372-90eb-aa5410463a3e, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" } } } }
2025-12-12 10:05:21,413 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteSchemeQuery, 55f5495f-d59d-45b0-b897-3e2b61b4750a, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,414 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteSchemeQuery, 55f5495f-d59d-45b0-b897-3e2b61b4750a, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" 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-12 10:05:21,457 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteSchemeQuery, 55f5495f-d59d-45b0-b897-3e2b61b4750a, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:21,470 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, c8aba9da-fd58-4deb-880e-1d6ab456f515, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,473 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, c8aba9da-fd58-4deb-880e-1d6ab456f515, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" 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-12 10:05:21,496 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, c8aba9da-fd58-4deb-880e-1d6ab456f515, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 10:05:21,497 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, fe7d219c-a0da-42a1-aa66-5dbfe61b1581, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,498 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, fe7d219c-a0da-42a1-aa66-5dbfe61b1581, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" 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-12 10:05:21,574 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, fe7d219c-a0da-42a1-aa66-5dbfe61b1581, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90bc8t9bbpm6xzfkrqsxnk" } } } } }
2025-12-12 10:05:21,576 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 95469ab9-5ac1-4dc0-b792-dafb304d9d8a, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,576 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 95469ab9-5ac1-4dc0-b792-dafb304d9d8a, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" 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-12 10:05:21,695 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 95469ab9-5ac1-4dc0-b792-dafb304d9d8a, ghrun-llwi4fnhxu.auto.internal:27863): 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: "01kc90bccqc2v5tes20txnnn1c" } } } } }
2025-12-12 10:05:21,699 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 4e5c9eae-55f4-408c-8826-413278248843, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,699 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 4e5c9eae-55f4-408c-8826-413278248843, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" tx_control { tx_id: "01kc90bc8t9bbpm6xzfkrqsxnk" } 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-12 10:05:21,745 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 4e5c9eae-55f4-408c-8826-413278248843, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90bc8t9bbpm6xzfkrqsxnk" } } } } }
2025-12-12 10:05:21,746 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 0661dc47-87aa-4b3d-9ee0-6f3506f8c89f, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,746 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 0661dc47-87aa-4b3d-9ee0-6f3506f8c89f, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" tx_control { tx_id: "01kc90bccqc2v5tes20txnnn1c" } 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-12 10:05:21,777 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 0661dc47-87aa-4b3d-9ee0-6f3506f8c89f, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90bccqc2v5tes20txnnn1c" } } } } }
2025-12-12 10:05:21,781 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, f16880d6-273b-4af3-a157-d4c0ab680291, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,782 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, f16880d6-273b-4af3-a157-d4c0ab680291, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" tx_id: "01kc90bc8t9bbpm6xzfkrqsxnk" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:21,807 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, f16880d6-273b-4af3-a157-d4c0ab680291, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:21,815 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, 38a7c053-fb3d-4fc2-b964-46b48986ad8e, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:21,861 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, 38a7c053-fb3d-4fc2-b964-46b48986ad8e, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NzRlYWE3NjQtMzZkNzMwN2EtMjJhYWZhM2YtZTYwYmFiY2M=" tx_id: "01kc90bccqc2v5tes20txnnn1c" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:21,875 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, 38a7c053-fb3d-4fc2-b964-46b48986ad8e, ghrun-llwi4fnhxu.auto.internal:27863): 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-12 10:05:21,877 - INFO - ya.test.2060683  - pytest_runtest_teardown: Test teardown
