Kopano - Mailempfang nicht mehr möglich


#1

Hallo,

unerklärlicher Weise kann ich plötzlich keine Mails mehr empfangen.
Die Mails werden per fetchmail vom externen Server noch gelesen und dann mit postfix weiter verarbeitet. Eine Zustellung in das jeweilige Postfach von Kopano scheitert allerdings mit folgender Fehlermeldung:

Jan 14 15:55:57 server fetchmail[7397]: 6 messages (5 seen) for stephan@xxxx.de at imap.strato.de.
Jan 14 15:55:57 server postfix/smtpd[23707]: connect from localhost[127.0.0.1]
Jan 14 15:55:57 server postfix/smtpd[23707]: 81A019A24A7: client=localhost[127.0.0.1]
Jan 14 15:55:57 server postfix/cleanup[23710]: 81A019A24A7: message-id=97F6F7C7-2988-4167-94C5-EC26853D7A54@absender.de
Jan 14 15:55:57 server fetchmail[7397]: reading message stephan@xxxx.de@imap.strato.de:6 of 6 (2999 header octets) (4871 body octets) not flushed
Jan 14 15:55:57 server postfix/qmgr[19812]: 81A019A24A7: from=absender@absender.de, size=8211, nrcpt=1 (queue active)
Jan 14 15:55:57 server postfix/smtpd[23707]: disconnect from localhost[127.0.0.1]
Jan 14 15:55:59 server postfix/smtpd[23715]: 4BF309A24FB: client=localhost[127.0.0.1], orig_queue_id=81A019A24A7, orig_client=localhost[127.0.0.1]
Jan 14 15:55:59 server postfix/cleanup[23710]: 4BF309A24FB: message-id=97F6F7C7-2988-4167-94C5-EC26853D7A54@absender.de
Jan 14 15:55:59 server postfix/smtpd[23715]: disconnect from localhost[127.0.0.1]
Jan 14 15:55:59 server postfix/qmgr[19812]: 4BF309A24FB: from=absender@absender.de, size=8926, nrcpt=1 (queue active)
Jan 14 15:55:59 server amavis[3725]: (03725-05) Passed CLEAN {RelayedInternal}, LOCAL [127.0.0.1]:42549 [192.0.27.101] absender@absender.de -> stephan@xxxx.de, Queue-ID: 81A019A24A7, Message-ID: 97F6F7C7-2988-4167-94C5-EC26853D7A54@ego-saar.de, mail_id: 3Ge3Dl5S5Mng, Hits: 3.871, size: 8210, queued_as: 4BF309A24FB, 1753 ms
Jan 14 15:55:59 server postfix/smtp[23711]: 81A019A24A7: to=stephan@xxxx.de, relay=127.0.0.1[127.0.0.1]:10024, delay=1.9, delays=0.12/0.02/0/1.8, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 4BF309A24FB)
Jan 14 15:55:59 server postfix/qmgr[19812]: 81A019A24A7: removed
Jan 14 15:55:59 server postfix/lmtp[23716]: 4BF309A24FB: to=stephan@xxxx.de, relay=127.0.0.1[127.0.0.1]:2003, delay=0.2, delays=0.1/0.01/0.09/0, dsn=5.1.1, status=bounced (host 127.0.0.1[127.0.0.1] said: 503 5.1.1 Internal error during delivery (in reply to DATA command))
Jan 14 15:55:59 server postfix/cleanup[23710]: 8146C9A24FE: message-id=20180114145559.8146C9A24FE@server.zuhause.xx
Jan 14 15:55:59 server postfix/bounce[23719]: 4BF309A24FB: sender non-delivery notification: 8146C9A24FE
Jan 14 15:55:59 server postfix/qmgr[19812]: 8146C9A24FE: from=<>, size=10937, nrcpt=1 (queue active)
Jan 14 15:55:59 server postfix/qmgr[19812]: 4BF309A24FB: removed

Postfix/lmtp bricht mit einem Fehler 503 ab.
Ich habe bei UCS 4.1 und die aktuelle Kopano-Version installiert.
Kennt jemand das Problem oder kann mir weiterhelfen?

Viele Grüße
Stephan


#2

Hallo @StephanT,

gibt es denn Fehlermeldungen in dagent.log oder server.log von Kopano?


#3

Hallo,

ich habe jetzt in den logs nachgeschaut.

In der server.log stehen folgende, auch Fehlermeldungen:

Sun Jan 14 16:48:22 2018: [ notice] Starting server version 8,1,2,1, pid 12107
Sun Jan 14 16:48:22 2018: [crit ] Unable to bind to port 236: Die Adresse wird bereits verwendet. This is usually caused by another process (most likely another server) already using this port. This program will terminate now.
Sun Jan 14 16:59:10 2018: [ notice] Starting server version 8,1,2,1, pid 12948
Sun Jan 14 16:59:10 2018: [crit ] Unable to bind to port 236: Die Adresse wird bereits verwendet. This is usually caused by another process (most likely another server) already using this port. This program will terminate now.
Sun Jan 14 17:02:08 2018: [warning] Shutting down
Sun Jan 14 17:02:10 2018: [ notice] Server shutdown complete.
Sun Jan 14 17:06:40 2018: [ notice] Starting server version 8,1,2,1, pid 5261
Sun Jan 14 17:06:40 2018: [warning] WARNING: Unable to place upgrade lockfile: Keine Berechtigung
Sun Jan 14 17:30:03 2018: [error ] LDAP search error: Can’t contact LDAP server. Will unbind, reconnect and retry.
Mon Jan 15 05:45:02 2018: [error ] Previous message logged 100 times
Mon Jan 15 05:45:02 2018: [error ] LDAP search error: Can’t contact LDAP server. Will unbind, reconnect and retry.
Mon Jan 15 11:00:04 2018: [error ] Previous message logged 49 times
Mon Jan 15 11:00:04 2018: [warning] SQL [00000152] info: Try to reconnect
Mon Jan 15 11:15:04 2018: [error ] LDAP search error: Can’t contact LDAP server. Will unbind, reconnect and retry.
Mon Jan 15 20:42:34 2018: [error ] Previous message logged 90 times
Mon Jan 15 20:42:34 2018: [warning] SQL [00000041] info: Try to reconnect

In der dagent.log steht folgendes:
Sun Jan 14 12:13:10 2018: [error ] [ 5189] StatsClient bind /tmp/.6d3d9b211a1ed21.sock: Permission denied
Sun Jan 14 12:13:10 2018: [error ] [ 5189] StatsClient bind /tmp/.5a9466881cef1a26.sock: Permission denied
Sun Jan 14 12:13:10 2018: [error ] [ 5189] StatsClient bind /tmp/.45a55dec2e1136b1.sock: Permission denied
Sun Jan 14 12:13:10 2018: [ notice] [ 5189] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 5189
Sun Jan 14 12:20:37 2018: [error ] [ 9257] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 12:24:46 2018: [error ] [ 9893] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 12:32:55 2018: [error ] [10762] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 13:07:45 2018: [ notice] [ 5189] LMTP service will now exit
Sun Jan 14 13:15:51 2018: [error ] [ 5726] StatsClient bind /tmp/.5164b285118dcba.sock: Permission denied
Sun Jan 14 13:15:51 2018: [error ] [ 5726] StatsClient bind /tmp/.51ec7d8e3ebef320.sock: Permission denied
Sun Jan 14 13:15:51 2018: [error ] [ 5726] StatsClient bind /tmp/.4935994a1fbeb28b.sock: Permission denied
Sun Jan 14 13:15:51 2018: [ notice] [ 5726] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 5726
Sun Jan 14 13:27:25 2018: [error ] [ 9860] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 13:47:35 2018: [error ] [12047] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 13:53:38 2018: [error ] [12538] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 14:12:11 2018: [error ] [14907] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 14:12:12 2018: [error ] [14910] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 14:55:34 2018: [ notice] [ 5726] LMTP service will now exit
Sun Jan 14 14:59:56 2018: [error ] [ 5053] StatsClient bind /tmp/.64b9d98f4ab21897.sock: Permission denied
Sun Jan 14 14:59:56 2018: [error ] [ 5053] StatsClient bind /tmp/.5a9ca44528a5382.sock: Permission denied
Sun Jan 14 14:59:56 2018: [error ] [ 5053] StatsClient bind /tmp/.e96e7e45ffac89c.sock: Permission denied
Sun Jan 14 14:59:56 2018: [ notice] [ 5053] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 5053
Sun Jan 14 15:07:53 2018: [ notice] [ 5053] LMTP service will now exit
Sun Jan 14 15:07:53 2018: [error ] [11655] StatsClient bind /tmp/.6a04ddf3444cc114.sock: Permission denied
Sun Jan 14 15:07:53 2018: [error ] [11655] StatsClient bind /tmp/.738ff8ff7162e470.sock: Permission denied
Sun Jan 14 15:07:53 2018: [error ] [11655] StatsClient bind /tmp/.79b951cf24b0cda2.sock: Permission denied
Sun Jan 14 15:07:53 2018: [ notice] [11655] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 11655
Sun Jan 14 15:15:39 2018: [error ] [19159] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:27:42 2018: [error ] [20195] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:29:44 2018: [error ] [21018] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:29:44 2018: [error ] [21020] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:29:44 2018: [error ] [21029] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:29:44 2018: [error ] [21030] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:35:47 2018: [error ] [21700] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 15:55:59 2018: [error ] [23717] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 16:06:06 2018: [error ] [24705] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 16:07:56 2018: [ notice] [11655] LMTP service will now exit
Sun Jan 14 16:12:36 2018: [error ] [ 5073] StatsClient bind /tmp/.26637536f863a15.sock: Permission denied
Sun Jan 14 16:12:36 2018: [error ] [ 5073] StatsClient bind /tmp/.30e637f98b1d934.sock: Permission denied
Sun Jan 14 16:12:36 2018: [error ] [ 5073] StatsClient bind /tmp/.74e59d4a4f36e626.sock: Permission denied
Sun Jan 14 16:12:36 2018: [ notice] [ 5073] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 5073
Sun Jan 14 17:02:07 2018: [ notice] [ 5073] LMTP service will now exit
Sun Jan 14 17:06:38 2018: [error ] [ 5080] StatsClient bind /tmp/.3e18c2747dee50c9.sock: Permission denied
Sun Jan 14 17:06:38 2018: [error ] [ 5080] StatsClient bind /tmp/.556e7f841fb5171b.sock: Permission denied
Sun Jan 14 17:06:38 2018: [error ] [ 5080] StatsClient bind /tmp/.69137b275dce7a9c.sock: Permission denied
Sun Jan 14 17:06:38 2018: [ notice] [ 5080] Starting kopano-dagent LMTP mode version 8,1,2,1 (1), pid 5080
Sun Jan 14 17:08:02 2018: [error ] [ 7583] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 17:14:04 2018: [error ] [ 9087] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 20:27:41 2018: [error ] [27241] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 21:20:08 2018: [error ] [ 474] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 21:40:18 2018: [error ] [ 2449] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 21:42:19 2018: [error ] [ 2610] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Sun Jan 14 21:56:27 2018: [error ] [ 3950] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 01:06:05 2018: [error ] [22556] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 01:40:21 2018: [error ] [25769] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 04:35:50 2018: [error ] [10697] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 04:37:49 2018: [error ] [10870] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 06:16:42 2018: [error ] [19869] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 06:18:43 2018: [error ] [20039] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 07:41:25 2018: [error ] [27797] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 20:17:46 2018: [error ] [31875] Unable to create temp file for email delivery. Please check write-access in /tmp directory. Error: Permission denied
Mon Jan 15 20:42:01 2018: [error ] [ 1824] Client disconnected

Aufgrund der Fehlermeldung “Error: Permission denied” habe ich die Rechte im Verzeichnis /tmp von 700 auf 777 geändert. Nun funktioniert die Weiterleitung.

Allerdings wurde die Berechtigung des tmp-Verzeichnisses im Vorfeld nicht geändert. Dies macht mich etwas stutzig.
Hier noch der Eintrag für das tmp Verzeichnis:
drwxrwxrwx 6 mysql root 4096 Jan 15 20:55 tmp

Auch an den Eintstellungen des mysql-Servers wurde nichts verändert. Als weitere Anwendung, die den mysql-Server nutzt läuft bei mir agorum.

Ich hoffe ich konnte etwas Licht ins Dunkel bringen und es hilft weiter.

Viele Grüße
Stephan