mails are not delivered
Sven Gehr
mailingliste at dreampixel.de
Wed Oct 31 17:39:51 CET 2012
Hi at all,
I have a problem with my kolab3-installation. The problem was not from the
beginning. I use two mail-domains witch are hostet on an external webserver:
- gehr-edv.de
- dreampixel.de
gehr-edv.de ist the primary domain. Mail for my first mail account was
delivered perfekt but mails for my secondary domain e.g.
mailinglist at dreampixel.de was not delivered to my kolab.
I have followed the path of the mail.
- Incomming mail was stored in the mailbox from the external mailserver
- fetchmail fetch the mail. The config:
poll mail.dreampixel.de protocol pop3 username "mailingliste at dreampixel.de"
password "#Web8597Mail" dropdelivered is "sven.gehr at gehr-edv.de" here
the output in the log /var/log/maillog see at the end of the mail.I found my
testmail in
/var/spool/amavisd/tmp/amavis-20121031T165403-01803
but I'm missing a lot of mail e.g. mailinglists.
What's wrong with my kolab?
The neverending log:
Oct 31 16:54:02 kolab postfix/smtpd[2074]: connect from localhost[127.0.0.1]
Oct 31 16:54:03 kolab postfix/smtpd[2074]: 2CC50120866:
client=localhost[127.0.0.1]
Oct 31 16:54:03 kolab postfix/cleanup[2076]: 2CC50120866: message-
id=<CCB6AFD5.25355%clarkpa at ornl.gov>
Oct 31 16:54:03 kolab postfix/qmgr[1744]: 2CC50120866: from=<bacula-users-
bounces at lists.sourceforge.net>, size=5838, nrcpt=1 (queue active)
Oct 31 16:54:03 kolab postfix/smtpd[2074]: 7016B120867:
client=localhost[127.0.0.1]
Oct 31 16:54:03 kolab postfix/cleanup[2076]: 7016B120867: message-
id=<2223275.1YzT7JRVCy at t7laptop1>
Oct 31 16:54:03 kolab amavis[1802]: Net::Server: 2012/10/31-16:54:03 CONNECT
TCP Peer: "127.0.0.1:44280" Local: "127.0.0.1:10024"
Oct 31 16:54:03 kolab amavis[1802]: loaded base policy bank
Oct 31 16:54:03 kolab amavis[1802]: lookup_ip_acl (inet_acl): key="127.0.0.1"
matches "127.0.0.1", result=1
Oct 31 16:54:03 kolab amavis[1802]: process_request: fileno sock=12, STDIN=0,
STDOUT=1
Oct 31 16:54:03 kolab postfix/qmgr[1744]: 7016B120867: from=<kolab-users-de-
bounces at kolab.org>, size=6019, nrcpt=1 (queue active)
Oct 31 16:54:03 kolab amavis[1802]: switch_to_my_time 480 s, new request
Oct 31 16:54:03 kolab amavis[1802]: process_request: suggested_protocol="" on
TCP
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) SMTP> 220 [127.0.0.1] ESMTP
amavisd-new service ready
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 4: was busy, 23.4
ms, total idle 0.000 s, busy 0.023 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.4 ms,
total idle 0.000 s, busy 0.023 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) SMTP< EHLO kolab.local.lan\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP EHLO received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-[127.0.0.1]
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-VRFY
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-PIPELINING
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-SIZE
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-ENHANCEDSTATUSCODES
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-8BITMIME
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250-DSN
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250 XFORWARD NAME ADDR
PORT PROTO HELO SOURCE
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 6: was busy, 3.5 ms,
total idle 0.000 s, busy 0.027 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.6 ms,
total idle 0.001 s, busy 0.027 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< XFORWARD NAME=localhost
ADDR=127.0.0.1 PORT=37320\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP XFORWARD received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250 2.5.0 Ok XFORWARD
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 6: was busy, 2.0 ms,
total idle 0.001 s, busy 0.029 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.001 s, busy 0.029 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< XFORWARD PROTO=ESMTP
HELO=kolab.local.lan SOURCE=LOCAL\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP XFORWARD received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250 2.5.0 Ok XFORWARD
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 6: was busy, 1.3 ms,
total idle 0.001 s, busy 0.030 s
Oct 31 16:54:03 kolab amavis[1803]: Net::Server: 2012/10/31-16:54:03 CONNECT
TCP Peer: "127.0.0.1:44281" Local: "127.0.0.1:10024"
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.4 ms,
total idle 0.002 s, busy 0.030 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< MAIL FROM:<bacula-users-
bounces at lists.sourceforge.net> SIZE=5838 BODY=7BIT\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP MAIL received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) check_mail_begin_task:
task_count=1
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) TempDir::prepare: creating
directory /var/spool/amavisd/tmp/amavis-20121031T165403-01802
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) TempDir::prepare_file: creating
file /var/spool/amavisd/tmp/amavis-20121031T165403-01802/email.txt
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) TempDir::prepare_file: layers:
unix,perlio
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ip_acl: key="127.0.0.1"
matches "127.0.0.0/8", result=1
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) loaded policy bank "MYNETS"
Oct 31 16:54:03 kolab amavis[1803]: loaded base policy bank
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [debug_sender] => undef,
"bacula-users-bounces at lists.sourceforge.net" does not match
Oct 31 16:54:03 kolab amavis[1803]: lookup_ip_acl (inet_acl): key="127.0.0.1"
matches "127.0.0.1", result=1
Oct 31 16:54:03 kolab amavis[1803]: process_request: fileno sock=12, STDIN=0,
STDOUT=1
Oct 31 16:54:03 kolab amavis[1803]: switch_to_my_time 480 s, new request
Oct 31 16:54:03 kolab postfix/smtpd[2074]: B950B12086A:
client=localhost[127.0.0.1]
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250 2.1.0 Sender
<bacula-users-bounces at lists.sourceforge.net> OK
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 6: was busy, 38.3
ms, total idle 0.002 s, busy 0.069 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.002 s, busy 0.069 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< RCPT TO:<sven.gehr at gehr-
edv.de> ORCPT=rfc822;sven.gehr at gehr-edv.de\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP RCPT received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_acl(sven.gehr at gehr-
edv.de) matches key ".gehr-edv.de", result=1
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [local_domains] => true,
"sven.gehr at gehr-edv.de" matches, result="1", matching_key=".gehr-edv.de"
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr, @gehr-edv.de, @.gehr-edv.de, @.de, @.
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap "sven.gehr at gehr-
edv.de", query keys: "sven.gehr at gehr-edv.de", "sven.gehr", "@gehr-edv.de",
"@.gehr-ed$
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ldap begin_work
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Connecting to LDAP server
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) connect_to_ldap: trying
localhost
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) connect_to_ldap: connected to
localhost
Oct 31 16:54:03 kolab amavis[1803]: process_request: suggested_protocol="" on
TCP
ct 31 16:54:03 kolab amavis[1803]: (01803-01) SMTP> 220 [127.0.0.1] ESMTP
amavisd-new service ready
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 4: was busy, 55.3
ms, total idle 0.000 s, busy 0.055 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.000 s, busy 0.055 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) SMTP< EHLO kolab.local.lan\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP EHLO received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) connect_to_ldap: bind
uid=kolab-service,ou=Special Users,dc=gehr-edv,dc=de succeeded
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: searching
base="dc=gehr-edv,dc=de", scope="sub", filter="(|(|(mail=sven.gehr at gehr-
edv.de)(mail=sven.$
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-[127.0.0.1]
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-VRFY
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-PIPELINING
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-SIZE
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-ENHANCEDSTATUSCODES
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-8BITMIME
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250-DSN
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250 XFORWARD NAME ADDR
PORT PROTO HELO SOURCE
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 6: was busy, 4.5 ms,
total idle 0.000 s, busy 0.060 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.000 s, busy 0.060 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< XFORWARD NAME=localhost
ADDR=127.0.0.1 PORT=37320\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP XFORWARD received
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250 2.5.0 Ok XFORWARD
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 6: was busy, 4.9 ms,
total idle 0.000 s, busy 0.065 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.001 s, busy 0.065 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< XFORWARD PROTO=ESMTP
HELO=kolab.local.lan SOURCE=LOCAL\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP XFORWARD received
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250 2.5.0 Ok XFORWARD
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 6: was busy, 1.4 ms,
total idle 0.001 s, busy 0.066 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.2 ms,
total idle 0.001 s, busy 0.066 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< MAIL FROM:<kolab-users-
de-bounces at kolab.org> SIZE=6019\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP MAIL received
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) check_mail_begin_task:
task_count=1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) TempDir::prepare: creating
directory /var/spool/amavisd/tmp/amavis-20121031T165403-01803
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisVirusLover" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamLover" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBannedFilesLover" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBadHeaderLover" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBypassVirusChecks" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBypassSpamChecks" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBypassBannedChecks" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBypassHeaderChecks" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamTagLevel" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamTag2Level" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamKillLevel" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamDsnCutoffLevel" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamQuarantineCutoffLevel" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamSubjectTag" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamSubjectTag2" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamModifiesSubj" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisVirusQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBannedQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBadHeaderQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisArchiveQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBlacklistSender" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisWhitelistSender" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisLocal" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisMessageSizeLimit" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisWarnVirusRecip" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisWarnBannedRecip" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisWarnBadHeaderRecip" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisVirusAdmin" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisNewVirusAdmin" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisSpamAdmin" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBannedAdmin" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBadHeaderAdmin" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap: reading attribute
"amavisBannedRuleNames" from object
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap(sven.gehr at gehr-
edv.de) matches, result=(dn=>"uid=gehr,ou=People,dc=gehr-edv,dc=de")
Oct 31 16:54:03 kolab amavis[1802]: (01802-01)
lookup_ldap_attr(amavismessagesizelimit), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [message_size_limit] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 250 2.1.5 Recipient
<sven.gehr at gehr-edv.de> OK
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 6: was busy, 37.5
ms, total idle 0.002 s, busy 0.106 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) idle_proc, 5: was idle, 0.1 ms,
total idle 0.002 s, busy 0.106 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< DATA\r\n
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
SMTP DATA received
Oct 31 16:54:03 kolab postfix/cleanup[2076]: B950B12086A: message-
id=<CAFJA1Eq9J=hRjVCifW5p2JM9qQ6ZsnKd+Z=mMDYu606wRgny7Q at mail.gmail.com>
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP::10024
/var/spool/amavisd/tmp/amavis-20121031T165403-01802: <bacula-users-
bounces at lists.sourceforge.net> ->$
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP> 354 End data with
<CR><LF>.<CR><LF>
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_client_time 480 s,
receiving data
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) TempDir::prepare_file: creating
file /var/spool/amavisd/tmp/amavis-20121031T165403-01803/email.txt
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) TempDir::prepare_file: layers:
unix,perlio
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ip_acl: key="127.0.0.1"
matches "127.0.0.0/8", result=1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) loaded policy bank "MYNETS"
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) switch_to_my_time 480 s,
data-end received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) ESMTP< .<CR><LF>
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [debug_sender] => undef,
"kolab-users-de-bounces at kolab.org" does not match
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250 2.1.0 Sender <kolab-
users-de-bounces at kolab.org> OK
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 6: was busy, 38.9
ms, total idle 0.001 s, busy 0.105 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.3 ms,
total idle 0.001 s, busy 0.105 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< RCPT TO:<sven.gehr at gehr-
edv.de> ORCPT=rfc822;sven.gehr at gehr-edv.de\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP RCPT received
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer digest_init:
timer set to 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_acl(sven.gehr at gehr-
edv.de) matches key ".gehr-edv.de", result=1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [local_domains] => true,
"sven.gehr at gehr-edv.de" matches, result="1", matching_key=".gehr-edv.de"
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) get_body_digest: reading header
section
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr, @gehr-edv.de, @.gehr-edv.de, @.de, @.
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap "sven.gehr at gehr-
edv.de", query keys: "sven.gehr at gehr-edv.de", "sven.gehr", "@gehr-edv.de",
"@.gehr-ed$
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ldap begin_work
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Connecting to LDAP server
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) connect_to_ldap: trying
localhost
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) connect_to_ldap: connected to
localhost
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer digest_hdr: timer
set to 480 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) get_body_digest: reading mail
body
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer digest_body:
timer set to 480 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) body type (ESMTP BODY): labeled
7BIT, good (h=0, b=0)
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) body hash:
be9c60bfbdcd39b1b7ff15770808a784
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Original mail size: 5838; quota
set to: 2919000 bytes
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) connect_to_ldap: bind
uid=kolab-service,ou=Special Users,dc=gehr-edv,dc=de succeeded
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: searching
base="dc=gehr-edv,dc=de", scope="sub", filter="(|(|(mail=sven.gehr at gehr-
edv.de)(mail=sven.$
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Checking: OwjruYsZGOTz MYNETS
[127.0.0.1] <bacula-users-bounces at lists.sourceforge.net> -> <sven.gehr at gehr-
edv.de>
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) 2822.From: <clarkpa at ornl.gov>,
2821.Mail_From: <bacula-users-bounces at lists.sourceforge.net>
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_ldap_attr(amavislocal),
no attribute, "sven.gehr at gehr-edv.de" result=1
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [local_domains] => true,
"sven.gehr at gehr-edv.de" matches, result="1", matching_key="/cached/"
Oct 31 16:54:03 kolab amavis[1802]: (01802-01)
lookup_ldap_attr(amavisbypassviruschecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [bypass_virus_checks] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1802]: (01802-01)
lookup_ldap_attr(amavisbypassbannedchecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [bypass_banned_checks]
=> undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1802]: (01802-01)
lookup_ldap_attr(amavisbypassspamchecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) lookup [bypass_spam_checks] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Extracting mime components
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisVirusLover" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamLover" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBannedFilesLover" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBadHeaderLover" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBypassVirusChecks" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBypassSpamChecks" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBypassBannedChecks" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBypassHeaderChecks" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamTagLevel" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamTag2Level" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamKillLevel" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamDsnCutoffLevel" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamQuarantineCutoffLevel" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamSubjectTag" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamSubjectTag2" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamModifiesSubj" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisVirusQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBannedQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBadHeaderQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisArchiveQuarantineTo" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBlacklistSender" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisWhitelistSender" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisLocal" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisMessageSizeLimit" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisWarnVirusRecip" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisWarnBannedRecip" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisWarnBadHeaderRecip" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisVirusAdmin" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisNewVirusAdmin" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisSpamAdmin" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBannedAdmin" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBadHeaderAdmin" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap: reading attribute
"amavisBannedRuleNames" from object
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap(sven.gehr at gehr-
edv.de) matches, result=(dn=>"uid=gehr,ou=People,dc=gehr-edv,dc=de")
Oct 31 16:54:03 kolab amavis[1803]: (01803-01)
lookup_ldap_attr(amavismessagesizelimit), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [message_size_limit] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 250 2.1.5 Recipient
<sven.gehr at gehr-edv.de> OK
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 6: was busy, 37.7
ms, total idle 0.001 s, busy 0.143 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) idle_proc, 5: was idle, 0.5 ms,
total idle 0.002 s, busy 0.143 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< DATA\r\n
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
SMTP DATA received
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP::10024
/var/spool/amavisd/tmp/amavis-20121031T165403-01803: <kolab-users-de-
bounces at kolab.org> -> <sven.geh$
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP> 354 End data with
<CR><LF>.<CR><LF>
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
smtp response sent
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_client_time 480 s,
receiving data
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) switch_to_my_time 480 s,
data-end received
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) ESMTP< .<CR><LF>
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Issued a new file name: p001
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer digest_init:
timer set to 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) get_body_digest: reading header
section
Oct 31 16:54:03 kolab postfix/qmgr[1744]: B950B12086A:
from=<sven.gehr at gmail.com>, size=2473, nrcpt=1 (queue active)
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) Charging 1387 bytes to
remaining quota 2919000 (out of 2919000, (0%)) - by mime_decode
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) p001 1 Content-Type:
text/plain, size: 1387 B, name:
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer mime_decode:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer mime_decode-1:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) inspect_dsn: parts: text/plain
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) inspect_dsn: not a bounce
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) prolong_timer dsn_parse:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) decode_parts: level=1, #parts=1
: p001
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) running file(1) on 1 files,
arglist size 18
Oct 31 16:54:03 kolab postfix/smtpd[2074]: disconnect from
localhost[127.0.0.1]
Oct 31 16:54:03 kolab amavis[1802]: (01802-01) run_command: [2081]
/usr/bin/file p001 </dev/null 2>&1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer digest_hdr: timer
set to 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) get_body_digest: reading mail
body
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer digest_body:
timer set to 480 s
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: target fd0
closing, to become < /dev/null
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) body type (ESMTP BODY):
unlabeled, good (h=0, b=0)
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) body hash:
d3a9ba33355b12aa7a19006d298e54d6
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: target fd1
closing, to become > &=16
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: target fd1
dup2 from fd16 > &=16
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: source
fd16 closed
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: target fd2
closing, to become > &1
Oct 31 16:54:03 kolab amavis[2081]: (01802-01) open_on_specific_fd: target fd2
dup2 from fd1 > &1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Original mail size: 6019; quota
set to: 3009500 bytes
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Checking: DrDevz+uxMkJ MYNETS
[127.0.0.1] <kolab-users-de-bounces at kolab.org> -> <sven.gehr at gehr-edv.de>
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) 2822.From:
<grote at kolabsys.com>, 2822.Sender: <kolab-users-de-bounces at kolab.org>
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_ldap_attr(amavislocal),
no attribute, "sven.gehr at gehr-edv.de" result=1
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [local_domains] => true,
"sven.gehr at gehr-edv.de" matches, result="1", matching_key="/cached/"
Oct 31 16:54:03 kolab amavis[1803]: (01803-01)
lookup_ldap_attr(amavisbypassviruschecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [bypass_virus_checks] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1803]: (01803-01)
lookup_ldap_attr(amavisbypassbannedchecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [bypass_banned_checks]
=> undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1803]: (01803-01)
lookup_ldap_attr(amavisbypassspamchecks), no attribute, "sven.gehr at gehr-
edv.de" result=undef
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) query_keys: sven.gehr at gehr-
edv.de, sven.gehr@, gehr-edv.de, .gehr-edv.de, .de, .
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup_hash(sven.gehr at gehr-
edv.de), no matches
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) lookup [bypass_spam_checks] =>
undef, "sven.gehr at gehr-edv.de" does not match
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Extracting mime components
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Issued a new file name: p001
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Issued a new file name: p002
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Issued a new file name: p003
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) mime_decode_preamble: 1 lines
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Issued a new pseudo part: p004
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) p004 1 Content-Type:
multipart/mixed
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) mime_decode_epilogue: 1 lines
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) mime_decode_preamble: 1 lines
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Issued a new pseudo part: p005
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) p005 1/1 Content-Type:
multipart/signed
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) mime_decode_epilogue: 2 lines
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Charging 566 bytes to remaining
quota 3009500 (out of 3009500, (0%)) - by mime_decode
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) p001 1/1/1 Content-Type:
text/plain, size: 566 B, name:
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) reparenting p001 from p000 to
p005
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Charging 316 bytes to remaining
quota 3008934 (out of 3009500, (0%)) - by mime_decode
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) p002 1/1/2 Content-Type:
application/pgp-signature, size: 316 B, name: signature.asc
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) reparenting p002 from p000 to
p005
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) Charging 173 bytes to remaining
quota 3008618 (out of 3009500, (0%)) - by mime_decode
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) p003 1/2 Content-Type:
text/plain, size: 173 B, name:
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) reparenting p003 from p000 to
p004
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer mime_decode:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer mime_decode-1:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) inspect_dsn: parts:
multipart/mixed, multipart/signed, text/plain
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) inspect_dsn: not a bounce
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) prolong_timer dsn_parse:
remaining time = 480 s
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) decode_parts: level=1, #parts=5
: p001, p002, p003, p004, p005
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) running file(1) on 3 files,
arglist size 28
Oct 31 16:54:03 kolab amavis[1803]: (01803-01) run_command: [2082]
/usr/bin/file p001 p002 p003 </dev/null 2>&1
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: target fd0
closing, to become < /dev/null
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: target fd1
closing, to become > &=16
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: target fd1
dup2 from fd16 > &=16
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: source
fd16 closed
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: target fd2
closing, to become > &1
Oct 31 16:54:03 kolab amavis[2082]: (01803-01) open_on_specific_fd: target fd2
dup2 from fd1 > &1
--
Viele Grüsse
Sven Gehr
77815 Bühl
More information about the users
mailing list