Received E-Mails (from one address) don't appear in inbox

[Volker Stoppe]

Hallo togehter!

I have a big problem with mail delivery / recieving in Kolab. Let me
discribe it in Form of a fable. The rabbit sends the fox a letter. But the
fox does not recieve any letter. The fox watches the postman putting the
mail in his letterbox, but when he opens it, there is no letter.
My postfix says that the receipient has received a mail, but it never
reaches his mailbox. The reciepient recieves mails from every other
domain/mailaccount, but not from this special account. This is definitely
reproduceable. Dozen of other users use this mailserver without
complaining. Maybe somebody could have a look over the postfix.log and
gets a clue. I couldn't find anything else in other log-files I looked in
(all cyrus-logs).
I use the Debian binaries of Kolab 2.1 with Debian Sarge.

Thanks for having a look and posting ideas in advance

Volker Stoppe



postfix.log:
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: connection established
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: master_notify: status 0
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: name_mask: resource
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: name_mask: software
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: name_mask: noanonymous
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: connect from
relay02.par.totalbanal.net[141.227.0.1]
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 127.0.0.0/8
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 127.0.0.0/8
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.6
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.6
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.4
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.4
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.11
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.11
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.252
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.252
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: send attr request =
connect
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: send attr ident =
smtp:141.227.0.1
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: status
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute name:
status
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: count
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute name:
count
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute value: 1
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: rate
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute name: rate
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute value: 1
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: (list terminator)
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 220 mail.magicwood.mgw ESMTP
Postfix
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: <
relay02.par.totalbanal.net[141.227.0.1]: EHLO relay02.par.totalbanal.net
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-mail.magicwood.mgw
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-PIPELINING
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-SIZE 20971520
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-VRFY
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-ETRN
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-STARTTLS
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-AUTH LOGIN PLAIN
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250-AUTH=LOGIN PLAIN
Jun 10 11:13:00 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250 8BITMIME
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: <
relay02.par.totalbanal.net[141.227.0.1]: MAIL FROM:<rabbit at mapa.de>
SIZE=1078
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: extract_addr: input:
<rabbit at mapa.de>
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: smtpd_check_addr:
addr=rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
rewrite
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr rule = local
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr address =
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: address
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
address
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: rewrite_clnt: local:
rabbit at mapa.de -> rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
resolve
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr address =
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: transport
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
transport
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
smtp
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: nexthop
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
nexthop
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: recipient
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
recipient
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
4096
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: resolve_clnt:
`rabbit at mapa.de' -> transp=`smtp' host=`mapa.de' rcpt=`rabbit at mapa.de'
flags= class=default
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: ctable_locate: install
entry key rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: extract_addr: result:
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: fsspace: .: block size
4096, blocks free 1623541
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: smtpd_check_size: blocks
4096 avail 1623541 min_free 0 msg_size_limit 20971520
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250 Ok
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: <
relay02.par.totalbanal.net[141.227.0.1]: RCPT TO:<fox at magicwood.mgw>
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: extract_addr: input:
<fox at magicwood.mgw>
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: smtpd_check_addr:
addr=fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
rewrite
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr rule = local
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr address =
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: address
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
address
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: rewrite_clnt: local:
fox at magicwood.mgw -> fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
resolve
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr address =
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: transport
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
transport
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
local
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: nexthop
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
nexthop
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
mail.magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: recipient
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
recipient
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
flags
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 256
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/rewrite socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: resolve_clnt:
`fox at magicwood.mgw' -> transp=`local' host=`mail.magicwood.mgw'
rcpt=`fox at magicwood.mgw' flags= class=local
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: ctable_locate: install
entry key fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: extract_addr: result:
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >>> START Sender address
RESTRICTIONS <<<
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_mynetworks
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: permit_mynetworks:
relay02.par.totalbanal.net 141.227.0.1
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 127.0.0.0/8
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 127.0.0.0/8
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.6
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.6
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.4
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.4
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.11
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.11
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.252
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.252
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_mynetworks status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=check_policy_service
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
smtpd_access_policy
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr protocol_state
= RCPT
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr protocol_name =
ESMTP
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr client_address
= 141.227.0.1
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr client_name =
relay02.par.totalbanal.net
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr helo_name =
relay02.par.totalbanal.net
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sender =
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr recipient =
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr queue_id =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr instance =
4d7.484e459d.0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr size = 1078
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_method =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_username =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_sender =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr ccert_subject =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr ccert_issuer =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr
ccert_fingerprint =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/kolabpolicy:
wanted attribute: action
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
action
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
DUNNO
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/kolabpolicy:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: check_table_result:
unix:private/kolabpolicy DUNNO policy query
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=check_policy_service status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >>> END Sender address
RESTRICTIONS <<<
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >>> START Recipient
address RESTRICTIONS <<<
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_mynetworks
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: permit_mynetworks:
relay02.par.totalbanal.net 141.227.0.1
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 127.0.0.0/8
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 127.0.0.0/8
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.6
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.6
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.4
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.4
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.11
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.11
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.252
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.252
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_mynetworks status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_sasl_authenticated
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=permit_sasl_authenticated status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=reject_unauth_destination
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]:
reject_unauth_destination: fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: permit_auth_destination:
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: ctable_locate: leave
existing entry key fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=reject_unauth_destination status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=reject_unlisted_recipient
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >>> CHECKING RECIPIENT
MAPS <<<
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: ctable_locate: leave
existing entry key fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
recipient_canonical_maps: fox at magicwood.mgw: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
recipient_canonical_maps: fox: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
recipient_canonical_maps: @magicwood.mgw: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: mail_addr_find:
fox at magicwood.mgw -> (not found)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
canonical_maps: fox at magicwood.mgw: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
canonical_maps: fox: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
canonical_maps: @magicwood.mgw: not found
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: mail_addr_find:
fox at magicwood.mgw -> (not found)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: In
dict_ldap_lookup
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: Using
existing connection for LDAP source /kolab/etc/postfix/ldapdistlist.cf
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup:
/kolab/etc/postfix/ldapdistlist.cf: Searching with filter
(&(objectClass=kolabGroupOfNames)(!(kolabDeleteFlag=*))(mail=fox at magicwood.mgw))
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_get_values[1]:
Search found 0 match(es)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_get_values[1]:
Leaving dict_ldap_get_values
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: Search
returned nothing
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: In
dict_ldap_lookup
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: Using
existing connection for LDAP source /kolab/etc/postfix/ldapvirtual.cf
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup:
/kolab/etc/postfix/ldapvirtual.cf: Searching with filter
(&(!(kolabDeleteFlag=*))(|(alias=fox at magicwood.mgw)(mail=fox at magicwood.mgw)))
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_get_values[1]:
Search found 1 match(es)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_get_values[1]:
search returned 1 value(s) for requested result attribute mail
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_get_values[1]:
Leaving dict_ldap_get_values
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: dict_ldap_lookup: Search
returned fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: maps_find:
virtual_alias_maps: ldap:/kolab/etc/postfix/ldapvirtual.cf(0,100):
fox at magicwood.mgw = fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: mail_addr_find:
fox at magicwood.mgw -> fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=reject_unlisted_recipient status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=check_policy_service
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr request =
smtpd_access_policy
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr protocol_state
= RCPT
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr protocol_name =
ESMTP
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr client_address
= 141.227.0.1
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr client_name =
relay02.par.totalbanal.net
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr helo_name =
relay02.par.totalbanal.net
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sender =
rabbit at mapa.de
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr recipient =
fox at magicwood.mgw
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr queue_id =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr instance =
4d7.484e459d.0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr size = 1078
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_method =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_username =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr sasl_sender =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr ccert_subject =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr ccert_issuer =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr
ccert_fingerprint =
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/kolabpolicy:
wanted attribute: action
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
action
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
DUNNO
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: private/kolabpolicy:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: check_table_result:
unix:private/kolabpolicy DUNNO policy query
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: generic_checks:
name=check_policy_service status=0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >>> END Recipient address
RESTRICTIONS <<<
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: smtpd_check_rewrite:
trying: permit_inet_interfaces
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: permit_inet_interfaces:
relay02.par.totalbanal.net 141.227.0.1
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: before
input_transp_cleanup: cleanup flags = enable_header_body_filter
enable_automatic_bcc enable_address_mapping
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: after
input_transp_cleanup: cleanup flags = enable_header_body_filter
enable_automatic_bcc enable_address_mapping
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: connect to subsystem
public/cleanup
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: public/cleanup socket:
wanted attribute: queue_id
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
queue_id
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
11714128067
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: public/cleanup socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: send attr flags = 50
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: 11714128067:
client=relay02.par.totalbanal.net[141.227.0.1]
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250 Ok
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: <
relay02.par.totalbanal.net[141.227.0.1]: DATA
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 354 End data with
<CR><LF>.<CR><LF>
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: public/cleanup socket:
wanted attribute: status
Jun 10 11:13:01 mail <info> postfix/cleanup[1221]: 11714128067: message-id=
Jun 10 11:13:01 mail <info> postfix/qmgr[1208]: 11714128067:
from=<rabbit at mapa.de>, size=1544, nrcpt=1 (queue active)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
status
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: public/cleanup socket:
wanted attribute: reason
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
reason
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute value:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: public/cleanup socket:
wanted attribute: (list terminator)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:01 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 250 Ok: queued as 11714128067
Jun 10 11:13:01 mail <info> postfix/smtpd[1226]: connect from
localhost[127.0.0.1]
Jun 10 11:13:01 mail <info> postfix/smtpd[1226]: 327E412806D:
client=localhost[127.0.0.1]
Jun 10 11:13:01 mail <info> postfix/cleanup[1227]: 327E412806D: message-id=
Jun 10 11:13:01 mail <info> postfix/qmgr[1208]: 327E412806D:
from=<rabbit at mapa.de>, size=1722, nrcpt=1 (queue active)
Jun 10 11:13:01 mail <info> postfix/smtpd[1226]: disconnect from
localhost[127.0.0.1]
Jun 10 11:13:01 mail <info> postfix/pipe[1222]: 11714128067:
to=<fox at magicwood.mgw>, relay=kolabfilter, delay=0, status=sent
(mail.magicwood.mgw)
Jun 10 11:13:01 mail <info> postfix/qmgr[1208]: 11714128067: removed
Jun 10 11:13:01 mail <info> postfix/pipe[1234]: 327E412806D:
to=<fox at magicwood.mgw>, relay=kolabmailboxfilter, delay=0, status=sent
(mail.magicwood.mgw)
Jun 10 11:13:01 mail <info> postfix/qmgr[1208]: 327E412806D: removed
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: <
relay02.par.totalbanal.net[141.227.0.1]: QUIT
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: >
relay02.par.totalbanal.net[141.227.0.1]: 221 Bye
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 127.0.0.0/8
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 127.0.0.0/8
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.6
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.6
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.4
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.4
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.1.11
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.1.11
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostname:
relay02.par.totalbanal.net ~? 192.168.2.252
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_hostaddr:
141.227.0.1 ~? 192.168.2.252
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_list_match:
relay02.par.totalbanal.net: no match
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: match_list_match:
141.227.0.1: no match
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: send attr request =
disconnect
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: send attr ident =
smtp:141.227.0.1
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: status
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: input attribute name:
status
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: input attribute value: 0
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: private/anvil: wanted
attribute: (list terminator)
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: input attribute name:
(end)
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: disconnect from
relay02.par.totalbanal.net[141.227.0.1]
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: master_notify: status 1
Jun 10 11:13:06 mail <info> postfix/smtpd[1239]: connection closed