2025-10-31 13:48:03,862 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:03,862 - INFO - ya.test - pytest_runtest_setup: test_isolation_mailing_list_example
2025-10-31 13:48:03,862 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 13:48:03,863 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 13:48:03,869 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_isolation.py::TestTransactionIsolation, test_name: test_isolation_mailing_list_example)
2025-10-31 13:48:03,872 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a388284d-b562-4a56-bbcb-956e598b1bbf, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,873 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a388284d-b562-4a56-bbcb-956e598b1bbf, ghrun-e45ihybpyu.auto.internal:20918): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:03,876 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a388284d-b562-4a56-bbcb-956e598b1bbf, 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=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" } } } }
2025-10-31 13:48:03,877 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, e9ad06b6-8348-47c4-b908-32886da56a78, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,877 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, e9ad06b6-8348-47c4-b908-32886da56a78, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:03,905 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, e9ad06b6-8348-47c4-b908-32886da56a78, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS } }
2025-10-31 13:48:03,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 17e14f17-9f1c-4803-867a-4091842db818, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 17e14f17-9f1c-4803-867a-4091842db818, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:03,937 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 17e14f17-9f1c-4803-867a-4091842db818, 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:03,938 - DEBUG - ydb.connection - _prepare_call: RpcState(PrepareDataQuery, ae1e76f7-d7df-4700-9633-e0a980ba48b5, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,945 - DEBUG - ydb.connection - _log_request: RpcState(PrepareDataQuery, ae1e76f7-d7df-4700-9633-e0a980ba48b5, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:03,965 - DEBUG - ydb.connection - _log_response: RpcState(PrepareDataQuery, ae1e76f7-d7df-4700-9633-e0a980ba48b5, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=edd6034f-efa8825c-1e237951-6de77c01" } } } }
2025-10-31 13:48:03,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 83aaee8b-fa22-438b-a7e1-6c37daf49730, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 83aaee8b-fa22-438b-a7e1-6c37daf49730, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:03,985 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 83aaee8b-fa22-438b-a7e1-6c37daf49730, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } query_meta { id: "ydb://preparedqueryid/4?id=edd6034f-efa8825c-1e237951-6de77c01" } } } } }
2025-10-31 13:48:03,986 - DEBUG - ydb.connection - _prepare_call: RpcState(PrepareDataQuery, 990e0cb4-415f-4fe0-950d-90a20dfff297, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:03,987 - DEBUG - ydb.connection - _log_request: RpcState(PrepareDataQuery, 990e0cb4-415f-4fe0-950d-90a20dfff297, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,008 - DEBUG - ydb.connection - _log_response: RpcState(PrepareDataQuery, 990e0cb4-415f-4fe0-950d-90a20dfff297, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=31c588c5-b7b6ec3b-a8f2c4e4-10d486fd" } } } }
2025-10-31 13:48:04,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5c6460e8-9c49-4def-9fee-39fd32384033, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5c6460e8-9c49-4def-9fee-39fd32384033, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,021 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5c6460e8-9c49-4def-9fee-39fd32384033, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8czhhe6r5tdv3eqxec5f6" } query_meta { id: "ydb://preparedqueryid/4?id=31c588c5-b7b6ec3b-a8f2c4e4-10d486fd" } } } } }
2025-10-31 13:48:04,022 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, db4dac55-5fe3-468d-a783-04913f4f75e5, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, db4dac55-5fe3-468d-a783-04913f4f75e5, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,034 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, db4dac55-5fe3-468d-a783-04913f4f75e5, ghrun-e45ihybpyu.auto.internal:20918): 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: "01k8x8czhva2skae57wmnxd58r" } query_meta { id: "ydb://preparedqueryid/4?id=31c588c5-b7b6ec3b-a8f2c4e4-10d486fd" } } } } }
2025-10-31 13:48:04,035 - DEBUG - ydb.connection - _prepare_call: RpcState(PrepareDataQuery, 4368ece1-fd3a-4c8c-80e8-48b3dec1f011, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,041 - DEBUG - ydb.connection - _log_request: RpcState(PrepareDataQuery, 4368ece1-fd3a-4c8c-80e8-48b3dec1f011, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,057 - DEBUG - ydb.connection - _log_response: RpcState(PrepareDataQuery, 4368ece1-fd3a-4c8c-80e8-48b3dec1f011, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=f6ba1557-a694156f-54d20670-7c0d61c3" } } } }
2025-10-31 13:48:04,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 434bad00-e85e-407d-8da2-413380969c41, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,064 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 434bad00-e85e-407d-8da2-413380969c41, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" tx_control { tx_id: "01k8x8czhhe6r5tdv3eqxec5f6" } 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-10-31 13:48:04,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 434bad00-e85e-407d-8da2-413380969c41, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czhhe6r5tdv3eqxec5f6" } query_meta { id: "ydb://preparedqueryid/4?id=f6ba1557-a694156f-54d20670-7c0d61c3" } } } } }
2025-10-31 13:48:04,078 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 48fc4edb-e842-486d-a2a8-2a91fb980254, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,085 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 48fc4edb-e842-486d-a2a8-2a91fb980254, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" tx_id: "01k8x8czhhe6r5tdv3eqxec5f6" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,101 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 48fc4edb-e842-486d-a2a8-2a91fb980254, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CommitTransactionResult] { } } } }
2025-10-31 13:48:04,102 - DEBUG - ydb.connection - _prepare_call: RpcState(PrepareDataQuery, cba1b68e-3065-40f0-98e6-04b8d7c01865, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,105 - DEBUG - ydb.connection - _log_request: RpcState(PrepareDataQuery, cba1b68e-3065-40f0-98e6-04b8d7c01865, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,123 - DEBUG - ydb.connection - _log_response: RpcState(PrepareDataQuery, cba1b68e-3065-40f0-98e6-04b8d7c01865, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.PrepareQueryResult] { query_id: "ydb://preparedqueryid/4?id=aac7e8b3-4a8c635b-525990c5-1c60a6f6" } } } }
2025-10-31 13:48:04,124 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 58abf99b-8efc-4633-b1f0-7aa78a15097f, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,126 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 58abf99b-8efc-4633-b1f0-7aa78a15097f, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" tx_control { tx_id: "01k8x8czhva2skae57wmnxd58r" } 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-10-31 13:48:04,129 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 58abf99b-8efc-4633-b1f0-7aa78a15097f, ghrun-e45ihybpyu.auto.internal:20918): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01k8x8czhva2skae57wmnxd58r" } query_meta { id: "ydb://preparedqueryid/4?id=aac7e8b3-4a8c635b-525990c5-1c60a6f6" } } } } }
2025-10-31 13:48:04,129 - DEBUG - ydb.connection - _prepare_call: RpcState(CommitTransaction, 4fe61e20-7996-47c6-b1e0-e3a23cb05ef3, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,130 - DEBUG - ydb.connection - _log_request: RpcState(CommitTransaction, 4fe61e20-7996-47c6-b1e0-e3a23cb05ef3, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" tx_id: "01k8x8czhva2skae57wmnxd58r" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-31 13:48:04,136 - DEBUG - ydb.connection - _log_response: RpcState(CommitTransaction, 4fe61e20-7996-47c6-b1e0-e3a23cb05ef3, ghrun-e45ihybpyu.auto.internal:20918): 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-10-31 13:48:04,137 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f3b08909-ad7a-4d2a-8743-76c7316e0d46, ghrun-e45ihybpyu.auto.internal:20918): creating call state
2025-10-31 13:48:04,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f3b08909-ad7a-4d2a-8743-76c7316e0d46, ghrun-e45ihybpyu.auto.internal:20918): request = { session_id: "ydb://session/3?node_id=1&id=MTBmMzg2OWMtNWM4ZDg2ODgtZGU4ZTAyM2ItYzZiNWY2OA==" 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-10-31 13:48:04,166 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f3b08909-ad7a-4d2a-8743-76c7316e0d46, ghrun-e45ihybpyu.auto.internal:20918): 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-10-31 13:48:04,172 - INFO - ya.test - pytest_runtest_teardown: Test teardown
