master: Error: service(log): command startup failed, throttling for 2 secs
master: Error: service(log): command startup failed, throttling for 2 secs
i see that due restart on any machine acting as IMAP/POP3 proxy why?
Am 25.10.2014 um 17:51 schrieb Timo Sirainen:
at least 2.2.14 and 2.2.15 with a really simple config
2.2.15: /etc/dovecot/dovecot.conf
OS: Linux 3.16.6-200.fc20.x86_64 x86_64 Fedora release 20 (Heisenbug)
auth_cache_size = 1 k auth_cache_ttl = 30 mins auth_failure_delay = 5 secs auth_mechanisms = CRAM-MD5 DIGEST-MD5 APOP LOGIN PLAIN SCRAM-SHA-1 auth_username_chars = abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ01234567890.-_@% auth_username_translation = %@AaBbCcDdEeFfGgHhIiJjKkLlMmNnOoPpQqRrSsTtUuVvWwXxYyZz auth_worker_max_count = 5 disable_plaintext_auth = no imap_capability = IMAP4 IMAP4rev1 ACL RIGHTS=texk NAMESPACE CHILDREN SORT QUOTA THREAD=ORDEREDSUBJECT UNSELECT IDLE login_greeting = login_log_format = %{login_status}: %s login_log_format_elements = %u %r %m %c mail_max_userip_connections = 100 passdb { args = /etc/dovecot/sql.conf driver = sql } pop3_client_workarounds = outlook-no-nuls oe-ns-eoh protocols = imap service auth { unix_listener /var/spool/postfix/private/auth { group = postfix mode = 0660 user = postfix } } service imap-login { inet_listener imap { address = * port = 143 } inet_listener imaps { address = * port = 993 } process_limit = 100 process_min_avail = 0 service_count = 1 vsz_limit = 64 M } shutdown_clients = no ssl_cert = </etc/postfix/certs/localhost.pem ssl_cipher_list = ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-GCM-SHA384:DHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:DHE-RSA-AES128-SHA256:DHE-RSA-AES256-SHA256:DHE-RSA-AES128-SHA:DHE-RSA-AES256-SHA:AES128-GCM-SHA256:AES256-GCM-SHA384:AES128-SHA:AES256-SHA:DHE-RSA-CAMELLIA128-SHA:DHE-RSA-CAMELLIA256-SHA:CAMELLIA128-SHA:CAMELLIA256-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA:!SSLv2 ssl_key = </etc/postfix/certs/localhost.pem ssl_options = no_compression ssl_prefer_server_ciphers = yes userdb { args = static uid=15000 gid=15000 home=/dev/null driver = static } version_ignore = yes
On 25 Oct 2014, at 08:59, Reindl Harald <h.reindl@thelounge.net> wrote:
Nothing else is logged besides that error? Does the log process start later on? I can't really think of a good reason why it would happen.
Am 25.10.2014 um 18:18 schrieb Timo Sirainen:
nothing else, see blow including "journalctl" output starting with the ssh-login and the service-status is showing "dovecot/log" finally
[root@srv-rhsoft:~]$ systemctl status dovecot.service dovecot.service - Dovecot IMAP/POP3 Proxy Loaded: loaded (/usr/lib/systemd/system/dovecot.service; enabled) Active: active (running) since Sa 2014-10-25 18:19:56 CEST; 52s ago Main PID: 1156 (dovecot) CGroup: /system.slice/dovecot.service ├─1156 /usr/sbin/dovecot -F ├─1157 dovecot/anvil ├─1158 dovecot/log └─1160 dovecot/config
Okt 25 18:19:56 srv-rhsoft.rhsoft.net systemd[1]: Started Dovecot IMAP/POP3 Proxy. Okt 25 18:19:56 srv-rhsoft.rhsoft.net dovecot[1156]: master: Dovecot v2.2.15 starting up for imap (core dumps disabled)
[root@srv-rhsoft:~]$ cat messages [root@srv-rhsoft:~]$ > messages [root@srv-rhsoft:~]$ > maillog [root@srv-rhsoft:~]$ systemctl restart dovecot [root@srv-rhsoft:~]$ cat messages Oct 25 18:19:56 srv-rhsoft systemd: Stopping Dovecot IMAP/POP3 Proxy... Oct 25 18:19:56 srv-rhsoft systemd: Starting Dovecot IMAP/POP3 Proxy... Oct 25 18:19:56 srv-rhsoft systemd: Started Dovecot IMAP/POP3 Proxy. [root@srv-rhsoft:~]$ cat maillog Oct 25 18:19:56 srv-rhsoft dovecot: master: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Oct 25 18:19:56 srv-rhsoft dovecot: log: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Oct 25 18:19:56 srv-rhsoft dovecot: master: Error: service(log): command startup failed, throttling for 2 secs Oct 25 18:19:56 srv-rhsoft dovecot: master: Dovecot v2.2.15 starting up for imap (core dumps disabled)
Okt 25 18:19:45 srv-rhsoft.rhsoft.net systemd[1]: Starting Session 11436 of user root. Okt 25 18:19:45 srv-rhsoft.rhsoft.net systemd-logind[824]: New session 11436 of user root. Okt 25 18:19:45 srv-rhsoft.rhsoft.net systemd[1]: Started Session 11436 of user root. Okt 25 18:19:45 srv-rhsoft.rhsoft.net sshd[1057]: pam_unix(sshd:session): session opened for user root by (uid=0) Okt 25 18:19:56 srv-rhsoft.rhsoft.net systemd[1]: Stopping Dovecot IMAP/POP3 Proxy... Okt 25 18:19:56 srv-rhsoft.rhsoft.net dovecot[31209]: master: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Okt 25 18:19:56 srv-rhsoft.rhsoft.net dovecot[31211]: log: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Okt 25 18:19:56 srv-rhsoft.rhsoft.net dovecot[31209]: master: Error: service(log): command startup failed, throttling for 2 secs Okt 25 18:19:56 srv-rhsoft.rhsoft.net systemd[1]: Starting Dovecot IMAP/POP3 Proxy... Okt 25 18:19:56 srv-rhsoft.rhsoft.net systemd[1]: Started Dovecot IMAP/POP3 Proxy. Okt 25 18:19:56 srv-rhsoft.rhsoft.net dovecot[1156]: master: Dovecot v2.2.15 starting up for imap (core dumps disabled) Okt 25 18:20:01 srv-rhsoft.rhsoft.net systemd[1]: Starting Session 11439 of user root.
On 25 Oct 2014, at 09:42, Timo Sirainen <tss@iki.fi> wrote:
http://hg.dovecot.org/dovecot-2.2/rev/d35288713625 probably fixes this?
Am 25.10.2014 um 23:08 schrieb Timo Sirainen:
indeed
i wonder how there can be a difference between start/restart since systemd kills the whole service cgroup with all processes and so how does the new startup know anyting about a previous run?
however, the message disappears with the patch
[root@srv-rhsoft:~]$ cat messages Oct 25 23:41:13 srv-rhsoft systemd: Stopping Dovecot IMAP/POP3 Proxy... Oct 25 23:41:14 srv-rhsoft systemd: Starting Dovecot IMAP/POP3 Proxy... Oct 25 23:41:14 srv-rhsoft systemd: Started Dovecot IMAP/POP3 Proxy.
[root@srv-rhsoft:~]$ cat maillog Oct 25 23:41:13 srv-rhsoft dovecot: master: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Oct 25 23:41:13 srv-rhsoft dovecot: log: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill) Oct 25 23:41:14 srv-rhsoft dovecot: master: Dovecot v2.2.15 starting up for imap (core dumps disabled)
participants (2)
-
Reindl Harald
-
Timo Sirainen