HowtoForge Forums | HowtoForge - Linux Howtos and Tutorials

HowtoForge Forums | HowtoForge - Linux Howtos and Tutorials (http://www.howtoforge.com/forums/index.php)
-   Installation/Configuration (http://www.howtoforge.com/forums/forumdisplay.php?f=27)
-   -   cant send mail to gmail accounts (http://www.howtoforge.com/forums/showthread.php?t=46141)

zogthegreat 26th May 2010 17:41

cant send mail to gmail accounts
 
Hi everyone,



After numerous rebuilds, I have finally gotten my ISPConfig 3 server mostly running. Right now I am having problems sending email's to certain servers, notably g-mail. Here is the output or tail -f /var/log/maillog:

May 26 11:16:03 server1 postfix/qmgr[1729]: 48EEC1807C: from=<bill@XXX.com>, size=976, nrcpt=1 (queue active)
May 26 11:16:03 server1 sendmail[21469]: o4QFG2K2021469: to=calvin.YYY.@gmail.com, ctladdr=bill@XXX.com (48/48), delay=00:00:01, xdelay=00:00:01, mailer=relay, pri=30525, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: queued as 48EEC1807C)
May 26 11:16:03 server1 postfix/smtpd[21433]: disconnect from localhost.localdomain[127.0.0.1]
May 26 11:16:03 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:16:03 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[41791], protocol=IMAP
May 26 11:16:04 server1 amavis[1813]: (01813-06) (!!)WARN: all primary virus scanners failed, considering backups
May 26 11:16:04 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=624, sent=203, time=1
May 26 11:16:04 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:16:04 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[41792], protocol=IMAP
May 26 11:16:04 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=213, body=0, rcvd=309, sent=1384, time=0
May 26 11:16:39 server1 postfix/smtpd[21509]: connect from unknown[127.0.0.1]
May 26 15:16:39 server1 postfix/smtpd[21509]: 4DBDF18157: client=unknown[127.0.0.1]
May 26 11:16:39 server1 postfix/cleanup[21487]: 4DBDF18157: message-id=<59308.192.168.0.1.1274886962.squirrel@XXX.com>
May 26 11:16:39 server1 postfix/qmgr[1729]: 4DBDF18157: from=<bill@XXX.com>, size=1427, nrcpt=1 (queue active)
May 26 11:16:39 server1 amavis[1813]: (01813-06) Passed CLEAN, MYNETS LOCAL [127.0.0.1] [127.0.0.1] <bill@XXX.com> -> <calvin.YYY.@gmail.com>, Message-ID: <59308.192.168.0.1.1274886962.squirrel@XXX.com>, mail_id: nvnR15B4E5LD, Hits: -1, size: 976, queued_as: 4DBDF18157, 36015 ms
May 26 15:16:39 server1 postfix/smtpd[21509]: disconnect from unknown[127.0.0.1]
May 26 11:16:39 server1 postfix/smtp[21491]: 48EEC1807C: to=<calvin.YYY.@gmail.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=37, delays=0.34/0.12/0.04/36, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01813-06, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 4DBDF18157)
May 26 11:16:39 server1 postfix/qmgr[1729]: 48EEC1807C: removed
May 26 11:17:09 server1 postfix/smtp[21511]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 26 11:17:39 server1 postfix/smtp[21511]: connect to alt1.gmail-smtp-in.l.google.com[209.85.219.3]: Connection timed out (port 25)
May 26 11:18:10 server1 postfix/smtp[21511]: connect to alt2.gmail-smtp-in.l.google.com[209.85.129.114]: Connection timed out (port 25)
May 26 11:18:40 server1 postfix/smtp[21511]: connect to alt3.gmail-smtp-in.l.google.com[74.125.43.27]: Connection timed out (port 25)
May 26 11:19:10 server1 postfix/smtp[21511]: connect to alt4.gmail-smtp-in.l.google.com[209.85.222.38]: Connection timed out (port 25)
May 26 11:19:10 server1 postfix/smtp[21511]: 4DBDF18157: to=<calvin.YYY.@gmail.com>, relay=none, delay=151, delays=0.14/0.28/150/0, dsn=4.4.1, status=deferred (connect to alt4.gmail-smtp-in.l.google.com[209.85.222.38]: Connection timed out)
May 26 11:19:53 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:19:53 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[41805], protocol=IMAP
May 26 11:19:53 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=391, time=0
May 26 11:20:04 server1 postfix/smtpd[21671]: connect from localhost.localdomain[127.0.0.1]
May 26 11:20:04 server1 postfix/smtpd[21671]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
May 26 11:20:04 server1 postfix/smtpd[21671]: disconnect from localhost.localdomain[127.0.0.1]
May 26 11:20:04 server1 pop3d: Connection, ip=[::ffff:127.0.0.1]
May 26 11:20:04 server1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
May 26 11:20:04 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:20:04 server1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
May 26 11:24:53 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:24:54 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[39628], protocol=IMAP
May 26 11:24:54 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=391, time=1
May 26 11:25:04 server1 postfix/smtpd[21881]: connect from localhost.localdomain[127.0.0.1]
May 26 11:25:04 server1 postfix/smtpd[21881]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
May 26 11:25:04 server1 postfix/smtpd[21881]: disconnect from localhost.localdomain[127.0.0.1]
May 26 11:25:04 server1 pop3d: Connection, ip=[::ffff:127.0.0.1]
May 26 11:25:04 server1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
May 26 11:25:04 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:25:04 server1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
May 26 11:29:54 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:29:54 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[52569], protocol=IMAP
May 26 11:29:54 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=391, time=0
May 26 11:30:04 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 11:30:04 server1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
May 26 11:30:04 server1 pop3d: Connection, ip=[::ffff:127.0.0.1]
May 26 11:30:04 server1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
May 26 11:30:04 server1 postfix/smtpd[22092]: connect from localhost.localdomain[127.0.0.1]
May 26 11:30:04 server1 postfix/smtpd[22092]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
May 26 11:30:04 server1 postfix/smtpd[22092]: disconnect from localhost.localdomain[127.0.0.1]

Does anyone have any suggestions as to what is wrong? Also, I have numerous connections and disconnections from ip=[::ffff:127.0.0.1] Is this normal? When I say "numerous" I mean hundreds per day.

Thanks

zog

zogthegreat 26th May 2010 17:49

p.s.

I can send to hotmail with no problems:

May 26 11:45:33 server1 postfix/qmgr[1729]: 806881815B: from=<bill@XXX.com>, size=1419, nrcpt=1 (queue active)
May 26 11:45:33 server1 amavis[1813]: (01813-07) (!!)WARN: all primary virus scanners failed, considering backups
May 26 11:45:33 server1 postfix/smtp[22600]: 806881815B: to=<w.YYY@sympatico.ca>, relay=mxmta.sympatico.ca[67.69.240.17]:25, delay=1, delays=0.54/0.04/0.32/0.11, dsn=2.0.0, status=sent (250 ok: Message 238700910 accepted)
May 26 11:45:33 server1 postfix/qmgr[1729]: 806881815B: removed
May 26 15:46:03 server1 postfix/smtpd[22768]: connect from unknown[127.0.0.1]
May 26 15:46:03 server1 postfix/smtpd[22768]: 0FA6218159: client=unknown[127.0.0.1]
May 26 11:46:03 server1 postfix/cleanup[22683]: 0FA6218159: message-id=<50596.192.168.0.1.1274888730.squirrel@XXX.com>
May 26 11:46:03 server1 postfix/qmgr[1729]: 0FA6218159: from=<bill@XXX.com>, size=1423, nrcpt=1 (queue active)
May 26 15:46:03 server1 postfix/smtpd[22768]: disconnect from unknown[127.0.0.1]
May 26 11:46:03 server1 amavis[1813]: (01813-07) Passed CLEAN, MYNETS LOCAL [127.0.0.1] [127.0.0.1] <bill@XXX.com> -> <w.YYY@sympatico.ca>, Message-ID: <50596.192.168.0.1.1274888730.squirrel@XXX.com>, mail_id: pQNKxRH4uAMt, Hits: -1, size: 976, queued_as: 0FA6218159, 31653 ms
May 26 11:46:03 server1 postfix/smtp[22764]: 04B651815A: to=<w.YYY@sympatico.ca>, relay=127.0.0.1[127.0.0.1]:10024, delay=32, delays=0.41/0.14/0.12/32, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01813-07, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 0FA6218159)
May 26 11:46:03 server1 postfix/qmgr[1729]: 04B651815A: removed
May 26 11:46:03 server1 postfix/smtp[22600]: 0FA6218159: to=<w.YYY@sympatico.ca>, relay=mxmta.sympatico.ca[67.69.240.21]:25, delay=0.47, delays=0.08/0.01/0.28/0.11, dsn=2.0.0, status=sent (250 ok: Message 160045881 accepted)
May 26 11:46:03 server1 postfix/qmgr[1729]: 0FA6218159: removed

(my isp uses hotmail as it's e-mail server)

also, the output of netstat -tap:

[root@server1 ~]# netstat -tap
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name
tcp 0 0 localhost.localdomain:10024 *:* LISTEN 1639/amavisd (maste
tcp 0 0 localhost.localdomain:10025 *:* LISTEN 25513/master
tcp 0 0 *:mysql *:* LISTEN 1563/mysqld
tcp 0 0 localhost.local:dyna-access *:* LISTEN 1475/clamd
tcp 0 0 *:ftp *:* LISTEN 1759/pure-ftpd (SER
tcp 0 0 192.168.0.110:domain *:* LISTEN 1597/mydns
tcp 0 0 localhost.localdomai:domain *:* LISTEN 1597/mydns
tcp 0 0 *:smtp *:* LISTEN 25513/master
tcp 1 1 server1.example.com:42888 mandril.creatis.insa-l:http LAST_ACK -
tcp 0 0 localhost.localdomain:ftp localhost.localdomain:46573 TIME_WAIT -
tcp 0 0 localhost.localdomain:39026 localhost.localdomai:domain TIME_WAIT -
tcp 0 0 localhost.localdomain:40226 localhost.localdomain:http TIME_WAIT -
tcp 1 1 server1.example.com:54926 benden.crazyfrogs.org:http LAST_ACK -
tcp 0 0 *:imaps *:* LISTEN 1657/couriertcpd
tcp 0 0 *:pop3s *:* LISTEN 1669/couriertcpd
tcp 0 0 *:pop3 *:* LISTEN 1663/couriertcpd
tcp 0 0 *:imap *:* LISTEN 1650/couriertcpd
tcp 0 0 *:webcache *:* LISTEN 18771/httpd
tcp 0 0 *:http *:* LISTEN 18771/httpd
tcp 0 0 *:tproxy *:* LISTEN 18771/httpd
tcp 0 0 *:ftp *:* LISTEN 1759/pure-ftpd (SER
tcp 0 0 localhost6.localdoma:domain *:* LISTEN 1597/mydns
tcp 0 0 *:ssh *:* LISTEN 1429/sshd
tcp 0 0 *:https *:* LISTEN 18771/httpd
tcp 0 0 server1.example.com:pop3 gateway:58704 TIME_WAIT -
tcp 0 144 server1.example.com:ssh ::ffff:192.168.0.189:44292 ESTABLISHED 21254/1
thanks

zog

maced0n 26th May 2010 19:54

Check if your IP is blacklisted http://mxtoolbox.com/blacklists.aspx


Connections and disconnections from ip=[::ffff:127.0.0.1] are normal.

zogthegreat 27th May 2010 00:15

Hi maced0n,

I tried the link you sent me and I am not on the blacklist. I noticed a few things though.

1. At http://mxtoolbox.com, I got the following error:

No connection could be made because the target machine actively refused it 74.13.202.19:25

2. Watching tail -f /var/log/maillog I notice the following:

May 26 18:07:22 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[40837], protocol=IMAP
May 26 18:07:22 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=485, sent=1654, time=1
May 26 18:07:22 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 18:07:22 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[40838], protocol=IMAP
May 26 18:07:22 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=213, body=0, rcvd=309, sent=1389, time=0
May 26 18:07:34 server1 sendmail[16397]: o4QM7Yig016397: Authentication-Warning: server1.example.com: apache set sender to bill@XXX.com using -f
May 26 18:07:34 server1 sendmail[16397]: o4QM7Yig016397: from=bill@XXX.com, size=511, class=0, nrcpts=1, msgid=<38189.192.168.0.1.1274911654.squirrel@XXX.c om>, relay=apache@localhost
May 26 18:07:34 server1 sendmail[16397]: o4QM7Yig016397: to=ping@mxtoolbox.com, ctladdr=bill@XXX.com (48/48), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=30511, relay=[127.0.0.1] [127.0.0.1], dsn=4.0.0, stat=Deferred: Connection refused by [127.0.0.1]
May 26 18:07:34 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 18:07:34 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[40841], protocol=IMAP
May 26 18:07:35 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=610, sent=203, time=1
May 26 18:07:35 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 26 18:07:35 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[40842], protocol=IMAP
May 26 18:07:35 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=213, body=0, rcvd=309, sent=1389, time=0

The "Connection refused by [127.0.0.1]" catches my attention. Is it possible something is misconfigured on my machine?

Thanks

zog

till 27th May 2010 08:45

Please restart postfix and then check the mail.log again if there are any new errors.

zogthegreat 27th May 2010 17:14

Hi till,

Did as you suggested, but no luck. Here is my output of tail -f /var/log/maillog:

[root@server1 ~]# service postfix restart
Shutting down postfix: [ OK ]
Starting postfix: [ OK ]
[root@server1 ~]# tail -f /var/log/maillog
May 27 11:07:38 server1 postfix/master[1845]: terminating on signal 15
May 27 11:07:38 server1 sendmail[5083]: alias database /etc/aliases rebuilt by root
May 27 11:07:38 server1 sendmail[5083]: /etc/aliases: 77 aliases, longest 10 bytes, 779 bytes total
May 27 11:07:40 server1 postfix/postfix-script: starting the Postfix mail system
May 27 11:07:40 server1 postfix/master[5129]: daemon started -- version 2.3.3, configuration /etc/postfix
May 27 11:07:40 server1 postfix/qmgr[5132]: 590FC803BE: from=<calvin@XXX.com>, size=1431, nrcpt=1 (queue active)
May 27 11:07:40 server1 postfix/qmgr[5132]: 53C68803A7: from=<calvin@XXX.com>, size=1435, nrcpt=1 (queue active)
May 27 11:07:40 server1 postfix/qmgr[5132]: 5D4AA803BC: from=<calvin@XXX.com>, size=1435, nrcpt=1 (queue active)
May 27 11:07:40 server1 postfix/qmgr[5132]: B4AAB803C8: from=<bill@XXX.com>, size=1616, nrcpt=1 (queue active)
May 27 11:07:40 server1 postfix/qmgr[5132]: 3730380388: from=<bill@XXX.com>, size=1620, nrcpt=1 (queue active)
May 27 11:07:50 server1 postfix/smtpd[5144]: connect from mail-vw0-f53.google.com[209.85.212.53]
May 27 11:07:51 server1 postfix/smtpd[5144]: 5F5EB8036C: client=mail-vw0-f53.google.com[209.85.212.53]
May 27 11:07:51 server1 postfix/cleanup[5152]: 5F5EB8036C: message-id=<AANLkTil4a5BgOCZSEOjyIZLn-kp9FaJOVDjYsWm3CG22@mail.gmail.com>
May 27 11:07:51 server1 postfix/qmgr[5132]: 5F5EB8036C: from=<william.YYY@gmail.com>, size=1845, nrcpt=1 (queue active)
May 27 11:07:51 server1 amavis[1816]: (01816-03) (!!)WARN: all primary virus scanners failed, considering backups
May 27 11:08:01 server1 postfix/smtpd[5156]: connect from unknown[127.0.0.1]
May 27 15:08:01 server1 postfix/smtpd[5156]: 075F4803A9: client=unknown[127.0.0.1]
May 27 11:08:01 server1 postfix/cleanup[5152]: 075F4803A9: message-id=<AANLkTil4a5BgOCZSEOjyIZLn-kp9FaJOVDjYsWm3CG22@mail.gmail.com>
May 27 15:08:01 server1 postfix/smtpd[5156]: disconnect from unknown[127.0.0.1]
May 27 11:08:01 server1 amavis[1816]: (01816-03) Passed CLEAN, [209.85.212.53] [209.85.212.53] <william.YYY@gmail.com> -> <bill@XXX.com>, Message-ID: <AANLkTil4a5BgOCZSEOjyIZLn-kp9FaJOVDjYsWm3CG22@mail.gmail.com>, mail_id: XkRaHhO3p4mG, Hits: -0.089, size: 1845, queued_as: 075F4803A9, dkim_id=@gmail.com,william.YYY@gmail.com, 9857 ms
May 27 11:08:01 server1 postfix/smtp[5153]: 5F5EB8036C: to=<bill@XXX.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=10, delays=0.36/0.03/0.01/9.9, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01816-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 075F4803A9)
May 27 11:08:01 server1 postfix/qmgr[5132]: 5F5EB8036C: removed
May 27 11:08:01 server1 postfix/qmgr[5132]: 075F4803A9: from=<william.YYY@gmail.com>, size=2278, nrcpt=1 (queue active)
May 27 11:08:02 server1 postfix/pipe[5158]: 075F4803A9: to=<bill@XXX.com>, relay=maildrop, delay=1, delays=0.53/0.07/0/0.44, dsn=2.0.0, status=sent (delivered via maildrop service)
May 27 11:08:02 server1 postfix/qmgr[5132]: 075F4803A9: removed
May 27 11:08:08 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:08 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[50660], protocol=IMAP
May 27 11:08:08 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=30, sent=238, time=0
May 27 11:08:09 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:09 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[50661], protocol=IMAP
May 27 11:08:09 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:09 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[50662], protocol=IMAP
May 27 11:08:09 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=431, sent=1435, time=0
May 27 11:08:09 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=3195, body=0, rcvd=328, sent=7308, time=0
May 27 11:08:10 server1 postfix/smtp[5139]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 27 11:08:10 server1 postfix/smtp[5142]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 27 11:08:10 server1 postfix/smtp[5140]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 27 11:08:10 server1 postfix/smtp[5141]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 27 11:08:12 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:12 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[50669], protocol=IMAP
May 27 11:08:12 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=2103, rcvd=148, sent=2951, time=0
May 27 11:08:16 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:16 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[50670], protocol=IMAP
May 27 11:08:16 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=2103, rcvd=148, sent=2928, time=0
May 27 11:08:19 server1 sendmail[5207]: o4RF8Jvi005207: Authentication-Warning: server1.example.com: apache set sender to bill@XXX.com using -f
May 27 11:08:19 server1 sendmail[5207]: o4RF8Jvi005207: from=bill@XXX.com, size=707, class=0, nrcpts=1, msgid=<55545.192.168.0.1.1274972899.squirrel@XXX.c om>, relay=apache@localhost
May 27 11:08:19 server1 postfix/smtpd[5208]: connect from localhost.localdomain[127.0.0.1]
May 27 11:08:19 server1 sendmail[5207]: STARTTLS=client, relay=[127.0.0.1], field=cn_subject, status=failed to extract CN
May 27 11:08:19 server1 sendmail[5207]: STARTTLS=client, relay=[127.0.0.1], field=cn_issuer, status=failed to extract CN
May 27 11:08:19 server1 sendmail[5207]: STARTTLS=client, relay=[127.0.0.1], version=TLSv1/SSLv3, verify=FAIL, cipher=DHE-RSA-AES256-SHA, bits=256/256
May 27 11:08:19 server1 postfix/smtpd[5208]: B5F3D8039C: client=localhost.localdomain[127.0.0.1]
May 27 11:08:19 server1 postfix/cleanup[5152]: B5F3D8039C: message-id=<55545.192.168.0.1.1274972899.squirrel@XXX.com>
May 27 11:08:19 server1 postfix/qmgr[5132]: B5F3D8039C: from=<bill@XXX.com>, size=1163, nrcpt=1 (queue active)
May 27 11:08:19 server1 sendmail[5207]: o4RF8Jvi005207: to="William YYY" <william.YYY@gmail.com>, ctladdr=bill@XXX.com (48/48), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=30707, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (Ok: queued as B5F3D8039C)
May 27 11:08:19 server1 postfix/smtpd[5208]: disconnect from localhost.localdomain[127.0.0.1]
May 27 11:08:19 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:19 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[36679], protocol=IMAP
May 27 11:08:19 server1 amavis[1817]: (01817-03) (!!)WARN: all primary virus scanners failed, considering backups
May 27 11:08:20 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=886, sent=554, time=1
May 27 11:08:20 server1 imapd: Connection, ip=[::ffff:127.0.0.1]
May 27 11:08:20 server1 imapd: LOGIN, user=bill@XXX.com, ip=[::ffff:127.0.0.1], port=[36680], protocol=IMAP
May 27 11:08:20 server1 imapd: LOGOUT, user=bill@XXX.com, ip=[::ffff:127.0.0.1], headers=3195, body=0, rcvd=328, sent=7323, time=0
May 27 11:08:22 server1 postfix/smtpd[5144]: disconnect from mail-vw0-f53.google.com[209.85.212.53]
May 27 15:08:29 server1 postfix/smtpd[5156]: connect from unknown[127.0.0.1]
May 27 15:08:29 server1 postfix/smtpd[5156]: 63CE5803CB: client=unknown[127.0.0.1]
May 27 11:08:29 server1 postfix/cleanup[5152]: 63CE5803CB: message-id=<55545.192.168.0.1.1274972899.squirrel@XXX.com>
May 27 11:08:29 server1 postfix/qmgr[5132]: 63CE5803CB: from=<bill@XXX.com>, size=1616, nrcpt=1 (queue active)
May 27 15:08:29 server1 postfix/smtpd[5156]: disconnect from unknown[127.0.0.1]
May 27 11:08:29 server1 amavis[1817]: (01817-03) Passed CLEAN, MYNETS LOCAL [127.0.0.1] [127.0.0.1] <bill@XXX.com> -> <william.YYY@gmail.com>, Message-ID: <55545.192.168.0.1.1274972899.squirrel@XXX.com>, mail_id: TZIlBALecblu, Hits: -1, size: 1163, queued_as: 63CE5803CB, 9649 ms
May 27 15:08:29 server1 postfix/smtp[5153]: B5F3D8039C: to=<william.YYY@gmail.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=9.8, delays=0.11/0/0.02/9.7, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01817-03, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 63CE5803CB)
May 27 11:08:29 server1 postfix/qmgr[5132]: B5F3D8039C: removed
May 27 11:08:40 server1 postfix/smtp[5139]: connect to alt1.gmail-smtp-in.l.google.com[209.85.229.27]: Connection timed out (port 25)
May 27 11:08:40 server1 postfix/smtp[5142]: connect to alt1.gmail-smtp-in.l.google.com[74.125.79.27]: Connection timed out (port 25)
May 27 11:08:40 server1 postfix/smtp[5140]: connect to alt1.gmail-smtp-in.l.google.com[74.125.79.27]: Connection timed out (port 25)
May 27 11:08:40 server1 postfix/smtp[5141]: connect to alt1.gmail-smtp-in.l.google.com[209.85.229.27]: Connection timed out (port 25)
May 27 11:08:59 server1 postfix/smtp[5217]: connect to gmail-smtp-in.l.google.com[74.125.113.27]: Connection timed out (port 25)
May 27 11:09:10 server1 postfix/smtp[5139]: connect to alt2.gmail-smtp-in.l.google.com[209.85.129.27]: Connection timed out (port 25)
May 27 11:09:10 server1 postfix/smtp[5142]: connect to alt2.gmail-smtp-in.l.google.com[209.85.129.27]: Connection timed out (port 25)
May 27 11:09:10 server1 postfix/smtp[5140]: connect to alt2.gmail-smtp-in.l.google.com[72.14.221.114]: Connection timed out (port 25)
May 27 11:09:10 server1 postfix/smtp[5141]: connect to alt2.gmail-smtp-in.l.google.com[72.14.221.114]: Connection timed out (port 25)

Not sure what to do next, Postfix seems to be working, I can send mail to other servers, just gmail and other certain servers are timing out.

zog

edge 27th May 2010 18:28

See if you can ping from the server in question any of the refused domains (alt1.gmail-smtp-in.l.google.com and so on).
If not, it might be a DNS isue.

zogthegreat 27th May 2010 18:33

hi edge,

No problem with the ping:

[root@server1 ~]# ping -c 3 alt1.gmail-smtp-in.l.google.com
PING alt1.gmail-smtp-in.l.google.com (74.125.79.114) 56(84) bytes of data.
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=1 ttl=242 time=97.0 ms
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=2 ttl=242 time=98.0 ms
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=3 ttl=242 time=97.9 ms

I am going to go back and look at my dns setup. Since my ISP does not provide a static dns, I am using a dynamic dns service, (DNS2GO), fo my external IP.

Any suggestions where I should start digging?

Thanks

zog

edge 27th May 2010 18:39

Quote:

Originally Posted by zogthegreat (Post 229404)
hi edge,

No problem with the ping:

[root@server1 ~]# ping -c 3 alt1.gmail-smtp-in.l.google.com
PING alt1.gmail-smtp-in.l.google.com (74.125.79.114) 56(84) bytes of data.
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=1 ttl=242 time=97.0 ms
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=2 ttl=242 time=98.0 ms
64 bytes from ey-in-f114.1e100.net (74.125.79.114): icmp_seq=3 ttl=242 time=97.9 ms

I am going to go back and look at my dns setup. Since my ISP does not provide a static dns, I am using a dynamic dns service, (DNS2GO), fo my external IP.

Any suggestions where I should start digging?

Thanks

zog

As your DNS is working fine, than this is not the problem, and no need to do any digging in it.

zogthegreat 27th May 2010 18:41

Hi edge,

So do you have any suggestions as what is going wrong?

Thanks

zog


All times are GMT +2. The time now is 01:32.

Powered by vBulletin® Version 3.8.7
Copyright ©2000 - 2014, vBulletin Solutions, Inc.