Plesk/Qmail - empfängt plötzlich keine eMails mehr


V

VisualBeo

New Member
Ach du Schreck, mein Strato VServer mit Plesk9.2 hat gestern Abend einfach keine eMails mehr empfangen... habe mich gewundert.

Irgendwas interes in den Konfigfiles scheint nicht zu klappen. In meiner Not habe ich ein mehrere Stunden altes komplettes backup eingespielt: problem nicht gelöst.

In meiner Not habe ich heut Nacht dann komplett neu installieren lassen und alles frisch in Plesk eingestellt. Und siehe da: wieder der gleiche effekt: Jede eingehende eMail wird abgewiesen:
Code: 
Hi. This is the qmail-send program at 63636565665.serverkompetenz.net.
I'm afraid I wasn't able to deliver your message to the following addresses.
This is a permanent error; I've given up. Sorry it didn't work out.

Per Telnet kann man die Mail normal übersenden, danach erst kommt die failure notice zurück.

Habe alle möglichen Threads dazu angeguckt und schon einige Besitzer (chown) von "mhandlers-user" mal auf "root" umgestellt. Hat wohl nicht viel Sinn, ich weiß. Aber ich bin verzweifelt.
Mitterweile gibt es keine failture notice mehr, aber ins Postfach kommen die Mails trotzdem nicht :(

mail.err unter jetzt opensuse, davor ubuntu

Code: 
Dec 11 01:36:45 h1125512 qmail-local-handlers[1527]: mailbox: /var/qmail/alias
Dec 11 02:39:19 h1125512 glmng[12272]: Unable to get parameters from stdin
Dec 11 02:59:18 h1125512 qmail-local-handlers[16146]: mailbox: /var/qmail/alias
Dec 11 02:59:18 h1125512 qmail-local-handlers[16148]: mailbox: /var/qmail/alias
Dec 11 03:02:08 h1125512 spf filter[16365]: Error code: (2) Could not find a valid SPF record
Dec 11 03:02:08 h1125512 spf filter[16365]: Failed to query MAIL-FROM: No DNS data for 't-netbox.de'.
Dec 11 03:02:08 h1125512 qmail-local-handlers[16367]: cannot create temporary file - (13) Permission denied
Dec 11 03:02:08 h1125512 qmail-local-handlers[16367]: cannot read message from stdin
Dec 11 03:02:09 h1125512 spf filter[16373]: Error code: (2) Could not find a valid SPF record
Dec 11 03:02:09 h1125512 spf filter[16373]: Failed to query MAIL-FROM: No DNS data for 't-netbox.de'.
Dec 11 03:02:09 h1125512 qmail-local-handlers[16375]: cannot create temporary file - (13) Permission denied
Dec 11 03:02:09 h1125512 qmail-local-handlers[16375]: cannot read message from stdin
Dec 11 03:02:09 h1125512 spf filter[16381]: Error code: (2) Could not find a valid SPF record
Dec 11 03:02:09 h1125512 spf filter[16381]: Failed to query MAIL-FROM: No DNS data for 't-netbox.de'.
Dec 11 03:02:09 h1125512 qmail-local-handlers[16383]: mailbox: /var/qmail/alias
Dec 11 03:02:09 h1125512 qmail-local-handlers[16383]: call_handlers: Error during call '/usr/local/psa/handlers/info/10-spam-BlvwWV/executable' handler
Dec 11 03:02:09 h1125512 qmail-local-handlers[16383]: LOG Internal error in handler '10-spam-BlvwWV'. Skip handler.
Dec 11 03:02:09 h1125512 qmail-local-handlers[16383]: mailsend: cannot open file /usr/local/psa/handlers/spool/messageOFR5vN with message - No such file or directory
Dec 11 03:02:09 h1125512 qmail-local-handlers[16383]: cannot reinject message to '[email protected]'
Dec 11 03:05:55 h1125512 spf filter[17588]: SPF status: REJECT
Dec 11 03:05:55 h1125512 qmail-queue-handlers[17587]: call_handlers: stop call handlers from dir '/usr/local/psa/handlers/before-queue/global'
Dec 11 03:13:02 h1125512 spf filter[22209]: Error code: (2) Could not find a valid SPF record
Dec 11 03:13:02 h1125512 spf filter[22209]: Failed to query MAIL-FROM: No DNS data for 't-online.de'.
Dec 11 03:13:02 h1125512 qmail-local-handlers[22211]: cannot create temporary file - (13) Permission denied
Dec 11 03:13:02 h1125512 qmail-local-handlers[22211]: cannot read message from stdin
Dec 11 03:13:48 h1125512 qmail-local-handlers[22234]: mailbox: /var/qmail/mailnames/ju-wuerzburg.de/mail
Dec 11 03:17:39 h1125512 qmail-local-handlers[23612]: cannot create temporary file - (13) Permission denied
Dec 11 03:17:39 h1125512 qmail-local-handlers[23612]: cannot read message from stdin
Dec 11 03:26:41 h1125512 qmail-local-handlers[24093]: mailbox: /var/qmail/mailnames/ju-wuerzburg.de/mail
Dec 11 03:37:31 h1125512 qmail-queue-handlers[25611]: Unable to change group ID: Operation not permitted
Dec 11 03:42:18 h1125512 pop3d: 1260499338.971524 LOGOUT, [email protected], ip=[93.222.113.168], top=0, retr=0, time=0, rcvd=12, sent=39, maildir=/var/qmail/mailnames/byteserv.de/mail/Maildir
Dec 11 03:43:05 h1125512 qmail-queue-handlers[25900]: Unable to change group ID: Operation not permitted
Dec 11 03:43:14 h1125512 qmail-queue-handlers[25906]: Unable to change group ID: Operation not permitted
Dec 11 03:44:12 h1125512 qmail-queue-handlers[25935]: Unable to change group ID: Operation not permitted
Dec 11 03:44:16 h1125512 qmail-queue-handlers[25945]: Unable to change group ID: Operation not permitted
Dec 11 03:45:16 h1125512 qmail-queue-handlers[26022]: Unable to change group ID: Operation not permitted
Dec 11 03:46:17 h1125512 qmail-queue-handlers[26057]: Unable to change group ID: Operation not permitted
Dec 11 03:47:35 h1125512 qmail-queue-handlers[26119]: Unable to change group ID: Operation not permitted
Dec 11 03:48:56 h1125512 qmail-queue-handlers[26187]: Unable to change group ID: Operation not permitted
Dec 11 03:49:56 h1125512 qmail-queue-handlers[26363]: Unable to change group ID: Operation not permitted

ich bin verzweifelt... die ganzen geschäfts emails die jetzt ankommen, einfach weg :(

wäre dankbar für Hilfestellung!
 
Last edited by a moderator:
VisualBeo said:
In meiner Not habe ich ein mehrere Stunden altes komplettes backup eingespielt
...
In meiner Not habe ich heut Nacht dann komplett neu installieren lassen
Click to expand...
Typisches Windows-Anwender-Verhalten. ;)

Folgende Fehler stechen förmlich ins Auge:
Unable to get parameters from stdin
cannot create temporary file - (13) Permission denied
cannot read message from stdin
Failed to query MAIL-FROM: No DNS data for 't-netbox.de'.
call_handlers: Error during call '/usr/local/psa/handlers/info/10-spam-BlvwWV/executable' handler
mailsend: cannot open file /usr/local/psa/handlers/spool/messageOFR5vN with message - No such file or directory
cannot reinject message to 'info@...'
Unable to change group ID: Operation not permitted
Click to expand...
Du hast aber gesagt, dass Du bereits an den Benutzerrechten rumgespielt hast. Damit hast Du wahrscheinlich noch mehr verhunzt.

Falls Du noch ein laufendes Backup hast, dann spiel das ein und mach danach erstmal einen Reboot.
Sobald dann die Emails nicht mehr laufen gibst Du uns hier die Auszüge aus /var/local/psa/var/log/maillog.

huschi.
 
Huschi said:
Sobald dann die Emails nicht mehr laufen gibst Du uns hier die Auszüge aus /var/local/psa/var/log/maillog.
Click to expand...

Bevor du sagst, dass das Logfile aber gar nicht existiert... das liegt in /usr/ nicht in /var/

Unabhängig davon tippe ich auch auf falsche Berechtigungen auf den Ordner /usr/local/psa/handlers/spool/ sowie dein konfigurierter SPF-Check, der bei Plesk erfahrungsgemäß in einigen Versionen noch Probleme bereitet.
 
Habe den Server zwischenzeitlich
- auf gestern restored
- auf vorgestern restored
- komplett neu aufgesetzt

In allen drei Fällen bliebt der Fehler erhalten, dass keine Mails mehr in die Postfächer gelangen. Das kann dann doch gar nicht mehr an der Konfiguration liegen, oder? Das ist mit Sicherheit eine höhere Macht.
 
Nach der Neuinstallation hast du die Domain wieder im Plesk eingetragen und das entsprechende Postfach auch angelegt, nehme ich an.
Du bist dir aber ganz sicher, dass dein Server überhaupt für die Emails der Domain zuständig ist?

Ich habe jetzt mal testweise eine Mail an [email protected] gesendet. Durchsuch die Logfiles mal nach der Message-ID 23763. Der Timestamp bewegt sich im Zeitraum um 1260527123.

Es ist übrigens nicht toll, dass dein Mailserver die Mail annimmt und die danach dann bounced, weil das Postfach nicht existiert... Das sollte dein Mailserver vorher prüfen und die Annahme dann direkt verweigern.
 
il/bin/relaylock[23753]: /var/qmail/bin/relaylock: mail from 85.25.148.101:51540 (kerneloops.de)
Dec 11 11:24:58 h1125512 qmail-queue-handlers[23763]: Handlers Filter before-queue for qmail started ...
745Dec 11 11:25:23 h1125512 qmail-queue-handlers[23763]: to=657574677.de
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23763]: hook_dir = '/opt/psa/handlers/before-queue'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23763]: recipient[3] = '746767467.de'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23763]: handlers dir = '/opt/psa/handlers/before-queue/recipient/7647678678.de'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23763]: starter: submitter[23809] exited normally
Dec 11 11:25:23 h1125512 qmail: 1260527123.102767 new msg 1820264
Dec 11 11:25:23 h1125512 qmail: 1260527123.102865 info msg 1820264: bytes 249 from <653653653.de> qp 23809 uid 2020
Dec 11 11:25:23 h1125512 qmail: 1260527123.108876 starting delivery 3: msg 1820264 to local 756888567.de
Dec 11 11:25:23 h1125512 qmail: 1260527123.109534 status: local 1/10 remote 0/20
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: Handlers Filter before-local for qmail started ...
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: from=3656536.de
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: to=356536536.de
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: mailbox: /var/qmail/alias
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: hook_dir = '/opt/psa/handlers/before-local'
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: recipient[3] = '34636536.de'
Dec 11 11:25:23 h1125512 qmail-local-handlers[23810]: handlers dir = '/opt/psa/handlers/before-local/recipient/[email protected]'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: Handlers Filter before-queue for qmail started ...
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: from=
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: to=563653656353.de
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: hook_dir = '/opt/psa/handlers/before-queue'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: recipient[3] = '356536536.de'
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: handlers dir = '/opt/psa/handlers/before-queue/recipient/[email protected]'
Dec 11 11:25:23 h1125512 qmail: 1260527123.126559 delivery 3: failure: Sorry,_no_mailbox_here_by_that_name._(#5.1.1)/
Dec 11 11:25:23 h1125512 qmail: 1260527123.126672 status: local 0/10 remote 0/20
Dec 11 11:25:23 h1125512 qmail-queue-handlers[23812]: starter: submitter[23813] exited normally
Dec 11 11:25:23 h1125512 qmail: 1260527123.133862 bounce msg 1820264 qp 23812
Dec 11 11:25:23 h1125512 qmail: 1260527123.133955 end msg 1820264
Dec 11 11:25:23 h1125512 qmail: 1260527123.135597 new msg 6307852
Dec 11 11:25:23 h1125512 qmail: 1260527123.135685 info msg 6307852: bytes 812 from <> qp 23813 uid 2522
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: Handlers Filter before-remote for qmail started ...
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: from=
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: to=36536536.de
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: hook_dir = '/opt/psa/handlers/before-remote'
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: recipient[3] = 'fgrwgrg.de'
Dec 11 11:25:23 h1125512 qmail-remote-handlers[23814]: handlers dir = '/opt/psa/handlers/before-remote/recipient/[email protected]'
Dec 11 11:25:23 h1125512 qmail: 1260527123.150739 starting delivery 4: msg 6307852 to remote wrgr.de
Dec 11 11:25:23 h1125512 qmail: 1260527123.150856 status: local 0/10 remote 1/20
Dec 11 11:25:23 h1125512 qmail: 1260527123.629501 delivery 4: success: 85.25.148.101_accepted_message./Remote_host_said:_250_2.0.0_Ok:_queued_as_9BB692C66035/
Dec 11 11:25:23 h1125512 qmail: 1260527123.629616 status: local 0/10 r
 
Last edited by a moderator:
Laut diesem Log ist alles in Ordnung bis auf das fehlende Postfach.
Gibt es /var/qmail/mailnames/ju-wuerzburg.de/info/ ?

huschi.
 
Ja, die Mails kommen mitlerweile beim Server an, aber eben nicht bis in die Mailbox. Die ganzen Mails seit gestern Nachmittag gehen mir verloren :( supergau....

das Postfach gibt es! allerdings ist es nicht meines...

mir gehört z.B.
schnipp schnapp schnippel schippel

Code: 
root@h1125512:/var/qmail/mailnames/xxxxxx.de# dir
mail
 
Last edited by a moderator:
Dein Server ist der Meinung, dass es das Postfach nicht gibt. Was sagt das Logfile zur Testmail "250 ok 1260532198 qp 5899"?


Code: 
<[email protected]>:
Sorry, no mailbox here by that name. (#5.1.1)
 
sieht in etwa so aus

deine mail ist nicht mehr in den Logs, hatte die file(1,6 gb) mal löschen müssen.

Code: 
Dec 11 13:30:47 h1125512 /var/qmail/bin/relaylock[13511]: /var/qmail/bin/relaylock: mail from 81.169.162.232:51472 (h84289.serverkompetenz.net)
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: Handlers Filter before-queue for qmail started ...
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: [email protected]
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: to=gergergrg
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: hook_dir = '/opt/psa/handlers/before-queue'
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: recipient[3] = 'erght4hth.de'
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: handlers dir = '/opt/psa/handlers/before-queue/recipient/[email protected]'
Dec 11 13:30:47 h1125512 qmail: 1260534647.408704 new msg 1820264
Dec 11 13:30:47 h1125512 qmail: 1260534647.408820 info msg 1820264: bytes 1241 from <[email protected]> qp 13516 uid 2020
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13515]: starter: submitter[13516] exited normally
Dec 11 13:30:47 h1125512 qmail: 1260534647.415614 starting delivery 59: msg 1820264 to local [email protected]
Dec 11 13:30:47 h1125512 qmail: 1260534647.415722 status: local 1/10 remote 0/20
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: Handlers Filter before-local for qmail started ...
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: [email protected]
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: [email protected]
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: mailbox: /var/qmail/alias 
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: hook_dir = '/opt/psa/handlers/before-local'
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: recipient[3] = '[email protected]'
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: handlers dir = '/opt/psa/handlers/before-local/recipient/[email protected]'
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: call_handlers: call executable = '/opt/psa/handlers/info/10-spam-CNLSML/executable'
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: got connection over /tmp/spamd_full.sock 
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: using default config for alias@qmail: /var/qmail/mailnames/qmail/alias/.spamassassin/user_prefs 
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: processing message <[email protected]> for alias@qmail:110 
Dec 11 13:30:47 h1125512 spamd[13445]: auto-whitelist: open of auto-whitelist file failed: locker: safe_lock: cannot create tmp lockfile /var/qmail/mailnames/qmail/alias/.spamassassin/auto-whitelist.lock.h1125512.serverkompetenz.net.13445 for /var/qmail/mailnames/qmail/alias/.spamassassin/auto-whitelist.lock: No such file or directory 
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: clean message (2.9/7.0) for alias@qmail:110 in 0.2 seconds, 1241 bytes. 
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: result: . 2 - TVD_SPACE_RATIO scantime=0.2,size=1241,user=alias@qmail,uid=110,required_score=7.0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd_full.sock,mid=<[email protected]>,autolearn=no 
Dec 11 13:30:47 h1125512 spamd[13442]: prefork: child states: I 
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: handlers_stderr: PASS
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: call_handlers: PASS during call '/opt/psa/handlers/info/10-spam-CNLSML/executable' handler
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: Handlers Filter before-queue for qmail started ...
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: from=
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: [email protected]
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: hook_dir = '/opt/psa/handlers/before-queue'
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: recipient[3] = '[email protected]'
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: handlers dir = '/opt/psa/handlers/before-queue/recipient/[email protected]'
Dec 11 13:30:47 h1125512 qmail: 1260534647.810794 delivery 59: failure: Sorry,_no_mailbox_here_by_that_name._(#5.1.1)/
Dec 11 13:30:47 h1125512 qmail: 1260534647.810888 status: local 0/10 remote 0/20
Dec 11 13:30:47 h1125512 qmail-queue-handlers[13521]: starter: submitter[13522] exited normally
Dec 11 13:30:47 h1125512 qmail: 1260534647.855502 bounce msg 1820264 qp 13521
Dec 11 13:30:47 h1125512 qmail: 1260534647.855598 end msg 1820264
Dec 11 13:30:47 h1125512 qmail: 1260534647.855885 new msg 1821002
Dec 11 13:30:47 h1125512 qmail: 1260534647.856522 info msg 1821002: bytes 1808 from <> qp 13522 uid 2522
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: Handlers Filter before-remote for qmail started ...
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: from=
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: [email protected]
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: hook_dir = '/opt/psa/handlers/before-remote'
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: recipient[3] = '[email protected]'
Dec 11 13:30:47 h1125512 qmail-remote-handlers[13523]: handlers dir = '/opt/psa/handlers/before-remote/recipient/[email protected]'
Dec 11 13:30:47 h1125512 qmail: 1260534647.902694 starting delivery 60: msg 1821002 to remote [email protected]
Dec 11 13:30:47 h1125512 qmail: 1260534647.902823 status: local 0/10 remote 1/20
Dec 11 13:30:48 h1125512 qmail: 1260534648.684550 delivery 60: success: 81.169.162.232_accepted_message./Remote_host_said:_250_2.0.0_Ok:_queued_as_A122686801E/
Dec 11 13:30:48 h1125512 qmail: 1260534648.684711 status: local 0/10 remote 0/20
Dec 11 13:30:48 h1125512 qmail: 1260534648.685348 end msg 1821002
 
Last edited by a moderator:
VisualBeo said:
deine mail ist nicht mehr in den Logs, hatte die file(1,6 gb) mal löschen müssen.
Click to expand...
Es ist natürlich hochgradig Sinnvoll während einer Fehleranalyse ein Logfile komplett zu löschen. :(

Allerdings erstaunt mich Folgendes:
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: [email protected]
Dec 11 13:30:47 h1125512 qmail-local-handlers[13517]: mailbox: /var/qmail/alias
...
Dec 11 13:30:47 h1125512 spamd[13445]: spamd: using default config for alias@qmail: /var/qmail/mailnames/qmail/alias/.spamassassin/user_prefs
Click to expand...
Aus irgendeinem Grund will Qmail die Mail an qmail/alias zustellen. Da frage ich mich doch, warum?
Gut, ich weiß, dass Qmail grundsätzlich in /var/qmail/alias/ nachschaut.
Und wo wie es aussieht wird er dort auch fündig.
Aber was...???

huschi.
 
nach 20 stunden konnte der fehler mit nur einem befehl behoben werden:

Code: 
~# /usr/local/psa/admin/sbin/autoinstaller --select-release-current --install-component postfix

danke für die unterstützung. chris
 
Öhm ja... Mein Reifen ist platt, ich bin mir kurz ein neues Auto kaufen.

Aber gut, dass das nun geklärt ist ;)
 
platter Reifen, naja... manche Admins würden qmail eher mit einem verrosteten Fahrrad vergleichen. Und ich bin jetzt schonmal mindestens auf einen Golf GTI umgestiegen.

Nicht nur, dass sich dieses Problem mit qmail einfach nicht lösen ließ (ist wohl ein Fehler auf virtualisierungs-ebene), sondern dass man mit einem einzigen Befehl auch noch vollautomatisch auf einen gescheiten MTA umsteigen kann, ist ja wohl Grund genug dafür, dass ich mir dieses Happy End gegönnt habe.

frohen dritten Advent, morgen. (langsam mal nach Geschenken umgucken!)
 
Seit gestern hatte ich ein identisches Problem bei meinem hosteurope vserver, und konnte bisher keine Lösung finden.

Zum Glück scheint Euer "Bugfix" auch bei mir zu funktionieren.
 
You must log in or register to reply here.

Back
Top