[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