Postfix, Dovecot und MySQL

finnq

finnq

New Member
Hallo,
mein Mailserver läuft schon seit Tagen nicht mehr und stehe vor einer Wand. Alle meine Recherchen haben nichts gebracht und jetzt weiß ich ehrlich gesagt nicht mehr weiter. Ich hatte meinem Mailserver (Dovecot + Postfix) mit MySQL zusammen eingerichtet. Alles hat perfekt funktioniert, E-Mails senden und empfangen und dann vor ca. 1 Woche ging einfach nichts mehr. Er konnte connecten aber meinte immer mein Passwort wäre falsch. Also ganz fix in die Logs geschaut und entdeckt, dass es ein Problem bei der Verbindung bzw. dem lookup der Tabellen gibt. Hier einmal ein paar Auszüge der Logs:
mail.err
Oct 14 14:50:33 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry
Oct 14 14:50:33 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip.ip.ip): Password query failed: Not connected to database
Oct 14 14:50:40 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip,ip,ip): Password query failed: Not connected to database

Oct 14 14:51:01 vs611 postfix/trivial-rewrite[27399]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Click to expand...

mail.info
Oct 14 14:51:26 vs611 postfix/cleanup[27019]: warning: 1898E64036: virtual_alias_maps map lookup problem for example@example.de
Oct 14 14:51:27 vs611 postfix/pickup[27395]: warning: 1EEA564036: message has been queued for 4 days
Oct 14 14:51:27 vs611 postfix/pickup[27395]: 1EEA564036: uid=0 from=<root>

Oct 14 14:51:35 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry
Oct 14 14:51:35 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip.ip.ip): Password query failed: Not connected to database
Oct 14 14:51:42 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip.ip.ip): Password query failed: Not connected to database
Oct 14 14:51:54 vs611 dovecot: imap-login: Disconnected (auth failed, 2 attempts): user=<example@example.de>, method=PLAIN, rip=ip.ip.ip.ip, lip=ip.ip.ip.ip, TLS
Oct 14 14:52:05 vs611 postfix/proxymap[27398]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 14:52:05 vs611 postfix/trivial-rewrite[421]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 14:52:06 vs611 postfix/smtpd[27028]: warning: problem talking to service rewrite: Success
Oct 14 14:52:06 vs611 postfix/cleanup[27019]: warning: problem talking to service rewrite: Connection reset by peer
Oct 14 14:52:06 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 421 exit status 1
Oct 14 14:52:06 vs611 postfix/master[27389]: warning: /usr/lib/postfix/trivial-rewrite: bad command startup -- throttling
Oct 14 14:52:37 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry

Oct 14 14:53:06 vs611 postfix/proxymap[27398]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 14:53:06 vs611 postfix/trivial-rewrite[5054]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 14:53:07 vs611 postfix/smtpd[27028]: warning: problem talking to service rewrite: Success
Oct 14 14:53:07 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 5054 exit status 1
Oct 14 14:53:07 vs611 postfix/master[27389]: warning: /usr/lib/postfix/trivial-rewrite: bad command startup -- throttling
Oct 14 14:53:07 vs611 postfix/cleanup[27019]: warning: problem talking to service rewrite: Connection reset by peer
Click to expand...

mail.log
Oct 14 14:56:38 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry
Oct 14 14:56:38 vs611 dovecot: auth-worker(default): sql(example@example.de,ip.ip.ip.ip): Password query failed: Not connected to database

Oct 14 14:56:43 vs611 postfix/cleanup[20152]: warning: 959D664036: virtual_alias_maps map lookup problem for example@example.de
Oct 14 14:56:44 vs611 postfix/pickup[27395]: warning: 9A1C264036: message has been queued for 2 days
Oct 14 14:56:44 vs611 postfix/pickup[27395]: 9A1C264036: uid=0 from=<root>

Oct 14 14:56:45 vs611 dovecot: auth-worker(default): sql(example@example.de,ip.ip.ip.ip): Password query failed: Not connected to database
Oct 14 14:56:57 vs611 dovecot: imap-login: Disconnected (auth failed, 2 attempts): user=<example@example.com>, method=PLAIN, rip=ip.ip.ip.ip, lip=ip.ip.ip.ip, TLS

Oct 14 14:57:14 vs611 postfix/proxymap[19584]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 14:57:14 vs611 postfix/trivial-rewrite[24450]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 14:57:14 vs611 postfix/trivial-rewrite[24451]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 14:57:15 vs611 postfix/smtpd[16050]: warning: problem talking to service rewrite: Success
Oct 14 14:57:15 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 24450 exit status 1
Oct 14 14:57:15 vs611 postfix/master[27389]: warning: /usr/lib/postfix/trivial-rewrite: bad command startup -- throttling
Oct 14 14:57:15 vs611 postfix/smtpd[20149]: warning: problem talking to service rewrite: Connection reset by peer
Oct 14 14:57:15 vs611 postfix/smtpd[27028]: warning: problem talking to service rewrite: Success
Oct 14 14:57:15 vs611 postfix/cleanup[20152]: warning: problem talking to service rewrite: Connection reset by peer
Oct 14 14:57:15 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 24451 exit status 1
Click to expand...

mail.warn
Oct 14 14:59:35 vs611 postfix/pickup[27395]: warning: 94F0D64036: message has been queued for 3 days
Oct 14 14:59:35 vs611 postfix/cleanup[20152]: warning: 94F0D64036: virtual_alias_maps map lookup problem for example@example.com

Oct 14 14:59:38 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry
Oct 14 14:59:38 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip.ip.ip): Password query failed: Not connected to database

Oct 14 15:00:17 vs611 postfix/proxymap[27398]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 15:00:17 vs611 postfix/trivial-rewrite[5034]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 15:00:18 vs611 postfix/smtpd[20149]: warning: problem talking to service rewrite: Success
Oct 14 15:00:18 vs611 postfix/smtpd[16050]: warning: problem talking to service rewrite: Connection reset by peer
Oct 14 15:00:18 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 5033 exit status 1
Oct 14 15:00:18 vs611 postfix/master[27389]: warning: /usr/lib/postfix/trivial-rewrite: bad command startup -- throttling

Oct 14 15:00:39 vs611 dovecot: auth-worker(default): mysql: Connect failed to 127.0.0.1 (mail): Lost connection to MySQL server at 'reading initial communication packet', system error: 0 - waiting for 75 seconds before retry
Oct 14 15:00:39 vs611 dovecot: auth-worker(default): sql(example@example.com,ip.ip.ip.ip): Password query failed: Not connected to database

Oct 14 15:01:18 vs611 postfix/proxymap[19584]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 15:01:18 vs611 postfix/trivial-rewrite[9457]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 15:01:18 vs611 postfix/trivial-rewrite[9455]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 15:01:18 vs611 postfix/proxymap[27398]: warning: connect to mysql server 127.0.0.1: Lost connection to MySQL server at 'reading initial communication packet', system error: 0
Oct 14 15:01:18 vs611 postfix/trivial-rewrite[9456]: fatal: proxy:mysql:/etc/postfix/mysql-virtual_domains.cf(0,lock|fold_fix): table lookup problem
Oct 14 15:01:19 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 9457 exit status 1
Oct 14 15:01:19 vs611 postfix/smtpd[16050]: warning: problem talking to service rewrite: Connection reset by peer
Oct 14 15:01:19 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 9455 exit status 1
Oct 14 15:01:19 vs611 postfix/master[27389]: warning: /usr/lib/postfix/trivial-rewrite: bad command startup -- throttling
Oct 14 15:01:19 vs611 postfix/smtpd[20149]: warning: problem talking to service rewrite: Success
Oct 14 15:01:19 vs611 postfix/master[27389]: warning: process /usr/lib/postfix/trivial-rewrite pid 9456 exit status 1
Oct 14 15:01:19 vs611 postfix/smtpd[27028]: warning: problem talking to service rewrite: Success
Oct 14 15:01:19 vs611 postfix/cleanup[20152]: warning: problem talking to service rewrite: Connection reset by peer
Click to expand...

Ich hoffe der Fehler ist nur ganz klein und ich habe ihn nicht bemerkt...

Vielen Dank schonmal für eure Hilfe nach einer Woche ohne Mailserver wäre es ganz schön wieder mal Mails empfangen zu können....

Einen schönen Tag wünsche ich euch noch.
 
Moin

Noch deutlicher kann da doch gar nicht stehen, das Du ein Problem mit deinem MySQL Server hast.

Thorashh
 
Der MySQL Server funktioniert aber perfekt. Per Shell und per PHPMyAdmin kann ich mich einloggen. Auch mit dem User. Tabellen sind auch alle da.
 
Last edited by a moderator:
Moin

Das wäre eine wirklich wichtige Info gewesen.

Überprüfe mal die Einstellungen für den Datenbankzugriff. Stell den mal von 127.0.0.1 auf localhost um. Dann sollten postfix und dovecot über den socket gehen.
Das müsste dein Problem eigentlich schon beheben.

Thorashh
 
Danke für eure Antworten. Funktionieren tuts immernoch nicht, aber die Logs sind leerer, bzw. es steht nur noch fast das selbe drin. Ich glaube ich weiß jetzt auch warum 127.0.0.1 nicht mehr ging. Ich habe kürzlich ein Update von MySQL gemacht und damit hängt es wahrscheinlich zusammen. Naja zurück zu dovecot und postfix, jetzt steht nur noch das hier in den Logs:

mail.warn
Oct 15 15:16:26 vs611 postfix/pickup[10140]: warning: 6F08364037: message has been queued for 5 days
Oct 15 15:16:26 vs611 postfix/cleanup[31854]: warning: 6F08364037: virtual_alias_maps map lookup problem for example@example.com
Click to expand...

mail.log
Oct 15 15:16:40 vs611 postfix/cleanup[31854]: warning: connect to mysql server localhost: Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2)
Oct 15 15:16:40 vs611 postfix/cleanup[31854]: warning: 9AA8D64037: virtual_alias_maps map lookup problem for example@example.de
Oct 15 15:16:41 vs611 postfix/pickup[10140]: warning: BDFD164037: message has been queued for 2 days
Oct 15 15:16:41 vs611 postfix/pickup[10140]: BDFD164037: uid=0 from=<root>
Click to expand...

Da ist zwischendruch eine Meldung das er durch das socket nicht zum MySQL Server kommt, aber warum? Sonst funktionieren alle DIenste die MySQL nutzen.

mail.info
Oct 15 15:17:06 vs611 postfix/pickup[10140]: warning: 2F0DC64037: message has been queued for 4 days
Oct 15 15:17:06 vs611 postfix/pickup[10140]: 2F0DC64037: uid=0 from=<root>
Oct 15 15:17:06 vs611 postfix/cleanup[31854]: warning: 2F0DC64037: virtual_alias_maps map lookup problem for example@example.com
Click to expand...

Edit: So, es funktioniert jetzt alles wieder. Danke für den Tip mit localhost. Das lag an denyhosts wie ich gerade bemerkt habe. Aber es funktionierte ja immer noch nicht. So eigentlich ja total logisch. Im Log steht virtual_alias_maps map lookup problem. In der main.cf hatte ich das proxy: vor mysql:/etc/postfix/mysql_virtual..... vergessen. Das proxy: gesetzt nun funktioniert wieder alles perfekt. Was mich trotzdem interessieren würde, was den Fehler verursacht hat. Danke an alle nochmal.
 
Last edited by a moderator:
Code: 
warning: connect to mysql server localhost: Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock'
Der Hinweis ist eigentlich eindeutig. Prüfen ob das Verzeichnis "/var/run/mysqld" exisitiert und auch die Zugriffsrechte gesetzt sind.
 
Ja existiert, Rechte haben auch gepasst. Alle anderen Dienste die mit MySQL arbeiteten funktionierten ja. Aber siehe letzer Post von mir das Edit.
 
Moin
finnq said:
In der main.cf hatte ich das proxy: vor mysql:/etc/postfix/mysql_virtual..... vergessen. ... Was mich trotzdem interessieren würde, was den Fehler verursacht hat.
Click to expand...
Fehler 1: Steht in dem Link von Joe User
Fehler 2: Die einzelnen Prozesse von postfix laufen in einem chroot und haben deshalb keinen Zugriff auf Verzeichnisse außerhalb des /var/spool/postfix
Deshalb braucht es einen privilegierten Prozess (proxy), der auf den mysql-socket zugreifen darf und den dann an die anderen Prozesse weiterreicht.

Thorashh
 
Danke. Was ich eigentlich meinte war, warum dieses Problem einfach so aus heiterem Himmel kam. Aber ich denke wirklich es lag an einem Update was anderes hatte ich nicht gemacht.
 
You must log in or register to reply here.
Back
Top