Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Bug: Investigate failing test with Notify instead of sleep #948

Closed
insipx opened this issue Aug 8, 2024 · 3 comments
Closed

Bug: Investigate failing test with Notify instead of sleep #948

insipx opened this issue Aug 8, 2024 · 3 comments
Labels
bug Something isn't working

Comments

@insipx
Copy link
Contributor

insipx commented Aug 8, 2024 •
edited
Loading

Describe the bug

changing the test test_subscribe_membership_changes from using sleep to using tokio::sync::Notify/Delivery results in test failure. The stream does not receive the group membership change message.

    #[tokio::test(flavor = "current_thread")]
    async fn test_subscribe_membership_changes() {
        let amal = Arc::new(ClientBuilder::new_test_client(&generate_local_wallet()).await);
        let bola = ClientBuilder::new_test_client(&generate_local_wallet()).await;

        let amal_group = amal
            .create_group(None, GroupMetadataOptions::default())
            .unwrap();

        let amal_ptr = amal.clone();
        let amal_group_ptr = amal_group.clone();
        let notify = Delivery::new(Some(Duration::from_secs(20)));
        let notify_ptr = notify.clone();

        let (tx, rx) = tokio::sync::mpsc::unbounded_channel();
        let mut stream = UnboundedReceiverStream::new(rx);
        tokio::spawn(async move {
            let mut stream = amal_group_ptr.stream(amal_ptr).await.unwrap();
            while let Some(item) = stream.next().await {
                let _ = tx.send(item);
                notify_ptr.notify_one();
            }
        });

        amal_group
            .add_members_by_inbox_id(&amal, vec![bola.inbox_id()])
            .await
            .unwrap();
        notify
            .wait_for_delivery()
            .await
            .expect("Never received group membership change from stream");
        let first_val = stream.next().await.unwrap();
        assert_eq!(first_val.kind, GroupMessageKind::MembershipChange);

        amal_group
            .send_message("hello".as_bytes(), &amal)
            .await
            .unwrap();
        notify
            .wait_for_delivery()
            .await
            .expect("Never received second message from stream");
        let second_val = stream.next().await.unwrap();
        assert_eq!(second_val.decrypted_message_bytes, "hello".as_bytes());
    }

The test fails with

2024-08-12T15:05:59.909954Z ERROR xmtp_mls::subscriptions: Error processing stream entry: Generic("Group intent could not be committed")

before the stream times out

Log output

 

running 1 test
2024-08-12T15:05:59.713631Z  INFO xmtp_mls::storage::encrypted_store: Setting up DB connection pool
2024-08-12T15:05:59.715331Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.716876Z  INFO xmtp_mls::storage::encrypted_store: Running DB migrations
2024-08-12T15:05:59.721105Z  INFO xmtp_mls::storage::encrypted_store: sqlite_version=3.45.3
2024-08-12T15:05:59.721135Z  INFO xmtp_mls::storage::encrypted_store: Migrations successful
2024-08-12T15:05:59.724873Z DEBUG xmtp_mls::builder: Building client
2024-08-12T15:05:59.724897Z DEBUG xmtp_mls::builder: Initializing identity
2024-08-12T15:05:59.724919Z  INFO xmtp_mls::identity: Initializing identity
2024-08-12T15:05:59.724929Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.725217Z DEBUG xmtp_mls::identity: identity in store: None
2024-08-12T15:05:59.725308Z  INFO get_inbox_ids: xmtp_mls::api::identity: Getting inbox_ids for account addresses: ["0x7b022f6bddeef2ea9715060f65628b38b1672789"]
2024-08-12T15:05:59.743239Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.743365Z DEBUG load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567"]
2024-08-12T15:05:59.756045Z  INFO xmtp_mls::client: registering identity
2024-08-12T15:05:59.769911Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.769998Z DEBUG load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567"]
2024-08-12T15:05:59.772736Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.774707Z DEBUG xmtp_mls::storage::sql_key_store: write KeyPackage
2024-08-12T15:05:59.775184Z DEBUG xmtp_mls::storage::sql_key_store: write KeyPackageReferences
2024-08-12T15:05:59.787208Z  INFO xmtp_mls::storage::encrypted_store: Setting up DB connection pool
2024-08-12T15:05:59.788145Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.792916Z  INFO xmtp_mls::storage::encrypted_store: Running DB migrations
2024-08-12T15:05:59.797672Z  INFO xmtp_mls::storage::encrypted_store: sqlite_version=3.45.3
2024-08-12T15:05:59.797700Z  INFO xmtp_mls::storage::encrypted_store: Migrations successful
2024-08-12T15:05:59.800233Z DEBUG xmtp_mls::builder: Building client
2024-08-12T15:05:59.800256Z DEBUG xmtp_mls::builder: Initializing identity
2024-08-12T15:05:59.800263Z  INFO xmtp_mls::identity: Initializing identity
2024-08-12T15:05:59.800272Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.800396Z DEBUG xmtp_mls::identity: identity in store: None
2024-08-12T15:05:59.800428Z  INFO get_inbox_ids: xmtp_mls::api::identity: Getting inbox_ids for account addresses: ["0xa71d6cc994e139f75cb7ae4ca5f482e364af9ddb"]
2024-08-12T15:05:59.805193Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.805274Z DEBUG load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e"]
2024-08-12T15:05:59.811177Z  INFO xmtp_mls::client: registering identity
2024-08-12T15:05:59.824567Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.824741Z DEBUG load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e"]
2024-08-12T15:05:59.830279Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.831449Z DEBUG xmtp_mls::storage::sql_key_store: write KeyPackage
2024-08-12T15:05:59.831888Z DEBUG xmtp_mls::storage::sql_key_store: write KeyPackageReferences
2024-08-12T15:05:59.845499Z  INFO xmtp_mls::client: creating group
2024-08-12T15:05:59.845613Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.847185Z DEBUG xmtp_mls::storage::sql_key_store: write Tree
2024-08-12T15:05:59.847643Z DEBUG xmtp_mls::storage::sql_key_store: write ConfirmationTag
2024-08-12T15:05:59.848061Z DEBUG xmtp_mls::storage::sql_key_store: write GroupContext
2024-08-12T15:05:59.848432Z DEBUG xmtp_mls::storage::sql_key_store: write InterimTranscriptHash
2024-08-12T15:05:59.848770Z DEBUG xmtp_mls::storage::sql_key_store: write OwnLeafNodeIndex
2024-08-12T15:05:59.849102Z DEBUG xmtp_mls::storage::sql_key_store: write EpochSecrets
2024-08-12T15:05:59.849398Z DEBUG xmtp_mls::storage::sql_key_store: write UseRatchetTree
2024-08-12T15:05:59.849790Z DEBUG xmtp_mls::storage::sql_key_store: write MessageSecrets
2024-08-12T15:05:59.850065Z DEBUG xmtp_mls::storage::sql_key_store: write ResumptionPskStore
2024-08-12T15:05:59.850442Z DEBUG xmtp_mls::storage::sql_key_store: Writing encryption epoch key pairs
2024-08-12T15:05:59.850456Z DEBUG xmtp_mls::storage::sql_key_store:   key: 1000000000000000b8dbff9a307c200db0387e97068f1cfa000000000000000000000000
2024-08-12T15:05:59.850466Z DEBUG xmtp_mls::storage::sql_key_store:   value: 010000000000000020000000000000009f6ae0606cd9b6129a9cef4ebaf6b0e00a37add63172b50c71eaa2ca26c51e7b200000000000000063890e3b38fd9d64b0e399c38a4652637737f851b465fe4ddebf05aba20e9d26
2024-08-12T15:05:59.850474Z DEBUG xmtp_mls::storage::sql_key_store: write EpochKeyPairs
2024-08-12T15:05:59.850844Z DEBUG xmtp_mls::storage::sql_key_store: write MlsGroupJoinConfig
2024-08-12T15:05:59.851190Z DEBUG xmtp_mls::storage::sql_key_store: write GroupState
2024-08-12T15:05:59.851586Z DEBUG xmtp_mls::storage::sql_key_store: write Tree
2024-08-12T15:05:59.851932Z DEBUG xmtp_mls::storage::sql_key_store: write ConfirmationTag
2024-08-12T15:05:59.852302Z DEBUG xmtp_mls::storage::sql_key_store: write GroupContext
2024-08-12T15:05:59.852668Z DEBUG xmtp_mls::storage::sql_key_store: write InterimTranscriptHash
2024-08-12T15:05:59.852980Z DEBUG xmtp_mls::storage::sql_key_store: write OwnLeafNodeIndex
2024-08-12T15:05:59.853376Z DEBUG xmtp_mls::storage::sql_key_store: write EpochSecrets
2024-08-12T15:05:59.853710Z DEBUG xmtp_mls::storage::sql_key_store: write UseRatchetTree
2024-08-12T15:05:59.854118Z DEBUG xmtp_mls::storage::sql_key_store: write MessageSecrets
2024-08-12T15:05:59.854473Z DEBUG xmtp_mls::storage::sql_key_store: write ResumptionPskStore
2024-08-12T15:05:59.857509Z DEBUG add_members_by_inbox_id: xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=10, total_connections=10
2024-08-12T15:05:59.857632Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.857802Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.857886Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.857960Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.857997Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.858039Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.858087Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.858119Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.858150Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.858238Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.858276Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.858306Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.858320Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.858348Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.858376Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.858579Z DEBUG add_members_by_inbox_id:get_membership_update_intent:load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567", "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e"]
2024-08-12T15:05:59.862561Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.862627Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.862709Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.862781Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.862819Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.862857Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.862906Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.862940Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.862972Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.863052Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.863094Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.863124Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.863140Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.863169Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.863200Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.863426Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::groups::intents: old group membership: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 0}
2024-08-12T15:05:59.863446Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::groups::intents: updated group membership: {"a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877, "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876}
2024-08-12T15:05:59.863466Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::identity_updates: Getting installation diff. Old: GroupMembership { members: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 0} }. New GroupMembership { members: {"a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877, "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876} }
2024-08-12T15:05:59.863579Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::identity_updates: Computing diff for "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e" from None to Some(1877)
2024-08-12T15:05:59.869588Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::storage::encrypted_store::association_state: Wrote association state to cache: a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e 1877
2024-08-12T15:05:59.869675Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::identity_updates: Computing diff for "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" from None to Some(1876)
2024-08-12T15:05:59.875145Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::storage::encrypted_store::association_state: Wrote association state to cache: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567 1876
2024-08-12T15:05:59.878289Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent:get_key_packages_for_installation_ids: xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=9, total_connections=10
2024-08-12T15:05:59.884332Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::storage::sql_key_store: write MessageSecrets
2024-08-12T15:05:59.885197Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::storage::sql_key_store: write GroupState
2024-08-12T15:05:59.890721Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents: xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] published intent [1] of type [UpdateGroupMembership]
2024-08-12T15:05:59.891274Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:publish_intents: xmtp_mls::groups::sync: client [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] set stored intent [1] to state `published`
2024-08-12T15:05:59.891324Z  INFO xmtp_mls::subscriptions: Received message streaming payload
2024-08-12T15:05:59.891351Z  INFO xmtp_mls::subscriptions: Extracted group id b8dbff9a307c200db0387e97068f1cfa
2024-08-12T15:05:59.891373Z  INFO xmtp_mls::groups::subscriptions: client [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567]  is about to process streamed envelope: [2764]
2024-08-12T15:05:59.891415Z DEBUG retry: xmtp_mls::storage::encrypted_store: Transaction async beginning
2024-08-12T15:05:59.891429Z DEBUG retry: xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=9, total_connections=10
2024-08-12T15:05:59.891505Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.892153Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.892253Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.892324Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.892361Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.892397Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.892474Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.892539Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.892575Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.892662Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.892700Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.892726Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.892739Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.892764Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.892791Z DEBUG retry:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.893342Z  INFO retry: xmtp_mls::groups::subscriptions: current epoch for [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] in process_stream_entry() is Epoch: [0]
2024-08-12T15:05:59.893619Z  INFO retry:process_message: xmtp_mls::groups::sync: client [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] is about to process own envelope [2764]
2024-08-12T15:05:59.893631Z  INFO retry:process_message: xmtp_mls::groups::sync: envelope [2764] is equal to intent [1]
2024-08-12T15:05:59.893650Z DEBUG retry:process_message:process_own_message: xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] processing own message for intent 1 / UpdateGroupMembership, group epoch: 0, message_epoch: 0
2024-08-12T15:05:59.893720Z DEBUG retry: xmtp_mls::storage::encrypted_store: Transaction async being rolled back
2024-08-12T15:05:59.893749Z  INFO xmtp_mls::groups::subscriptions: error is not retryable. ReceiveError(EpochIncrementNotAllowed)
2024-08-12T15:05:59.893761Z DEBUG xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=9, total_connections=10
2024-08-12T15:05:59.893797Z  INFO xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] syncing group
2024-08-12T15:05:59.893819Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.893875Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.893937Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.894001Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.894033Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.894066Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.894108Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.894136Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.894163Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.894238Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.894275Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.894304Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.894317Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.894341Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.894368Z DEBUG load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.894686Z  INFO xmtp_mls::groups::sync: current epoch for [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] in sync() is Epoch: [0]
2024-08-12T15:05:59.894796Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.894832Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.894886Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.894951Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.894984Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.895019Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.895062Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.895091Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.895119Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.895194Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.895230Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.895257Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.895270Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.895362Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.895393Z DEBUG get_membership_update_intent:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.895655Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.895723Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.895739Z DEBUG get_membership_update_intent:load_identity_updates: xmtp_mls::identity_updates: Fetching identity updates for: ["366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567"]
2024-08-12T15:05:59.895796Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.895892Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.895934Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.895974Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.896024Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.896061Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.896097Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.896178Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.896218Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.896250Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.896266Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.896296Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.896326Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.896712Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message: xmtp_mls::storage::encrypted_store: Transaction async beginning
2024-08-12T15:05:59.896738Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message: xmtp_mls::storage::encrypted_store: Pulling connection from pool, idle_connections=8, total_connections=10
2024-08-12T15:05:59.897312Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message: xmtp_mls::groups::sync: client [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] is about to process own envelope [2764]
2024-08-12T15:05:59.897334Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message: xmtp_mls::groups::sync: envelope [2764] is equal to intent [1]
2024-08-12T15:05:59.897357Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] processing own message for intent 1 / UpdateGroupMembership, group epoch: 0, message_epoch: 0
2024-08-12T15:05:59.897376Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] Validating commit for intent 1. Message timestamp: 1723475159888500000
2024-08-12T15:05:59.897560Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::groups::validated_commit: Group context extensions proposal found: GroupMembership { members: {"a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877, "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876} }
2024-08-12T15:05:59.897596Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::identity_updates: Getting installation diff. Old: GroupMembership { members: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 0} }. New GroupMembership { members: {"a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877, "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876} }
2024-08-12T15:05:59.897726Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::identity_updates: Computing diff for "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e" from None to Some(1877)
2024-08-12T15:05:59.897914Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::encrypted_store::association_state: Loaded association state from cache: a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e 1877
2024-08-12T15:05:59.897950Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::identity_updates: Computing diff for "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" from None to Some(1876)
2024-08-12T15:05:59.898010Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::encrypted_store::association_state: Loaded association state from cache: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567 1876
2024-08-12T15:05:59.898153Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::encrypted_store::association_state: Loaded association state from cache: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567 1876
2024-08-12T15:05:59.898198Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::groups::sync: [366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567] merging pending commit for intent 1
2024-08-12T15:05:59.898227Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write GroupState
2024-08-12T15:05:59.898297Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: Reading encryption epoch key pairs
2024-08-12T15:05:59.898323Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store:   key: 45706f63684b657950616972731000000000000000b8dbff9a307c200db0387e97068f1cfa0000000000000000000000000001
2024-08-12T15:05:59.898494Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write Tree
2024-08-12T15:05:59.898607Z DEBUG xmtp_mls::groups::sync: Adding missing installations UpdateGroupMembershipIntentData { membership_updates: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876}, removed_members: [] }
2024-08-12T15:05:59.898770Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write ConfirmationTag
2024-08-12T15:05:59.898851Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write GroupContext
2024-08-12T15:05:59.898889Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write InterimTranscriptHash
2024-08-12T15:05:59.898936Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write EpochSecrets
2024-08-12T15:05:59.899019Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write MessageSecrets
2024-08-12T15:05:59.899064Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: Writing encryption epoch key pairs
2024-08-12T15:05:59.899084Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store:   key: 1000000000000000b8dbff9a307c200db0387e97068f1cfa010000000000000000000000
2024-08-12T15:05:59.899106Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store:   value: 02000000000000002000000000000000ea4dec1347679789f5f8a264754dda5f75aacdc0c41b5ea028c6641642ad272e200000000000000031173072990cc5c710a82c59cf50edc49068d9a52232227496aba4d3ac4b9e092000000000000000beb36f6e77185388f82eb256d288beadd007330bb2f017ec56c449cc0ee44e062000000000000000deb737ffc9114608c602bc9b6694125a64e486b63dfcce1b84fd9524b3d2969c
2024-08-12T15:05:59.899126Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::storage::sql_key_store: write EpochKeyPairs
2024-08-12T15:05:59.899202Z  INFO add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message:process_message:process_own_message: xmtp_mls::groups::sync: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567: Storing a transcript message with 1 members added and 0 members removed and 0 metadata changes
2024-08-12T15:05:59.899812Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:receive:process_messages:retry:consume_message: xmtp_mls::storage::encrypted_store: Transaction async being committed
2024-08-12T15:05:59.900544Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.900634Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.900758Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.900841Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.900891Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.900932Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.900985Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.901021Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.901056Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.901119Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:post_commit{self=MlsGroup { group_id: [184, 219, 255, 154, 48, 124, 32, 13, 176, 56, 126, 151, 6, 143, 28, 250], created_at_ns: 1723475159854841000, context: XmtpMlsLocalContext { identity: Identity { inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567", installation_keys: SignatureKeyPair { private: "***", public: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67], signature_scheme: ED25519 }, credential: Credential { credential_type: Basic, serialized_credential_content: VLBytes { 0x0a4033363664343062366537366335613266646436353363653138363638343538316363616361663065636539396331643339393765396539343638336131353637 } }, signature_request: Some(SignatureRequest { pending_actions: [PendingIdentityAction { unsigned_action: CreateInbox(UnsignedCreateInbox { nonce: 1, account_address: "0x7b022f6bddeef2ea9715060f65628b38b1672789" }), pending_signatures: {InitialAddress: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789")} }, PendingIdentityAction { unsigned_action: AddAssociation(UnsignedAddAssociation { new_member_identifier: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]) }), pending_signatures: {ExistingMember: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789"), NewMember: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67])} }], signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signatures: {Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]): InstallationKeySignature { signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signature_bytes: [185, 202, 22, 253, 153, 145, 242, 110, 150, 142, 140, 93, 23, 114, 148, 216, 89, 82, 148, 104, 25, 46, 164, 202, 196, 229, 54, 104, 34, 186, 151, 227, 79, 8, 128, 205, 110, 59, 128, 64, 224, 191, 166, 84, 95, 52, 56, 106, 233, 205, 199, 35, 38, 34, 50, 7, 123, 208, 153, 35, 49, 64, 66, 9], verifying_key: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67] }}, client_timestamp_ns: 1723475159742166000, inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" }) }, store: EncryptedMessageStore { connect_opt: Persistent("/var/folders/qb/tjxs_0n17694m8w9hbskqjqw0000gn/T//F1pJ7mIPWJFNui1cQHO26pc4.db3"), pool: RwLock { data: Some(Pool { state: State { connections: 10, idle_connections: 8 }, config: Config { max_size: 10, min_idle: None, test_on_check_out: true, max_lifetime: Some(1800s), idle_timeout: Some(600s), connection_timeout: 30s, error_handler: LoggingErrorHandler, event_handler: NopEventHandler, connection_customizer: NopConnectionCustomizer }, manager: ConnectionManager<diesel::sqlite::connection::SqliteConnection> }) }, enc_key: None } } }}:send_welcomes: xmtp_mls::groups::sync: total welcome message proto bytes=1682
2024-08-12T15:05:59.901142Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.901231Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.901210Z DEBUG add_members_by_inbox_id:sync_until_intent_resolved{intent_id=1}:sync_with_conn:post_commit{self=MlsGroup { group_id: [184, 219, 255, 154, 48, 124, 32, 13, 176, 56, 126, 151, 6, 143, 28, 250], created_at_ns: 1723475159854841000, context: XmtpMlsLocalContext { identity: Identity { inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567", installation_keys: SignatureKeyPair { private: "***", public: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67], signature_scheme: ED25519 }, credential: Credential { credential_type: Basic, serialized_credential_content: VLBytes { 0x0a4033363664343062366537366335613266646436353363653138363638343538316363616361663065636539396331643339393765396539343638336131353637 } }, signature_request: Some(SignatureRequest { pending_actions: [PendingIdentityAction { unsigned_action: CreateInbox(UnsignedCreateInbox { nonce: 1, account_address: "0x7b022f6bddeef2ea9715060f65628b38b1672789" }), pending_signatures: {InitialAddress: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789")} }, PendingIdentityAction { unsigned_action: AddAssociation(UnsignedAddAssociation { new_member_identifier: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]) }), pending_signatures: {ExistingMember: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789"), NewMember: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67])} }], signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signatures: {Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]): InstallationKeySignature { signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signature_bytes: [185, 202, 22, 253, 153, 145, 242, 110, 150, 142, 140, 93, 23, 114, 148, 216, 89, 82, 148, 104, 25, 46, 164, 202, 196, 229, 54, 104, 34, 186, 151, 227, 79, 8, 128, 205, 110, 59, 128, 64, 224, 191, 166, 84, 95, 52, 56, 106, 233, 205, 199, 35, 38, 34, 50, 7, 123, 208, 153, 35, 49, 64, 66, 9], verifying_key: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67] }}, client_timestamp_ns: 1723475159742166000, inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" }) }, store: EncryptedMessageStore { connect_opt: Persistent("/var/folders/qb/tjxs_0n17694m8w9hbskqjqw0000gn/T//F1pJ7mIPWJFNui1cQHO26pc4.db3"), pool: RwLock { data: Some(Pool { state: State { connections: 10, idle_connections: 8 }, config: Config { max_size: 10, min_idle: None, test_on_check_out: true, max_lifetime: Some(1800s), idle_timeout: Some(600s), connection_timeout: 30s, error_handler: LoggingErrorHandler, event_handler: NopEventHandler, connection_customizer: NopConnectionCustomizer }, manager: ConnectionManager<diesel::sqlite::connection::SqliteConnection> }) }, enc_key: None } } }}:send_welcomes: xmtp_mls::groups::sync: welcome chunk_size=28053
2024-08-12T15:05:59.901267Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.901312Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.901352Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.901384Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.901560Z  INFO sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::groups::intents: old group membership: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876, "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877}
2024-08-12T15:05:59.901582Z  INFO sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::groups::intents: updated group membership: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876, "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877}
2024-08-12T15:05:59.901604Z  INFO sync_until_intent_resolved{intent_id=2}:sync_with_conn:publish_intents:retry:get_publish_intent_data:apply_update_group_membership_intent: xmtp_mls::identity_updates: Getting installation diff. Old: GroupMembership { members: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876, "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877} }. New GroupMembership { members: {"366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567": 1876, "a87f2c211c39b9cf70efc81663d67dfd6aeffbfb0f6f93481fe7641740af020e": 1877} }
2024-08-12T15:05:59.905005Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read MlsGroupJoinConfig
2024-08-12T15:05:59.905146Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read Tree
2024-08-12T15:05:59.905269Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupContext
2024-08-12T15:05:59.905349Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read InterimTranscriptHash
2024-08-12T15:05:59.905389Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read ConfirmationTag
2024-08-12T15:05:59.905428Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read EpochSecrets
2024-08-12T15:05:59.905478Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read OwnLeafNodeIndex
2024-08-12T15:05:59.905513Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read UseRatchetTree
2024-08-12T15:05:59.905547Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read MessageSecrets
2024-08-12T15:05:59.905633Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read ResumptionPskStore
2024-08-12T15:05:59.905674Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read_list ProposalQueueRefs
2024-08-12T15:05:59.905706Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: own_leaf_nodes
2024-08-12T15:05:59.905723Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read_list OwnLeafNodes
2024-08-12T15:05:59.905753Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read AAD
2024-08-12T15:05:59.905783Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:receive:process_messages:load_mls_group: xmtp_mls::storage::sql_key_store: read GroupState
2024-08-12T15:05:59.906962Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:post_commit{self=MlsGroup { group_id: [184, 219, 255, 154, 48, 124, 32, 13, 176, 56, 126, 151, 6, 143, 28, 250], created_at_ns: 1723475159854841000, context: XmtpMlsLocalContext { identity: Identity { inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567", installation_keys: SignatureKeyPair { private: "***", public: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67], signature_scheme: ED25519 }, credential: Credential { credential_type: Basic, serialized_credential_content: VLBytes { 0x0a4033363664343062366537366335613266646436353363653138363638343538316363616361663065636539396331643339393765396539343638336131353637 } }, signature_request: Some(SignatureRequest { pending_actions: [PendingIdentityAction { unsigned_action: CreateInbox(UnsignedCreateInbox { nonce: 1, account_address: "0x7b022f6bddeef2ea9715060f65628b38b1672789" }), pending_signatures: {InitialAddress: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789")} }, PendingIdentityAction { unsigned_action: AddAssociation(UnsignedAddAssociation { new_member_identifier: Installation([11, 55,154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]) }), pending_signatures: {ExistingMember: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789"), NewMember: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67])} }], signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signatures: {Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]): InstallationKeySignature { signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signature_bytes: [185, 202, 22, 253, 153, 145, 242, 110, 150, 142, 140, 93, 23, 114, 148, 216, 89, 82, 148, 104, 25, 46, 164, 202, 196, 229, 54, 104, 34, 186, 151, 227, 79, 8, 128, 205, 110, 59, 128, 64, 224, 191, 166, 84, 95, 52, 56, 106, 233, 205, 199, 35, 38, 34, 50, 7, 123, 208, 153, 35, 49, 64, 66, 9], verifying_key: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67] }}, client_timestamp_ns: 1723475159742166000, inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" }) }, store: EncryptedMessageStore { connect_opt: Persistent("/var/folders/qb/tjxs_0n17694m8w9hbskqjqw0000gn/T//F1pJ7mIPWJFNui1cQHO26pc4.db3"), pool: RwLock { data: Some(Pool { state: State { connections: 10, idle_connections: 8 }, config: Config { max_size: 10, min_idle: None, test_on_check_out: true, max_lifetime: Some(1800s), idle_timeout: Some(600s), connection_timeout: 30s, error_handler: LoggingErrorHandler, event_handler: NopEventHandler, connection_customizer: NopConnectionCustomizer }, manager: ConnectionManager<diesel::sqlite::connection::SqliteConnection> }) }, enc_key: None } } }}:send_welcomes: xmtp_mls::groups::sync: total welcome message proto bytes=1682
2024-08-12T15:05:59.907066Z DEBUG sync_until_intent_resolved{intent_id=2}:sync_with_conn:post_commit{self=MlsGroup { group_id: [184, 219, 255, 154, 48, 124, 32, 13, 176, 56, 126, 151, 6, 143, 28, 250], created_at_ns: 1723475159854841000, context: XmtpMlsLocalContext { identity: Identity { inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567", installation_keys: SignatureKeyPair { private: "***", public: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67], signature_scheme: ED25519 }, credential: Credential { credential_type: Basic, serialized_credential_content: VLBytes { 0x0a4033363664343062366537366335613266646436353363653138363638343538316363616361663065636539396331643339393765396539343638336131353637 } }, signature_request: Some(SignatureRequest { pending_actions: [PendingIdentityAction { unsigned_action: CreateInbox(UnsignedCreateInbox { nonce: 1, account_address: "0x7b022f6bddeef2ea9715060f65628b38b1672789" }), pending_signatures: {InitialAddress: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789")} }, PendingIdentityAction { unsigned_action: AddAssociation(UnsignedAddAssociation { new_member_identifier: Installation([11, 55,154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]) }), pending_signatures: {ExistingMember: Address("0x7b022f6bddeef2ea9715060f65628b38b1672789"), NewMember: Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67])} }], signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signatures: {Installation([11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67]): InstallationKeySignature { signature_text: "XMTP : Authenticate to inbox\n\nInbox ID: 366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567\nCurrent time: 2024-08-12T15:05:59Z\n\n- Create inbox\n  (Owner: 0x7b022f6bddeef2ea9715060f65628b38b1672789)\n- Grant messaging access to app\n  (ID: 0b379a999175811f5e16cadcb478556f77f93839a57f5e8ae530e383bc37f443)\n\nFor more info: https://xmtp.org/signatures", signature_bytes: [185, 202, 22, 253, 153, 145, 242, 110, 150, 142, 140, 93, 23, 114, 148, 216, 89, 82, 148, 104, 25, 46, 164, 202, 196, 229, 54, 104, 34, 186, 151, 227, 79, 8, 128, 205, 110, 59, 128, 64, 224, 191, 166, 84, 95, 52, 56, 106, 233, 205, 199, 35, 38, 34, 50, 7, 123, 208, 153, 35, 49, 64, 66, 9], verifying_key: [11, 55, 154, 153, 145, 117, 129, 31, 94, 22, 202, 220, 180, 120, 85, 111, 119, 249, 56, 57, 165, 127, 94, 138, 229, 48, 227, 131, 188, 55, 244, 67] }}, client_timestamp_ns: 1723475159742166000, inbox_id: "366d40b6e76c5a2fdd653ce186684581ccacaf0ece99c1d3997e9e94683a1567" }) }, store: EncryptedMessageStore { connect_opt: Persistent("/var/folders/qb/tjxs_0n17694m8w9hbskqjqw0000gn/T//F1pJ7mIPWJFNui1cQHO26pc4.db3"), pool: RwLock { data: Some(Pool { state: State { connections: 10, idle_connections: 8 }, config: Config { max_size: 10, min_idle: None, test_on_check_out: true, max_lifetime: Some(1800s), idle_timeout: Some(600s), connection_timeout: 30s, error_handler: LoggingErrorHandler, event_handler: NopEventHandler, connection_customizer: NopConnectionCustomizer }, manager: ConnectionManager<diesel::sqlite::connection::SqliteConnection> }) }, enc_key: None } } }}:send_welcomes: xmtp_mls::groups::sync: welcome chunk_size=28053
2024-08-12T15:05:59.909896Z  WARN sync_until_intent_resolved{intent_id=2}: xmtp_mls::groups::sync: not retrying intent ID 2. since it is in state Error
2024-08-12T15:05:59.909954Z ERROR xmtp_mls::subscriptions: Error processing stream entry: Generic("Group intent could not be committed")
thread 'groups::subscriptions::tests::test_subscribe_membership_changes' panicked at xmtp_mls/src/groups/subscriptions.rs:323:14:
Never received group membership change from stream: Elapsed(())
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
test groups::subscriptions::tests::test_subscribe_membership_changes ... FAILED

Expected behavior

Test works with blocking behavior of Notify

Steps to reproduce the bug

uncomment #[ignore] from test_subscribe_membership_changes
@insipx insipx added the bug Something isn't working label Aug 8, 2024
@insipx insipx added this to libxmtp Aug 9, 2024
@insipx insipx moved this to Backlog in libxmtp Aug 9, 2024
@nplasterer nplasterer moved this to Todo in V3 Backlog Aug 12, 2024
This was referenced Aug 14, 2024
Merged
Closed
@nplasterer nplasterer mentioned this issue Aug 14, 2024
Closed
@nplasterer
Copy link
Contributor

I think this should be fixed by #969

@github-project-automation github-project-automation bot moved this from Todo to Done in V3 Backlog Aug 16, 2024
@github-project-automation github-project-automation bot moved this from Backlog to Done in libxmtp Aug 16, 2024
@neekolas
Copy link
Contributor

My changes didn't have anything to do with streaming or Notify/Sleep. Just fixed a bug in group syncing.

Does the test case now pass?

@nplasterer
Copy link
Contributor

Yes it was throwing the same error the test now passes. Opened a PR to remove the ignore from the test #970

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
V3 Backlog
Status: Done
libxmtp
Status: Done
Milestone
No milestone
Development

No branches or pull requests
3 participants
@neekolas @nplasterer @insipx