Sync_client fails after upgrade 2.3.16 to 2.4.4

[Bron Gondwana]

On Mon, Nov 29, 2010 at 02:15:57PM +1030, Stephen Carr wrote:
> Dear Bron
> 
> Here is what is in the syncserver at the same time - note USER1
> matches the prior info for the sync_client.
> 
> Nov 27 14:22:03 proxy syncserver[18772]: Deleted mailbox
> DELETED.user.USERX.drafts.49A9E880
> Nov 27 14:22:03 proxy syncserver[18772]: Deleted mailbox
> DELETED.user.USERX.sent.49A9E885
> Nov 27 14:22:37 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:24:43 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:24:45 proxy syncserver[18772]: Repacking mailbox user.USERY
> Nov 27 14:25:06 proxy syncserver[18772]: skiplist: checkpointed
> /maildata/imap/user/m/USERZ.seen (3 records, 400 bytes) in 0 seconds
> Nov 27 14:25:24 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:29:09 proxy syncserver[18772]: skiplist: checkpointed
> /maildata/imap/user/m/USERY.seen (4 records, 424 bytes) in 0 seconds
> Nov 27 14:29:54 proxy syncserver[18772]: skiplist: checkpointed
> /maildata/imap/user/l/USERA.seen (5 records, 500 bytes) in 0 seconds
> Nov 27 14:30:00 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:30:08 proxy syncserver[18785]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:35:05 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:35:13 proxy syncserver[18785]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:38:08 proxy syncserver[18772]: skiplist: checkpointed
> /maildata/imap/user/c/USER1.seen (19 records, 2820 bytes) in 0
> seconds
> Nov 27 14:39:18 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:39:42 proxy syncserver[18785]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:39:43 proxy syncserver[18785]: Repacking mailbox user.USERA
> Nov 27 14:39:51 proxy syncserver[18785]: Index upgrade:
> DELETED.user.USERA.drafts.49A9E61A (10 -> 12)
> Nov 27 14:39:51 proxy syncserver[18785]: Index upgrade:
> DELETED.user.USERA.sent.49A9E623 (10 -> 12)
> Nov 27 14:39:51 proxy syncserver[18785]: Index upgrade:
> DELETED.user.USERA.trash.49A9E61F (10 -> 12)
> Nov 27 14:39:51 proxy syncserver[18785]: skiplist: checkpointed
> /maildata/imap/user/s/USERA.seen (28 records, 2188 bytes) in 0
> seconds
> Nov 27 14:39:51 proxy syncserver[18785]: Deleted mailbox
> DELETED.user.USERA.drafts.49A9E61A
> Nov 27 14:39:51 proxy syncserver[18785]: Deleted mailbox
> DELETED.user.USERA.sent.49A9E623
> Nov 27 14:39:51 proxy syncserver[18785]: Deleted mailbox
> DELETED.user.USERA.trash.49A9E61F
> Nov 27 14:40:00 proxy syncserver[18772]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:40:08 proxy syncserver[18785]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:42:24 proxy syncserver[18788]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:45:00 proxy syncserver[18790]: login:
> brooks.civeng.adelaide.edu.au [129.127.16.1] cyrus DIGEST-MD5 User
> logged in
> Nov 27 14:45:01 proxy syncserver[18790]: skiplist: checkpointed
> /maildata/imap/user/s/USERB.seen (50 records, 3968 bytes) in 0
> seconds
> Nov 27 14:45:03 proxy syncserver[18790]: Index upgrade:
> DELETED.user.USERD.drafts.49A9E7F0 (10 -> 12)
> Nov 27 14:45:03 proxy syncserver[18790]: Index upgrade:
> DELETED.user.USERD.sent.49A9E7E7 (10 -> 12)
> Nov 27 14:45:03 proxy syncserver[18790]: Index upgrade:
> DELETED.user.USERD.trash.49A9E7E2 (10 -> 12)

So it's upgrading the mailboxes just to delete them!  Oh well.
Nothing wrong with that.  Just a bit of extra IO.
 
> I did a full reconstruct on sync_client & syncserver after reverting
> back to 2.3.16 - going OK.

Good.  Glad to hear it.

> The reason I wanted to install 2.4.4 was that a user was getting
> Thunderbird (IMAP) to freeze and I suspect it was due to a locking
> problem - the user is a PA to Head of School with about 9,800 email
> in INBOX. No other users have the problem or have reported a similar
> problem.

2.4.x is definitely a lot better about locking!

The "DELETED" folders there are all delayed delete folders that may not
have been noticed by the previous sync code, but are noticed now because
sync_client's "do_user" does the deleted namespace as well now.

As for your earlier errors...

There is a bug with sync_crc calculation in 2.4.4, so I would recommend
waiting for 2.4.5 before upgrading again.  I'm hoping we can release
2.4.5 very soon, and Kolab will be building their RPMs pretty quickly
too I imagine.

I'm based in Melbourne, so I'm in the same timezone, near enough :)

Regards,

Bron.