[Postfixbuch-users] Mail-Annahme langsam
Stefan G. Weichinger
monitor at oops.co.at
Fr Aug 12 09:56:28 CEST 2005
Ralf Hildebrandt wrote:
> * Stefan G. Weichinger <monitor at oops.co.at>:
>
>
>>>>>>Aug 12 09:28:13 mail postfix/smtpd[32562]: connect from mx-XY.at[ir.ge.nd.ip]
>>>>>>Aug 12 09:28:49 mail postfix/smtpd[32562]: 0CD178B8321: client=mx-XY.at[ir.ge.nd.ip]
>
>
>>delay=53
>
> 49-13 = 36
> irgendwer lügt hier.
Mhm.
>
> Wie wärs mit kompletten Logeinträgen...
>
>
>>Keine body_ oder header_checks, aber den maia-amavisd auf log_level=3
>>...? ;)
>
>
> Ich sehe hier keinen Logeintrag von maia-amavisd
>
Hier sind ein paar, das Ding schreibt viele Logs auf Level 3, aber nur
so kann ich das Ding kennenlernen, bevor ich es in Produktion schicke.
---
Aug 12 09:28:13 mail postfix/smtpd[32562]: connect from
lieferant.tld[ip.vom.lieferant]
Aug 12 09:28:49 mail postfix/smtpd[32562]: 0CD178B8321:
client=lieferant.tld[ip.vom.lieferant]
Aug 12 09:28:49 mail postfix/cleanup[32568]: 0CD178B8321:
message-id=<42FC4F8F.6050006 at oops.co.at>
Aug 12 09:28:49 mail postfix/qmgr[32552]: 0CD178B8321:
from=<monitor at oops.co.at>, size=1122, nrcpt=1 (queue active)
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Connecting to SQL
database server
Aug 12 09:28:49 mail postfix/smtpd[32562]: disconnect from
lieferant.tld[ip.vom.lieferant]
Aug 12 09:28:49 mail amavis[32314]: (32314-01) connect_to_sql:
'DBI:mysql:database=maia:localhost' succeeded
Aug 12 09:28:49 mail amavis[32314]: (32314-01) LMTP::10024
/var/spool/amavis/tmp/amavis-20050812T092849-32314: <monitor at oops.co.at>
-> <zielbox at meine.tld> Received: SIZE=1122 from pop.meine.tld
([127.0.0.1]) by localhost (matrix.meine.tld [127.0.0.1]) (amavisd-new,
port 10024) with LMTP id 32314-01 for <zielbox at meine.tld>; Fri, 12 Aug
2005 09:28:49 +0200 (CEST)
Aug 12 09:28:49 mail amavis[32314]: (32314-01) body hash:
5c368dbaf966a05c17c27d90fe557c02
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Checking:
<monitor at oops.co.at> -> <zielbox at meine.tld>
Aug 12 09:28:49 mail amavis[32314]: (32314-01) cached
5c368dbaf966a05c17c27d90fe557c02 from <monitor at oops.co.at> (1,1,0)
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia: [connect_to_sql]
Connecting to SQL database server
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia: [connect_to_sql]
'DBI:mysql:database=maia:localhost' succeeded
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia: [connect] Database
type is MySQL
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] False negative management is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Virus scanning is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Spam filtering is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Bad header checking is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Banned files checking is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] User autocreation is DISABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Internal authentication mechanism is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Stats tracking is ENABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Spam traps are DISABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Mail larger than 5000000 bytes will be PASSED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] The system default user (@.) WILL apply to
non-local recipients
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[read_system_config] Blowfish encryption is DISABLED
Aug 12 09:28:49 mail amavis[32314]: (32314-01) Maia:
[get_mysql_size_limit] MySQL max_allowed_packet size is 5241856 bytes
Aug 12 09:28:49 mail amavis[32314]: (32314-01) p001 1 Content-Type:
text/plain, size: 142 B, name:
Aug 12 09:29:06 mail amavis[32314]: (32314-01) spam_scan: hits=0.509
tests=AWL
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [get_recipient_id]
Recipient zielbox at meine.tld (id = 1)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [store_mail] Stored
mail item 59 (1092 bytes)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [get_recipient_id]
Recipient zielbox at meine.tld (id = 1)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [get_recipient_id]
Recipient zielbox at meine.tld (id = 1)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [record_tests]
SpamAssassin is using score set 1
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [record_tests]
Triggered SpamAssassin rule AWL (id = 625, set = 1, score = 1.000)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [record_tests]
Linking SpamAssassin test 625 to mail item 59
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [record_tests]
Assigning mail item 59 a final score of 0.509
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [check_mail] Not
caching ham at the request of user 1
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia:
[recipient_is_local] Recipient zielbox at meine.tld is non-local
Aug 12 09:29:06 mail amavis[32314]: (32314-01) SPAM-TAG,
<monitor at oops.co.at> -> <zielbox at meine.tld>, No, hits=0.509
tagged_above=-998 required=6.31 tests=AWL
Aug 12 09:29:06 mail amavis[32314]: (32314-01) FWD via SMTP:
[127.0.0.1]:10025 <monitor at oops.co.at> -> <zielbox at meine.tld>
Aug 12 09:29:06 mail postfix/smtpd[32574]: connect from localhost[127.0.0.1]
Aug 12 09:29:06 mail amavis[32314]: (32314-01) AUTH not needed, user='',
MTA offers 'PLAIN DIGEST-MD5 CRAM-MD5 LOGIN'
Aug 12 09:29:06 mail postfix/smtpd[32574]: 308B18B8322:
client=localhost[127.0.0.1]
Aug 12 09:29:06 mail postfix/cleanup[32568]: 308B18B8322:
message-id=<42FC4F8F.6050006 at oops.co.at>
Aug 12 09:29:06 mail postfix/smtpd[32574]: disconnect from
localhost[127.0.0.1]
Aug 12 09:29:06 mail postfix/qmgr[32552]: 308B18B8322:
from=<monitor at oops.co.at>, size=1654, nrcpt=1 (queue active)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) mail_via_smtp: 250 2.6.0
Ok, id=32314-01, from MTA: 250 Ok: queued as 308B18B8322
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Passed CLEAN,
[172.32.99.12] <monitor at oops.co.at> -> <zielbox at meine.tld>, Message-ID:
<42FC4F8F.6050006 at oops.co.at>, Hits: 0.509, 17167 ms
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [cleanup] Unwanted
mail item 59 (no registered recipients)
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [delete_mail]
Removing mail item 59
Aug 12 09:29:06 mail amavis[32314]: (32314-01) Maia: [disconnect]
Disconnecting from SQL database
Aug 12 09:29:06 mail amavis[32314]: (32314-01) TIMING [total 17177 ms] -
sql-prepare: 3 (0%), SMTP LHLO: 2 (0%), SMTP pre-MAIL: 0 (0%), mkdir
tempdir: 0 (0%), create email.txt: 0 (0%), sql-connect: 8 (0%),
lookup_sql: 2 (0%), SMTP pre-DATA-flush: 1 (0%), SMTP DATA: 29 (0%),
body_hash: 1 (0%), mkdir parts: 1 (0%), maia_connect: 2 (0%),
maia_read_system_config: 2 (0%), maia_get_mysql_size_limit: 1 (0%),
mime_decode: 9 (0%), get-file-type1: 8 (0%), decompose_part: 1 (0%),
parts_decode: 0 (0%), lookup_sql: 2 (0%), lookup_sql: 2 (0%),
spam-wb-list: 1 (0%), SA msg read: 0 (0%), SA parse: 2 (0%), SA check:
16983 (99%), update_cache: 1 (0%), maia_store_mail: 13 (0%),
deal_with_mail_size: 2 (0%), maia_record_tests: 5 (0%),
maia_delete_mail_recipient_reference: 2 (0%), fwd-connect: 14 (0%),
fwd-mail-from: 2 (0%), fwd-rcpt-to: 3 (0%), write-header: 4 (0%),
fwd-data: 0 (0%), fwd-data-end: 44 (0%), fwd-rundown: 1 (0%),
main_log_entry: 18 (0%), update_snmp: 1 (0%), maia_delete_mail: 4 (0%),
maia_cleanup: 0 (0%),...
Aug 12 09:29:06 mail amavis[32314]: (32314-01) ... maia_disconnect: 0
(0%), unlink-1-files: 2 (0%), rundown: 0 (0%)
Aug 12 09:29:06 mail postfix/lmtp[32570]: 0CD178B8321:
to=<zielbox at meine.tld>, orig_to=<sgw at xunil.at>,
relay=127.0.0.1[127.0.0.1], delay=53, status=sent (250 2.6.0 Ok,
id=32314-01, from MTA: 250 Ok: queued as 308B18B8322)
Aug 12 09:29:06 mail postfix/qmgr[32552]: 0CD178B8321: removed
Aug 12 09:29:06 mail postfix/lmtp[32576]: 308B18B8322:
to=<zielbox at meine.tld>, relay=public/lmtp[public/lmtp], delay=0,
status=sent (250 2.1.5 Ok)
Aug 12 09:29:06 mail postfix/qmgr[32552]: 308B18B8322: removed
--
Stefan
Mehr Informationen über die Mailingliste Postfixbuch-users