offlineimap hanging on deleting messages

Tue Aug 31 16:26:54 BST 2010

I've been using offlineimap for about two months now syncing gmail plus
my own courier imap mail with my openbsd machine.  I use Gnus as the
mail client.

Just yesterday I started having problems with the sync hanging.  I think
I expired a number of messages in a folder called "newsletters" that
resides on the courier imap server.  Now when I sync, the debug log shows
the need to delete 139 messages on the server, then hangs:

<....lots of stuff....>
Deleting 139 messages (2683, 2684, 2685, 2686, 2687, 2688, 2689, 2690, 2691, 2692, 2693, 2694, 2695, 2696, 2697, 2698, 2699, 2700, 2701, 2702, 2703, 2704, 2705, 2706, 2707, 2708, 2709, 2710, 2711, 2712, 2713, 2714, 2715, 2716, 2717, 2718, 2719, 2720, 2721, 2722, 2723, 2724, 2725, 2726, 2727, 2728, 2729, 2730, 2731, 2732, 2733, 2734, 2735, 2736, 2737, 2738, 2739, 2740, 2741, 2742, 2743, 2744, 2745, 2746, 2747, 2748, 2749, 2750, 2751, 2752, 2753, 2754, 2755, 2756, 2757, 2758, 2759, 2760, 2761, 2762, 2763, 2764, 2765, 2766, 2767, 2768, 2769, 2770, 2771, 2772, 2773, 2774, 2775, 2776, 2777, 2778, 2779, 2780, 2781, 2782, 2783, 2784, 2785, 2786, 2787, 2788, 2789, 2790, 2791, 2792, 2793, 2794, 2795, 2796, 2797, 2798, 2799, 2800, 2801, 2802, 2803, 2804, 2805, 2806, 2807, 2808, 2809, 2810, 2811, 2812, 2813, 2814, 2815, 2816, 2817, 2818, 2819, 2820, 2821) in IMAP[INBOX.newsletters], LocalStatus[INBOX.newsletters]
DEBUG[imap]:   00:11.40 > GCPD25 SELECT INBOX.newsletters
DEBUG[imap]:   00:29.90 < * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)
DEBUG[imap]:   00:29.90         matched r'\* (?P<type>[A-Z-]+)( (?P<data>.*))?' => ('FLAGS', ' (\\Draft \\Answered \\Flagged \\Deleted \\Seen \\Recent)', '(\\Draft \\Answered \\Flagged \\Deleted \\Seen \\Recent)')
DEBUG[imap]:   00:29.90 untagged_responses[FLAGS] 0 += ["(\Draft \Answered \Flagged \Deleted \Seen \Recent)"]
DEBUG[imap]:   00:29.90 < * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited
DEBUG[imap]:   00:29.90         matched r'\* (?P<type>[A-Z-]+)( (?P<data>.*))?' => ('OK', ' [PERMANENTFLAGS (\\* \\Draft \\Answered \\Flagged \\Deleted \\Seen)] Limited', '[PERMANENTFLAGS (\\* \\Draft \\Answered \\Flagged \\Deleted \\Seen)] Limited')
DEBUG[imap]:   00:29.90 untagged_responses[OK] 0 += ["[PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited"]
DEBUG[imap]:   00:29.90         matched r'\[(?P<type>[A-Z-]+)( (?P<data>[^\]]*))?\]' => ('PERMANENTFLAGS', ' (\\* \\Draft \\Answered \\Flagged \\Deleted \\Seen)', '(\\* \\Draft \\Answered \\Flagged \\Deleted \\Seen)')
DEBUG[imap]:   00:29.90 untagged_responses[PERMANENTFLAGS] 0 += ["(\* \Draft \Answered \Flagged \Deleted \Seen)"]
DEBUG[imap]:   00:29.90 < * 3334 EXISTS
DEBUG[imap]:   00:29.90         matched r'\* (?P<data>\d+) (?P<type>[A-Z-]+)( (?P<data2>.*))?' => ('3334', 'EXISTS', None, None)
DEBUG[imap]:   00:29.90 untagged_responses[EXISTS] 0 += ["3334"]
DEBUG[imap]:   00:29.90 < * 0 RECENT
DEBUG[imap]:   00:29.90         matched r'\* (?P<data>\d+) (?P<type>[A-Z-]+)( (?P<data2>.*))?' => ('0', 'RECENT', None, None)
DEBUG[imap]:   00:29.90 untagged_responses[RECENT] 0 += ["0"]
DEBUG[imap]:   00:29.90 < * OK [UIDVALIDITY 1279586275] Ok
DEBUG[imap]:   00:29.90         matched r'\* (?P<type>[A-Z-]+)( (?P<data>.*))?' => ('OK', ' [UIDVALIDITY 1279586275] Ok', '[UIDVALIDITY 1279586275] Ok')
DEBUG[imap]:   00:29.90 untagged_responses[OK] 1 += ["[UIDVALIDITY 1279586275] Ok"]
DEBUG[imap]:   00:29.90         matched r'\[(?P<type>[A-Z-]+)( (?P<data>[^\]]*))?\]' => ('UIDVALIDITY', ' 1279586275', '1279586275')
DEBUG[imap]:   00:29.90 untagged_responses[UIDVALIDITY] 0 += ["1279586275"]
DEBUG[imap]:   00:29.90 < * OK [MYRIGHTS "acdilrsw"] ACL
DEBUG[imap]:   00:29.90         matched r'\* (?P<type>[A-Z-]+)( (?P<data>.*))?' => ('OK', ' [MYRIGHTS "acdilrsw"] ACL', '[MYRIGHTS "acdilrsw"] ACL')
DEBUG[imap]:   00:29.90 untagged_responses[OK] 2 += ["[MYRIGHTS "acdilrsw"] ACL"]
DEBUG[imap]:   00:29.90         matched r'\[(?P<type>[A-Z-]+)( (?P<data>[^\]]*))?\]' => ('MYRIGHTS', ' "acdilrsw"', '"acdilrsw"')
DEBUG[imap]:   00:29.90 untagged_responses[MYRIGHTS] 0 += [""acdilrsw""]
DEBUG[imap]:   00:29.90 < GCPD25 OK [READ-WRITE] Ok
DEBUG[imap]:   00:29.90         matched r'(?P<tag>GCPD\d+) (?P<type>[A-Z]+) (?P<data>.*)' => ('GCPD25', 'OK', '[READ-WRITE] Ok')
DEBUG[imap]:   00:29.90         matched r'\[(?P<type>[A-Z-]+)( (?P<data>[^\]]*))?\]' => ('READ-WRITE', None, None)
DEBUG[imap]:   00:29.90 untagged_responses[READ-WRITE] 0 += [""]
DEBUG[imap]:   00:29.90 > GCPD26 UID STORE 2683:2782 +FLAGS (\Deleted)

At this point it hangs.

I've tried setting maxsyncaccounts to 1 to eliminate the threading
issue, and changed holdconnectionopen from true to false (thinking there
might be some kind of timeout issue).  I've also looked at the server
log and the ownership/privileges of the messages on the server, and
changed them all to "a+rwx" in an effort to avoid any privileges issues
(in case that was the problem).  Lastly, I've updated from offlineimap
6.2 to 6.2.0.3. 

None of the above has made any difference.  Any ideas?  I basically
cannot receive email on my gnus client'ed machine at this point as the
sync hangs, so any help would be appreciated.

thank

Nigel