Postfix stellt Mails an einen bestimmten Benutzer nicht zu

classic Classic list List threaded Threaded
13 messages Options
Reply | Threaded
Open this post in threaded view
|

Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
Liebe Liste,

ich hoffe ihr könnt mir helfen. Suche nun schon seit Tagen nach einer
Lösung und es ist mir echt ein Rätsel.

Ich habe einen Postfix Server auf Debian Jessie am Laufen. Version
postfix   2.11.3 mit Paket postfix-ldap.

Als imap Server kommt Cyrus 2.4.17 zum Einsatz.

Ich möchte euch nicht mit endlosen Konfigdateien belasten, daher hier
nur erstmal der Auszug aus den Logs, wo es wohl hakt.

Der Server nimmt die Mail an und stellt sie nur für einen bestimmten neu
angelegten Benutzer nicht zu. Alles andere wird zugestellt. Diese Mails
befinden sich nun schon seit Tagen in der Queue. Es gibt auch keine
Rückmeldung wie sonst, dass die Mails nicht zugestellt werden können.

So sieht die Mailzustellung normalerweise aus:

root@server:/# grep 80D41241356 /var/log/mail.log

Apr 25 11:39:11 server postfix/smtpd[12439]: 80D41241356:
client=kes.domain.de[IP]
Apr 25 11:39:11 server postfix/cleanup[12442]: 80D41241356:
message-id=<[hidden email]>
Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356:
from=[hidden email], size=997, nrcpt=1 (queue active)
Apr 25 11:39:11 server postfix/pipe[12445]: 80D41241356:
to=[hidden email], orig_to=[hidden email], relay=cyrus, delay=0.1,
delays=0/0.02/0/0.08, dsn=2.0.0, status=sent (delivered via cyrus service)
Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356: removed

Bei diesem Benutzer bleibt die Mail in der Queue hängen, es passiert
nichts weiter:

root@server:/# grep 706AB240FC4 /var/log/mail.log
Apr 25 11:38:45 server postfix/smtpd[12439]: 706AB240FC4:
client=kes.domain.de[IP]
Apr 25 11:38:45 server postfix/cleanup[12442]: 706AB240FC4:
message-id=[hidden email]
Apr 25 11:38:45 server postfix/qmgr[12406]: 706AB240FC4:
from=[hidden email], size=1006, nrcpt=1 (queue active)

Nach Einschalten diverser Debuglevel bekomme ich dies hier, erst wieder
die erste Mail:

Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward[3]: set
user_attr: atest
Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 3005 egid
201**7*
Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 110 egid 116*
Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward: path
/home/atest/.forward expand_status 0 look_status -1
Apr 29 11:28:57 server postfix/local[15680]: deliver_mailbox[3]: local
atest recip [hidden email] exten deliver [hidden email]
exp_from

und hier die nicht zugestellte Mail:

Apr 29 11:30:36 server postfix/local[15680]: deliver_dotforward[3]: set
user_attr: mvogt
Apr 29 11:30:36 server postfix/local[15680]:*set_eugid: euid 3146 egid 2017*

danach passiert nichts mehr.

Hat jemand eine Idee nach was ich noch suchen könnte, warum kommt es
hier nicht zur zweiten Zeile?

Info: /etc/groups: postfix:x:116:und /etc/passwd postfix:x:110:116:

Ich hatte auch schon versucht, den Benutzer ganz neu anzulegen, gleiches
Prinzip. Es kann also auch sein, dass es nicht mehr möglich ist für
irgendeinen neuen Benutzer eine neue Mailbox anzulegen.

Was hatte ich ganz am Anfang getan?

Dies war ein neuer Benutzer für den ich nur ein Forwarding per Mailalias
im openldap-Server eingetragen hatte. Dies hat funktioniert, bis er doch
eine Mailbox wollte. Diesen Fall hatte ich noch nie. Der Server läuft
schon seit ca. 2013 anstandslos durch.*
*

Bitte gebt Bescheid, falls ich noch andere Infos hinzufügen soll, danke!

herzliche Grüße

Sylvia*
*

--
Sylvia Gelman
IT Organisation
Technische Universität Darmstadt
Institut für Automatisierungstechnik und Mechatronik - IAT
Fachgebiet Regelungsmethoden und Robotik (RMR)
Landgraf-Georg-Str. 4
D-64283 Darmstadt
Tel.: +49 6151 / 16-25055
Email: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
als Nachtrag, da hat die Mailinglistensoftware die Formatierung versucht
gerade zu biegen:

die zwei Zeilen sehen im Original so aus:

Apr 29 11:28:57 server postfix/local[15680]: set_eugid: euid 3005 egid 2017
Apr 29 11:28:57 server postfix/local[15680]: set_eugid: euid 110 egid 116
>
> und hier die nicht zugestellte Mail:
>
> Apr 29 11:30:36 server postfix/local[15680]: deliver_dotforward[3]:
> set user_attr: mvogt
> Apr 29 11:30:36 server postfix/local[15680]:set_eugid: euid 3146 egid 2017
>
Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Kai Fürstenberg
In reply to this post by Sylvia Gelman
Am 07.05.2019 um 11:28 schrieb Sylvia Gelman:

> Liebe Liste,
>
> ich hoffe ihr könnt mir helfen. Suche nun schon seit Tagen nach einer
> Lösung und es ist mir echt ein Rätsel.
>
> Ich habe einen Postfix Server auf Debian Jessie am Laufen. Version
> postfix   2.11.3 mit Paket postfix-ldap.
>
> Als imap Server kommt Cyrus 2.4.17 zum Einsatz.
>
> Ich möchte euch nicht mit endlosen Konfigdateien belasten, daher hier
> nur erstmal der Auszug aus den Logs, wo es wohl hakt.

die belasten nicht, die sind sogar nötig...
Für die Konfig einfach die Ausgabe von 'postconf -n' posten.

> Der Server nimmt die Mail an und stellt sie nur für einen bestimmten neu
> angelegten Benutzer nicht zu. Alles andere wird zugestellt. Diese Mails
> befinden sich nun schon seit Tagen in der Queue. Es gibt auch keine
> Rückmeldung wie sonst, dass die Mails nicht zugestellt werden können.

Die Logs unten helfen mir nicht wirklich weiter, sie erscheinen mir
unvollständig.

Daher rate ich mal ins Blaue (Es ist lange her, dass ich mit Cyrus
gearbeitet habe):

Cyrus hat eine eigene Benutzerliste. Eventuell hast du nur Postfix den
Benutzer bekannt gemacht aber nicht Cyrus?

Das würde sich auch damit decken, dass der Benutzer nun eine Mailbox
wollte: die Mail wird von Postfix angenommen, da der Benutzer existiert,
kann die Mail mangels Mailbox aber nicht Cyrus übergeben.

Es müsste aber eine Fehlermeldung im Log dazu geben, evtl eine die die
Queue-ID nicht beinhaltet und daher über ein grep nach selbiger nicht zu
finden ist.


> So sieht die Mailzustellung normalerweise aus:
>
> root@server:/# grep 80D41241356 /var/log/mail.log
>
> Apr 25 11:39:11 server postfix/smtpd[12439]: 80D41241356:
> client=kes.domain.de[IP]
> Apr 25 11:39:11 server postfix/cleanup[12442]: 80D41241356:
> message-id=<[hidden email]>
> Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356:
> from=[hidden email], size=997, nrcpt=1 (queue active)
> Apr 25 11:39:11 server postfix/pipe[12445]: 80D41241356:
> to=[hidden email], orig_to=[hidden email], relay=cyrus, delay=0.1,
> delays=0/0.02/0/0.08, dsn=2.0.0, status=sent (delivered via cyrus service)
> Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356: removed
>
> Bei diesem Benutzer bleibt die Mail in der Queue hängen, es passiert
> nichts weiter:
>
> root@server:/# grep 706AB240FC4 /var/log/mail.log
> Apr 25 11:38:45 server postfix/smtpd[12439]: 706AB240FC4:
> client=kes.domain.de[IP]
> Apr 25 11:38:45 server postfix/cleanup[12442]: 706AB240FC4:
> message-id=[hidden email]
> Apr 25 11:38:45 server postfix/qmgr[12406]: 706AB240FC4:
> from=[hidden email], size=1006, nrcpt=1 (queue active)
>
> Nach Einschalten diverser Debuglevel bekomme ich dies hier, erst wieder
> die erste Mail:
>
> Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward[3]: set
> user_attr: atest
> Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 3005 egid
> 201**7*
> Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 110 egid 116*
> Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward: path
> /home/atest/.forward expand_status 0 look_status -1
> Apr 29 11:28:57 server postfix/local[15680]: deliver_mailbox[3]: local
> atest recip [hidden email] exten deliver [hidden email]
> exp_from
>
> und hier die nicht zugestellte Mail:
>
> Apr 29 11:30:36 server postfix/local[15680]: deliver_dotforward[3]: set
> user_attr: mvogt
> Apr 29 11:30:36 server postfix/local[15680]:*set_eugid: euid 3146 egid 2017*
>
> danach passiert nichts mehr.
>
> Hat jemand eine Idee nach was ich noch suchen könnte, warum kommt es
> hier nicht zur zweiten Zeile?
>
> Info: /etc/groups: postfix:x:116:und /etc/passwd postfix:x:110:116:
>
> Ich hatte auch schon versucht, den Benutzer ganz neu anzulegen, gleiches
> Prinzip. Es kann also auch sein, dass es nicht mehr möglich ist für
> irgendeinen neuen Benutzer eine neue Mailbox anzulegen.
>
> Was hatte ich ganz am Anfang getan?
>
> Dies war ein neuer Benutzer für den ich nur ein Forwarding per Mailalias
> im openldap-Server eingetragen hatte. Dies hat funktioniert, bis er doch
> eine Mailbox wollte. Diesen Fall hatte ich noch nie. Der Server läuft
> schon seit ca. 2013 anstandslos durch.*
> *
>
> Bitte gebt Bescheid, falls ich noch andere Infos hinzufügen soll, danke!
>
> herzliche Grüße
>
> Sylvia*
> *
>


--
Kai Fürstenberg

PM an: kai at fuerstenberg punkt ws

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
Hallo Kai (jetzt nochmal an alle),

danke für deine Nachricht! Es sieht für mich so aus als ob der Cyrus
darauf überhaupt nicht reagiert, weil die Anfrage bei ihm garnicht an
kommt. Der Benutzer hat eine Mailbox aus der heraus auch geschickt
werden kann. Der postfix/local scheint ja der letzte Prozess zu sein
bevor es an Cyrus weitergegeben wird.

Das Log ist leider nicht unvollständig, dies sind die letzten 2 Zeilen
die bei der nicht zugestellten Mail auftauchen:
Apr 29 11:30:36 glia postfix/local[15680]: deliver_dotforward[3]: set
user_attr: michavogt
Apr 29 11:30:36 glia postfix/local[15680]: set_eugid: euid 3146 egid 2017

dann kommt nichts mehr...

ein funktionierender Logeintrag:
Apr 29 11:28:57 glia postfix/local[15680]: deliver_dotforward[3]: set
user_attr: atest
Apr 29 11:28:57 glia postfix/local[15680]: set_eugid: euid 3005 egid 2017
Apr 29 11:28:57 glia postfix/local[15680]: set_eugid: euid 110 egid 116
Apr 29 11:28:57 glia postfix/local[15680]: deliver_dotforward: path
/home/atest/.forward expand_status 0 look_status -1
Apr 29 11:28:57 glia postfix/local[15680]: deliver_mailbox[3]: local
atest recip [hidden email] exten deliver
[hidden email] exp_from
Apr 29 11:28:57 glia postfix/local[15680]: been_here: mailbox atest: 0
Apr 29 11:28:57 glia postfix/local[15680]: connect to subsystem
private/cyrus

viele Grüße

Sylvia

postconf -n

alias_database = hash:/etc/aliases
alias_maps = hash:/etc/aliases, ldap:/etc/postfix/aliases.ldap
append_dot_mydomain = no
biff = no
broken_sasl_auth_clients = yes
canonical_maps =
config_directory = /etc/postfix
delay_warning_time = 4h
inet_interfaces = all
mailbox_command =
mailbox_size_limit = 0
mailbox_transport = cyrus
masquerade_domains = rt.e-technik.domain.de rtr.domain.de
message_size_limit = 104857600
mydestination = $myhostname, localhost, mailhost, mailhost.$mydomain,
localhost.$mydomain, $mydomain, $myorigin, domain.de, rtr.domain.de
mydomain = rt.e-technik.domain.de
myhostname = server.rt.e-technik.domain.de
mynetworks = IP/25 127.0.0.0/8
myorigin = /etc/mailname
readme_directory = no
recipient_delimiter = +
relay_domains = hash:/etc/postfix/relay_domains
relayhost = mailout.hrz.tu-darmstadt.de
relocated_maps = hash:/etc/postfix/relocated
smtp_tls_CAfile = /etc/ldap/ssl/TU-CAcertN.pem
smtp_tls_loglevel = 1
smtp_tls_security_level = may
smtp_tls_session_cache_database = btree:${data_directory}/smtp_scache
smtpd_banner = $myhostname ESMTP
smtpd_recipient_restrictions =
permit_mynetworks,permit_sasl_authenticated,reject_unauth_destination
smtpd_relay_restrictions =
permit_mynetworks,permit_sasl_authenticated,defer_unauth_destination
smtpd_sasl_auth_enable = yes
smtpd_sasl_local_domain =
smtpd_sasl_security_options = noanonymous, noplaintext
smtpd_sasl_tls_security_options = noanonymous
smtpd_tls_CAfile = /etc/ldap/ssl/TU-CAcertN.pem
smtpd_tls_auth_only = yes
smtpd_tls_cert_file = /etc/ldap/ssl/server.pem
smtpd_tls_key_file = /etc/ldap/ssl/serverkey.pem
smtpd_tls_loglevel = 1
smtpd_tls_security_level = may
smtpd_tls_session_cache_database = btree:${data_directory}/smtpd_scache
smtpd_use_tls = yes
virtual_alias_maps = ldap:/etc/postfix/ldap-aliases.cf


Am 07.05.19 um 11:44 schrieb Kai Fürstenberg:

> Am 07.05.2019 um 11:28 schrieb Sylvia Gelman:
>> Liebe Liste,
>>
>> ich hoffe ihr könnt mir helfen. Suche nun schon seit Tagen nach einer
>> Lösung und es ist mir echt ein Rätsel.
>>
>> Ich habe einen Postfix Server auf Debian Jessie am Laufen. Version
>> postfix   2.11.3 mit Paket postfix-ldap.
>>
>> Als imap Server kommt Cyrus 2.4.17 zum Einsatz.
>>
>> Ich möchte euch nicht mit endlosen Konfigdateien belasten, daher hier
>> nur erstmal der Auszug aus den Logs, wo es wohl hakt.
> die belasten nicht, die sind sogar nötig...
> Für die Konfig einfach die Ausgabe von 'postconf -n' posten.
>
>> Der Server nimmt die Mail an und stellt sie nur für einen bestimmten neu
>> angelegten Benutzer nicht zu. Alles andere wird zugestellt. Diese Mails
>> befinden sich nun schon seit Tagen in der Queue. Es gibt auch keine
>> Rückmeldung wie sonst, dass die Mails nicht zugestellt werden können.
> Die Logs unten helfen mir nicht wirklich weiter, sie erscheinen mir
> unvollständig.
>
> Daher rate ich mal ins Blaue (Es ist lange her, dass ich mit Cyrus
> gearbeitet habe):
>
> Cyrus hat eine eigene Benutzerliste. Eventuell hast du nur Postfix den
> Benutzer bekannt gemacht aber nicht Cyrus?
>
> Das würde sich auch damit decken, dass der Benutzer nun eine Mailbox
> wollte: die Mail wird von Postfix angenommen, da der Benutzer existiert,
> kann die Mail mangels Mailbox aber nicht Cyrus übergeben.
>
> Es müsste aber eine Fehlermeldung im Log dazu geben, evtl eine die die
> Queue-ID nicht beinhaltet und daher über ein grep nach selbiger nicht zu
> finden ist.
>
>
>> So sieht die Mailzustellung normalerweise aus:
>>
>> root@server:/# grep 80D41241356 /var/log/mail.log
>>
>> Apr 25 11:39:11 server postfix/smtpd[12439]: 80D41241356:
>> client=kes.domain.de[IP]
>> Apr 25 11:39:11 server postfix/cleanup[12442]: 80D41241356:
>> message-id=<[hidden email]>
>> Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356:
>> from=[hidden email], size=997, nrcpt=1 (queue active)
>> Apr 25 11:39:11 server postfix/pipe[12445]: 80D41241356:
>> to=[hidden email], orig_to=[hidden email], relay=cyrus, delay=0.1,
>> delays=0/0.02/0/0.08, dsn=2.0.0, status=sent (delivered via cyrus service)
>> Apr 25 11:39:11 server postfix/qmgr[12406]: 80D41241356: removed
>>
>> Bei diesem Benutzer bleibt die Mail in der Queue hängen, es passiert
>> nichts weiter:
>>
>> root@server:/# grep 706AB240FC4 /var/log/mail.log
>> Apr 25 11:38:45 server postfix/smtpd[12439]: 706AB240FC4:
>> client=kes.domain.de[IP]
>> Apr 25 11:38:45 server postfix/cleanup[12442]: 706AB240FC4:
>> message-id=[hidden email]
>> Apr 25 11:38:45 server postfix/qmgr[12406]: 706AB240FC4:
>> from=[hidden email], size=1006, nrcpt=1 (queue active)
>>
>> Nach Einschalten diverser Debuglevel bekomme ich dies hier, erst wieder
>> die erste Mail:
>>
>> Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward[3]: set
>> user_attr: atest
>> Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 3005 egid
>> 201**7*
>> Apr 29 11:28:57 server postfix/local[15680]: *set_eugid: euid 110 egid 116*
>> Apr 29 11:28:57 server postfix/local[15680]: deliver_dotforward: path
>> /home/atest/.forward expand_status 0 look_status -1
>> Apr 29 11:28:57 server postfix/local[15680]: deliver_mailbox[3]: local
>> atest recip [hidden email] exten deliver [hidden email]
>> exp_from
>>
>> und hier die nicht zugestellte Mail:
>>
>> Apr 29 11:30:36 server postfix/local[15680]: deliver_dotforward[3]: set
>> user_attr: mvogt
>> Apr 29 11:30:36 server postfix/local[15680]:*set_eugid: euid 3146 egid 2017*
>>
>> danach passiert nichts mehr.
>>
>> Hat jemand eine Idee nach was ich noch suchen könnte, warum kommt es
>> hier nicht zur zweiten Zeile?
>>
>> Info: /etc/groups: postfix:x:116:und /etc/passwd postfix:x:110:116:
>>
>> Ich hatte auch schon versucht, den Benutzer ganz neu anzulegen, gleiches
>> Prinzip. Es kann also auch sein, dass es nicht mehr möglich ist für
>> irgendeinen neuen Benutzer eine neue Mailbox anzulegen.
>>
>> Was hatte ich ganz am Anfang getan?
>>
>> Dies war ein neuer Benutzer für den ich nur ein Forwarding per Mailalias
>> im openldap-Server eingetragen hatte. Dies hat funktioniert, bis er doch
>> eine Mailbox wollte. Diesen Fall hatte ich noch nie. Der Server läuft
>> schon seit ca. 2013 anstandslos durch.*
>> *
>>
>> Bitte gebt Bescheid, falls ich noch andere Infos hinzufügen soll, danke!
>>
>> herzliche Grüße
>>
>> Sylvia*
>> *
>>
>
--
Sylvia Gelman
IT Organisation
Technische Universität Darmstadt
Institut für Automatisierungstechnik und Mechatronik - IAT
Fachgebiet Regelungsmethoden und Robotik (RMR)
Landgraf-Georg-Str. 4
Gebäude S3|10 Raum 409
D-64283 Darmstadt
Tel.: +49 6151 / 16-25055
Email: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Kai Fürstenberg
Hallo Sylvia,

Am 07.05.2019 um 12:13 schrieb Sylvia Gelman:
> danke für deine Nachricht! Es sieht für mich so aus als ob der Cyrus
> darauf überhaupt nicht reagiert, weil die Anfrage bei ihm garnicht an
> kommt. Der Benutzer hat eine Mailbox aus der heraus auch geschickt
> werden kann. Der postfix/local scheint ja der letzte Prozess zu sein
> bevor es an Cyrus weitergegeben wird.

Das hat miteinander nichts zu tun. Ich kann auch ohne Mailbox senden,
wenn ich mag, solange ich einen Zugang zum smtpd habe. Der hat mit einer
Mailbox nichts zu tun.

> Das Log ist leider nicht unvollständig, dies sind die letzten 2 Zeilen
> die bei der nicht zugestellten Mail auftauchen:
> Apr 29 11:30:36 glia postfix/local[15680]: deliver_dotforward[3]: set
> user_attr: michavogt
> Apr 29 11:30:36 glia postfix/local[15680]: set_eugid: euid 3146 egid 2017
>
> dann kommt nichts mehr...

Ich bin immer noch nicht überzeugt, dass Cyrus den user kennt, bzw. auch
eine Mailbox für diesen hat.

Was gibt denn 'mailq' aus?

Im cyradm, ist die Mailbox mit 'listmailbox' gelistet?

--
Kai Fürstenberg

PM an: kai at fuerstenberg punkt ws

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Christian Schmidt
In reply to this post by Sylvia Gelman
Hallo Sylvia,

Sylvia Gelman, 07.05.19:

> danke für deine Nachricht! Es sieht für mich so aus als ob der Cyrus
> darauf überhaupt nicht reagiert, weil die Anfrage bei ihm garnicht an
> kommt. Der Benutzer hat eine Mailbox aus der heraus auch geschickt
> werden kann. Der postfix/local scheint ja der letzte Prozess zu sein
> bevor es an Cyrus weitergegeben wird.
>
> Das Log ist leider nicht unvollständig, dies sind die letzten 2 Zeilen
> die bei der nicht zugestellten Mail auftauchen:
> Apr 29 11:30:36 glia postfix/local[15680]: deliver_dotforward[3]: set
> user_attr: michavogt
> Apr 29 11:30:36 glia postfix/local[15680]: set_eugid: euid 3146 egid 2017
>
> dann kommt nichts mehr...
>
> ein funktionierender Logeintrag:
> Apr 29 11:28:57 glia postfix/local[15680]: deliver_dotforward[3]: set
> user_attr: atest
> Apr 29 11:28:57 glia postfix/local[15680]: set_eugid: euid 3005 egid 2017
> Apr 29 11:28:57 glia postfix/local[15680]: set_eugid: euid 110 egid 116
> Apr 29 11:28:57 glia postfix/local[15680]: deliver_dotforward: path
> /home/atest/.forward expand_status 0 look_status -1
Mich irritieren postfix' Aussagen zu "dotforward" ein wenig.

Existiert im Home-Verzeichnis des problematischen Beenutzers eine
.forward-Datei? Wenn ja: Was ist darin aufgeführt? Funktioniert die
Zustellung bzw. gibt es andere Meldungen im Log, wenn Du die Datei aus
dem Weg räumst?

Mit freundlichen Grüßen
Christian Schmidt

--
No signature available.


smime.p7s (7K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
In reply to this post by Kai Fürstenberg
Hallo Kai,

> Das hat miteinander nichts zu tun. Ich kann auch ohne Mailbox senden,
> wenn ich mag, solange ich einen Zugang zum smtpd habe. Der hat mit einer
> Mailbox nichts zu tun.
Da hast du natürlich völlig Recht!
> Ich bin immer noch nicht überzeugt, dass Cyrus den user kennt, bzw. auch
> eine Mailbox für diesen hat.
>
> Was gibt denn 'mailq' aus?

Die 12 hängenden Mails ala:

-Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient-------
1AD5F240CBB     1012 Tue Apr 30 15:59:14  [hidden email]
[hidden email]

AFECC240FAC     1021 Thu Apr 25 17:36:29  [hidden email]
[hidden email]

8D67B24092E     6732 Wed May  1 22:39:31  [hidden email]
[hidden email]

9B5BE2412F2     6398 Wed May  1 22:39:33  [hidden email]
[hidden email]

E57042401C0      983 Thu Apr 25 17:21:36  [hidden email]
[hidden email]

6591B240C30     1850 Thu May  2 08:35:09  [hidden email]
[hidden email]

4B1C92401BE     3621 Mon May  6 16:01:46  [hidden email]
[hidden email]

usw.

>
> Im cyradm, ist die Mailbox mit 'listmailbox' gelistet?

ja beide Accounts. Ich habe ja nochmal alles neu gemacht, mit den
üblichen Mitarbeiterrechten:

user/michavogt (\HasChildren)
user/michavogt/Drafts (\HasNoChildren)
user/michavogt/Junk (\HasNoChildren)
user/michavogt/Outbox (\HasNoChildren)
user/michavogt/Sent (\HasNoChildren)
user/michavogt/Templates (\HasNoChildren)
user/michavogt/Trash (\HasNoChildren)
user/mvogt (\HasChildren)
user/mvogt/Drafts (\HasNoChildren)
user/mvogt/Sent (\HasNoChildren)
user/mvogt/Templates (\HasNoChildren)

Die Rechte unter /var/spool/cyrus/mail/m/user stimmen bei den Accounts auch.

viele Grüße

Sylvia

--
Sylvia Gelman
IT Organisation
Technische Universität Darmstadt
Institut für Automatisierungstechnik und Mechatronik - IAT
Fachgebiet Regelungsmethoden und Robotik (RMR)
Landgraf-Georg-Str. 4
Gebäude S3|10 Raum 409
D-64283 Darmstadt
Tel.: +49 6151 / 16-25055
Email: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
In reply to this post by Christian Schmidt
Hallo Christian,


> Mich irritieren postfix' Aussagen zu "dotforward" ein wenig.
>
> Existiert im Home-Verzeichnis des problematischen Beenutzers eine
> .forward-Datei? Wenn ja: Was ist darin aufgeführt? Funktioniert die
> Zustellung bzw. gibt es andere Meldungen im Log, wenn Du die Datei aus
> dem Weg räumst?
>
>
Es gibt keine .forward Datei in den Verzeichnissen und wir benutzen
diese Methode auch nicht. Ehrlich gesagt hat mich der Eintrag auch
irritiert. In der Postfix Konfiguration finde ich hierzu auch nur folgendes:

postfix-files:$readme_directory/FORWARD_SECRECY_README:f:root:-:644
postfix-files:$readme_directory/XFORWARD_README:f:root:-:644
postfix-files:$html_directory/FORWARD_SECRECY_README.html:f:root:-:644
postfix-files:$html_directory/XFORWARD_README.html:f:root:-:644

viele Grüße

Sylvia

--
Sylvia Gelman
IT Organisation
Technische Universität Darmstadt
Institut für Automatisierungstechnik und Mechatronik - IAT
Fachgebiet Regelungsmethoden und Robotik (RMR)
Landgraf-Georg-Str. 4
Gebäude S3|10 Raum 409
D-64283 Darmstadt
Tel.: +49 6151 / 16-25055
Email: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Kai Fürstenberg
In reply to this post by Sylvia Gelman
Am 08.05.2019 um 10:20 schrieb Sylvia Gelman:

> Hallo Kai,
>
>> Das hat miteinander nichts zu tun. Ich kann auch ohne Mailbox senden,
>> wenn ich mag, solange ich einen Zugang zum smtpd habe. Der hat mit einer
>> Mailbox nichts zu tun.
> Da hast du natürlich völlig Recht!
>> Ich bin immer noch nicht überzeugt, dass Cyrus den user kennt, bzw. auch
>> eine Mailbox für diesen hat.
>>
>> Was gibt denn 'mailq' aus?
>
> Die 12 hängenden Mails ala:
>
> -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient-------
> 1AD5F240CBB     1012 Tue Apr 30 15:59:14  [hidden email]
> [hidden email]
>
> AFECC240FAC     1021 Thu Apr 25 17:36:29  [hidden email]
> [hidden email]
>
> 8D67B24092E     6732 Wed May  1 22:39:31  [hidden email]
> [hidden email]
>
> 9B5BE2412F2     6398 Wed May  1 22:39:33  [hidden email]
> [hidden email]
>
> E57042401C0      983 Thu Apr 25 17:21:36  [hidden email]
> [hidden email]
>
> 6591B240C30     1850 Thu May  2 08:35:09  [hidden email]
> [hidden email]
>
> 4B1C92401BE     3621 Mon May  6 16:01:46  [hidden email]
> [hidden email]
>
> usw.
>
>>
>> Im cyradm, ist die Mailbox mit 'listmailbox' gelistet?
>
> ja beide Accounts. Ich habe ja nochmal alles neu gemacht, mit den
> üblichen Mitarbeiterrechten:
>
> user/michavogt (\HasChildren)
> user/michavogt/Drafts (\HasNoChildren)
> user/michavogt/Junk (\HasNoChildren)
> user/michavogt/Outbox (\HasNoChildren)
> user/michavogt/Sent (\HasNoChildren)
> user/michavogt/Templates (\HasNoChildren)
> user/michavogt/Trash (\HasNoChildren)
> user/mvogt (\HasChildren)
> user/mvogt/Drafts (\HasNoChildren)
> user/mvogt/Sent (\HasNoChildren)
> user/mvogt/Templates (\HasNoChildren)
>
> Die Rechte unter /var/spool/cyrus/mail/m/user stimmen bei den Accounts auch.

mich irritiert, dass es keine Logeinträge gibt. Mich irritiert auch,
dass unter mailq keine Angaben zur Verzögerung eingetragen sind.
Normalerweise steht da, warum es länger dauert oder ob die Mail of HOLD
steht.

Du könntest Postfix mal anschubsen, die Mails nochmal zuzustellen
(postfix flush) und das Log, das dann folgt, zu beobachten. Dabei aber
nicht nur auf Postfix achten sondern auch auf Cyrus. Evtl. vorher den
Loglevel von Cyrus höhersetzen.

Irgendwo muss es einen Logeintrag geben, was und warum oder nicht mit
der Mail passiert.

In den Logs fehlt auch noch was: Da die Mails in der Warteschlange
stehen, müsste es vom qmgr einen Eintrag "queued as XXXXX" geben.

Erst danach werden die Mail an den LDA weiter gereicht. Ein eventueller
Fehler dürfte kurz danach erscheinen.

Also die Bitte an dich, nochmal eine Mail einzuliefern und das komplette
Log (ausgehend von "connect"), dass dabei entsteht, zu posten.

--
Kai Fürstenberg

PM an: kai at fuerstenberg punkt ws

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
Hallo Kai,

vielen Dank für die Hinweise. postfix flush habe ich natürlich schon
x-mal versucht. In Kurzform:

May  8 11:46:01 glia postfix/qmgr[25181]: 1AD5F240CBB: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: AFECC240FAC: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 8D67B24092E: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 9B5BE2412F2: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: E57042401C0: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 6591B240C30: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 4B1C92401BE: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 887BE2414AF: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 1017B24014C: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 56DBF240F35: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: 78BE1240A20: skipped, still
being delivered
May  8 11:46:01 glia postfix/qmgr[25181]: C133624159E: skipped, still
being delivered

nach Einschalten des -vv bei qmgr, kommt das Log unten heraus.

> mich irritiert, dass es keine Logeinträge gibt. Mich irritiert auch,
> dass unter mailq keine Angaben zur Verzögerung eingetragen sind.
> Normalerweise steht da, warum es länger dauert oder ob die Mail of HOLD
> steht.
ja das irritiert mich ja auch, es müsste schon längst der Sender
informiert worden sein, dass die Mail nicht zugestellt werden konnte.
>   Evtl. vorher den
> Loglevel von Cyrus höhersetzen.

Wo setze ich den Loglevel von Cyrus höher? Finde dazu nichts.

danke und viele Grüße

Sylvia

>

> May  8 11:48:02 glia postfix/qmgr[25573]: trigger_server_accept_local: trigger arrived
> May  8 11:48:02 glia postfix/qmgr[25573]: master_notify: status 0
> May  8 11:48:02 glia postfix/qmgr[25573]: request: 70 (F)
> May  8 11:48:02 glia postfix/qmgr[25573]: request: 65 (A)
> May  8 11:48:02 glia postfix/qmgr[25573]: request: 68 (D)
> May  8 11:48:02 glia postfix/qmgr[25573]: request: 73 (I)
> May  8 11:48:02 glia postfix/qmgr[25573]: request: 0 (?)
> May  8 11:48:02 glia postfix/qmgr[25573]: request ignored
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_enable_all
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_scan_start: start incoming queue scan
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_enable_all
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_scan_start: start deferred queue scan
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred
> May  8 11:48:02 glia postfix/qmgr[25573]: master_notify: status 1
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 1AD5F240CBB
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/1AD5F240CBB
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 1AD5F240CBB
> May  8 11:48:02 glia postfix/qmgr[25573]: 1AD5F240CBB: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 1AD5F240CBB after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 1AD5F240CBB
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found D
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/D
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/D
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 9
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/9
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/9
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 4
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/4
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/4
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found F
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/F
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/F
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found E
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/E
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/E
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 8
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/8
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/8
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found B
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/B
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/B
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found C
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/C
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/C
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 3
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/3
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/3
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 1
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/1
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/1
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 7
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/7
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/7
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 5
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/5
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/5
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 2
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/2
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/2
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found A
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/A
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/A
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 6
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_push: open deferred/6
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred/6
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close deferred
> May  8 11:48:02 glia postfix/qmgr[25573]: done deferred queue scan
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip ..
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found AFECC240FAC
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/AFECC240FAC
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active AFECC240FAC
> May  8 11:48:02 glia postfix/qmgr[25573]: AFECC240FAC: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup AFECC240FAC after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer AFECC240FAC
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 8D67B24092E
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/8D67B24092E
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 8D67B24092E
> May  8 11:48:02 glia postfix/qmgr[25573]: 8D67B24092E: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 8D67B24092E after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 8D67B24092E
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 9B5BE2412F2
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/9B5BE2412F2
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 9B5BE2412F2
> May  8 11:48:02 glia postfix/qmgr[25573]: 9B5BE2412F2: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 9B5BE2412F2 after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 9B5BE2412F2
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found E57042401C0
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/E57042401C0
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active E57042401C0
> May  8 11:48:02 glia postfix/qmgr[25573]: E57042401C0: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup E57042401C0 after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer E57042401C0
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 6591B240C30
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/6591B240C30
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 6591B240C30
> May  8 11:48:02 glia postfix/qmgr[25573]: 6591B240C30: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 6591B240C30 after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 6591B240C30
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 4B1C92401BE
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/4B1C92401BE
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 4B1C92401BE
> May  8 11:48:02 glia postfix/qmgr[25573]: 4B1C92401BE: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 4B1C92401BE after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 4B1C92401BE
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: skip .
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 887BE2414AF
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/887BE2414AF
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 887BE2414AF
> May  8 11:48:02 glia postfix/qmgr[25573]: 887BE2414AF: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 887BE2414AF after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 887BE2414AF
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 1017B24014C
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/1017B24014C
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 1017B24014C
> May  8 11:48:02 glia postfix/qmgr[25573]: 1017B24014C: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 1017B24014C after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 1017B24014C
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 56DBF240F35
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/56DBF240F35
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 56DBF240F35
> May  8 11:48:02 glia postfix/qmgr[25573]: 56DBF240F35: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 56DBF240F35 after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 56DBF240F35
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found B9725240D4F
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/B9725240D4F
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active B9725240D4F
> May  8 11:48:02 glia postfix/qmgr[25573]: B9725240D4F: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup B9725240D4F after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer B9725240D4F
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found 78BE1240A20
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/78BE1240A20
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active 78BE1240A20
> May  8 11:48:02 glia postfix/qmgr[25573]: 78BE1240A20: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup 78BE1240A20 after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer 78BE1240A20
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_next: found C133624159E
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: queue incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_feed: incoming/C133624159E
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_message_alloc: active C133624159E
> May  8 11:48:02 glia postfix/qmgr[25573]: C133624159E: skipped, still being delivered
> May  8 11:48:02 glia postfix/qmgr[25573]: wakeup C133624159E after 60 secs
> May  8 11:48:02 glia postfix/qmgr[25573]: qmgr_active_defer: defer C133624159E
> May  8 11:48:02 glia postfix/qmgr[25573]: watchdog_start: 0x557f178e7bf0
> May  8 11:48:02 glia postfix/qmgr[25573]: scan_dir_pop: close incoming
> May  8 11:48:02 glia postfix/qmgr[25573]: done incoming queue scan

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Sylvia Gelman
Hallo Kai,

ohne debug Modus erkennt man nicht viel, dies ist das komplette Log beim
Senden einer Nachricht die dann in der Queue dauerhaft bleibt:

May  8 13:44:12 server postfix/smtpd[28381]: connect from
client.rt.e-technik.domain.de[IP]
May  8 13:44:12 server postfix/smtpd[28381]: Anonymous TLS connection
established from client.rt.e-technik.domain.de[IP]: TLSv1.2 with cipher
ECDHE-RSA-AES128
-GCM-SHA256 (128/128 bits)
May  8 13:44:12 server postfix/smtpd[28381]: 931E4240DF1:
client=client.rt.e-technik.domain.de[IP]
May  8 13:44:12 server postfix/cleanup[28385]: 931E4240DF1:
message-id=<[hidden email]>
May  8 13:44:12 server postfix/qmgr[28369]: 1AD5F240CBB: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 931E4240DF1:
from=<[hidden email]>, size=989, nrcpt=1 (queue active)
May  8 13:44:12 server postfix/qmgr[28369]: AFECC240FAC: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 8D67B24092E: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 9B5BE2412F2: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: E57042401C0: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 6591B240C30: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 4B1C92401BE: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 887BE2414AF: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 1017B24014C: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 56DBF240F35: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: B9725240D4F: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: 78BE1240A20: skipped, still
being delivered
May  8 13:44:12 server postfix/qmgr[28369]: C133624159E: skipped, still
being delivered
May  8 13:44:12 server postfix/smtpd[28381]: disconnect from
client.rt.e-technik.domain.de[IP]

Wie gesagt, im Debug Modus sieht man als letzte Zeile dies:

May  8 11:13:03 server postfix/local[25188]: set_eugid: euid 3147 egid 2002

dann steigt er aus, es kommt einfach garnichts mehr.

Bei einer normalen Mail die zugestellt wird, geht es ab hier
folgendermaßen weiter:

May 8 11:14:29 server postfix/local[25197]: set_eugid: euid 3005 egid 2017

May 8 11:14:29 server postfix/local[25197]: set_eugid: euid 110 egid 116

May 8 11:14:29 server postfix/local[25197]: deliver_dotforward: path
/home/atest/.forward expand_status 0 look_status -1

May 8 11:14:29 server postfix/local[25197]: deliver_mailbox[3]: local
atest recip [hidden email] exten deliver
[hidden email] exp_from

May 8 11:14:29 server postfix/local[25197]: been_here: mailbox atest: 0

May 8 11:14:29 server postfix/local[25197]: connect to subsystem
private/cyrus

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: status

May 8 11:14:29 server postfix/local[25197]: input attribute name: status

May 8 11:14:29 server postfix/local[25197]: input attribute value: 0

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: (list terminator)

May 8 11:14:29 server postfix/local[25197]: input attribute name: (end)

May 8 11:14:29 server postfix/local[25197]: send attr flags = 3

May 8 11:14:29 server postfix/local[25197]: send attr queue_name = active

May 8 11:14:29 server postfix/local[25197]: send attr queue_id = A682F240BE8

May 8 11:14:29 server postfix/local[25197]: send attr offset = 796

May 8 11:14:29 server postfix/local[25197]: send attr size = 991

May 8 11:14:29 server postfix/local[25197]: send attr nexthop =
server.rt.e-technik.domain.de

May 8 11:14:29 server postfix/local[25197]: send attr encoding = 8bit

May 8 11:14:29 server postfix/local[25197]: send attr sender =
[hidden email]

May 8 11:14:29 server postfix/local[25197]: send attr envelope_id =

May 8 11:14:29 server postfix/local[25197]: send attr ret_flags = 0

May 8 11:14:29 server postfix/local[25197]: send attr time = [data 88 bytes]

May 8 11:14:29 server postfix/local[25197]: send attr log_client_name =
client.rt.e-technik.domain.de

May 8 11:14:29 server postfix/local[25197]: send attr log_client_address
= IP

May 8 11:14:29 server postfix/local[25197]: send attr log_client_port =
59252

May 8 11:14:29 server postfix/local[25197]: send attr log_protocol_name
= ESMTP

May 8 11:14:29 server postfix/local[25197]: send attr log_helo_name = [IP]

May 8 11:14:29 server postfix/local[25197]: send attr sasl_method =

May 8 11:14:29 server postfix/local[25197]: send attr sasl_username =

May 8 11:14:29 server postfix/local[25197]: send attr sasl_sender =

May 8 11:14:29 server postfix/local[25197]: send attr log_ident =
A682F240BE8

May 8 11:14:29 server postfix/local[25197]: send attr rewrite_context =
remote

May 8 11:14:29 server postfix/local[25197]: send attr recipient_count = 1

May 8 11:14:29 server postfix/local[25197]: send attr original_recipient
= [hidden email]

May 8 11:14:29 server postfix/local[25197]: send attr recipient =
[hidden email]

May 8 11:14:29 server postfix/local[25197]: send attr offset =
18446744073709551615

May 8 11:14:29 server postfix/local[25197]: send attr dsn_orig_rcpt =
rfc822;[hidden email]

May 8 11:14:29 server postfix/local[25197]: send attr notify_flags = 0

May 8 11:14:29 server cyrus/master[25200]: about to exec
/usr/lib/cyrus/bin/lmtpd

May 8 11:14:29 server cyrus/lmtpunix[25200]: executed

May 8 11:14:29 server cyrus/lmtpunix[25200]: accepted connection

May 8 11:14:29 server cyrus/lmtpunix[25200]: lmtp connection preauth'd
as postman

May 8 11:14:29 server cyrus/lmtpunix[25200]: Delivered:
<[hidden email]> to mailbox: user.atest

May 8 11:14:29 server cyrus/lmtpunix[25200]: USAGE atest user: 0.004000
sys: 0.000000

May 8 11:14:29 server postfix/pipe[25198]: A682F240BE8:
to=<[hidden email]>, orig_to=<[hidden email]>,
relay=cyrus, delay=0.28, delays=0.04/0.15/0/0.09, dsn=2.0.0, status=sent
(delivered via cyrus service)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: status

May 8 11:14:29 server postfix/local[25197]: input attribute name: status

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: diag_type

May 8 11:14:29 server postfix/local[25197]: input attribute name: diag_type

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: diag_text

May 8 11:14:29 server postfix/local[25197]: input attribute name: diag_text

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: mta_type

May 8 11:14:29 server postfix/local[25197]: input attribute name: mta_type

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: mta_mname

May 8 11:14:29 server postfix/local[25197]: input attribute name: mta_mname

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: action

May 8 11:14:29 server postfix/local[25197]: input attribute name: action

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: reason

May 8 11:14:29 server postfix/local[25197]: input attribute name: reason

May 8 11:14:29 server postfix/local[25197]: input attribute value: (end)

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: status

May 8 11:14:29 server postfix/local[25197]: input attribute name: status

May 8 11:14:29 server postfix/local[25197]: input attribute value: 0

May 8 11:14:29 server postfix/local[25197]: private/cyrus socket: wanted
attribute: (list terminator)

May 8 11:14:29 server postfix/local[25197]: input attribute name: (end)

May 8 11:14:29 server postfix/local[25197]: deliver_request_final: send:
"" 0

May 8 11:14:29 server postfix/local[25197]: send attr status =

May 8 11:14:29 server postfix/local[25197]: send attr diag_type =

May 8 11:14:29 server postfix/local[25197]: send attr diag_text =

May 8 11:14:29 server postfix/local[25197]: send attr mta_type =

May 8 11:14:29 server postfix/local[25197]: send attr mta_mname =

May 8 11:14:29 server postfix/local[25197]: send attr action =

May 8 11:14:29 server postfix/local[25197]: send attr reason =

May 8 11:14:29 server postfix/local[25197]: send attr status = 0

May 8 11:14:29 server postfix/local[25197]: master_notify: status 1

May 8 11:14:29 server postfix/qmgr[25181]: A682F240BE8: removed

May 8 11:14:29 server postfix/local[25197]: connection closed

May 8 11:14:29 server postfix/local[25197]: watchdog_stop: 0x55ce67b12120

May 8 11:14:29 server postfix/local[25197]: watchdog_start: 0x55ce67b12120


Am 08.05.19 um 12:15 schrieb Kai Fürstenberg:

> Hi Sylvia,
>
> zunächst habe ich mich einmal geirrt: Postfix loggt kein "queued as", er
> gibt dieses aber an den einliefernden Server zurück, der es seinerseits
> loggt oder loggen kann.
>
>
> Bitte kein verbose und kein rumgeschnippsel mehr.
> Ich hätte gerne mal ein komplettes Log in ganz normaler Form.
>
> Nimm also bitte mal alle -vv raus aus der master.cf, postfix reload,
> postfix flush und poste den kompletten Logausschnmitt am besten so wie
> es ist, ohne Auslassungen oder Ergänzungen.
>
> Bzw. liefere mal eine neue Mail an den Empfänger ein und poste den
> kompletten Logausschnmitt am besten so wie es ist, ohne Auslassungen
> oder Ergänzungen.
>
>>> mich irritiert, dass es keine Logeinträge gibt. Mich irritiert auch,
>>> dass unter mailq keine Angaben zur Verzögerung eingetragen sind.
>>> Normalerweise steht da, warum es länger dauert oder ob die Mail of HOLD
>>> steht.
>> ja das irritiert mich ja auch, es müsste schon längst der Sender
>> informiert worden sein, dass die Mail nicht zugestellt werden konnte.
>>>    Evtl. vorher den
>>> Loglevel von Cyrus höhersetzen.
>> Wo setze ich den Loglevel von Cyrus höher? Finde dazu nichts.
> Der Loglevel von Cyrus wird über syslogd gesteuert. In der Cyrus-Zeile
> musst du debug gesondert setzen.
>
> Wie gesagt, ist ewig her, dass ich den benutzt habe...
>
--
Sylvia Gelman
IT Organisation
Technische Universität Darmstadt
Institut für Automatisierungstechnik und Mechatronik - IAT
Fachgebiet Regelungsmethoden und Robotik (RMR)
Landgraf-Georg-Str. 4
Gebäude S3|10 Raum 409
D-64283 Darmstadt
Tel.: +49 6151 / 16-25055
Email: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Markus Winkler
Hallo Sylvia,

ich habe folgende Fragen/Bitten (in der Hoffnung, diese Infos im Thread
nicht überlsen zu haben):

(1)
Schicke mal bitte noch Deine master.conf


(2)
Nur zur Sicherheit gefragt: Du schreibst eingangs, dass das Problem bei
_einem_ neu angelegten User auftritt. Im weiteren Verlauf erwähnst Du dann
aber das hier:

>> Im cyradm, ist die Mailbox mit 'listmailbox' gelistet?
>
> ja beide Accounts. Ich habe ja nochmal alles neu gemacht, mit den üblichen Mitarbeiterrechten:

-----^^^^^

Handelt es sich demnach also eigentlich um _zwei_ Accounts? Das würde sich
ja auch mit dieser Auflistung decken:

> user/michavogt (\HasChildren)
[...]
> user/mvogt (\HasChildren)
[...]

Oder war das nur zum Testen ("nochmal alles neu gemacht"), damit Du einen
weiteren neuen User zur Verfügung hast?


(3)
> Die Rechte unter /var/spool/cyrus/mail/m/user stimmen bei den Accounts auch.

Ich glaube Dir das zwar ;-), aber schicke bitte trotzdem mal das Listing
dieser drei Verzeichnisse, damit man die Owner/Permissions sehen kann:

  a) /var/spool/cyrus/mail/a/user/atest
  b) /var/spool/cyrus/mail/m/user/michavogt
  c) /var/spool/cyrus/mail/m/user/mvogt


(4)
Welchen Output erhälst Du jeweils, wenn Du diese Abfragen durchführst:

  a) postmap -q [hidden email]
ldap:/etc/postfix/ldap-aliases.cf
  b) postmap -q [hidden email]
ldap:/etc/postfix/ldap-aliases.cf
  c) postmap -q [hidden email]
ldap:/etc/postfix/ldap-aliases.cf

In diesem Zusammenhang: Es dürfte mit Deinem konkreten Problem nix zu tun
haben, aber ändere in Deiner main.cf diese Zeile:

virtual_alias_maps = ldap:/etc/postfix/ldap-aliases.cf

mal besser folgendermaßen:

virtual_alias_maps = proxy:ldap:/etc/postfix/ldap-aliases.cf


(5)
Auch wenn es lt. Deiner Info nicht genutzt wird - zeige mal bitte die
Owner/Permissions und Inhalte von:

  a) /home/atest/
  b) /home/michavogt/
  c) /home/mvogt/



Was Deine Frage zum Cyrus-Debug betrifft: Ich habe hier u. a. noch ein
älteres Debian-System, da steht in der /etc/imapd.conf das hier drin (falls
Du das ganze Cyrus-Zeugs genauer untersuchen willst):

##
## DEBUGGING
##
# Debugging hook. See /usr/share/doc/cyrus-common-2.2/README.Debian.debug
# Keep the hook disabled when it is not in use
#
# gdb Back-traces
#debug_command: /usr/bin/gdb -batch -cd=/tmp -x
/usr/lib/cyrus/get-backtrace.gdb /usr/lib/cyrus/bin/%s %d
 >/tmp/gdb-backtrace.cyrus.%1$s.%2$d <&- 2>&1 &
#
# system-call traces
#debug_command: /usr/bin/strace -tt -o /tmp/strace.cyrus.%s.%d -p %2$d <&-
2>&1 &
#
# library traces
#debug_command: /usr/bin/ltrace -tt -n 2 -o /tmp/ltrace.cyrus.%s.%d -p %2$d
<&- 2>&1 &


Viele Grüße
Markus

Reply | Threaded
Open this post in threaded view
|

Re: Postfix stellt Mails an einen bestimmten Benutzer nicht zu

Jost Krieger
In reply to this post by Sylvia Gelman
Am 08.05.2019 um 10:29 schrieb Sylvia Gelman:

> Es gibt keine .forward Datei in den Verzeichnissen und wir benutzen
> diese Methode auch nicht. Ehrlich gesagt hat mich der Eintrag auch
> irritiert. In der Postfix Konfiguration finde ich hierzu auch nur
> folgendes:
>
> postfix-files:$readme_directory/FORWARD_SECRECY_README:f:root:-:644
> postfix-files:$readme_directory/XFORWARD_README:f:root:-:644
> postfix-files:$html_directory/FORWARD_SECRECY_README.html:f:root:-:644
> postfix-files:$html_directory/XFORWARD_README.html:f:root:-:644
>
Falls dein Problem noch nicht gelöst ist, es sieht so aus, als ob die
local-Prozesse einfach hängen bleiben.

Mögliche Ansätze (nach steigendem Aufwand):

1. ls -lRa auf das Benutzer-Home-Verzeichnis und mit anderen vergleichen.

2. Rechner booten.

3. Rechner booten und Filesystem-Check.

4. Hängende Prozesse rausfinden und tracen.

Gruß Jost