Results 1 to 9 of 9

Thread: [SOLVED] Amavisd-Postfix RCPT TO Takes a long time

  1. #1
    samuraii is offline Junior Member
    Join Date
    Feb 2007
    Posts
    8
    Rep Power
    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,

  2. #2
    jholder's Avatar
    jholder is offline Former Zimbran
    Join Date
    Oct 2005
    Location
    Thatcher, AZ
    Posts
    5,606
    Rep Power
    20

    Default

    Can you provide a log?

  3. #3
    samuraii is offline Junior Member
    Join Date
    Feb 2007
    Posts
    8
    Rep Power
    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

  4. #4
    samuraii is offline Junior Member
    Join Date
    Feb 2007
    Posts
    8
    Rep Power
    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]

  5. #5
    samuraii is offline Junior Member
    Join Date
    Feb 2007
    Posts
    8
    Rep Power
    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))

  6. #6
    jholder's Avatar
    jholder is offline Former Zimbran
    Join Date
    Oct 2005
    Location
    Thatcher, AZ
    Posts
    5,606
    Rep Power
    20

    Default

    Looks like you need to adjust smtpd_timeout and/or smtp_rcpt_timeout in postfix.

    man 5 postconf

  7. #7
    samuraii is offline Junior Member
    Join Date
    Feb 2007
    Posts
    8
    Rep Power
    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

  8. #8
    jfone is offline Intermediate Member
    Join Date
    Oct 2008
    Posts
    17
    Rep Power
    6

    Default

    Hi Samuraii... Did you resolve this problem?? I'm having very similar issues.

  9. #9
    uxbod's Avatar
    uxbod is offline Moderator
    Join Date
    Nov 2006
    Location
    UK
    Posts
    8,017
    Rep Power
    24

    Default

    Please post another thread instead of resurrecting something from 2007!

Thread Information

Users Browsing this Thread

There are currently 1 users browsing this thread. (0 members and 1 guests)

Similar Threads

  1. Replies: 3
    Last Post: 11-03-2007, 10:55 PM
  2. Replies: 0
    Last Post: 07-20-2007, 08:37 AM
  3. Rejecting at SMTP time based on amavisd - can Zimbra do it?
    By Tony Lewis in forum Administrators
    Replies: 0
    Last Post: 06-24-2007, 06:06 AM

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •