Currently running 1.0.2 with 1.1b1 listening on a different port. On
first pop3 login on 1.1b1 I received an immediate disconnect (I was
testing with telnet) with the log entries below. Subsequent logins work
fine. Other accounts that I tried did not have the same problem. So this
is probably isolated to some bad indexes or something, but I thought I
should let Timo know.
Regards,
David
Here is what I found in the logs:
-----------------------------------------
Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: Fixed index
file /home/vmail/x/xxxxx.com/xxx/Maildir/dovecot.index: first_recent_uid
0 -> 1
Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: file
mail-index-view-sync.c: line 304 (mail_index_view_sync_begin): assertion
failed: (view->index->map->hdr.messages_count >=
ctx->finish_min_msg_count)
Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: Raw
backtrace: pop3 [0x80cd42e] -> pop3(i_fatal+0) [0x80cce93] ->
pop3(mail_index_view_sync_begin+0x228) [0x80a91d2] ->
pop3(index_mailbox_sync_init+0xac) [0x8093abe] ->
pop3(maildir_storage_sync_init+0x11e) [0x80646a1] ->
pop3(mailbox_sync_init+0x1b) [0x80bb428] -> pop3(mailbox_sync+0x18)
[0x80bb49b] -> pop3 [0x80582f5] -> pop3(client_create+0x237) [0x80587ac]
-> pop3 [0x805afe8] -> pop3(main+0x95) [0x805b0e4] ->
/lib/tls/libc.so.6(__libc_start_main+0xd3) [0x477de3] -> pop3 [0x80581ed]
Sep 28 15:51:14 kelly-a dovecot: child 8112 (pop3) killed with signal 6
Other information
------------------------
$ cat /etc/redhat-release
Red Hat Enterprise Linux ES release 4 (Nahant Update 5)
$ uname -r
2.6.9-42.0.10.ELsmp
$ ./dovecot -n
# 1.1.beta1: /usr/local/dovecot-1.1/etc/dovecot.conf
base_dir: /var/run/dovecot-1.1/
protocols: imap pop3
listen(default): *:9143
listen(imap): *:9143
listen(pop3): *:9110
ssl_disable: yes
login_dir: /var/run/dovecot-1.1/login
login_executable(default): /usr/local/dovecot-1.1/libexec/dovecot/imap-login
login_executable(imap): /usr/local/dovecot-1.1/libexec/dovecot/imap-login
login_executable(pop3): /usr/local/dovecot-1.1/libexec/dovecot/pop3-login
login_greeting: postoffice.no-ip.com (1.1)
login_log_format_elements: user=<%u@%d> method=%m rip=%r lip=%l %c
login_process_per_connection: no
verbose_proctitle: yes
first_valid_uid: 89
mail_uid: vmail
mail_gid: vmail
mail_executable(default): /usr/local/dovecot-1.1/libexec/dovecot/imap
mail_executable(imap): /usr/local/dovecot-1.1/libexec/dovecot/imap
mail_executable(pop3): /usr/local/dovecot-1.1/libexec/dovecot/pop3
mail_plugin_dir(default): /usr/local/dovecot-1.1/lib/dovecot/imap
mail_plugin_dir(imap): /usr/local/dovecot-1.1/lib/dovecot/imap
mail_plugin_dir(pop3): /usr/local/dovecot-1.1/lib/dovecot/pop3
mail_log_prefix: %Us(%u)[%p]:
pop3_uidl_format(default): %08Xu%08Xv
pop3_uidl_format(imap): %08Xu%08Xv
pop3_uidl_format(pop3): %f
pop3_client_workarounds(default):
pop3_client_workarounds(imap):
pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh
auth default:
mechanisms: plain login digest-md5 cram-md5
user: nobody
username_translation: %@
username_format: %Lu
verbose: yes
passdb:
driver: sql
args: /usr/local/dovecot-1.1/etc/dovecot-sql.conf
userdb:
driver: prefetch
socket:
type: listen
client:
path: /var/run/dovecot-1.1/auth-client
mode: 432
plugin:
quota: dict:user::proxy::quota
dict:
quota: mysql:/usr/local/dovecot-1.1/etc/dovecot-dict-quota.conf
On Fri, 2007-09-28 at 16:12 -0700, David Jonas wrote:> Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: Fixed index > file /home/vmail/x/xxxxx.com/xxx/Maildir/dovecot.index: first_recent_uid > 0 -> 1Fixed now: http://hg.dovecot.org/dovecot/rev/392a49f0c69a> Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: file > mail-index-view-sync.c: line 304 (mail_index_view_sync_begin): assertion > failed: (view->index->map->hdr.messages_count >= ctx->finish_min_msg_count)I guess you don't have a core file from this crash? It probably had something to do with upgrading index files from v1.0, but it still shouldn't happen and I'd like to fix it. -------------- next part -------------- A non-text attachment was scrubbed... Name: signature.asc Type: application/pgp-signature Size: 189 bytes Desc: This is a digitally signed message part URL: <http://dovecot.org/pipermail/dovecot/attachments/20070930/4b6780ef/attachment-0002.bin>
Timo Sirainen wrote:> On Fri, 2007-09-28 at 16:12 -0700, David Jonas wrote: >> Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: Fixed index >> file /home/vmail/x/xxxxx.com/xxx/Maildir/dovecot.index: first_recent_uid >> 0 -> 1 > > Fixed now: http://hg.dovecot.org/dovecot/rev/392a49f0c69a > >> Sep 28 15:51:14 kelly-a dovecot: POP3(xxx at xxxxx.com)[8112]: file >> mail-index-view-sync.c: line 304 (mail_index_view_sync_begin): assertion >> failed: (view->index->map->hdr.messages_count >= ctx->finish_min_msg_count) > > I guess you don't have a core file from this crash? It probably had > something to do with upgrading index files from v1.0, but it still > shouldn't happen and I'd like to fix it.I dug around the filesystem and didn't see a core file hanging around. What do I need to do to get a core dump? I can set a script to login into a bunch of accounts until it gets one that core dumps, if that'd be helpful. I just need to know how to make it produce the core and where the core might be written.