[Dovecot] 2.0.3 crashes

Mike Abbott michael.abbott at apple.com
Wed Oct 20 20:36:39 EEST 2010


One system running dovecot-2.0.3 assert-crashed in a few ways recently.  I know 2.0.3 < 2.0.5 but these have not been reported (or, presumably, fixed) since 2.0.3.  Here are some logs:

Fri Oct 15 21:50:00 server3 dovecot[349]: lda(pid 349 user user10): Error: Transaction log file /Volumes/mail/user10/dovecot.index.log: marked corrupted
Fri Oct 15 21:50:00 server3 dovecot[348]: lda(pid 348 user user10): Error: Transaction log file /Volumes/mail/user10/dovecot.index.log: marked corrupted
Fri Oct 15 21:50:00 server3 dovecot[348]: lda(pid 348 user user10): Error: Corrupted index file /Volumes/mail/user10/dovecot.index: messages_count too large (329591 > 329505)
Fri Oct 15 21:50:00 server3 dovecot[348]: lda(pid 348 user user10): Warning: fscking index file /Volumes/mail/user10/dovecot.index
Fri Oct 15 21:50:00 server3 dovecot[348]: lda(pid 348 user user10): Error: Fixed index file /Volumes/mail/user10/dovecot.index: log_file_seq 840 -> 841
Fri Oct 15 21:50:00 server3 dovecot[349]: lda(pid 349 user user10): Error: Corrupted index file /Volumes/mail/user10/dovecot.index: messages_count too large (329591 > 329505)
Fri Oct 15 21:50:00 server3 dovecot[349]: lda(pid 349 user user10): Warning: fscking index file /Volumes/mail/user10/dovecot.index
Fri Oct 15 21:50:00 server3 dovecot[349]: lda(pid 349 user user10): Error: Fixed index file /Volumes/mail/user10/dovecot.index: log_file_seq 840 -> 841
Fri Oct 15 21:50:06 server3 dovecot[348]: lda(pid 348 user user10): Error: Fixed index file /Volumes/mail/user10/dovecot.index: messages_count 329591 -> 329505
Fri Oct 15 21:50:06 server3 dovecot[349]: lda(pid 349 user user10): Error: Fixed index file /Volumes/mail/user10/dovecot.index: messages_count 329591 -> 329505
Fri Oct 15 21:50:07 server3 dovecot[349]: lda(pid 349 user user10): Error: Index /Volumes/mail/user10/dovecot.index: Lost log for seq=1 offset=0
Fri Oct 15 21:50:07 server3 dovecot[349]: lda(pid 349 user user10): Warning: fscking index file /Volumes/mail/user10/dovecot.index
Fri Oct 15 21:50:07 server3 dovecot[349]: lda(pid 349 user user10): Error: Fixed index file /Volumes/mail/user10/dovecot.index: log_file_seq 1 -> 841
Fri Oct 15 21:50:07 server3 dovecot[349]: lda(pid 349 user user10): Panic: file mail-index-write.c: line 59 (mail_index_recreate): assertion failed: (map->hdr.indexid == index->indexid)
Fri Oct 15 21:50:07 server3 dovecot[349]: lda(pid 349 user user10): Error: Raw backtrace:
	 2   libdovecot.0.dylib                  0x00000001005d3887 i_set_failure_file + 241 ->
	 3   libdovecot.0.dylib                  0x00000001005d2d50 i_syslog_error_handler + 0 ->
	 4   libdovecot.0.dylib                  0x00000001005d3040 i_debug + 0 ->
	 5   libdovecot-storage.0.dylib          0x0000000100515f9d mail_index_set_fsync_mode + 0 ->
	 6   libdovecot-storage.0.dylib          0x0000000100503146 mail_index_fsck + 3214 ->
	 7   libdovecot-storage.0.dylib          0x000000010050c355 mail_index_sync_map + 370 ->
	 8   libdovecot-storage.0.dylib          0x000000010050481c mail_index_map + 2629 ->
	 9   libdovecot-storage.0.dylib          0x000000010051676d mail_index_refresh + 90 ->
	 10  libdovecot-storage.0.dylib          0x0000000100516ab7 mail_index_open + 296 ->
	 11  libdovecot-storage.0.dylib          0x0000000100532d32 index_storage_mailbox_open + 208 ->
	 12  libdovecot-storage.0.dylib          0x000000010053cc0f maildir_get_uidvalidity_next + 2042 ->
	 13  libdovecot-storage.0.dylib          0x000000010053cc5e maildir_get_uidvalidity_next + 2121 ->
	 14  lib20_zlib_plugin.so                0x00000001006b9932 zlib_plugin_deinit + 2731 ->
	 15  libdovecot-storage.0.dylib          0x00000001004f578c mailbox_create_fd + 596 ->
	 16  libdovecot-lda.0.dylib              0x00000001005a809a mail_deliver_save_open + 263 ->
	 17  libdovecot-lda.0.dylib              0x00000001005a846f mail_deliver_save + 146 ->
	 18  libdovecot-lda.0.dylib              0x00000001005a877a mail_deliver + 131 ->
	 19  dovecot-lda                         0x00000001004e9341 main + 2577 ->
	 20  dovecot-lda                         0x00000001004e8928 start + 52
Fri Oct 15 21:50:10 server3 dovecot[348]: lda(pid 348 user user10): Error: rename(/Volumes/mail/user10/dovecot.index.tmp, /Volumes/mail/user10/dovecot.index) failed: No such file or directory
Fri Oct 15 21:50:10 server3 dovecot[348]: lda(pid 348 user user10): Error: unlink(/Volumes/mail/user10/dovecot.index.tmp) failed: No such file or directory
Fri Oct 15 21:50:10 server3 dovecot[348]: lda(pid 348 user user10): Error: Index (in-memory index): Lost log for seq=1 offset=0
Fri Oct 15 21:50:10 server3 dovecot[348]: lda(pid 348 user user10): Warning: fscking index file (in-memory index)
Fri Oct 15 21:50:10 server3 dovecot[348]: lda(pid 348 user user10): Error: Fixed index file (in-memory index): log_file_seq 1 -> 842
Fri Oct 15 21:50:18 server3 dovecot[348]: lda(pid 348 user user10): Panic: file mail-transaction-log.c: line 133 (mail_transaction_log_close): assertion failed: (log->files == NULL)
Fri Oct 15 21:50:18 server3 dovecot[348]: lda(pid 348 user user10): Error: Raw backtrace:
	 2   libdovecot.0.dylib                  0x000000010aff7887 i_set_failure_file + 241 ->
	 3   libdovecot.0.dylib                  0x000000010aff6d50 i_syslog_error_handler + 0 ->
	 4   libdovecot.0.dylib                  0x000000010aff7040 i_debug + 0 ->
	 5   libdovecot-storage.0.dylib          0x000000010af40c32 mail_transaction_log_move_to_memory + 0 ->
	 6   libdovecot-storage.0.dylib          0x000000010af3b92a mail_index_close + 61 ->
	 7   libdovecot-storage.0.dylib          0x000000010af2720d mail_index_alloc_cache_index_opened + 168 ->
	 8   libdovecot-storage.0.dylib          0x000000010af2729a mail_index_alloc_cache_index_opened + 309 ->
	 9   libdovecot-storage.0.dylib          0x000000010af14714 mail_namespace_get_storage_name + 161 ->
	 10  libdovecot-storage.0.dylib          0x000000010af1487e mail_namespaces_deinit + 29 ->
	 11  libdovecot-storage.0.dylib          0x000000010af1bef6 mail_user_alloc + 358 ->
	 12  lib10_quota_plugin.so               0x000000010b07c104 quota_mail_user_created + 287 ->
	 13  dovecot-lda                         0x000000010af0e446 main + 2838 ->
	 14  dovecot-lda                         0x000000010af0d928 start + 52


An imap process also crashed just before the above:

Date/Time:       2010-10-15 21:49:43.955 -0700
Exception Type:	 EXC_BAD_ACCESS (SIGSEGV)
Exception Codes: KERN_INVALID_ADDRESS at 0x0000000000000010
 
0   libdovecot-storage.0.dylib		0x000000010fc30540 mail_index_move_to_memory + 174
1   libdovecot-storage.0.dylib		0x000000010fc30b79 mail_index_open + 490
2   libdovecot-storage.0.dylib		0x000000010fc4cd32 index_storage_mailbox_open + 208
3   libdovecot-storage.0.dylib		0x000000010fc56c0f maildir_get_uidvalidity_next + 2042
4   libdovecot-storage.0.dylib		0x000000010fc56c5e maildir_get_uidvalidity_next + 2121
5   lib20_zlib_plugin.so		0x000000010fd83932 zlib_plugin_deinit + 2731
6   libdovecot-storage.0.dylib		0x000000010fc0f78c mailbox_create_fd + 596
7   imap				0x000000010fbe7891 cmd_select_full + 1818
8   imap				0x000000010fbe454a cmd_examine + 14
9   imap				0x000000010fbe9d88 client_command_input + 36
10  imap				0x000000010fbe9f04 client_command_input + 416
11  imap				0x000000010fbe9fdf client_handle_input + 134
12  imap				0x000000010fbe94e0 client_input + 118
13  libdovecot.0.dylib			0x000000010fce47e9 io_loop_handler_run + 234
14  libdovecot.0.dylib			0x000000010fce52bb io_loop_run + 56
15  libdovecot.0.dylib			0x000000010fcdaf98 master_service_run + 27
16  imap				0x000000010fbf1d6d main + 689
17  imap				0x000000010fbe17d8 start + 52

The backtraces may be obfuscated by compilation optimizations.


More information about the dovecot mailing list