1 (edited by paradox5566 2013-08-07 10:11:53)

Topic: Dovecot killed itself ?? Slow performance

==== Required information ====
- iRedMail version: iRedAdmin-MySQL-1.7.0
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): MySQL
- Linux/BSD distribution name and version: FreeBSD v9.1
- Related log if you're reporting an issue:

Aug 06 17:36:56 imap(test@example.com): Debug: maildir++: root=//var/vmail/vmail1/example.com/t/e/s/test-2013.08.06.14.14.33//Maildir, index=, indexpvt=, control=, inbox=//var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33//Maildir, alt=
Aug 06 17:36:56 imap(test@example.com): Debug: Namespace : type=shared, prefix=Shared/%u/, sep=/, inbox=no, hidden=no, list=children, subscriptions=yes location=maildir:/%Lh/Maildir/:INDEX=/%Lh/Maildir/Shared/%u
Aug 06 17:36:56 imap(test@example.com): Debug: shared: root=/var/run/dovecot, index=, indexpvt=, control=, inbox=, alt=
Aug 06 17:36:56 imap(test@example.com): Info: Disconnected: Logged out in=233 out=923
Aug 06 17:37:16 auth: Debug: auth client connected (pid=2902)
Aug 06 17:37:16 auth: Debug: client in: AUTH    1    PLAIN    service=imap    secured    session=KuigK0TjYQB/AAAB    lip=127.0.0.1    rip=127.0.0.1    lport=143    rport=33633    resp=<hidden>
Aug 06 17:37:16 auth-worker(2869): Debug: sql(test@example.com,127.0.0.1): query: SELECT password FROM mailbox WHERE username='test@example.com' AND active='1'
Aug 06 17:37:16 auth: Debug: client passdb out: OK    1    user=test@example.com
Aug 06 17:37:33 auth: Debug: master in: REQUEST    2663645185    2902    1    c0af169f2d21bfb9f7260b9bff6818c7    session_pid=2904
Aug 06 17:37:33 auth-worker(2869): Debug: sql(test@example.com,127.0.0.1): SELECT 'test@example.com' AS master_user, CONCAT(mailbox.storagebasedirectory, '/', mailbox.storagenode, '/', mailbox.maildir) AS home, CONCAT('*:bytes=', mailbox.quota*1048576) AS quota_rule FROM mailbox,domain WHERE mailbox.username='test@example.com' AND mailbox.domain='example.com' AND mailbox.`enableimapsecured`=1 AND mailbox.domain=domain.domain AND domain.backupmx=0 AND domain.active=1 AND mailbox.active=1
Aug 06 17:37:33 auth: Debug: master userdb out: USER    2663645185    test@example.com    master_user=test@example.com    home=/var/vmail/vmail1/example.com/t/e/s/test-2013.08.06.14.14.33/    quota_rule=*:bytes=1073741824    auth_token=079c45e55c4e1129d81ca628f5f1c201ef0063ea
Aug 06 17:37:33 imap-login: Info: Login: user=<test@example.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=2904, secured, session=<KuigK0TjYQB/AAAB>
Aug 06 17:37:33 imap: Debug: Loading modules from directory: /usr/local/lib/dovecot
Aug 06 17:37:33 imap: Debug: Module loaded: /usr/local/lib/dovecot/lib10_quota_plugin.so
Aug 06 17:37:33 imap: Debug: Module loaded: /usr/local/lib/dovecot/lib11_imap_quota_plugin.so
Aug 06 17:37:33 imap: Debug: Module loaded: /usr/local/lib/dovecot/lib20_autocreate_plugin.so
Aug 06 17:37:33 imap: Debug: Added userdb setting: plugin/master_user=test@example.com
Aug 06 17:37:33 imap: Debug: Added userdb setting: plugin/quota_rule=*:bytes=1073741824
Aug 06 17:37:33 imap(test@example.com): Warning: autocreate plugin is deprecated, use mailbox { auto } setting instead
Aug 06 17:37:33 imap(test@example.com): Debug: Effective uid=2000, gid=2000, home=/var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33/
Aug 06 17:37:33 imap(test@example.com): Debug: Quota root: name=user backend=dict args=:proxy::quotadict
Aug 06 17:37:33 imap(test@example.com): Debug: Quota rule: root=user mailbox=* bytes=1073741824 messages=0
Aug 06 17:37:33 imap(test@example.com): Debug: Quota warning: bytes=912680550 (85%) messages=0 reverse=no command=quota-warning 85 test@example.com
Aug 06 17:37:33 imap(test@example.com): Debug: Quota warning: bytes=966367641 (90%) messages=0 reverse=no command=quota-warning 90 test@example.com
Aug 06 17:37:33 imap(test@example.com): Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95 test@example.com
Aug 06 17:37:33 imap(test@example.com): Debug: Quota grace: root=user bytes=107374182 (10%)
Aug 06 17:37:33 imap(test@example.com): Debug: dict quota: user=test@digi.tw, uri=proxy::quotadict, noenforcing=0
Aug 06 17:37:33 imap(test@example.com): Debug: Namespace : type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir://var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33//Maildir/:INDEX=//var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33//Maildir/
Aug 06 17:37:33 imap(test@example.com): Debug: maildir++: root=//var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33//Maildir, index=, indexpvt=, control=, inbox=//var/vmail/vmail1/digi.tw/t/e/s/test-2013.08.06.14.14.33//Maildir, alt=
Aug 06 17:37:33 imap(test@example.com): Debug: Namespace : type=shared, prefix=Shared/%u/, sep=/, inbox=no, hidden=no, list=children, subscriptions=yes location=maildir:/%Lh/Maildir/:INDEX=/%Lh/Maildir/Shared/%u
Aug 06 17:37:33 imap(test@example.com): Debug: shared: root=/var/run/dovecot, index=, indexpvt=, control=, inbox=, alt=
Aug 06 17:37:33 imap(test@example.com): Info: Disconnected: Logged out in=233 out=923
Aug 06 17:37:48 master: Warning: Killed with signal 15 (by pid=2907 uid=0 code=kill)
Aug 06 17:37:48 master: Info: Dovecot v2.2.4 starting up
Aug 06 17:38:15 anvil: Fatal: Error reading configuration: Timeout reading config from /var/run/dovecot/config
Aug 06 17:38:15 master: Error: service(anvil): command startup failed, throttling for 2 secs
Aug 06 17:38:15 ssl-params: Fatal: Error reading configuration: Timeout reading config from /var/run/dovecot/config
Aug 06 17:38:15 master: Error: service(ssl-params): command startup failed, throttling for 2 secs
Aug 06 17:38:22 imap-login: Fatal: Error reading configuration: Timeout reading config from /var/run/dovecot/config
Aug 06 17:38:22 master: Error: service(imap-login): command startup failed, throttling for 2 secs
Aug 06 17:38:51 imap-login: Warning: Auth process not responding, delayed sending initial response (greeting): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<wBZVMUTj/wB/AAAB>
Aug 06 17:39:34 imap-login: Info: Login: user=<test@example.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=2945, secured, session=<wBZVMUTj/wB/AAAB>
Aug 06 17:39:34 imap(test@example.com): Warning: autocreate plugin is deprecated, use mailbox { auto } setting instead
Aug 06 17:39:34 imap(test@example.com): Info: Disconnected: Logged out in=233 out=924
Aug 06 17:40:19 imap-login: Info: Login: user=<test@example.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=2949, secured, session=<UTfDNUTjSwB/AAAB>
Aug 06 17:40:19 imap(test@example.com): Warning: autocreate plugin is deprecated, use mailbox { auto } setting instead
Aug 06 17:40:19 imap(example.com): Info: Disconnected: Logged out in=233 out=923
Aug 06 17:41:19 imap-login: Info: Login: user=<test@example.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=2953, secured, session=<07RVOUTjRwB/AAAB>
Aug 06 17:41:19 imap(test@example.com): Warning: autocreate plugin is deprecated, use mailbox { auto } setting instead
Aug 06 17:41:19 imap(test@example.com): Info: Disconnected: Logged out in=233 out=923
Aug 06 17:42:18 imap(test@example.com): Warning: autocreate plugin is deprecated, use mailbox { auto } setting instead
Aug 06 17:42:18 imap-login: Info: Login: user=<test@example.com>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=2963, secured, session=<azPoPETjRgB/AAAB>
Aug 06 17:42:18 imap(test@example.com): Info: Disconnected: Logged out in=233 out=923
Aug 06 17:43:18 master: Warning: Killed with signal 15 (by pid=3097 uid=0 code=kill)
Aug 06 17:43:18 imap-login: Error: read(imap) failed: Software caused connection abort
Aug 06 17:43:18 imap-login: Info: Internal login failure (pid=2966 id=1) (internal failure, 1 successful auths): user=<test@digi.tw>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured, session=<zuN6QETj8AB/AAAB>
Aug 07 09:45:29 master: Info: Dovecot v2.2.4 starting up
Aug 07 09:45:59 master: Error: service(anvil): Initial status notification not received in 30 seconds, killing the process
Aug 07 09:45:59 master: Error: service(ssl-params): Initial status notification not received in 30 seconds, killing the process
Aug 07 09:45:59 ssl-params: Fatal: master: service(ssl-params): child 2568 killed with signal 9
Aug 07 09:45:59 anvil: Fatal: master: service(anvil): child 2566 killed with signal 9
Aug 07 09:46:23 anvil: Error: Anvil client input error: Unknown command: VERSION
Aug 07 09:46:23 anvil: Error: Anvil client input error: Unknown command: VERSION
Aug 07 09:50:55 master: Error: service(imap-login): Initial status notification not received in 30 seconds, killing the process
Aug 07 09:50:55 imap-login: Fatal: master: service(imap-login): child 2821 killed with signal 9
Aug 07 09:51:25 imap-login: Info: Disconnected (disconnected before auth was ready, waited 7 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<dNaAx1Hj5gB/AAAB>
Aug 07 09:59:09 master: Warning: Killed with signal 15 (by pid=2846 uid=0 code=kill)
Aug 07 09:59:10 master: Info: Dovecot v2.2.4 starting up

====
Too slow with logging in and checking new mails in Roundcube.(Other were fine)
It's nearly drove me crazy.
Does anybody help deal with this???

2

Re: Dovecot killed itself ?? Slow performance

So many 'Error', ''Fatal' in log.
Maybe you can try to upgrade to the latest Dovecot-2.2.5.