2025-11-26 18:41:41,764 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:41,765 - INFO - ya.test.2332018  - pytest_runtest_setup: test_write_skew_g2_item
2025-11-26 18:41:41,765 - INFO - ya.test.2332018  - pytest_runtest_setup: ####################################################################################################
2025-11-26 18:41:41,765 - INFO - ya.test.2332018  - pytest_runtest_setup: Test setup
2025-11-26 18:41:41,767 - INFO - ya.test.2332018  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_write_skew_g2_item)
2025-11-26 18:41:41,768 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CreateSession, 37392613-048e-4f0d-b571-49405da83b03, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,768 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CreateSession, 37392613-048e-4f0d-b571-49405da83b03, ghrun-ihttfokzrq.auto.internal:8570): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:41,771 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CreateSession, 37392613-048e-4f0d-b571-49405da83b03, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" } } } }
2025-11-26 18:41:41,772 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteSchemeQuery, 005eb83c-c44f-417b-b822-d818fada24db, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,773 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteSchemeQuery, 005eb83c-c44f-417b-b822-d818fada24db, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" 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-11-26 18:41:41,797 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteSchemeQuery, 005eb83c-c44f-417b-b822-d818fada24db, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS } }
2025-11-26 18:41:41,798 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 3f270223-3d18-4023-a7c3-93aa71841b7f, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,799 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 3f270223-3d18-4023-a7c3-93aa71841b7f, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" 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-11-26 18:41:41,814 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 3f270223-3d18-4023-a7c3-93aa71841b7f, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-26 18:41:41,815 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 0bd79444-82ea-4476-89cd-5c12ab9a0bba, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,816 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 0bd79444-82ea-4476-89cd-5c12ab9a0bba, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" 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-11-26 18:41:41,883 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 0bd79444-82ea-4476-89cd-5c12ab9a0bba, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qhafqezbmr23zn31g5395" } } } } }
2025-11-26 18:41:41,884 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, bf4e86e1-497d-4ddd-9312-8fcee2d2da4e, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,884 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, bf4e86e1-497d-4ddd-9312-8fcee2d2da4e, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" 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-11-26 18:41:41,935 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, bf4e86e1-497d-4ddd-9312-8fcee2d2da4e, ghrun-ihttfokzrq.auto.internal:8570): 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: "01kb0qhaha2em4dyyh7axf47vm" } } } } }
2025-11-26 18:41:41,936 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, a8d21db2-260a-41f3-bcb0-e666e4e19c24, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,937 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, a8d21db2-260a-41f3-bcb0-e666e4e19c24, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" tx_control { tx_id: "01kb0qhafqezbmr23zn31g5395" } 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-11-26 18:41:41,965 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, a8d21db2-260a-41f3-bcb0-e666e4e19c24, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qhafqezbmr23zn31g5395" } } } } }
2025-11-26 18:41:41,966 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(ExecuteDataQuery, 4da73030-1170-49a3-8161-0f1e792f66ce, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:41,967 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(ExecuteDataQuery, 4da73030-1170-49a3-8161-0f1e792f66ce, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" tx_control { tx_id: "01kb0qhaha2em4dyyh7axf47vm" } 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-11-26 18:41:41,998 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(ExecuteDataQuery, 4da73030-1170-49a3-8161-0f1e792f66ce, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kb0qhaha2em4dyyh7axf47vm" } } } } }
2025-11-26 18:41:41,999 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, cf655cbc-b0c2-4b2e-887a-c44f02b08ff0, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,000 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, cf655cbc-b0c2-4b2e-887a-c44f02b08ff0, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" tx_id: "01kb0qhafqezbmr23zn31g5395" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,007 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, cf655cbc-b0c2-4b2e-887a-c44f02b08ff0, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-11-26 18:41:42,008 - DEBUG - ydb.connection.2332018  - _prepare_call: RpcState(CommitTransaction, cc17e7fb-cca1-407d-a7ef-3aeaff8a8f27, ghrun-ihttfokzrq.auto.internal:8570): creating call state
2025-11-26 18:41:42,008 - DEBUG - ydb.connection.2332018  - _log_request: RpcState(CommitTransaction, cc17e7fb-cca1-407d-a7ef-3aeaff8a8f27, ghrun-ihttfokzrq.auto.internal:8570): request = { session_id: "ydb://session/3?node_id=1&id=YjY2MjgzODgtYmNmNDBiODctODE5NDA3YjQtNjVlNzdhMGI=" tx_id: "01kb0qhaha2em4dyyh7axf47vm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 18:41:42,014 - DEBUG - ydb.connection.2332018  - _log_response: RpcState(CommitTransaction, cc17e7fb-cca1-407d-a7ef-3aeaff8a8f27, ghrun-ihttfokzrq.auto.internal:8570): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_write_skew_g2_item`" issue_code: 2001 severity: 1 } } }
2025-11-26 18:41:42,017 - INFO - ya.test.2332018  - pytest_runtest_teardown: Test teardown
