2025-12-12 10:05:16,846 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:16,880 - INFO - ya.test.2060683  - pytest_runtest_setup: test_isolation_mailing_list_example
2025-12-12 10:05:16,880 - INFO - ya.test.2060683  - pytest_runtest_setup: ####################################################################################################
2025-12-12 10:05:16,881 - INFO - ya.test.2060683  - pytest_runtest_setup: Test setup
2025-12-12 10:05:16,886 - INFO - ya.test.2060683  - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_isolation_mailing_list_example)
2025-12-12 10:05:16,887 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CreateSession, e10208f2-dca3-486c-b474-7681686dd1d5, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,918 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CreateSession, e10208f2-dca3-486c-b474-7681686dd1d5, ghrun-llwi4fnhxu.auto.internal:27863): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:16,930 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CreateSession, e10208f2-dca3-486c-b474-7681686dd1d5, 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=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" } } } }
2025-12-12 10:05:16,965 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteSchemeQuery, 2d7f4a75-60ed-40ca-8f30-8fc95a87e0eb, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:16,971 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteSchemeQuery, 2d7f4a75-60ed-40ca-8f30-8fc95a87e0eb, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" yql_text: "create table test_isolation_mailing_list_example (id Int32, value Int32, primary key(id)); " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,022 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteSchemeQuery, 2d7f4a75-60ed-40ca-8f30-8fc95a87e0eb, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS } }
2025-12-12 10:05:17,035 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, d7418699-3bd5-4efd-866e-2e6c834b3de2, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,048 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, d7418699-3bd5-4efd-866e-2e6c834b3de2, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into test_isolation_mailing_list_example (id, value) values (1, 10), (2, 20); " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,086 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, d7418699-3bd5-4efd-866e-2e6c834b3de2, 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:17,114 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(PrepareDataQuery, c4ad98a2-a455-417a-9205-186e0c788a3f, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,122 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(PrepareDataQuery, c4ad98a2-a455-417a-9205-186e0c788a3f, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 3);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,152 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(PrepareDataQuery, c4ad98a2-a455-417a-9205-186e0c788a3f, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=6e1b2e95-b984b5b0-bb31410f-59d03774" } } } }
2025-12-12 10:05:17,153 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 01573866-c785-42d1-bc04-8545d7840868, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,153 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 01573866-c785-42d1-bc04-8545d7840868, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 3);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,159 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 01573866-c785-42d1-bc04-8545d7840868, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } query_meta { id: "ydb://preparedqueryid/4?id=6e1b2e95-b984b5b0-bb31410f-59d03774" } } } } }
2025-12-12 10:05:17,160 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(PrepareDataQuery, df9d4816-14b0-4bf5-8722-095262a0f25c, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,163 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(PrepareDataQuery, df9d4816-14b0-4bf5-8722-095262a0f25c, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,185 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(PrepareDataQuery, df9d4816-14b0-4bf5-8722-095262a0f25c, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=c3fe2019-19799774-d2b4e675-323b595" } } } }
2025-12-12 10:05:17,186 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 1b6a2d82-4d25-483c-ac26-966aa2161835, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,187 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 1b6a2d82-4d25-483c-ac26-966aa2161835, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,192 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 1b6a2d82-4d25-483c-ac26-966aa2161835, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } items { int32_value: 3 } } format: FORMAT_VALUE } tx_meta { id: "01kc90b8057jrnf2vje53nc806" } query_meta { id: "ydb://preparedqueryid/4?id=c3fe2019-19799774-d2b4e675-323b595" } } } } }
2025-12-12 10:05:17,200 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, a77cc726-54fc-4b3f-8eff-bfcd8b9dc55d, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,213 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, a77cc726-54fc-4b3f-8eff-bfcd8b9dc55d, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: " select id, value FROM test_isolation_mailing_list_example WHERE id = 1" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,220 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, a77cc726-54fc-4b3f-8eff-bfcd8b9dc55d, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } items { int32_value: 3 } } format: FORMAT_VALUE } tx_meta { id: "01kc90b80zc6pa2x39tgdf7zfz" } query_meta { id: "ydb://preparedqueryid/4?id=c3fe2019-19799774-d2b4e675-323b595" } } } } }
2025-12-12 10:05:17,229 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(PrepareDataQuery, 69f981da-1fce-4b05-8563-6b1a40d28d16, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,230 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(PrepareDataQuery, 69f981da-1fce-4b05-8563-6b1a40d28d16, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 4);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,251 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(PrepareDataQuery, 69f981da-1fce-4b05-8563-6b1a40d28d16, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=95e04148-7843178f-10d46c08-836d3004" } } } }
2025-12-12 10:05:17,263 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, a1470426-5234-4444-abc9-c09461319bb7, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,271 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, a1470426-5234-4444-abc9-c09461319bb7, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { tx_id: "01kc90b8057jrnf2vje53nc806" } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 4);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,276 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, a1470426-5234-4444-abc9-c09461319bb7, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b8057jrnf2vje53nc806" } query_meta { id: "ydb://preparedqueryid/4?id=95e04148-7843178f-10d46c08-836d3004" } } } } }
2025-12-12 10:05:17,277 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, cf96df3e-9adb-47f8-9285-db114402013e, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,278 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, cf96df3e-9adb-47f8-9285-db114402013e, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_id: "01kc90b8057jrnf2vje53nc806" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,291 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, cf96df3e-9adb-47f8-9285-db114402013e, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-12-12 10:05:17,294 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(PrepareDataQuery, d563eaf7-603e-4343-a637-3854f4432b74, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,295 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(PrepareDataQuery, d563eaf7-603e-4343-a637-3854f4432b74, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 5);" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,332 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(PrepareDataQuery, d563eaf7-603e-4343-a637-3854f4432b74, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=2e76b5fc-8a2938c-63254e00-71ce2bdc" } } } }
2025-12-12 10:05:17,333 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, 604265f3-55b2-4883-aafe-ad1d950dfd41, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,347 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, 604265f3-55b2-4883-aafe-ad1d950dfd41, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { tx_id: "01kc90b80zc6pa2x39tgdf7zfz" } query { yql_text: " upsert into test_isolation_mailing_list_example (id, value) values (1, 5);" } query_cache_policy { keep_in_cache: true } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,349 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, 604265f3-55b2-4883-aafe-ad1d950dfd41, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01kc90b80zc6pa2x39tgdf7zfz" } query_meta { id: "ydb://preparedqueryid/4?id=2e76b5fc-8a2938c-63254e00-71ce2bdc" } } } } }
2025-12-12 10:05:17,355 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(CommitTransaction, 7d5fb7d4-7a3a-49ef-b8ec-097641959d66, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,366 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(CommitTransaction, 7d5fb7d4-7a3a-49ef-b8ec-097641959d66, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_id: "01kc90b80zc6pa2x39tgdf7zfz" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,387 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(CommitTransaction, 7d5fb7d4-7a3a-49ef-b8ec-097641959d66, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: ABORTED issues { message: "Transaction locks invalidated. Table: `/Root/test_isolation_mailing_list_example`." issue_code: 2001 severity: 1 issues { message: "Operation is aborting because locks are not valid" issue_code: 2001 severity: 1 } } } }
2025-12-12 10:05:17,388 - DEBUG - ydb.connection.2060683  - _prepare_call: RpcState(ExecuteDataQuery, e81b4311-f07d-4434-ae79-79154a8c19cf, ghrun-llwi4fnhxu.auto.internal:27863): creating call state
2025-12-12 10:05:17,412 - DEBUG - ydb.connection.2060683  - _log_request: RpcState(ExecuteDataQuery, e81b4311-f07d-4434-ae79-79154a8c19cf, ghrun-llwi4fnhxu.auto.internal:27863): request = { session_id: "ydb://session/3?node_id=1&id=NWMzMjAzMzMtNWIxM2EyYWYtYzQwMGFkOTItNDE3YmNkMWY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: " select id, value from test_isolation_mailing_list_example where id = 1;" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 10:05:17,469 - DEBUG - ydb.connection.2060683  - _log_response: RpcState(ExecuteDataQuery, e81b4311-f07d-4434-ae79-79154a8c19cf, ghrun-llwi4fnhxu.auto.internal:27863): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: INT32 } } } } columns { name: "value" type { optional_type { item { type_id: INT32 } } } } rows { items { int32_value: 1 } items { int32_value: 4 } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-12 10:05:17,481 - INFO - ya.test.2060683  - pytest_runtest_teardown: Test teardown
