Sorry guys!
Here is the CLEAN LOG (I added some trash on the other message);
# TEST 1 - ZOOM4U.COM.BR
13:19:55 - Sending email #1...OK
13:19:56 - Sending email #2...OK
# PF
Mar 15 13:21:05 mxcluster postfix/smtpd[32624]: connect from
srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:05 mxcluster postfix/smtpd[32624]: 4B5ED20FA17:
client=srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:05 mxcluster postfix/cleanup[32629]: 4B5ED20FA17:
message-id=<cfa989796f031bc8b7ce2dcb36bcd...@iagentemail.com.br>
Mar 15 13:21:05 mxcluster dkim-filter[4513]: 4B5ED20FA17
"DKIM-Signature" header added
Mar 15 13:21:05 mxcluster postfix/qmgr[32622]: 4B5ED20FA17:
from=<reto...@iagentemail.com.br>, size=631, nrcpt=2 (queue active)
Mar 15 13:21:05 mxcluster postfix/smtpd[32624]: BEADA20FA21:
client=srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:05 mxcluster postfix/cleanup[32629]: BEADA20FA21:
message-id=<dbb4e00eee98482ba5a38b204393b...@iagentemail.com.br>
Mar 15 13:21:06 mxcluster dkim-filter[4513]: BEADA20FA21
"DKIM-Signature" header added
Mar 15 13:21:06 mxcluster postfix/qmgr[32622]: BEADA20FA21:
from=<reto...@iagentemail.com.br>, size=631, nrcpt=2 (queue active)
Mar 15 13:21:08 mxcluster postfix/smtp[32630]: 4B5ED20FA17:
to=<raf...@gmail.com>,
relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=3,
delays=0.48/0.01/0.48/2, dsn=2.0.0, status=sent (250 2.0.0 OK
1300205995 v5si10220362ybk.19)
Mar 15 13:21:08 mxcluster postfix/smtp[32633]: BEADA20FA21:
to=<raf...@gmail.com>,
relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=3,
delays=0.47/0.01/0.47/2, dsn=2.0.0, status=sent (250 2.0.0 OK
1300205996 c4si22142822ana.147)
Mar 15 13:21:11 mxcluster imapd: Connection, ip=[::ffff:189.38.86.124]
Mar 15 13:21:11 mxcluster authdaemond: received auth request,
service=imap, authtype=login
Mar 15 13:21:11 mxcluster authdaemond: authmysql: trying this module
Mar 15 13:21:11 mxcluster authdaemond: authmysqllib: connected.
Versions: header 50153, client 50153, server 50153
Mar 15 13:21:11 mxcluster authdaemond: SQL query: SELECT username,
password, "", '207', '207', '/home/vmail/',
CONCAT('/home/vmail/',maildir), "", name, "" FROM mailbox WHERE
username = 'mail...@iagentemail.com.br'
Mar 15 13:21:11 mxcluster authdaemond: password matches successfully
Mar 15 13:21:11 mxcluster authdaemond: authmysql: sysusername=<null>,
sysuserid=207, sysgroupid=207, homedir=/home/vmail/,
address=mail...@iagentemail.com.br, fullname=Diego,
maildir=/home/vmail/mail...@iagentemail.com.br/, quota=<null>,
options=<null>
Mar 15 13:21:11 mxcluster authdaemond: Authenticated:
sysusername=<null>, sysuserid=207, sysgroupid=207,
homedir=/home/vmail/, address=mail...@iagentemail.com.br,
fullname=Diego, maildir=/home/vmail/mail...@iagentemail.com.br/,
quota=<null>, options=<null>
Mar 15 13:21:11 mxcluster imapd: LOGIN,
user=mail...@iagentemail.com.br, ip=[::ffff:189.38.86.124],
port=[42229], protocol=IMAP
Mar 15 13:21:12 mxcluster postfix/smtp[32634]: BEADA20FA21:
to=<di...@iagente.com.br>, relay=mx2.iagente.com.br[189.38.95.92]:25,
delay=7, delays=0.47/0.02/0.32/6.2, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as 39BA26DFCFC3D)
Mar 15 13:21:12 mxcluster postfix/qmgr[32622]: BEADA20FA21: removed
Mar 15 13:21:12 mxcluster imapd: LOGOUT,
user=mail...@iagentemail.com.br, ip=[::ffff:189.38.86.124], headers=0,
body=1700893, rcvd=5894, sent=1784438, time=1
# TEST 2 - IAGENTEMAIL.COM.BR
13:20:06 - Sending email #1...OK
13:20:07 - Sending email #2...OK
# PF
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: lost connection after
RSET from srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: disconnect from
srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster dkim-filter[4513]: cache: 105368 queries,
5037 hits (4%), 5 expired
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: connect from
srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: 4034320FAC4:
client=srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster postfix/cleanup[32629]: 4034320FAC4:
message-id=<71101a571841567787cf650fdf6dc...@iagentemail.com.br>
Mar 15 13:21:16 mxcluster postfix/smtp[32631]: 4B5ED20FA17:
to=<di...@iagente.com.br>, relay=mx3.iagente.com.br[189.38.95.5]:25,
delay=11, delays=0.48/0.02/0.33/10, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as B8DEB1FDFA1D9)
Mar 15 13:21:16 mxcluster postfix/qmgr[32622]: 4B5ED20FA17: removed
Mar 15 13:21:16 mxcluster dkim-filter[4513]: 4034320FAC4
"DKIM-Signature" header added
Mar 15 13:21:16 mxcluster postfix/qmgr[32622]: 4034320FAC4:
from=<reto...@iagentemail.com.br>, size=632, nrcpt=2 (queue active)
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: 5A4A520FADE:
client=srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster postfix/cleanup[32629]: 5A4A520FADE:
message-id=<b18bac1205dff5edc6f56bd24ccd4...@iagentemail.com.br>
Mar 15 13:21:16 mxcluster dkim-filter[4513]: 5A4A520FADE
"DKIM-Signature" header added
Mar 15 13:21:16 mxcluster postfix/qmgr[32622]: 5A4A520FADE:
from=<reto...@iagentemail.com.br>, size=632, nrcpt=2 (queue active)
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: lost connection after
RSET from srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:16 mxcluster postfix/smtpd[32624]: disconnect from
srv01.iagentemail.com.br[189.38.86.124]
Mar 15 13:21:18 mxcluster postfix/smtp[32630]: 4034320FAC4:
to=<raf...@gmail.com>,
relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=2.6,
delays=0.1/0/0.46/2, dsn=2.0.0, status=sent (250 2.0.0 OK 1300206006
x1si22136093anx.190)
Mar 15 13:21:18 mxcluster postfix/smtp[32633]: 5A4A520FADE:
to=<raf...@gmail.com>,
relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=2.6,
delays=0.09/0/0.46/2, dsn=2.0.0, status=sent (250 2.0.0 OK 1300206006
p36si10211256ybk.38)
Mar 15 13:21:22 mxcluster postfix/smtp[32631]: 4034320FAC4:
to=<di...@iagente.com.br>, relay=mx2.iagente.com.br[189.38.95.92]:25,
delay=6.6, delays=0.1/0/0.31/6.2, dsn=2.0.0, status=sent (250 2.0.0
Ok: queued as 438906DFD1E63)
Mar 15 13:21:22 mxcluster postfix/qmgr[32622]: 4034320FAC4: removed
Mar 15 13:21:27 mxcluster postfix/smtp[32634]: 5A4A520FADE:
to=<di...@iagente.com.br>, relay=mx2.iagente.com.br[189.38.95.92]:25,
delay=11, delays=0.09/0/0.34/11, dsn=2.0.0, status=sent (250 2.0.0 Ok:
queued as 4059C6DFD3439)
Mar 15 13:21:27 mxcluster postfix/qmgr[32622]: 5A4A520FADE: removed
On Tue, Mar 15, 2011 at 1:18 PM, Rafael Azevedo <raf...@gmail.com> wrote:
> Sorry! I forgot the log files..
>
> In order to "reduce" the logs I've made a test with 2 destinations per
> domain sender, as follows:
>
>
> # TEST 1:
> 13:15:14 - Sending email #1...OK
> 13:15:15 - Sending email #2...OK
>
> # TEST 2
> 13:15:15 - Sending email #1...OK
> 13:15:15 - Sending email #2...OK
>
> POSTFIX LOGS:
>
> Mar 15 13:16:23 mxcluster postfix/smtpd[31423]: connect from
> srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:23 mxcluster postfix/smtpd[31423]: D40E120EAA8:
> client=srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:23 mxcluster postfix/cleanup[31427]: D40E120EAA8:
> message-id=<da148727b8e4e2c708f2247e952e3...@iagentemail.com.br>
> Mar 15 13:16:24 mxcluster dkim-filter[4513]: D40E120EAA8
> "DKIM-Signature" header added
> Mar 15 13:16:24 mxcluster postfix/qmgr[6659]: D40E120EAA8:
> from=<reto...@iagentemail.com.br>, size=631, nrcpt=2 (queue active)
> Mar 15 13:16:24 mxcluster postfix/smtpd[31423]: 5E9C320EAC6:
> client=srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:24 mxcluster postfix/cleanup[31427]: 5E9C320EAC6:
> message-id=<bbf2745b30c38b261c50af0e222b7...@iagentemail.com.br>
> Mar 15 13:16:24 mxcluster postfix/smtp[31429]: D40E120EAA8:
> to=<di...@iagente.com.br>, relay=mx2.iagente.com.br[189.38.95.92]:25,
> delay=0.67, delays=0.52/0.01/0.02/0.12, dsn=2.0.0, status=sent (250
> 2.0.0 Ok: queued as F1EAF6DFA4E64)
> Mar 15 13:16:24 mxcluster dkim-filter[4513]: 5E9C320EAC6
> "DKIM-Signature" header added
> Mar 15 13:16:24 mxcluster postfix/qmgr[6659]: 5E9C320EAC6:
> from=<reto...@iagentemail.com.br>, size=631, nrcpt=2 (queue active)
> Mar 15 13:16:24 mxcluster postfix/smtpd[31423]: lost connection after
> RSET from srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:24 mxcluster postfix/smtpd[31423]: disconnect from
> srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:24 mxcluster postfix/smtpd[31423]: connect from
> srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:24 mxcluster postfix/smtpd[31423]: EE82220EB26:
> client=srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:25 mxcluster postfix/cleanup[31427]: EE82220EB26:
> message-id=<49aacc28e1c170356dc8fb54834a8...@iagentemail.com.br>
> Mar 15 13:16:25 mxcluster dkim-filter[4513]: EE82220EB26
> "DKIM-Signature" header added
> Mar 15 13:16:25 mxcluster postfix/qmgr[6659]: EE82220EB26:
> from=<reto...@iagentemail.com.br>, size=632, nrcpt=2 (queue active)
> Mar 15 13:16:25 mxcluster postfix/smtpd[31423]: 133F620EB53:
> client=srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:25 mxcluster postfix/cleanup[31427]: 133F620EB53:
> message-id=<dfcd063ee7c7c3843829ad4c45969...@iagentemail.com.br>
> Mar 15 13:16:25 mxcluster dkim-filter[4513]: 133F620EB53
> "DKIM-Signature" header added
> Mar 15 13:16:25 mxcluster postfix/qmgr[6659]: 133F620EB53:
> from=<reto...@iagentemail.com.br>, size=632, nrcpt=2 (queue active)
> Mar 15 13:16:25 mxcluster postfix/smtpd[31423]: lost connection after
> RSET from srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:25 mxcluster postfix/smtpd[31423]: disconnect from
> srv01.iagentemail.com.br[189.38.86.124]
> Mar 15 13:16:25 mxcluster postfix/smtp[31438]: EE82220EB26:
> to=<di...@iagente.com.br>, relay=mx3.iagente.com.br[189.38.95.5]:25,
> delay=0.47, delays=0.1/0.02/0.15/0.21, dsn=2.0.0, status=sent (250
> 2.0.0 Ok: queued as DB5861FDE7702)
> Mar 15 13:16:25 mxcluster postfix/smtp[31440]: 133F620EB53:
> to=<di...@iagente.com.br>, relay=mx2.iagente.com.br[189.38.95.92]:25,
> delay=0.43, delays=0.11/0.01/0.13/0.17, dsn=2.0.0, status=sent (250
> 2.0.0 Ok: queued as EA8BA6DFA521F)
> Mar 15 13:16:27 mxcluster postfix/smtp[29978]: D40E120EAA8:
> to=<raf...@gmail.com>,
> relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=3.1,
> delays=0.52/0/0.45/2.2, dsn=2.0.0, status=sent (250 2.0.0 OK
> 1300205714 x34si22095693anx.127)
> Mar 15 13:16:27 mxcluster postfix/qmgr[6659]: D40E120EAA8: removed
> Mar 15 13:16:27 mxcluster postfix/smtp[31429]: 5E9C320EAC6:
> to=<raf...@gmail.com>,
> relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=3.1,
> delays=0.58/0/0.45/2.1, dsn=2.0.0, status=sent (250 2.0.0 OK
> 1300205714 p50si22191243yhi.8)
> Mar 15 13:16:27 mxcluster postfix/smtp[31437]: EE82220EB26:
> to=<raf...@gmail.com>,
> relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=2.7,
> delays=0.1/0.01/0.45/2.1, dsn=2.0.0, status=sent (250 2.0.0 OK
> 1300205715 m1si10205166ybn.28)
> Mar 15 13:16:27 mxcluster postfix/qmgr[6659]: EE82220EB26: removed
> Mar 15 13:16:27 mxcluster postfix/smtp[31439]: 133F620EB53:
> to=<raf...@gmail.com>,
> relay=gmail-smtp-in.l.google.com[74.125.67.27]:25, delay=2.6,
> delays=0.11/0.01/0.45/2, dsn=2.0.0, status=sent (250 2.0.0 OK
> 1300205715 o2si378647ybn.42)
> Mar 15 13:16:27 mxcluster postfix/qmgr[6659]: 133F620EB53: removed
> Mar 15 13:16:35 mxcluster postfix/smtp[31431]: 5E9C320EAC6:
> to=<di...@iagente.com.br>, relay=mx1.iagente.com.br[189.38.95.91]:25,
> delay=11, delays=0.58/0.01/5.2/5.2, dsn=2.0.0, status=sent (250 2.0.0
> Ok: queued as CFB4A6DB6AC99)
>
>
> Thanks once again.
> Rafael
>
>
> On Tue, Mar 15, 2011 at 1:02 PM, Ralf Hildebrandt
> <ralf.hildebra...@charite.de> wrote:
>> * Rafael Azevedo <raf...@gmail.com>:
>>> Hi Wietse,
>>>
>>> Thanks so much for your help!
>>>
>>> I've already fixed the DNS problem.
>>>
>>> For testing pourposes, I'm using now another IP C CLASS that has no DNS
>>> fails.
>>>
>>> This is my test output - sending 5 emails per sender domain:
>>>
>>> # DOMAIN ZOOM4U.COM.BR (about 1 email per second)
>>> 12:56:10 - Sending email #1...OK
>>> 12:56:10 - Sending email #2...OK
>>> 12:56:11 - Sending email #3...OK
>>> 12:56:12 - Sending email #4...OK
>>> 12:56:13 - Sending email #5...OK
>>>
>>> # NOW USING IAGENTEMAIL.COM.BR (5 emails per second)
>>> 12:56:14 - Sending email #1...OK
>>> 12:56:14 - Sending email #2...OK
>>> 12:56:14 - Sending email #3...OK
>>> 12:56:14 - Sending email #4...OK
>>> 12:56:14 - Sending email #5...OK
>>>
>>> In both cases I'm using the same SMTP that uses another C class.
>>> See the difference?
>>
>> Where are the postfix logs for that?
>> --
>> Ralf Hildebrandt
>> Geschäftsbereich IT | Abteilung Netzwerk
>> Charité - Universitätsmedizin Berlin
>> Campus Benjamin Franklin
>> Hindenburgdamm 30 | D-12203 Berlin
>> Tel. +49 30 450 570 155 | Fax: +49 30 450 570 962
>> ralf.hildebra...@charite.de | http://www.charite.de
>>
>>
>
