Can't send mail to kolab-Server: (mail for .... loops back to myself)

Mon May 20 13:12:54 CEST 2013

Hello,

Finnally I got a running kolab, at least as far as the services go.
(Thanks to Richard and Aeneas)

But now I am facing another problem, probably a misconfiguration:

The situation:
---------------

I have a virtual server running for my domain r-goetz.de. This is an
old postfix/qpopper system which I intend to replace by kolab once
kolab is running satiesfactory.

In order to assign the kolab server to the same domain I added a host
rgo.r-goetz.de with the IP of the kolab server to the DNS.

On the kolab server I added a test-user called test.user

The problem:
--------------

Now I send a mail from another server (either r-goetz.de or a third server 
I can use) to test.user at rgo.r-goetz.de.

The mail is received by the kolab server as expected, passed through to amavis
but finally reject with the message status=bounced (mail for rgo.r-goetz.de loops back to myself)

Why is the system not deliviering to the local mailboxes?

The kolab server is on a root-server running a openSuSE 12.2 x86_64 system.

The log in /var/log/mail.info is:

May 20 13:10:52 rgo postfix/smtpd[6204]: connect from static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:52 rgo postfix/smtpd[6204]: E47DA3D00566: client=static.42.163.47.78.clients.May 20 13:10:52 rgo postfix/smtpd[6204]: connect from static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:52 rgo postfix/smtpd[6204]: E47DA3D00566: client=static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:52 rgo postfix/cleanup[6215]: E47DA3D00566: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/qmgr[5744]: E47DA3D00566: from=<goetz at mail.brief-an-steve.de>, size=711, nrcpt=1 (queue active)
May 20 13:10:53 rgo postfix/smtpd[6204]: disconnect from static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:53 rgo amavis[5777]: (05777-02) ESMTP::10024 /var/spool/amavis/tmp/amavis-20130520T130001-05777-ilPU7q2k: <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de> SIZE=711 Received: from rgo.r-goetz.de ([127.0.0.1]) by localhost (rgo.r-goetz.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <test.user at rgo.r-goetz.de>; Mon, 20 May 2013 13:10:53 +0200 (CEST)
May 20 13:10:53 rgo amavis[5777]: (05777-02) Checking: CczhM8EJ9JbS [78.47.163.42] <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>
May 20 13:10:53 rgo amavis[5777]: (05777-02) p001 1 Content-Type: text/plain, size: 6 B, name:
May 20 13:10:53 rgo postfix/smtpd[6219]: connect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/smtpd[6219]: 983633D005C3: client=localhost.localdomain[127.0.0.1], orig_queue_id=E47DA3D00566, orig_client=static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:53 rgo postfix/cleanup[6215]: 983633D005C3: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/smtpd[6219]: disconnect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/qmgr[5744]: 983633D005C3: from=<goetz at mail.brief-an-steve.de>, size=1300, nrcpt=1 (queue active)
May 20 13:10:53 rgo amavis[5777]: (05777-02) FWD from <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>,BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 983633D005C3
May 20 13:10:53 rgo postfix/smtp[6220]: 983633D005C3: to=<test.user at rgo.r-goetz.de>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.07, delays=0.06/0/0/0, dsn=2.0.0, status=sent (250 Ok)
May 20 13:10:53 rgo postfix/qmgr[5744]: 983633D005C3: removed
May 20 13:10:53 rgo amavis[5777]: (05777-02) Passed CLEAN {RelayedInbound}, [78.47.163.42]:48727 [78.47.163.42] <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>, Queue-ID: E47DA3D00566, Message-ID: <20130520111052.78449480A2D at mail.brief-an-steve.de>, mail_id: CczhM8EJ9JbS, Hits: 0, size: 711, queued_as: 983633D005C3, 654 ms
May 20 13:10:53 rgo amavis[5777]: (05777-02) TIMING-SA total 469 ms - parse: 0.51 (0.1%), extract_message_metadata: 71 (15.0%), poll_dns_idle: 81 (17.2%), get_uri_detail_list: 0.14 (0.0%), tests_pri_-1000: 1.80 (0.4%), tests_pri_-950: 0.45 (0.1%), tests_pri_-900: 0.45 (0.1%), tests_pri_-400: 0.35 (0.1%), tests_pri_0: 289 (61.5%), check_dkim_adsp: 35 (7.6%), check_spf: 26 (5.5%), check_razor2: 207 (44.1%), check_pyzor: 0.17 (0.0%), tests_pri_500: 2 (0.5%), learn: 93 (19.9%), get_report: 0.70 (0.1%)
May 20 13:10:53 rgo postfix/smtp[6216]: E47DA3D00566: to=<test.user at rgo.r-goetz.de>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=0.38/0/0/0.66, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 983633D005C3)
May 20 13:10:53 rgo postfix/qmgr[5744]: E47DA3D00566: removed
May 20 13:10:53 rgo amavis[5777]: (05777-02) size: 711, TIMING [total 659 ms] - SMTP greeting: 1 (0%)0, SMTP EHLO: 3 (0%)1, SMTP pre-MAIL: 5 (1%)1, lookup_ldap: 15 (2%)4, SMTP pre-DATA-flush: 3 (0%)4, SMTP DATA: 15 (2%)6, check_init: 0 (0%)6, digest_hdr: 1 (0%)7, digest_body_dkim: 1 (0%)7, mime_decode: 6 (1%)7, get-file-type1: 8 (1%)9, decompose_part: 0 (0%)9, parts_decode: 0 (0%)9, check_header: 2 (0%)9, AV-scan-1: 13 (2%)11, spam-wb-list: 5 (1%)12, SA parse: 6 (1%)13, SA check: 464 (70%)83, decide_mail_destiny: 12 (2%)85, notif-quar: 1 (0%)85, fwd-connect: 15 (2%)87, fwd-xforward: 2 (0%)88, fwd-mail-pip: 4 (1%)88, fwd-rcpt-pip: 0 (0%)88, fwd-data-chkpnt: 0 (0%)88, write-header: 0 (0%)89, fwd-data-contents: 0 (0%)89, fwd-end-chkpnt: 63 (10%)98, prepare-dsn: 1 (0%)98, main_log_entry: 8 (1%)99, update_snmp: 3 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-2-files: 0 (0%)100, rundown: 0 (0%)100
May 20 13:10:53 rgo postfix/smtpd[6222]: connect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/smtpd[6222]: CA5A83D005C3: client=localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/cleanup[6215]: CA5A83D005C3: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/qmgr[5744]: CA5A83D005C3: from=<goetz at mail.brief-an-steve.de>, size=1500, nrcpt=1 (queue active)
May 20 13:10:53 rgo postfix/smtpd[6222]: disconnect from localhost.localdomain[127.0.0.1]
May 20 13:10:54 rgo postfix/smtp[6223]: CA5A83D005C3: to=<test.user at rgo.r-goetz.de>, relay=none, delay=0.14, delays=0.08/0.01/0.05/0, dsn=5.4.6, status=bounced (mail for rgo.r-goetz.de loops back to myself)
May 20 13:10:54 rgo postfix/cleanup[6215]: 0114B3D005CB: message-id=<20130520111054.0114B3D005CB at rgo.r-goetz.de>
May 20 13:10:54 rgo postfix/qmgr[5744]: 0114B3D005CB: from=<>, size=3357, nrcpt=1 (queue active)
May 20 13:10:54 rgo postfix/bounce[6224]: CA5A83D005C3: sender non-delivery notification: 0114B3D005CB
May 20 13:10:54 rgo postfix/qmgr[5744]: CA5A83D005C3: removed
May 20 13:10:54 rgo postfix/smtp[6226]: 0114B3D005CB: to=<goetz at mail.brief-an-steve.de>, relay=mail.brief-an-steve.de[78.47.163.42]:25, delay=0.26, delays=0.04/0.01/0.15/0.06, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 3E904480A22)
May 20 13:10:54 rgo postfix/qmgr[5744]: 0114B3D005CB: removed
 your-server.de[78.47.163.42]
May 20 13:10:52 rgo postfix/cleanup[6215]: E47DA3D00566: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/qmgr[5744]: E47DA3D00566: from=<goetz at mail.brief-an-steve.de>, size=711, nrcpt=1 (queue active)
May 20 13:10:53 rgo postfix/smtpd[6204]: disconnect from static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:53 rgo amavis[5777]: (05777-02) ESMTP::10024 /var/spool/amavis/tmp/amavis-20130520T130001-05777-ilPU7q2k: <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de> SIZE=711 Received: from rgo.r-goetz.de ([127.0.0.1]) by localhost (rgo.r-goetz.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <test.user at rgo.r-goetz.de>; Mon, 20 May 2013 13:10:53 +0200 (CEST)
May 20 13:10:53 rgo amavis[5777]: (05777-02) Checking: CczhM8EJ9JbS [78.47.163.42] <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>
May 20 13:10:53 rgo amavis[5777]: (05777-02) p001 1 Content-Type: text/plain, size: 6 B, name:
May 20 13:10:53 rgo postfix/smtpd[6219]: connect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/smtpd[6219]: 983633D005C3: client=localhost.localdomain[127.0.0.1], orig_queue_id=E47DA3D00566, orig_client=static.42.163.47.78.clients.your-server.de[78.47.163.42]
May 20 13:10:53 rgo postfix/cleanup[6215]: 983633D005C3: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/smtpd[6219]: disconnect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/qmgr[5744]: 983633D005C3: from=<goetz at mail.brief-an-steve.de>, size=1300, nrcpt=1 (queue active)
May 20 13:10:53 rgo amavis[5777]: (05777-02) FWD from <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>,BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 983633D005C3
May 20 13:10:53 rgo postfix/smtp[6220]: 983633D005C3: to=<test.user at rgo.r-goetz.de>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.07, delays=0.06/0/0/0, dsn=2.0.0, status=sent (250 Ok)
May 20 13:10:53 rgo postfix/qmgr[5744]: 983633D005C3: removed
May 20 13:10:53 rgo amavis[5777]: (05777-02) Passed CLEAN {RelayedInbound}, [78.47.163.42]:48727 [78.47.163.42] <goetz at mail.brief-an-steve.de> -> <test.user at rgo.r-goetz.de>, Queue-ID: E47DA3D00566, Message-ID: <20130520111052.78449480A2D at mail.brief-an-steve.de>, mail_id: CczhM8EJ9JbS, Hits: 0, size: 711, queued_as: 983633D005C3, 654 ms
May 20 13:10:53 rgo amavis[5777]: (05777-02) TIMING-SA total 469 ms - parse: 0.51 (0.1%), extract_message_metadata: 71 (15.0%), poll_dns_idle: 81 (17.2%), get_uri_detail_list: 0.14 (0.0%), tests_pri_-1000: 1.80 (0.4%), tests_pri_-950: 0.45 (0.1%), tests_pri_-900: 0.45 (0.1%), tests_pri_-400: 0.35 (0.1%), tests_pri_0: 289 (61.5%), check_dkim_adsp: 35 (7.6%), check_spf: 26 (5.5%), check_razor2: 207 (44.1%), check_pyzor: 0.17 (0.0%), tests_pri_500: 2 (0.5%), learn: 93 (19.9%), get_report: 0.70 (0.1%)
May 20 13:10:53 rgo postfix/smtp[6216]: E47DA3D00566: to=<test.user at rgo.r-goetz.de>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=0.38/0/0/0.66, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 983633D005C3)
May 20 13:10:53 rgo postfix/qmgr[5744]: E47DA3D00566: removed
May 20 13:10:53 rgo amavis[5777]: (05777-02) size: 711, TIMING [total 659 ms] - SMTP greeting: 1 (0%)0, SMTP EHLO: 3 (0%)1, SMTP pre-MAIL: 5 (1%)1, lookup_ldap: 15 (2%)4, SMTP pre-DATA-flush: 3 (0%)4, SMTP DATA: 15 (2%)6, check_init: 0 (0%)6, digest_hdr: 1 (0%)7, digest_body_dkim: 1 (0%)7, mime_decode: 6 (1%)7, get-file-type1: 8 (1%)9, decompose_part: 0 (0%)9, parts_decode: 0 (0%)9, check_header: 2 (0%)9, AV-scan-1: 13 (2%)11, spam-wb-list: 5 (1%)12, SA parse: 6 (1%)13, SA check: 464 (70%)83, decide_mail_destiny: 12 (2%)85, notif-quar: 1 (0%)85, fwd-connect: 15 (2%)87, fwd-xforward: 2 (0%)88, fwd-mail-pip: 4 (1%)88, fwd-rcpt-pip: 0 (0%)88, fwd-data-chkpnt: 0 (0%)88, write-header: 0 (0%)89, fwd-data-contents: 0 (0%)89, fwd-end-chkpnt: 63 (10%)98, prepare-dsn: 1 (0%)98, main_log_entry: 8 (1%)99, update_snmp: 3 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100, unlink-2-files: 0 (0%)100, rundown: 0 (0%)100
May 20 13:10:53 rgo postfix/smtpd[6222]: connect from localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/smtpd[6222]: CA5A83D005C3: client=localhost.localdomain[127.0.0.1]
May 20 13:10:53 rgo postfix/cleanup[6215]: CA5A83D005C3: message-id=<20130520111052.78449480A2D at mail.brief-an-steve.de>
May 20 13:10:53 rgo postfix/qmgr[5744]: CA5A83D005C3: from=<goetz at mail.brief-an-steve.de>, size=1500, nrcpt=1 (queue active)
May 20 13:10:53 rgo postfix/smtpd[6222]: disconnect from localhost.localdomain[127.0.0.1]
May 20 13:10:54 rgo postfix/smtp[6223]: CA5A83D005C3: to=<test.user at rgo.r-goetz.de>, relay=none, delay=0.14, delays=0.08/0.01/0.05/0, dsn=5.4.6, status=bounced (mail for rgo.r-goetz.de loops back to myself)
May 20 13:10:54 rgo postfix/cleanup[6215]: 0114B3D005CB: message-id=<20130520111054.0114B3D005CB at rgo.r-goetz.de>
May 20 13:10:54 rgo postfix/qmgr[5744]: 0114B3D005CB: from=<>, size=3357, nrcpt=1 (queue active)
May 20 13:10:54 rgo postfix/bounce[6224]: CA5A83D005C3: sender non-delivery notification: 0114B3D005CB
May 20 13:10:54 rgo postfix/qmgr[5744]: CA5A83D005C3: removed
May 20 13:10:54 rgo postfix/smtp[6226]: 0114B3D005CB: to=<goetz at mail.brief-an-steve.de>, relay=mail.brief-an-steve.de[78.47.163.42]:25, delay=0.26, delays=0.04/0.01/0.15/0.06, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 3E904480A22)
May 20 13:10:54 rgo postfix/qmgr[5744]: 0114B3D005CB: removed

I hope someone can put some light on this issue.

Thank you

R"udiger

-- 
Those who would give up essential liberty
to purchase a little temporary safety
deserve neither liberty nor safety.
           Benjamin Franklin
--------------------------------------------
R"udiger Goetz
rg at r-goetz.de
WWW: http://www.r-goetz.de
Mail send by a Mac running Linux (SuSE-PPC)