[Dovecot] Crash with 1.1alpha2 when lazy_expunge is enabled?
I'm seeing a crash in 1.1alpha2 when trying to expunge a folder when "lazy_expunge" is enabled:
namespace private { separator = . prefix = .EXPUNGED/ location = maildir:/mailstore/%u/Expunged hidden = yes } ... lazy_expunge = .EXPUNGED/ .EXPUNGED/ .EXPUNGED/
Commenting out "lazy_expunge" prevents the crash and mail is expunged.
It happens with and without ACL's. Attached is the log output.
The messages never actually get moved to the "Expunged" directory, but there is a "temp" file created each time:
/mailstore/testuser/Expunged/
drwx------ 6 testuser users 4096 Aug 9 11:39 . drwxr-xr-x 4 testuser users 4096 Aug 9 10:59 .. drwx------ 2 testuser users 4096 Aug 9 10:59 cur -rw------- 1 testuser users 24 Aug 9 11:39 dovecot.list.index.log -rw------- 1 testuser users 28 Aug 9 11:39 dovecot.list.index.uidmap drwx------ 5 testuser users 4096 Aug 9 11:02 .Junk -rw------- 1 testuser users 0 Aug 9 10:59 maildirfolder drwx------ 2 testuser users 4096 Aug 9 10:59 new -rw------- 1 testuser users 0 Aug 9 11:04 temp.mail4.whidbey.net.8596.7b344a891b2c6691 -rw------- 1 testuser users 0 Aug 9 11:32 temp.mail4.whidbey.net.8761.bfe04f5457e4ba7e -rw------- 1 testuser users 0 Aug 9 11:33 temp.mail4.whidbey.net.8765.44e803d87275348a -rw------- 1 testuser users 0 Aug 9 11:37 temp.mail4.whidbey.net.9031.f4a972591af59e05 -rw------- 1 testuser users 0 Aug 9 11:39 temp.mail4.whidbey.net.9125.d67b160254a01631 drwx------ 2 testuser users 4096 Aug 9 10:59 tmp
Lazy expunge works on our production 1.0.0 systems. Anyone else seen this?
Rich
dovecot: Aug 09 11:04:25 Info: imap-login: Login: user=<testuser>, method=PLAIN, rip=209.166.64.35, lip=172.16.125.4 dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Loading modules from directory: /usr/local/lib/dovecot/imap dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Module loaded: /usr/local/lib/dovecot/imap/lib01_acl_plugin.so dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Module loaded: /usr/local/lib/dovecot/imap/lib02_lazy_expunge_plugin.so dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Module loaded: /usr/local/lib/dovecot/imap/lib10_quota_plugin.so dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Module loaded: /usr/local/lib/dovecot/imap/lib11_imap_quota_plugin.so dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Effective uid=40308, gid=100, home=/var/mail/testuser dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Quota rule: root=storage=102400 mailbox= storage=256000kB messages=0 dovecot: Aug 09 11:04:25 Info: IMAP(testuser): Namespace: type=private, prefix=, sep=., inbox=yes, hidden=no, list=yes dovecot: Aug 09 11:04:25 Info: IMAP(testuser): maildir: data=/mailstore/testuser/Maildir:INDEX=MEMORY dovecot: Aug 09 11:04:25 Info: IMAP(testuser): maildir++: root=/mailstore/testuser/Maildir, index=, control=, inbox=/mailstore/testuser/Maildir dovecot: Aug 09 11:04:25 Info: IMAP(testuser): acl: initializing backend with data: vfile:/adminstore/configs/dovecot-acls:cache_secs=600 dovecot: Aug 09 11:04:25 Error: IMAP(testuser): Sending log messages too fast, throttling.. dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl: acl username = testuser dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl: owner = 1 dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl vfile: Global ACL directory: /adminstore/configs/dovecot-acls dovecot: Aug 09 11:04:26 Info: IMAP(testuser): Namespace: type=private, prefix=.EXPUNGED/, sep=., inbox=no, hidden=yes, list=no dovecot: Aug 09 11:04:26 Info: IMAP(testuser): maildir: data=/mailstore/testuser/Expunged:INDEX=MEMORY dovecot: Aug 09 11:04:26 Info: IMAP(testuser): maildir++: root=/mailstore/testuser/Expunged, index=, control=, inbox=/mailstore/testuser/Expunged dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl: initializing backend with data: vfile:/adminstore/configs/dovecot-acls:cache_secs=600 dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl: acl username = testuser dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl: owner = 1 dovecot: Aug 09 11:04:26 Info: IMAP(testuser): acl vfile: Global ACL directory: /adminstore/configs/dovecot-acls dovecot: Aug 09 11:04:28 Info: IMAP(testuser): acl vfile: reading file /adminstore/configs/dovecot-acls/Junk dovecot: Aug 09 11:04:28 Info: IMAP(testuser): acl vfile: file /mailstore/testuser/Maildir/.Junk/dovecot-acl not found dovecot: Aug 09 11:04:41 Info: IMAP(testuser): acl vfile: reading file /adminstore/configs/dovecot-acls/Junk dovecot: Aug 09 11:04:41 Info: IMAP(testuser): acl vfile: file /mailstore/testuser/Expunged/.Junk/dovecot-acl not found dovecot: Aug 09 11:04:41 Error: IMAP(testuser): file mailbox-list-maildir.c: line 161 (maildir_list_get_path): assertion failed: (mailbox_list_is_valid_existing_name(_list, name)) dovecot: Aug 09 11:04:41 Error: IMAP(testuser): Raw backtrace: imap [0x80c5df1] -> imap [0x80c5d0c] -> imap [0x806ba2d] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so [0x46cfab] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_object_init_from_name+0x1d) [0x46b93d] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_backend_vfile_acllist_rebuild+0x1c7) [0x46d627] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_backend_vfile_acllist_refresh+0x1fd) [0x46db5d] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_backend_vfile_acllist_verify+0x1f) [0x46dcdf] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so [0x46cf13] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_object_have_right+0x2d) [0x46bc6d] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so(acl_storage_rights_ctx_have_right+0x5a) [0x4703aa] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so [0x470466] -> /usr/local/lib/dovecot/imap/lib01_acl_plugin.so [0x4707d8] -> /usr/local/lib/dovecot/imap/lib02_lazy_expunge_plugin.so [0xf5ad3a] -> /usr/local/lib/dovecot/imap/lib10_q dovecot: Aug 09 11:04:41 Error: IMAP(testuser): uota_plugin.so [0x51b92a] -> imap(mailbox_open+0x46) [0x80b6ff6] -> /usr/local/lib/dovecot/imap/lib02_lazy_expunge_plugin.so [0xf5b092] -> /usr/local/lib/dovecot/imap/lib10_quota_plugin.so [0x51b0e8] -> imap(imap_expunge+0xb4) [0x8060034] -> imap(cmd_expunge+0x43) [0x805a4d3] -> imap [0x805e649] -> imap [0x805e6e3] -> imap(_client_input+0x65) [0x805ecb5] -> imap(io_loop_handler_run+0x10f) [0x80cba3f] -> imap(io_loop_run+0x28) [0x80cb3a8] -> imap(main+0x4ec) [0x806637c] -> /lib/libc.so.6(__libc_start_main+0xdc) [0x707dec] -> imap [0x8058e41] dovecot: Aug 09 11:04:41 Error: child 8596 (imap) killed with signal 6
participants (1)
-
Rich at Whidbey Telecom