Hi all, topology: java/tomcat app mailing to the outside via a C7 postfix relay server. problem: java app submits mail to postfix but there is _nothing_ logged in the postfix maillog. This happen for 2/3 of all mail submitted. We cannot see any trace of this submitted mail either incoming/stored/outgoing. Log from java app (shortened): DEBUG: getProvider() returning javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Sun Microsystems, Inc] DEBUG SMTP: useEhlo true, useAuth true DEBUG SMTP: trying to connect to host "xxxxxxxx", port 25, isSSL false DEBUG SMTP: Attempt to authenticate AUTH LOGIN 235 2.7.0 Authentication successful DEBUG SMTP: use8bit false DEBUG SMTP: Verified Addresses DATA 354 End data with <CR><LF>.<CR><LF> ****message text*** 250 2.0.0 Ok: queued as 8D83AC2756DF QUIT 221 2.0.0 Bye Log from the postfix server: [root at xxx postfix]# grep 8D83AC2756DF /var/log/maillog [root at xxx postfix]# This happens for 2/3 of all messages send to this server. Any idea what is happening here? Thx Rainer
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA256 On 01/28/2016 03:24 PM, Rainer Traut wrote:> Hi all,Hi,> topology: java/tomcat app mailing to the outside via a C7 postfix > relay server. > > problem: java app submits mail to postfix but there is _nothing_ > logged in the postfix maillog. This happen for 2/3 of all mail > submitted. We cannot see any trace of this submitted mail either > incoming/stored/outgoing. > > Log from java app (shortened): DEBUG: getProvider() returning > javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Sun > > >Microsystems, Inc]> DEBUG SMTP: useEhlo true, useAuth true DEBUG SMTP: trying to > connect to host "xxxxxxxx", port 25, isSSL false DEBUG SMTP: > Attempt to authenticate AUTH LOGIN 235 2.7.0 Authentication > successful DEBUG SMTP: use8bit false DEBUG SMTP: Verified Addresses > DATA 354 End data with <CR><LF>.<CR><LF> ****message text*** 250 > 2.0.0 Ok: queued as 8D83AC2756DF QUIT 221 2.0.0 Bye > > Log from the postfix server: [root at xxx postfix]# grep 8D83AC2756DF > /var/log/maillog [root at xxx postfix]# > > This happens for 2/3 of all messages send to this server. > > Any idea what is happening here?first, I'd recommend to increase debug level of postfix (if it doesn't totally flood your logs): /etc/postfix/master.cf: # Postfix master process configuration file. For details on the format # of the file, see the master(5) manual page (command: "man 5 master"). # # Do not forget to execute "postfix reload" after editing this file. # # ========================================================================# service type private unpriv chroot wakeup maxproc command + args # (yes) (yes) (yes) (never) (100) # ========================================================================smtp inet n - n - - smtpd -v <= !!! #smtp inet n - n - 1 postscreen #smtpd pass - - n - - smtpd smtpd -v instead of smtpd -- that will hopefully give some more insight.> Thx RainerBest, T. -----BEGIN PGP SIGNATURE----- Version: GnuPG v2 iF4EAREIAAYFAlaqJWUACgkQuSPmkPhAW0r0FQD/dCq8EBA3OH8PKRSBmO83llOw u54IjRZwZTk51N9u8A4A/A91QRmkVXXIKNfdC0viGP8ar2proK+ju6Gdo1s8cz9C =7ELn -----END PGP SIGNATURE-----
Hi Timo, Am 28.01.2016 um 15:27 schrieb Timo Schoeler:> -----BEGIN PGP SIGNED MESSAGE----- > Hash: SHA256 > smtp inet n - n - - smtpd -v <= !!! > #smtp inet n - n - 1 postscreen > #smtpd pass - - n - - smtpd > > smtpd -v instead of smtpd -- that will hopefully give some more insight.Did that and I think I have a candidate: # grep B8678C1DD078 /var/log/maillog Jan 28 15:35:11 x postfix/smtpd[17752]: input attribute value: B8678C1DD078 Jan 28 15:35:11 x postfix/smtpd[17752]: B8678C1DD078: client=x, sasl_method=LOGIN, sasl_username=x Jan 28 15:35:11 x postfix/cleanup[17755]: B8678C1DD078: message-id=<1535444742.91269.1453991711730.JavaMail.tomcat at x> Jan 28 15:35:11 x postfix/smtpd[17752]: > x: 250 2.0.0 Ok: queued as B8678C1DD078 Jan 28 15:35:11 x postfix/qmgr[17622]: B8678C1DD078: from=<x>, size=20507, nrcpt=1 (queue active) And another one working with the same destination domain: # grep 9DD4AC1DD078 /var/log/maillog Jan 28 15:35:11 x postfix/smtpd[17752]: input attribute value: 9DD4AC1DD078 Jan 28 15:35:11 x postfix/smtpd[17752]: 9DD4AC1DD078: client=x, sasl_method=LOGIN, sasl_username=x Jan 28 15:35:11 x postfix/cleanup[17755]: 9DD4AC1DD078: message-id=<995903891.91260.1453991711622.JavaMail.tomcat at x> Jan 28 15:35:11 x postfix/qmgr[17622]: 9DD4AC1DD078: from=<x>, size=3099, nrcpt=1 (queue active) Jan 28 15:35:11 x postfix/smtpd[17752]: > x: 250 2.0.0 Ok: queued as 9DD4AC1DD078 Jan 28 15:35:11 x postfix/smtp[17756]: 9DD4AC1DD078: to=<x>, relay=x:25, delay=0.11, delays=0.02/0/0.04/0.05, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as AE7B241052) Jan 28 15:35:11 x postfix/qmgr[17622]: 9DD4AC1DD078: removed In the broken one there is a qmgr missing and smtp is not sending out. Any ideas? Thx