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.

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

tentative fork repro test #534

Open
wants to merge 2 commits into
base: main
Choose a base branch
from
Open

Conversation

nmalzieu
Copy link
Collaborator

@nmalzieu nmalzieu commented Nov 13, 2024

Tentative fork repro test
When adding users to a group then removing multiple users at the same time using Promise.all, sometimes it appears that the two initial members get forked

I'm not 100% sure this is exactly a fork but it looks like it because, once it's forked, I can try like 5 times, syncing the client & the group, but no messages go through

Logs of a non forked run:

Testing that messages sent by alix are received by bo
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 1 messages in group
Alix & Bo are not forked at the beginning
Adding new members to the group...
Adding member 0x4dc68234aaea2791ecc6f9aa0960593d2fc41c3f...
Adding member 0xf35bef60a4e111ec444eab45fc5cfdb1a808dbde...
Removing members in parallel
Removing member 0x4dc68234aaea2791ecc6f9aa0960593d2fc41c3f...
Removing member 0xf35bef60a4e111ec444eab45fc5cfdb1a808dbde...
Checking fork status 1/5
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 6 messages in group
Not forked!
Checking fork status 2/5
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 7 messages in group
Not forked!
Checking fork status 3/5
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 8 messages in group
Not forked!
Checking fork status 4/5
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 9 messages in group
Not forked!
Checking fork status 5/5
0xe80c2245788834a2817c22ea7742b3f0ccf736b9 sees 10 messages in group
Not forked!

Logs of a forked run:

Testing that messages sent by alix are received by bo
0x8bb7f123353da7e08081063e8055d26c4102acad sees 1 messages in group
Alix & Bo are not forked at the beginning
Adding new members to the group...
Adding member 0x1e90f720fadf0be991a80c16d52fd5076429ab9a...
Adding member 0x8bf13036dc8c0a8cc48ad9d85e700328bc55a359...
Removing members in parallel
Removing member 0x1e90f720fadf0be991a80c16d52fd5076429ab9a...
Removing member 0x8bf13036dc8c0a8cc48ad9d85e700328bc55a359...
Checking fork status 1/5
0x8bb7f123353da7e08081063e8055d26c4102acad sees 4 messages in group
Forked!
[Error: 0x8bb7f123353da7e08081063e8055d26c4102acad should have received the message, FORK? undefined !== 0.jq75fukh9c]
Checking fork status 2/5
0x8bb7f123353da7e08081063e8055d26c4102acad sees 4 messages in group
Forked!
[Error: 0x8bb7f123353da7e08081063e8055d26c4102acad should have received the message, FORK? undefined !== 0.mx14609xvld]
Checking fork status 3/5
0x8bb7f123353da7e08081063e8055d26c4102acad sees 4 messages in group
Forked!
[Error: 0x8bb7f123353da7e08081063e8055d26c4102acad should have received the message, FORK? undefined !== 0.5b1pd29o4qd]
Checking fork status 4/5
0x8bb7f123353da7e08081063e8055d26c4102acad sees 4 messages in group
Forked!
[Error: 0x8bb7f123353da7e08081063e8055d26c4102acad should have received the message, FORK? undefined !== 0.1e1vb4l88l7j]
Checking fork status 5/5
0x8bb7f123353da7e08081063e8055d26c4102acad sees 4 messages in group
Forked!
[Error: 0x8bb7f123353da7e08081063e8055d26c4102acad should have received the message, FORK? undefined !== 0.ecncec1qgh]
Capture d’écran 2024-11-13 à 16 07 01

@nmalzieu nmalzieu requested a review from a team as a code owner November 13, 2024 17:14
await alix.conversations.sync()
await syncClientAndGroup(alix)

// NB => if we don't use Promise.all but a loop, we don't get a fork
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

If we use Promise.all we typically get a fork, the double negatives are confusing me 😆

So probably a race condition, but I guess where.

What we need to confirm from additional tests is:

  1. If we have 2 clients of the same inboxId removing members at the same time does it fork?
  2. If we have 2 clients with 2 different inboxIds does it fork?

If no to both then probably something to sure up on the SDK side

@cameronvoell
Copy link
Contributor

cameronvoell commented Nov 14, 2024

Looks like when running removals in parallel, the removal initiating client is processing their own removal commit as an external envelope which is leading to an openmls UnableToDecrypt error. I believe this could lead to user who initiated the commit being on a fork separate from the rest of the group:

2024-11-14 06:33:13.942   338-1601  INFO                      I  [libxmtp][t:510368144560] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] published intent [8] of type [UpdateGroupMembership]
2024-11-14 06:33:13.942   338-1601  INFO                      I  [libxmtp][t:510368144560] Commit sent. Stopping further publishes for this round
2024-11-14 06:33:13.945   338-1653  INFO                      I  [libxmtp][t:508718156976] Processing envelope with hash "93f45f8f094fd8cd4080d698fe9117b8bd85b3b25967fa88254816d6ae8de751"
2024-11-14 06:33:13.945   338-1653  INFO                      I  [libxmtp][t:508718156976] client [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] is about to process external envelope [3881]
2024-11-14 06:33:13.945   338-1608  WARN                      W  [libxmtp][t:509320858800] retrying function that failed with error=storage error: Diesel result error: database is locked
2024-11-14 06:33:13.946   338-1653  ERROR                     E  [libxmtp][t:508718156976] This is the wrong ratchet type.
2024-11-14 06:33:13.947   338-1653  ERROR                     E  [libxmtp][t:508718156976]   Ciphertext generation out of bounds 0
                                                                                                    	RatchetTypeError
2024-11-14 06:33:13.947   338-1653  INFO                      I  [libxmtp][t:508718156976] error is not retryable. OpenMlsProcessMessage(ValidationError(UnableToDecrypt(SecretTreeError(RatchetTypeError)))):openmls process message error: Cannot create decryption secrets from own sender ratchet or encryption secrets from the sender ratchets of other members.

surrounding libxmtp snippet from the failed run:

2024-11-14 06:33:13.904   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] syncing group
2024-11-14 06:33:13.904   338-1608  INFO                      I  [libxmtp][t:509320858800] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] syncing group
2024-11-14 06:33:13.905   338-1608  INFO                      I  [libxmtp][t:509320858800] current epoch for [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] in sync() is Epoch: [3]
2024-11-14 06:33:13.917   338-1601  INFO                      I  [libxmtp][t:510368144560] Getting inbox_ids for account addresses: ["0x7f76aafcd2860f15839c49e9875840522359feeb"]
2024-11-14 06:33:13.918   338-1608  INFO                      I  [libxmtp][t:509320858800] Getting inbox_ids for account addresses: ["0x9860c56cb181b3b6a066027b1c1c2c0de6fc1353"]
2024-11-14 06:33:13.925   338-637   INFO                      I  [libxmtp][t:516723530928] old group membership: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "19c813bf41c33f7c27017a6a7ba5d890daa68be7810875c015be6141ff887474": 2257}
2024-11-14 06:33:13.925   338-637   INFO                      I  [libxmtp][t:516723530928] updated group membership: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256}
2024-11-14 06:33:13.925   338-637   INFO                      I  [libxmtp][t:516723530928] Getting installation diff. Old: GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "19c813bf41c33f7c27017a6a7ba5d890daa68be7810875c015be6141ff887474": 2257} }. New GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256} }
2024-11-14 06:33:13.929   338-1601  INFO                      I  [libxmtp][t:510368144560] old group membership: {"153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "19c813bf41c33f7c27017a6a7ba5d890daa68be7810875c015be6141ff887474": 2257, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255}
2024-11-14 06:33:13.929   338-1601  INFO                      I  [libxmtp][t:510368144560] updated group membership: {"153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255}
2024-11-14 06:33:13.930   338-1601  INFO                      I  [libxmtp][t:510368144560] Getting installation diff. Old: GroupMembership { members: {"153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "19c813bf41c33f7c27017a6a7ba5d890daa68be7810875c015be6141ff887474": 2257, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }. New GroupMembership { members: {"153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }
2024-11-14 06:33:13.939   338-1601  INFO                      I  [libxmtp][t:510368144560] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] published intent [8] of type [UpdateGroupMembership]
2024-11-14 06:33:13.939   338-1601  INFO                      I  [libxmtp][t:510368144560] Commit sent. Stopping further publishes for this round
2024-11-14 06:33:13.942   338-1601  INFO                      I  [libxmtp][t:510368144560] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] published intent [8] of type [UpdateGroupMembership]
2024-11-14 06:33:13.942   338-1601  INFO                      I  [libxmtp][t:510368144560] Commit sent. Stopping further publishes for this round
2024-11-14 06:33:13.945   338-1653  INFO                      I  [libxmtp][t:508718156976] Processing envelope with hash "93f45f8f094fd8cd4080d698fe9117b8bd85b3b25967fa88254816d6ae8de751"
2024-11-14 06:33:13.945   338-1653  INFO                      I  [libxmtp][t:508718156976] client [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] is about to process external envelope [3881]
2024-11-14 06:33:13.945   338-1608  WARN                      W  [libxmtp][t:509320858800] retrying function that failed with error=storage error: Diesel result error: database is locked
2024-11-14 06:33:13.946   338-1653  ERROR                     E  [libxmtp][t:508718156976] This is the wrong ratchet type.
2024-11-14 06:33:13.947   338-1653  ERROR                     E  [libxmtp][t:508718156976]   Ciphertext generation out of bounds 0
                                                                                                    	RatchetTypeError
2024-11-14 06:33:13.947   338-1653  INFO                      I  [libxmtp][t:508718156976] error is not retryable. OpenMlsProcessMessage(ValidationError(UnableToDecrypt(SecretTreeError(RatchetTypeError)))):openmls process message error: Cannot create decryption secrets from own sender ratchet or encryption secrets from the sender ratchets of other members.
2024-11-14 06:33:13.948   338-1653  INFO                      I  [libxmtp][t:508718156976] Processing envelope with hash "b1d68751ee5bbd405194edbba88d6eac22e3b2a84e83efc1914b6a8fcef3c2c1"
2024-11-14 06:33:13.948   338-1653  INFO                      I  [libxmtp][t:508718156976] client [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] is about to process own envelope [3882] for intent [8]
2024-11-14 06:33:13.949   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] Validating commit for intent 8. Message timestamp: 1731594794839881000
2024-11-14 06:33:13.949   338-1653  INFO                      I  [libxmtp][t:508718156976] Group context extensions proposal found: GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256} }
2024-11-14 06:33:13.950   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting installation diff. Old: GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "19c813bf41c33f7c27017a6a7ba5d890daa68be7810875c015be6141ff887474": 2257} }. New GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256} }
2024-11-14 06:33:13.951   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] merging pending commit for intent 8
2024-11-14 06:33:13.951   338-1653  INFO                      I  [libxmtp][t:508718156976] 153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94: Storing a transcript message with 0 members added and 1 members removed and 0 metadata changes
2024-11-14 06:33:13.953   338-1653  ERROR                     E  [libxmtp][t:508718156976] Message processing errors: [OpenMlsProcessMessage(ValidationError(UnableToDecrypt(SecretTreeError(RatchetTypeError))))]
2024-11-14 06:33:13.953   338-1653  ERROR                     E  [libxmtp][t:508718156976] receive error ReceiveErrors([OpenMlsProcessMessage(ValidationError(UnableToDecrypt(SecretTreeError(RatchetTypeError))))])
2024-11-14 06:33:14.006   338-1653  INFO                      I  [libxmtp][t:508718156976] error is not retryable. AlreadyProcessed(3881):[3881] already processed
2024-11-14 06:33:14.006   338-1653  INFO                      I  [libxmtp][t:508718156976] error is not retryable. AlreadyProcessed(3882):[3882] already processed
2024-11-14 06:33:14.007   338-1653  ERROR                     E  [libxmtp][t:508718156976] Message processing errors: [AlreadyProcessed(3881), AlreadyProcessed(3882)]
2024-11-14 06:33:14.008   338-1653  ERROR                     E  [libxmtp][t:508718156976] receive error ReceiveErrors([AlreadyProcessed(3881), AlreadyProcessed(3882)])
2024-11-14 06:33:14.008   338-1653  WARN                      W  [libxmtp][t:508718156976] retrying intent ID 9. intent currently in state ToPublish
2024-11-14 06:33:14.009   338-1653  INFO                      I  [libxmtp][t:508718156976] old group membership: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255}
2024-11-14 06:33:14.009   338-1653  INFO                      I  [libxmtp][t:508718156976] updated group membership: {"aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255}
2024-11-14 06:33:14.010   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting installation diff. Old: GroupMembership { members: {"61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }. New GroupMembership { members: {"aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }
2024-11-14 06:33:14.021   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] published intent [9] of type [UpdateGroupMembership]
2024-11-14 06:33:14.021   338-1653  INFO                      I  [libxmtp][t:508718156976] Commit sent. Stopping further publishes for this round
2024-11-14 06:33:14.024   338-1653  INFO                      I  [libxmtp][t:508718156976] Processing envelope with hash "9b6e4c6e9db10456a1a20a2b414fccb411a24cd542b6c418bf1f6500f0cb96a2"
2024-11-14 06:33:14.024   338-1653  INFO                      I  [libxmtp][t:508718156976] client [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] is about to process own envelope [3883] for intent [9]
2024-11-14 06:33:14.025   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] Validating commit for intent 9. Message timestamp: 1731594794917916000
2024-11-14 06:33:14.026   338-1653  INFO                      I  [libxmtp][t:508718156976] Group context extensions proposal found: GroupMembership { members: {"aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }
2024-11-14 06:33:14.026   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting installation diff. Old: GroupMembership { members: {"153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "61dee041a990bb122015cee18884d33e184de4fb8cd35d92d3a2d3e2f1b05a9f": 2258, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }. New GroupMembership { members: {"aa45f46f2c6145f8f1094b554ddcc49ac381c7fd36e4735fedebfedadf138169": 2256, "153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94": 2254, "de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2": 2255} }
2024-11-14 06:33:14.027   338-1653  INFO                      I  [libxmtp][t:508718156976] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] merging pending commit for intent 9
2024-11-14 06:33:14.028   338-1653  INFO                      I  [libxmtp][t:508718156976] 153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94: Storing a transcript message with 0 members added and 1 members removed and 0 metadata changes
2024-11-14 06:33:15.579   338-1608  INFO                      I  [libxmtp][t:509320858800] [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] syncing group
2024-11-14 06:33:15.580   338-1608  INFO                      I  [libxmtp][t:509320858800] current epoch for [153f35dbaa000220a92abf684172ac13cb96d3cb48abb10c58a265b1f46f4a94] in sync() is Epoch: [5]
2024-11-14 06:33:15.600   338-1653  INFO                      I  [libxmtp][t:508718156976] [de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2] syncing group
2024-11-14 06:33:15.600   338-1653  INFO                      I  [libxmtp][t:508718156976] current epoch for [de309b4d48b4de25755d1f6b278faf5bf9efe11f0797c3052386a866b9eb20b2] in sync() is Epoch: [1]

happy path libxmtp logs when remove in parallel is set to false:

2024-11-14 06:46:49.796   338-1608  INFO                      I  [libxmtp][t:509320858800] current epoch for [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] in sync() is Epoch: [3]
2024-11-14 06:46:49.810   338-1608  INFO                      I  [libxmtp][t:509320858800] Getting inbox_ids for account addresses: ["0xdc90a58017ec865721da83bb1eaeb51e4311b739"]
2024-11-14 06:46:49.820   338-1653  INFO                      I  [libxmtp][t:508718156976] old group membership: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "6ee78321a0a2b13739cc6f0c58038159fe4fb8aea1514cd9751ffd0a90c21a0d": 2272, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271}
2024-11-14 06:46:49.820   338-1653  INFO                      I  [libxmtp][t:508718156976] updated group membership: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271}
2024-11-14 06:46:49.821   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting installation diff. Old: GroupMembership { members: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "6ee78321a0a2b13739cc6f0c58038159fe4fb8aea1514cd9751ffd0a90c21a0d": 2272, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271} }. New GroupMembership { members: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271} }
2024-11-14 06:46:49.834   338-1653  INFO                      I  [libxmtp][t:508718156976] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] published intent [8] of type [UpdateGroupMembership]
2024-11-14 06:46:49.834   338-1653  INFO                      I  [libxmtp][t:508718156976] Commit sent. Stopping further publishes for this round
2024-11-14 06:46:49.837   338-1608  INFO                      I  [libxmtp][t:509320858800] Processing envelope with hash "3ce410545b347bc6184a51427b374e489d96a9ed0996d3a17e43e501443935a3"
2024-11-14 06:46:49.837   338-1608  INFO                      I  [libxmtp][t:509320858800] client [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] is about to process own envelope [3960] for intent [8]
2024-11-14 06:46:49.837   338-1608  INFO                      I  [libxmtp][t:509320858800] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] Validating commit for intent 8. Message timestamp: 1731595610691298000
2024-11-14 06:46:49.838   338-1608  INFO                      I  [libxmtp][t:509320858800] Group context extensions proposal found: GroupMembership { members: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270} }
2024-11-14 06:46:49.838   338-1608  INFO                      I  [libxmtp][t:509320858800] Getting installation diff. Old: GroupMembership { members: {"9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "6ee78321a0a2b13739cc6f0c58038159fe4fb8aea1514cd9751ffd0a90c21a0d": 2272} }. New GroupMembership { members: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270} }
2024-11-14 06:46:49.838   338-1608  INFO                      I  [libxmtp][t:509320858800] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] merging pending commit for intent 8
2024-11-14 06:46:49.839   338-1608  INFO                      I  [libxmtp][t:509320858800] 2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d: Storing a transcript message with 0 members added and 1 members removed and 0 metadata changes
2024-11-14 06:46:50.376   338-1653  INFO                      I  [libxmtp][t:508718156976] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] syncing group
2024-11-14 06:46:50.377   338-1653  INFO                      I  [libxmtp][t:508718156976] current epoch for [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] in sync() is Epoch: [4]
2024-11-14 06:46:50.392   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting inbox_ids for account addresses: ["0x998e61bbf9c842daddd0b2299bf43453fc87e936"]
2024-11-14 06:46:50.401   338-1608  INFO                      I  [libxmtp][t:509320858800] old group membership: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269}
2024-11-14 06:46:50.401   338-1608  INFO                      I  [libxmtp][t:509320858800] updated group membership: {"491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269}
2024-11-14 06:46:50.403   338-1608  INFO                      I  [libxmtp][t:509320858800] Getting installation diff. Old: GroupMembership { members: {"f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269} }. New GroupMembership { members: {"491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269} }
2024-11-14 06:46:50.416   338-1608  INFO                      I  [libxmtp][t:509320858800] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] published intent [9] of type [UpdateGroupMembership]
2024-11-14 06:46:50.416   338-1608  INFO                      I  [libxmtp][t:509320858800] Commit sent. Stopping further publishes for this round
2024-11-14 06:46:50.419   338-1653  INFO                      I  [libxmtp][t:508718156976] Processing envelope with hash "2eb86c8eb95a9bd8a8f3bcfa1386d38bd6d2c39da98a194a33082da0f9d1727b"
2024-11-14 06:46:50.419   338-1653  INFO                      I  [libxmtp][t:508718156976] client [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] is about to process own envelope [3961] for intent [9]
2024-11-14 06:46:50.420   338-1653  INFO                      I  [libxmtp][t:508718156976] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] Validating commit for intent 9. Message timestamp: 1731595611272514000
2024-11-14 06:46:50.420   338-1653  INFO                      I  [libxmtp][t:508718156976] Group context extensions proposal found: GroupMembership { members: {"491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270} }
2024-11-14 06:46:50.421   338-1653  INFO                      I  [libxmtp][t:508718156976] Getting installation diff. Old: GroupMembership { members: {"491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "f8db4b55933e974c1c2b0e9b8a60e84e8320a995a3fd4566f09551f93431769e": 2273, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269} }. New GroupMembership { members: {"491bd7dceca4e472890a60ae6a05c7d3edfb056046f48ad60e965f57c6437f2b": 2271, "2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d": 2269, "9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d": 2270} }
2024-11-14 06:46:50.422   338-1653  INFO                      I  [libxmtp][t:508718156976] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] merging pending commit for intent 9
2024-11-14 06:46:50.423   338-1653  INFO                      I  [libxmtp][t:508718156976] 2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d: Storing a transcript message with 0 members added and 1 members removed and 0 metadata changes
2024-11-14 06:46:51.977   338-1608  INFO                      I  [libxmtp][t:509320858800] [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] syncing group
2024-11-14 06:46:51.978   338-1608  INFO                      I  [libxmtp][t:509320858800] current epoch for [2ba085159d1d64a08b6e5decc0353184b49386ded9273cccfa742ab27c50597d] in sync() is Epoch: [5]
2024-11-14 06:46:51.992   338-1608  INFO                      I  [libxmtp][t:509320858800] [9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d] syncing group
2024-11-14 06:46:51.993   338-1608  INFO                      I  [libxmtp][t:509320858800] current epoch for [9c5eb45e769d49f67f6818cbdc75211c6a3f5e532ea2418097b483fd5e10e32d] in sync() is Epoch: [1]

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging this pull request may close these issues.

3 participants