2025-10-31 15:41:14,050 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:14,060 - INFO - ya.test - pytest_runtest_setup: test_write_skew_g2_item
2025-10-31 15:41:14,061 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 15:41:14,062 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 15:41:14,083 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2025-10-31 15:41:14,093 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 89217ec7-31cc-45b3-8604-9ffb27c38a5a, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,094 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 89217ec7-31cc-45b3-8604-9ffb27c38a5a, ghrun-xldpwfo7ey.auto.internal:6264): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:14,097 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 89217ec7-31cc-45b3-8604-9ffb27c38a5a, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" } } } }
2025-10-31 15:41:14,100 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 060c7580-c018-4aee-8db8-a92a346909b7, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,102 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 060c7580-c018-4aee-8db8-a92a346909b7, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" 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 15:41:14,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 060c7580-c018-4aee-8db8-a92a346909b7, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS } }
2025-10-31 15:41:14,162 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 02610839-4583-4f74-98a8-8a44155894b6, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 02610839-4583-4f74-98a8-8a44155894b6, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" 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 15:41:14,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 02610839-4583-4f74-98a8-8a44155894b6, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-31 15:41:14,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8d094c2d-bd97-4f83-a8c2-c9eee456c017, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8d094c2d-bd97-4f83-a8c2-c9eee456c017, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" 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 15:41:14,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8d094c2d-bd97-4f83-a8c2-c9eee456c017, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew6mf3xh2mngrxd6jh9b2" } } } } }
2025-10-31 15:41:14,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d0d5a0c4-c3e0-4e20-af78-798b2dc456bf, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,268 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d0d5a0c4-c3e0-4e20-af78-798b2dc456bf, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" 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 15:41:14,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d0d5a0c4-c3e0-4e20-af78-798b2dc456bf, ghrun-xldpwfo7ey.auto.internal:6264): 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: "01k8xew6pq0jba8z65fwqrw8v8" } } } } }
2025-10-31 15:41:14,347 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c185bca3-c409-40ca-9375-4057ad8e6885, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,348 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c185bca3-c409-40ca-9375-4057ad8e6885, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" tx_control { tx_id: "01k8xew6mf3xh2mngrxd6jh9b2" } 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 15:41:14,388 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c185bca3-c409-40ca-9375-4057ad8e6885, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew6mf3xh2mngrxd6jh9b2" } } } } }
2025-10-31 15:41:14,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 075004b4-2211-41de-8b0c-d09d88467ce5, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,389 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 075004b4-2211-41de-8b0c-d09d88467ce5, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" tx_control { tx_id: "01k8xew6pq0jba8z65fwqrw8v8" } 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 15:41:14,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 075004b4-2211-41de-8b0c-d09d88467ce5, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8xew6pq0jba8z65fwqrw8v8" } } } } }
2025-10-31 15:41:14,431 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 6130bdc3-7c4d-4130-a9ad-c7ef965f50d5, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,442 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 6130bdc3-7c4d-4130-a9ad-c7ef965f50d5, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" tx_id: "01k8xew6mf3xh2mngrxd6jh9b2" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:14,459 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 6130bdc3-7c4d-4130-a9ad-c7ef965f50d5, ghrun-xldpwfo7ey.auto.internal:6264): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 15:41:14,474 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, a4d82092-4d1c-41ca-b90f-655c46d78d41, ghrun-xldpwfo7ey.auto.internal:6264): creating call state
2025-10-31 15:41:14,474 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, a4d82092-4d1c-41ca-b90f-655c46d78d41, ghrun-xldpwfo7ey.auto.internal:6264): request = { session_id: "ydb://session/3?node_id=1&id=ZDkzNGJhMTktYmI2Y2JlN2EtMmU1MDc5MjctYmE5MGU4YmE=" tx_id: "01k8xew6pq0jba8z65fwqrw8v8" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 15:41:14,484 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, a4d82092-4d1c-41ca-b90f-655c46d78d41, ghrun-xldpwfo7ey.auto.internal:6264): 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 15:41:14,498 - INFO - ya.test - pytest_runtest_teardown: Test teardown
