[SOLVED] Testsystem: Mailzustellung intern bricht ab

sbudach

Moin,

ich bin gerade dabei mir Grommunio anzusehen und habe die aktuelle ISO auf einer KVM unter Proxmox installiert und nach Anleitung konfiguriert. Ich habe dann einen Account angelegt und kann z.B. Mails an externe Domains versenden, aber nicht innerhalb des Grommunio-Systems. Dazu hatte ich einen zweiten User konfiguriert, an den ich dann testweise über das WebIF und per telnet mal Mails geschickt habe.

Soweit ich das nachvollziehen kann, bricht der postfix/stmpd immer nach einem timeout die Verbindung ab. Bis dahin sieht mal die Mail auch im Admin in der Gromox mail queue. Im Log sieht das dann so aus:

Jan 21 18:58:06 grommunio postfix/master[2195]: daemon started -- version 3.5.9, configuration /etc/postfix Jan 21 18:58:07 grommunio systemd[1]: Started Postfix Mail Transport Agent. Jan 21 19:00:35 grommunio postfix/smtpd[14637]: connect from localhost[::1] Jan 21 19:00:35 grommunio postfix/smtpd[14637]: 69B5D21783: client=localhost[::1] Jan 21 19:00:35 grommunio postfix/cleanup[14643]: 69B5D21783: message-id=<6641dbf2791e0142830747e0ba7bec70@grommunio> Jan 21 19:00:35 grommunio postfix/qmgr[2197]: 69B5D21783: from=<budy@stephanbudach.de>, size=1754, nrcpt=1 (queue active) Jan 21 19:00:35 grommunio postfix/smtpd[14637]: disconnect from localhost[::1] helo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 Jan 21 19:00:35 grommunio postfix/smtp[14645]: 69B5D21783: to=<wohnzimmer@stephanbudach.de>, relay=::1[::1]:24, delay=0.1, delays=0.06/0.01/0/0.02, dsn=2.0.0, status=sent (250 Ok queue-id: 1) Jan 21 19:00:35 grommunio postfix/qmgr[2197]: 69B5D21783: removed Jan 21 19:00:35 grommunio postfix/smtpd[14637]: connect from localhost[::1] Jan 21 19:00:35 grommunio postfix/smtpd[14637]: 8BA9E21787: client=localhost[::1] Jan 21 19:03:35 grommunio postfix/smtpd[14637]: lost connection after DATA (941 bytes) from localhost[::1] Jan 21 19:03:35 grommunio postfix/smtpd[14637]: disconnect from localhost[::1] ehlo=2 starttls=1 mail=1 rcpt=1 data=0/1 commands=5/6 Jan 21 19:03:35 grommunio postfix/cleanup[14643]: 8BA9E21787: message-id=<6641dbf2791e0142830747e0ba7bec70@grommunio> Jan 21 19:07:17 grommunio postfix/smtpd[15349]: connect from localhost[::1] Jan 21 19:07:17 grommunio postfix/smtpd[15349]: 9875721969: client=localhost[::1] Jan 21 19:07:17 grommunio postfix/cleanup[15353]: 9875721969: message-id=<20220121180717.9875721969@grommunio.stephanbudach.de> Jan 21 19:07:17 grommunio postfix/qmgr[2197]: 9875721969: from=<budy@stephanbudach.de>, size=807, nrcpt=1 (queue active) Jan 21 19:07:17 grommunio postfix/smtpd[15349]: disconnect from localhost[::1] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7 Jan 21 19:07:17 grommunio postfix/smtp[15355]: 9875721969: to=<wohnzimmer@stephanbudach.de>, relay=::1[::1]:24, delay=0.06, delays=0.02/0.01/0/0.02, dsn=2.0.0, status=sent (250 Ok queue-id: 3) Jan 21 19:07:17 grommunio postfix/qmgr[2197]: 9875721969: removed Jan 21 19:07:17 grommunio postfix/smtpd[15349]: connect from localhost[::1] Jan 21 19:07:17 grommunio postfix/smtpd[15349]: A63AF2196B: client=localhost[::1] Jan 21 19:10:17 grommunio postfix/smtpd[15349]: lost connection after DATA (885 bytes) from localhost[::1] Jan 21 19:10:17 grommunio postfix/cleanup[15353]: A63AF2196B: message-id=<20220121180717.9875721969@grommunio.stephanbudach.de> Jan 21 19:10:17 grommunio postfix/smtpd[15349]: disconnect from localhost[::1] ehlo=2 starttls=1 mail=1 rcpt=1 data=0/1 commands=5/6
Ich komme einfach nicht drauf, was das Problem sein könnte.

crpb

Morsche,
Updates ( zypper refresh && zypper update ) haste alle gemacht und mal durchgestartet?
Zertifikat hast du installiert? openssl s_client -starttls host:25 / https://github.com/rbsec/sslscan (kann man backen und auch auf anderen hosts ausführen)
Was kommt denn bei dem simplen 'telnet host 25' 'ehlo mein.host.name` usw.. zurück? Oder einfach nur 'Timeout'/Abbruch irgendwann?
Vielleicht mal nach dem 'Gromox' Dienst schauen in /var/log/gromox/ ... wenn sonst nix an is..
Und zu faul für Telnet? Dann das Schweizer Taschenmesser probieren :-).

Und ansonten halt mal postfix mit '-Dvv' oder so wenn nix hilft :-)

sbudach

Die Updates habe ich bereits gemacht und einen reboot hat die VM auch schon hinter sich. Der Postfix auf Port 25 nimmt auch ganz normal Mails an, aber er scheint sie nicht an die Postfächer loszuwerden.

Logs wären ja auch echt nett, aber in /var/log/gromox sind genau 0 Dateien… Es ist wie, als wenn da etwas mit dem delivery wäre, aber was da nicht geht, erschließt sich mir nicht… im Log der delivery-queue sieht das so aus:

grommunio:/etc/gromox # journalctl -fu gromox-delivery -- Logs begin at Thu 2022-01-20 14:35:48 CET. -- Jan 22 11:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 12:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 13:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 14:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 15:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 16:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 17:58:05 grommunio delivery[1572]: [alias_resolve]: I-1612: refreshed alias map (0 entries) Jan 22 18:21:56 grommunio delivery[1572]: [remote_delivery]: SMTP output to ::1 ok Jan 22 18:24:56 grommunio delivery[1572]: [remote_delivery]: Local code: Connection timed out. SMTP reason string: . Recipient(s) affected: Jan 22 18:24:56 grommunio delivery[1572]: [remote_delivery]: wohnzimmer@stephanbudach.de

crpb

Also die laufen so lokal die E-Mails ja?
Was sagt denn

nslookup stephanbudach.de
host stephanbudach.de
dig stephanbudach.de MX

Was auch immer grad da ist.. Die zeigen Intern 'also zumindest vom Grommunio aus' auf sich selbst und nicht auf die externe IP?

Also ich glaube der versucht einen MX-Lookup und trifft auf ein Ziel das halt keine tolle Antwort/Gegenstelle bietet.. Ich mutmaße hier mal :-0

https://github.com/grommunio/gromox/blob/96ab64592104487c6d697e99893adc5df2a937d8/mda/remote_delivery.cpp#L358

crpb

Hängt eigentlich irgendwas im mailq / postqueue -p?

WalterH

Bitte das mal kontrollieren: https://community.grommunio.com/d/259-zweite-e-mail-domain/5

sbudach

WalterH Bitte das mal kontrollieren: https://community.grommunio.com/d/259-zweite-e-mail-domain/5

Habe ich gemacht und das passt. Ich habe nur eine Domain und da stimmt das:

MariaDB [grommunio]> SELECT 1 FROM domains WHERE domain_status=0 AND domainname='stephanbudach.de';
+---+
| 1 |
+---+
| 1 |
+---+
1 row in set (0,000 sec)

MariaDB [grommunio]> select domainname,homedir from domains WHERE domain_status=0;
+------------------+--------------------------+
| domainname       | homedir                  |
+------------------+--------------------------+
| stephanbudach.de | /var/lib/gromox/domain/1 |
+------------------+--------------------------+
1 row in set (0,001 sec)

Die Ordner existieren auch. Was ich auch gerade noch nachgeschaut habe sind die Ordner des eigebtlichen accounts und da verhält es sich so, dass die exchange.sqlite3 schon angefasst wurde, als ich meine letzte Mail manuell per telnet eingeliefdert habe.

Dennoch zeigt das WebIF nichts an und einen Outlook-Client zum testen habe ich nicht.

WalterH

Die Updates von gestern installieren und dann den Server rebooten.
Sollte diese Version zeigen:
rpm -qi gromox | head -4 Name : gromox Version : 1.13.39.9c645a8d Release : lp153.6.1 Architecture: x86_64
Für das Web IFC, anderen Browser oder privates Fenster versuchen.

sbudach

Die Updates hatte ich installiert und sie werden auch entsprechend angezeigt:

grommunio:/etc/gromox # rpm -qi gromox | head -4
Name        : gromox
Version     : 1.13.39.9c645a8d
Release     : lp153.6.1
Architecture: x86_64

Neue Session in einem privaten Browserfenster habe ich auch gerade probiert, aber die Inbox bleibt leer. Der Gesendet-Ordner hingegen zeigt die ganzen Testmails an, welche ich vom WebIF aus versendet habe.

Ich glaube, ich mache die ganze Appliance noch mal neu….

sbudach

Okay… VM neu gemacht, erneut von ISO gebootet, Appliance installiert. Dann, weil es schon ein laufendes Mailsystem gibt, dnsmaq installiert und einen lokalen MX record gesetzt, so dass für die Appilance der MX-Record auf die Appliance zeigt.

Dann grommunio-setup ausgeführt - ins Admin IF eingeloggt und eine die domain stephanbudach.de eingetragen, zwei User dazu und im User-WebIF angemeldet. So weit so gut.

Dann habe ich eine Telnet Session gegen den Postfix auf Port 25 gestartet und eine Mail von einer externen Domain an beide Grummunio-Accounts geschickt. Die gehen dann von Postfix zur gromox-delivery und dort geht's dann mit dem bereits beschriebenen Problem nicht mehr weiter… im Log des delivery service sehe ich nur wieder sowas…

Jan 23 16:52:25 grommunio delivery[1562]: [remote_delivery]: Local code: Connection timed out. SMTP reason string: . Recipient(s) affected:
Jan 23 16:52:25 grommunio delivery[1562]: [remote_delivery]:        budy@stephanbudach.de

Ich weiß ja nicht, ob da nicht eher so was wie local…delivery stehen müsste, denn die Domain stephanbudach.de ist die einzige und somit die default domain in gromox.

WalterH

Zeigt der Befehlt mailq irgend was an?
was zeigt gromox-mailq an?
was zeigt dieser Befehl dig MX stephanbudach.de und wohin zeigt der dyndns Pointer?

sbudach

Moin Walter,

Alles mal in einem Rutsch…

grommunio:~ # mailq
Mail queue is empty
grommunio:~ # gromox-mailq
#Qid    date                  msg_size        Fid  Sender Recipient
grommunio:~ # dig MX stephanbudach.de

; <<>> DiG 9.16.6 <<>> MX stephanbudach.de
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 51617
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 2

;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags:; udp: 4096
;; QUESTION SECTION:
;stephanbudach.de.		IN	MX

;; ANSWER SECTION:
stephanbudach.de.	0	IN	MX	1 grommunio.stephanbudach.de.

;; ADDITIONAL SECTION:
grommunio.stephanbudach.de. 0	IN	A	10.10.0.5

;; Query time: 0 msec
;; SERVER: 127.0.0.1#53(127.0.0.1)
;; WHEN: Mo Jan 24 21:34:52 CET 2022
;; MSG SIZE  rcvd: 103

Dyndns kannst du erst mal weglassen, denn ich habe einen Split-DNS mit eigenem, internen DNS Server. Ich bin mir auch mittlerweile sicher, dass das Problem irgendwo im gromox-delivery liegt. Leider gibt es da so gut wie keine Möglichkeit mehr Logs rauszubekommen, selbst wenn man gromox-delivery direkt von der CLI gestartet wird.

Ich habe die man-pages von delivery und delivery-queue gelesen, aber es scheint keine Möglichkeit zu geben, irgendwelchge Debug-Logs zu konfigurieren.

Ich würde mal davon ausgehen, dass gromox-delivery am Ende die Mails direkt in die User-Ordner liefert - warum gromox-delivery versucht über SMTP - und vor allem, wohin?, auszuliefern anstatt, sie local abzulegen. Schlussendlich habe ich noch mal versucht mir strace weiterzukommen, aber da gibt es überhaupt keine Ausgaben - offenbar setzt gromox-delivery keinen einzigen Systemcall nachdem es gestartet wurde…

sbudach

Ich habe heute Morgen die ganze VM nochmal aus der OVA-Vorlage installiert, aber das Ergebnis bleibt dasselbe. Es werden keine Mails an die lokalen Postfächer ausgeliefert. Die Mails verbleiben 120s in der gromox-queue, bevor sie dann einfach gelöscht werden. Ich nehme mal an die 120s sind der normale tcp-timeout.

sbrunner

sbudach Ich hatte das gleiche Verhalten mit der OVA Vorlage auch schon. Hast du die Installation einmal mit der ISO gemacht? Diese funktionierte bei mir auf Anhieb.

sbudach

sbrunner

Ja, ich hatte zuerst die ISO probiert, weil mir das für KVM besser erschien und auch die VM hatte ich mind. drei mal neu installiert. Das OVA habe ich erst heute probiert, aber das Problem ist dasselbe. Wie im anderen Thread, glaube ich dass die Installation einfach unvollständig ist - sonst wüsste der gromox-delivery, wohin er liefern sollte.

Man kann ja auch im tcpdump eindeutig sehen, dass er nicht mal versucht irgendwohin eine neue SMTP-Verbindung hin aufzubauen.

grommunio:/var/lib/gromox # grep -i select /tmp/strace.txt
sendto(3, ":\0\0\0\3SELECT `value` FROM `option"..., 62, MSG_DONTWAIT|MSG_NOSIGNAL, NULL, 0) = 62
write(1, "[authmgr]: backend selection non"..., 34) = 34

Here's what gromox-delivery tries to load and fails:

grommunio:/var/lib/gromox # grep -i enoent /tmp/strace.txt | grep gromox
openat(AT_FDCWD, "/etc/gromox/delivery/domain_list.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/domain_list.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/log_plugin.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/log_plugin.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/ldap_adaptor.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/ldap_adaptor.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/mysql_adaptor.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/cpid.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/lcid.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/lang_charset.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/mime_extension.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/folder_names.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/authmgr.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/authmgr.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/mysql_adaptor.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/alias_resolve.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/alias_resolve.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/exmdb_local.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/local_bounce", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/exmdb_list.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/exmdb_list.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/propnames.txt", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/gromox/delivery/mlist_bounce", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/delivery/remote_delivery.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/gromox/remote_delivery.cfg", O_RDONLY) = -1 ENOENT (No such file or directory)

sbudach

Oha… das Problem ist gelöst… es lag an vertauschten FQDN- und Hostname-Settings im Netzwerk-Setup. Jetzt läuft die interne Zustellung…