Zimbra offers Open Source email server software and shared calendar for Linux and the Mac
 
Go Back   Zimbra - Forums > Zimbra Collaboration Suite > Administrators
Reload this Page [SOLVED] Amavisd-Postfix RCPT TO Takes a long time

Welcome to the Zimbra - Forums!
Welcome, if you would like to post a comment please register. We also encourage you to explore all things Zimbra with our team and members of the community.

Reply
 
LinkBack Thread Tools Display Modes
  #1 (permalink)  
Old 02-28-2007, 01:07 AM
Junior Member
  
Posts: 8
Default [SOLVED] Amavisd-Postfix RCPT TO Takes a long time
It seems that amavis takes a long time getting RCPT TO answers for every email in a list which is causing smtpd to time out when returning the message to postfix.

This problem is apparent to lists larger than 20

Is there any parameter or tweak in amavis that would solve this?

Support is greatly appreciated.

Regards,
Reply With Quote
  #2 (permalink)  
Old 02-28-2007, 10:21 PM
Zimbra-Yahoo Consultant
  
Posts: 5,608
Default
Can you provide a log?
Reply With Quote
  #3 (permalink)  
Old 03-01-2007, 06:42 AM
Junior Member
  
Posts: 8
Default The logfile
Kindly find below the logfile. (Amavisd was run with verbosity 4)
Also some redundant entries were removed to accommodate the max post size

Code:
Mar  1 14:23:37 mail-leb postfix/smtpd[14795]: connect from mail-leb.p-group.cc[82.205.159.212]
Mar  1 14:23:46 mail-leb postfix/smtpd[14795]: 90CEB61C05C: client=mail-leb.p-group.cc[82.205.159.212]
Mar  1 14:24:14 mail-leb postfix/cleanup[14827]: 90CEB61C05C: message-id=<17107187.31172751817074.JavaMail.root@mail-leb.p-group.cc>
Mar  1 14:24:15 mail-leb postfix/qmgr[378]: 90CEB61C05C: from=<28@p-group.cc>, size=534, nrcpt=33 (queue active)
Mar  1 14:24:15 mail-leb postfix/smtpd[14795]: disconnect from mail-leb.p-group.cc[82.205.159.212]
Mar  1 14:24:15 mail-leb amavis[14739]: loaded base policy bank
Mar  1 14:24:15 mail-leb amavis[14739]: lookup_ip_acl (inet_acl): key="127.0.0.1" matches "127.0.0.1", result=1
Mar  1 14:24:15 mail-leb amavis[14739]: process_request: fileno sock=12, STDIN=0, STDOUT=1
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) SMTP< EHLO mail-leb.p-group.cc\r\n
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-[127.0.0.1]
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-VRFY
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-PIPELINING
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-SIZE
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-ENHANCEDSTATUSCODES
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-8BITMIME
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250-DSN
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP> 250 XFORWARD NAME ADDR PROTO HELO
Mar  1 14:24:15 mail-leb amavis[14739]: (14739-01) ESMTP< XFORWARD NAME=mail-leb.p-group.cc ADDR=82.205.159.212\r\n
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.5.0 Ok XFORWARD
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP< XFORWARD PROTO=ESMTP HELO=mail-leb.p-group.cc\r\n
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.5.0 Ok XFORWARD
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP< MAIL FROM:<28@p-group.cc> SIZE=534\r\n
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) check_mail_begin_task: task_count=1
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) TempDir::prepare: creating directory /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) TempDir::prepare_file: creating file /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/email.txt
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) lookup_ip_acl (mynetworks): key="82.205.159.212", no match
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) lookup (debug_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.0 Sender 28@p-group.cc OK
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<1@p-group.cc>\r\n
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "1@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) lookup_ldap "1@p-group.cc", query keys: "1@p-group.cc", "1", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) Connecting to LDAP server
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) connect_to_ldap: trying ldap://mail-leb.p-group.cc, ldap://mail2.p-group.cc
Mar  1 14:24:16 mail-leb amavis[14739]: (14739-01) connect_to_ldap: connected to ldap://mail-leb.p-group.cc, ldap://mail2.p-group.cc
Mar  1 14:24:17 mail-leb amavis[14739]: (14739-01) connect_to_ldap: bind uid=zimbra,cn=admins,cn=zimbra succeeded
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup_ldap(1@p-group.cc) matches, result=(dn=>"uid=1,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "1@p-group.cc" does not match
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 1@p-group.cc OK
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<1@p-group.cc>\r\n
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "1@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup_ldap "1@p-group.cc", query keys: "1@p-group.cc", "1", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup_ldap(1@p-group.cc) matches, result=(dn=>"uid=1,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "1@p-group.cc" does not match
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 1@p-group.cc OK
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<2@p-group.cc>\r\n
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "2@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:18 mail-leb amavis[14739]: (14739-01) lookup_ldap "2@p-group.cc", query keys: "2@p-group.cc", "2", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) lookup_ldap(2@p-group.cc) matches, result=(dn=>"uid=2,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "2@p-group.cc" does not match
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 2@p-group.cc OK
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<3@p-group.cc>\r\n
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "3@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:19 mail-leb amavis[14739]: (14739-01) lookup_ldap "3@p-group.cc", query keys: "3@p-group.cc", "3", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) lookup_ldap(3@p-group.cc) matches, result=(dn=>"uid=3,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "3" does not match
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 3 OK
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<4@p-group.cc>\r\n
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "4@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:20 mail-leb amavis[14739]: (14739-01) lookup_ldap "4@p-group.cc", query keys: "4@p-group.cc", "4", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup_ldap(4@p-group.cc) matches, result=(dn=>"uid=4,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "4@p-group.cc" does not match
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 4@p-group.cc OK
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<5@p-group.cc>\r\n
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "5@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup_ldap "5@p-group.cc", query keys: "5@p-group.cc", "5", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup_ldap(5@p-group.cc) matches, result=(dn=>"uid=5,ou=people,dc=p-group,dc=cc")
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "5@p-group.cc" does not match
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) ESMTP> 250 2.1.5 Recipient 5@p-group.cc OK
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) ESMTP< RCPT TO:<6@p-group.cc>\r\n
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "6@p-group.cc" matches, result="1", matching_key="."
Mar  1 14:24:21 mail-leb amavis[14739]: (14739-01) lookup_ldap "6@p-group.cc", query keys: "6@p-group.cc", "6", "@p-group.cc", "@.p-group.cc", "@.cc", "@.", 

base: , filter: (&(objectClass=amavisAccount)(mail=%m))
<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3@p-group.cc>,<4@p-group.cc>,<5@p-group.cc> SIZE=534 Received: from mail-leb.p-group.cc ([127.0.0.1]) by localhost (mail-leb.p-group.cc [127.0.0.1]) 

(amavisd-new, port 10024) with ESMTP; Thu,  1 Mar 2007 14:24:16 +0200 (EET)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ESMTP< .<CR><LF>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) setting body type: 8BITMIME (h=1, b=1)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) body hash: 2205e48de5f93c784733ffcca841d2b5
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Original mail size: 534; quota set to: 267000 bytes
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Checking: Tr9Qb6oK-GKR [82.205.159.212] <28@p-group.cc> -> 

<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3@p-group.cc>,<4@p-group.cc>,<5@p-group.cc>,<6@p-group.cc>,<7@p-group.cc>,<8@p-group.cc>,<9@p-group.cc>,<10@p-g

roup.cc>,<11@p-group.cc>,<12@p-group.cc>,<13@p-group.cc>,<14@p-group.cc>,<15@p-group.cc>,<16@p-group.cc>,<17h@p-group.cc>,<18@p-group.cc>,<19e@p-group.cc>,<2

0@p-group.cc>,<21@p-group.cc>,<22@p-...<29@p-group.cc>

,<30s@p-group.cc>,<31@p-group.cc>,<32@p-group.cc>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Extracting mime components
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Issued a new file name: p001
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Charging 5 bytes to remaining quota 267000 (out of 267000, (0%)) - by mime_decode
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p001 1 Content-Type: text/plain, size: 5 B, name:
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) decode_parts: level=1, #parts=1 : p001
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (map_full_type_to_short_type) => true,  "ASCII text" matches, result="asc", 

matching_key="(?i-xsm:^(ASCII|text)\\b)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) File-type of p001: ASCII text; (asc)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) do_ascii: Decoding part p001
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) do_ascii: Setting sigaction handler, was 0
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) do_ascii: Decoding part p001 (0 items), uulib V0.5pl20
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) decompose_part: p001 - atomic
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_header_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Checking for banned types and filenames
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "1@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 1@p-group.cc, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "1@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 1@p-group.cc, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "2@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 2@p-group.cc, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "3@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 3, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "4@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 4@p-group.cc, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_banned_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (banned_filename), 1 matches for "5@p-group.cc", results: "(constant:DEFAULT)"=>"DEFAULT"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) collect banned table[0]: 5@p-group.cc, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x8c67ec4)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) starting banned checks - traversing message structure tree
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) check_for_banned (p001) text/plain,.asc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (check_bann:1@p-group.cc) => undef, ["text/plain",".asc"] does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) skip banned check for 1@p-group.cc, same tables as previous, result =>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) skip banned check for 2@p-group.cc, same tables as previous, result =>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) skip banned check for 3@p-group.cc, same tables as previous, result =>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) skip banned check for 4@p-group.cc, same tables as previous, result =>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) skip banned check for 5@p-group.cc, same tables as previous, result =>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 1@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 1@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 2@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 3@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 4@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) p.path 5@p-group.cc: "P=p001,L=1,M=text/plain,T=asc"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) banned check: any=0, all=N (33)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (keep_decoded_original) => undef, "MAIL" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Using ClamAV-clamd: (built-in interface)
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) Using (ClamAV-clamd) on dir: CONTSCAN /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/parts\n
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ClamAV-clamd: Connecting to socket  127.0.0.1:3310
Mar  1 14:24:42 mail-leb clamd[322]: SelfCheck: Database status OK.
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ClamAV-clamd: Sending CONTSCAN /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/parts\n to INET socket 

127.0.0.1:3310
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ask_av (ClamAV-clamd) result: /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/parts: OK\n
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ask_av (ClamAV-clamd): /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/parts CLEAN
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) ClamAV-clamd result: clean
Reply With Quote
  #4 (permalink)  
Old 03-01-2007, 06:43 AM
Junior Member
  
Posts: 8
Default The Logs part 2
Code:
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_virus_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) wbl: checking sender <28@p-group.cc>
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_recip<1@p-group.cc>) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_recip<1@p-group.cc>) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender), 1 matches for "1@p-group.cc", results: 

"."=>[Amavis::Lookup::RE=ARRAY(0x87b88a0),HASH(0x87b890c)]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender<28@p-group.cc>) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_recip<1@p-group.cc>) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_recip<1@p-group.cc>) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender), 1 matches for "1@p-group.cc", results: 

"."=>[Amavis::Lookup::RE=ARRAY(0x87b88a0),HASH(0x87b890c)]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender<28@p-group.cc>) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_recip<2@p-group.cc>) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_recip<2@p-group.cc>) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender), 1 matches for "2@p-group.cc", results: 

"."=>[Amavis::Lookup::RE=ARRAY(0x87b88a0),HASH(0x87b890c)]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender<28@p-group.cc>) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_recip<3@p-group.cc>) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_recip<3@p-group.cc>) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender), 1 matches for "3@p-group.cc", results: 

"."=>[Amavis::Lookup::RE=ARRAY(0x87b88a0),HASH(0x87b890c)]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender<28@p-group.cc>) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_recip<4@p-group.cc>) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (blacklist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_recip<4@p-group.cc>) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (whitelist_sender) => undef, "28@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (score_sender), 1 matches for "4@p-group.cc", results: 

"."=>[Amavis::Lookup::RE=ARRAY(0x87b88a0),HASH(0x87b890c)]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) CALLING SA check
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) spam_scan: score=-4.399 tests=[ALL_TRUSTED=-1.8,BAYES_00=-2.599]
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "1@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "1@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "1@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 1@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "1@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "1@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "1@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "1@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 1@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "2@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "2@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "2@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "2@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 2@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "3@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "3@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "3@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "3@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 3@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "4@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "4@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "4@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "4@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 4@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "5@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "5@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_tag3_level) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (spam_kill_level) => true,  "5@p-group.cc" matches, result="15", matching_key="(constant:15)"
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (message_size_limit) => undef, "5@p-group.cc" does not match
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) final_destiny PASS, recip 5@p-group.cc
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) do_notify_and_quar: ccat=CleanTag (1,1) ("1,1":CleanTag, "1":Clean, "0":CatchAll), q_mth=, 

qar_mth=smtp:[127.0.0.1]:10025
Mar  1 14:24:42 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "2@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "3@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "4@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (archive_quarantine_to) => undef, "5@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) skip admin notification, no administrators
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) defang? ccat "0":
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "1@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "1@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "1@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "1@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) headers CLUSTERING: NEW CLUSTER <1@p-group.cc>: score=-4.399, tag=1, tag2=0, local=1, bl=, s=
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "1@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "1@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "1@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "1@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "1@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "2@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "2@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "2@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "2@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "2@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "2@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "3@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "3@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "3@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "3@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "3@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "3@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "4@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "4@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "4@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "4@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "4@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "4@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (local_domains) => true,  "5@p-group.cc" matches, result="1", matching_key="/cached/"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (bypass_spam_checks) => undef, "5@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag_level) => true,  "5@p-group.cc" matches, result="-10", matching_key="(constant:-10)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_tag2_level) => true,  "5@p-group.cc" matches, result="6.6", matching_key="(constant:6.6)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_modifies_subj) => true,  "5@p-group.cc" matches, result="1", matching_key="(constant:1)"
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) lookup (spam_subject_tag) => undef, "5@p-group.cc" does not match
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) SPAM-TAG, <28@p-group.cc> -> 

<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3@p-group.cc>,<4@p-group.cc>,<5@p-group.cc>, No, score=-4.399 tagged_above=-10 required=6.6 tests=[ALL_TRUSTED=-1.8, BAYES_00=-2.599]
Reply With Quote
  #5 (permalink)  
Old 03-01-2007, 06:45 AM
Junior Member
  
Posts: 8
Default The Logs part 3
Code:
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) (about to connect to [127.0.0.1]:10025) FWD via SMTP: <28@p-group.cc> -> 

<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3>,<4@p-group.cc>,<5@p-group.cc>
Mar  1 14:24:43 mail-leb postfix/smtpd[14979]: connect from localhost.localdomain[127.0.0.1]
Mar  1 14:24:43 mail-leb amavis[14739]: (14739-01) AUTH not needed, user='', MTA offers ''
Mar  1 14:24:49 mail-leb postfix/smtpd[14979]: EDF1C61C061: client=localhost.localdomain[127.0.0.1]
Mar  1 14:24:49 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <1@p-group.cc>: "250 Ok"
Mar  1 14:24:52 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <1@p-group.cc>: "250 Ok"
Mar  1 14:24:54 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <2@p-group.cc>: "250 Ok"
Mar  1 14:24:56 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <3@p-group.cc3>: "250 Ok"
Mar  1 14:24:58 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <4@p-group.cc>: "250 Ok"
Mar  1 14:25:01 mail-leb amavis[14739]: (14739-01) response to RCPT TO for <5@p-group.cc>: "250 Ok"
Mar  1 14:25:47 mail-leb amavis[14739]: (14739-01) mail_via_smtp: session failed: timed out
Mar  1 14:25:48 mail-leb postfix/smtpd[14979]: disconnect from localhost.localdomain[127.0.0.1]
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) (!)FWD via SMTP: <28@p-group.cc> -> 

<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3@p-group.cc>,<4@p-group.cc>,<5@p-group.cc>, BODY=8BITMIME 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) ndn_needed=, exit=75, 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) lookup (spam_dsn_cutoff_level_bysender) => true,  "28@p-group.cc" matches, result="15", 

matching_key="(constant:15)"
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <1@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <1@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <2@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <3@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <4@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) DSN: TMPFAIL from MTA 450 TempFail, need not be reported: <28@p-group.cc> -> <5@p-group.cc>
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) lookup_ip_acl (publicnetworks): key="82.205.159.212" matches "::FFFF:0:0/96", result=1
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) Blocked TEMPFAIL, [82.205.159.212] [82.205.159.212] <28@p-group.cc> -> 

<1@p-group.cc>,<1@p-group.cc>,<2@p-group.cc>,<3@p-group.cc>,<4@p-group.cc>,<5@p-group.cc>, Message-ID: <17107187.31172751817074.JavaMail.root@mail-leb.p-group.cc>, mail_id: Tr9Qb6oK-GKR, Hits: 

-4.399, 92347 ms
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) sending SMTP response: "450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01"
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) TempDir::strip: /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) rmdir_recursively: /opt/zimbra/amavisd/tmp/amavis-20070301T142416-14739/parts, excl=1
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) TIMING [total 92352 ms] - ldap-prepare: 4 (0%)0, SMTP EHLO: 4 (0%)0, SMTP pre-MAIL: 3 (0%)0, mkdir 

tempdir: 1 (0%)0, create email.txt: 1 (0%)0, ldap-connect: 1470 (2%)2, lookup_ldap: 731 (1%)2, lookup_ldap: 732 (1%)3, lookup_ldap: 728 (1%)4, lookup_ldap: 

732 (1%)5, lookup_ldap: 729 (1%)6, lookup_ldap: 731 (1%)6, lookup_ldap: 728 (1%)7, lookup_ldap: 739 (1%)8, lookup_ldap: 829 (1%)9, lookup_ldap: 853 (1%)10, 

lookup_ldap: 740 (1%)11, lookup_ldap: 730 (1%)11, lookup_ldap: 728 (1%)12, lookup_ldap: 732 (1%)13, lookup_ldap: 820 (1%)14, lookup_ldap: 731 (1%)15, 

lookup_ldap: 729 (1%)15, lookup_ldap: 731 (1%)16, lookup_ldap: 884 (1%)17, lookup_ldap: 735 (1%)18, lookup_ldap: 730 (1%)19, lookup_ldap: 730 (1%)20, 

lookup_ldap: 721 (1%)20, lookup_ldap: 740 (1%)21, lookup_ldap: 730 (1%)22, lookup_ldap: 730 (1%)23, lookup_ldap: 730 (1%)23, lookup_ldap: 739 (1%)24, 

lookup_ldap: 731 (1%)25, lookup_ldap: 750 (1%)26, lookup_ldap: 731 (1%)27, lookup_ldap: 760 (1%)27, looku...
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) ...p_ldap: 726 (1%)28, SMTP pre-DATA-flush: 3 (0%)28, SMTP DATA: 1 (0%)28, body_digest: 2 (0%)28, 

gen_mail_id: 1 (0%)28, mkdir parts: 1 (0%)28, mime_decode: 22 (0%)28, get-file-type1: 12 (0%)28, decompose_part: 2 (0%)28, parts_decode: 0 (0%)28, AV-scan-1: 

223 (0%)29, spam-wb-list: 304 (0%)29, SA msg read: 35 (0%)29, SA parse: 1 (0%)29, SA check: 111 (0%)29, SA finish: 2 (0%)29, update_cache: 2 (0%)29, 

decide_mail_destiny: 158 (0%)29, fwd-connect: 278 (0%)30, fwd-mail-from: 3743 (4%)34, fwd-rundown: 61308 (66%)100, prepare-dsn: 8 (0%)100, main_log_entry: 9 

(0%)100, update_snmp: 2 (0%)100, unlink-1-files: 1 (0%)100, rundown: 0 (0%)100
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) ESMTP> 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) ESMTP< QUIT\r\n
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) SMTP session over, timer stopped
Mar  1 14:25:48 mail-leb amavis[14739]: (14739-01) load: 100 %, total idle 0.002 s, busy 92.360 s
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<1@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<1@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<2@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<3@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<4@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Mar  1 14:25:48 mail-leb postfix/smtp[14971]: 90CEB61C05C: to=<5@p-group.cc>, orig_to=<listname@p-group.cc>, relay=127.0.0.1[127.0.0.1], delay=127, 

status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.2 Timed out during fwd-rcpt-to, 000 , MTA([127.0.0.1]:10025), id=14739-01 (in reply to end of DATA command))
Reply With Quote
  #6 (permalink)  
Old 03-06-2007, 09:11 AM
Zimbra-Yahoo Consultant
  
Posts: 5,608
Default
Looks like you need to adjust smtpd_timeout and/or smtp_rcpt_timeout in postfix.

man 5 postconf
Reply With Quote
  #7 (permalink)  
Old 03-09-2007, 04:26 AM
Junior Member
  
Posts: 8
Default RCPT TO takes a logn time even after setting smtpd_timeout and smtp_rcpt_timeout
Dear jholder,

Even after updating the two parameters (on both the master LDAP srver and slave LDAP server) and reloading postifx I still get the smtp timeout with no changes in the interval whatsoever , like the parameters are being ignored.

Any other parameters to tweak?

Best Regards,
Samer
Reply With Quote
  #8 (permalink)  
Old 02-11-2009, 12:50 PM
Intermediate Member
  
Posts: 16
Default
Hi Samuraii... Did you resolve this problem?? I'm having very similar issues.
Reply With Quote
  #9 (permalink)  
Old 02-11-2009, 03:34 PM
Moderator
  
Posts: 5,806
Default
Please post another thread instead of resurrecting something from 2007!
__________________
SplatNIX IT Services :: Innovation through Collaboration™


http://www.messagefortress.com
Reply With Quote
Reply

« Previous Thread | Today's Posts or Week's Posts | Next Thread »

Thread Tools
Display Modes
Linear Mode Linear Mode


Similar Threads

Product Information

Why Join?

Registering let's you ask questions, makes it easier to search, displays any files attached to posts, and notifies you about replies.

Zimbrablog.com


Home - Blog - Contact Us - Archive - Top


 

Search Engine Optimization by vBSEO 3.1.0