Help with getting email working: kolab-server-2.2.1-beta-1

Gunnar Wrobel wrobel at pardus.de
Sat Dec 20 19:34:53 CET 2008


Quoting Harkin Banks <harkin.as at gmail.com>:

> This is a followup as I have been looking through the logs and
> trying to diagnose what's going on.  Below is the tail of
> /kolab/var/postfix/log/postfix.log that I get when it try to email
> myself (harkin at example.com[1] to harkin at example.com[2]).  Again, I
> get the same behavior in Horde:  the email is in the Sent box, but
> does not appear in the Inbox.  It appears that the message appears
> to bounce on the basis of 'Command died with status 255:
> "/kolab/bin/php"' (the full log associated with an email
> attempt is below; this is just one line that looks particularly
> important to my untrained eye):

This is a dumb configuration error we had in the configuration files.  
I discovered and fixed this yesterday:

https://www.intevation.de/roundup/kolab/issue3331

This should not have happened.

Cheers,

Gunnar

>  Dec 19 16:55:39 smtp.example.com[3] <info>
> postfix/pipe[17699]: 8A0A45883DA: to=<harkin at example.com[4]>,
> relay
> =kolabmailboxfilter, delay=0.51, delays=0.1/0.08/0/0.34, dsn=5.3.0,
> status=bounced (Command died with status 2
> 55: "/kolab/bin/php")
>
>
> The issue with LDAP that I mentioned in the previous email seems to
> have been resolved by restarting all the kolab services. Still,
it's
> a little scary since I don't what happened or why.   I have found  
> another issue in searching the logs (I don't know if
> there might be some relationship with the above bounce/command died
> issue).  Here's a line from /var/log/maillog:
> Dec 19 14:45:02 vps kolabquotawarn[17454]: fatal: Can't read
> configuration, please make sure that kolabquotawarn runs with
> sufficient privileges
>  /kolab/var/postfix/log/postfix.log:
> Dec 19 16:52:20 smtp.example.com[5] <warning>
> postfix/smtpd[11717]: warning: database /kolab/etc/postfix/canon
> ical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:52:20 smtp.example.com[6] <warning>
> postfix/smtpd[11718]: warning: database /kolab/etc/postfix/canon
> ical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:52:21 smtp.example.com[7] <info>
> postfix/smtpd[11718]: connect from
> 118-165-88-73.dynamic.hinet.net[8][
> 118.165.88.73]
> Dec 19 16:52:21 smtp.example.com[9] <info>
> postfix/smtpd[11718]: lost connection after CONNECT from 118-165-88
> -73.dynamic.hinet.net[10][118.165.88.73]
> Dec 19 16:52:21 smtp.example.com[11] <info>
> postfix/smtpd[11718]: disconnect from 118-165-88-73.dynamic.hinet.n
> et[118.165.88.73]
> Dec 19 16:52:21 smtp.example.com[12] <info>
> postfix/smtpd[11717]: connect from
> 118-165-88-73.dynamic.hinet.net[13][
> 118.165.88.73]
> Dec 19 16:52:21 smtp.example.com[14] <info>
> postfix/smtpd[11717]: lost connection after CONNECT from 118-165-88
> -73.dynamic.hinet.net[15][118.165.88.73]
> Dec 19 16:52:21 smtp.example.com[16] <info>
> postfix/smtpd[11717]: disconnect from 118-165-88-73.dynamic.hinet.n
> et[118.165.88.73]
> Dec 19 16:55:34 smtp.example.com[17] <warning>
> postfix/smtpd[17625]: warning: database /kolab/etc/postfix/canon
> ical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:55:34 smtp.example.com[18] <info>
> postfix/smtpd[17625]: connect from localhost.localdomain[127.0.0.1]
> Dec 19 16:55:35 smtp.example.com[19] <info>
> postfix/smtpd[17625]: setting up TLS connection from localhost.loca
> ldomain[127.0.0.1]
> Dec 19 16:55:35 smtp.example.com[20] <info>
> postfix/smtpd[17625]: TLS connection established from localhost.loc
> aldomain[127.0.0.1]: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256
> bits)
> Dec 19 16:55:35 smtp.example.com[21] <warning>
> postfix/trivial-rewrite[17648]: warning: database /kolab/etc/pos
> tfix/relocated.db is older than source file
> /kolab/etc/postfix/relocated
> Dec 19 16:55:35 smtp.example.com[22] <warning>
> postfix/cleanup[17653]: warning: database /kolab/etc/postfix/can
> onical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:55:35 smtp.example.com[23] <info>
> postfix/smtpd[17625]: 78B865883DA: client=localhost.localdomain[127
> .0.0.1], sasl_method=LOGIN, sasl_username=harkin at example.com[24]
> Dec 19 16:55:35 smtp.example.com[25] <info>
> postfix/cleanup[17653]: 78B865883DA:
> message-id=<20081219165533.170
>  07aqp55a1mvk8 at example.com[26]>
> Dec 19 16:55:35 smtp.example.com[27] <info>
> postfix/qmgr[30201]: 78B865883DA:
> from=<harkin at example.com[28]>, siz
> e=1029, nrcpt=1 (queue active)
> Dec 19 16:55:35 smtp.example.com[29] <info>
> postfix/smtpd[17625]: disconnect from localhost.localdomain[127.0.0
> .1]
> Dec 19 16:55:37 smtp.example.com[30] <warning>
> postfix/smtpd[17680]: warning: database /kolab/etc/postfix/canon
> ical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:55:37 smtp.example.com[31] <info>
> postfix/smtpd[17680]: connect from localhost.localdomain[127.0.0.1]
> Dec 19 16:55:37 smtp.example.com[32] <warning>
> postfix/cleanup[17682]: warning: database /kolab/etc/postfix/can
> onical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:55:37 smtp.example.com[33] <info>
> postfix/smtpd[17680]: 13DCB5883DC: client=localhost.localdomain[127
> .0.0.1]
> Dec 19 16:55:37 smtp.example.com[34] <info>
> postfix/cleanup[17682]: 13DCB5883DC:
> message-id=<20081219165533.170
>  07aqp55a1mvk8 at example.com[35]>
> Dec 19 16:55:37 smtp.example.com[36] <info>
> postfix/qmgr[30201]: 13DCB5883DC:
> from=<harkin at example.com[37]>, siz
> e=1224, nrcpt=1 (queue active)
> Dec 19 16:55:37 smtp.example.com[38] <info>
> postfix/smtpd[17680]: disconnect from localhost.localdomain[127.0.0
> .1]
> Dec 19 16:55:37 smtp.example.com[39] <info>
> postfix/pipe[17660]: 78B865883DA: to=<harkin at example.com[40]>,
> relay
> =kolabfilter, delay=2.2, delays=0.53/0.25/0/1.4, dsn=2.0.0,
> status=sent (delivered via kolabfilter service)
> Dec 19 16:55:37 smtp.example.com[41] <info>
> postfix/qmgr[30201]: 78B865883DA: removed
> Dec 19 16:55:38 smtp.example.com[42] <warning>
> postfix/smtpd[17696]: warning: database /kolab/etc/postfix/canon
> ical.db is older than source file /kolab/etc/postfix/canonical
> Dec 19 16:55:38 smtp.example.com[43] <info>
> postfix/smtpd[17696]: connect from localhost.localdomain[127.0.0.1]
> Dec 19 16:55:38 smtp.example.com[44] <info>
> postfix/smtpd[17696]: 8A0A45883DA: client=localhost.localdomain[127
> .0.0.1]
> Dec 19 16:55:38 smtp.example.com[45] <info>
> postfix/cleanup[17682]: 8A0A45883DA:
> message-id=<20081219165533.170
> 07aqp55a1mvk8 at example.com[46]>
> Dec 19 16:55:38 smtp.example.com[47] <info>
> postfix/qmgr[30201]: 8A0A45883DA:
> from=<harkin at example.com[48]>, siz
> e=1699, nrcpt=1 (queue active)
> Dec 19 16:55:38 smtp.example.com[49] <info>
> postfix/smtpd[17696]: disconnect from localhost.localdomain[127.0.0
> .1]
> Dec 19 16:55:38 smtp.example.com[50] <info>
> postfix/smtp[17684]: 13DCB5883DC: to=<harkin at example.com[51]>,
> relay
> =127.0.0.1[127.0.0.1]:10024, delay=1.6, delays=0.17/0.38/0.01/1,
> dsn=2.0.0, status=sent (250 2.0.0 Ok: queued
> as 8A0A45883DA)
> Dec 19 16:55:38 smtp.example.com[52] <info>
> postfix/qmgr[30201]: 13DCB5883DC: removed
> Dec 19 16:55:39 smtp.example.com[53] <info>
> postfix/pipe[17699]: 8A0A45883DA: to=<harkin at example.com[54]>,
> relay
> =kolabmailboxfilter, delay=0.51, delays=0.1/0.08/0/0.34, dsn=5.3.0,
> status=bounced (Command died with status 2
> 55: "/kolab/bin/php")
> Dec 19 16:55:39 smtp.example.com[55] <info>
> postfix/cleanup[17653]: 141605883DE:
> message-id=<20081219165539.141
> 605883DE at mail101.example.com[56]>
> Dec 19 16:55:39 smtp.example.com[57] <info>
> postfix/qmgr[30201]: 141605883DE: from=<>, size=3599, nrcpt=1
> (queu
> e active)
> Dec 19 16:55:39 smtp.example.com[58] <info>
> postfix/bounce[17707]: 8A0A45883DA: sender non-delivery notificatio
> n: 141605883DE
> Dec 19 16:55:39 smtp.example.com[59] <info>
> postfix/qmgr[30201]: 8A0A45883DA: removed
> Dec 19 16:55:40 smtp.example.com[60] <info>
> postfix/pipe[17699]: 141605883DE: to=<harkin at example.com[61]>,
> relay
> =kolabmailboxfilter, delay=1.1, delays=0.18/0/0/0.91, dsn=5.3.0,
> status=bounced (Command died with status 255:
>  "/kolab/bin/php")
> Dec 19 16:55:40 smtp.example.com[62] <info>
> postfix/qmgr[30201]: 141605883DE: removed
> Dec 19 16:55:41 smtp.example.com[63] <info>
> postfix/anvil[11774]: statistics: max connection rate 2/60s for (0.
> 0.0.0:smtp:118.165.88.73) at Dec 19 16:52:21
> Dec 19 16:55:41 smtp.example.com[64] <info>
> postfix/anvil[11774]: statistics: max connection count 1 for (0.0.0
> .0:smtp:118.165.88.73) at Dec 19 16:52:21
> Dec 19 16:55:41 smtp.example.com[65] <info>
> postfix/anvil[11774]: statistics: max cache size 1 at Dec 19 16:52:
> 21
> #
>
> On Thu, Dec 18, 2008 at 8:53 PM, Harkin
> Banks <harkin.as[66]@gmail.com[67]>
> wrote:
>  0pt 0.8ex; padding-left: 1ex;" class="gmail_quote">
bgcolor="#ffffff"> I just
> installed Kolab on a server with a fresh install of CentOS 5.2.  I
> got the source rpm's from   face="Arial">
href="http://ftp.belnet.be/packages/kolab/server/beta/kolab-server-2.2.1-beta-1/">http://ftp.belnet.be/packages/kolab/server/beta/kolab-server-2.2.1-beta-1/
>    size="2" face="Arial">.  I did the install according to
>   target="_blank"
>
href="http://wiki.kolab.org/index.php/Kolab2_Installation_-_Source">http://wiki.kolab.org/index.php/Kolab2_Installation_-_Source
>  Everything looks
> great, except that email is not quite working correctly.  I created
> two users on the local domain (
href="mailto:user1 at domain.com">user1 at domain.com,  target="_blank"
> href="mailto:user2 at domain.com">user2 at domain.com) and sent each
> an email from the other via Horde.  In each case the email showed
up
> in the senders Sent box, but did not appear in the Inbox of the
> recipient.    face="Arial">   face="Arial">Subsequent to this,
after working with the system for
> an hour or so, I am getting the following when I try to log in
(with
> any of the three accounts I've created (one admin, two users)):    
> face="Times New Roman">Failed identifying the UID of the Kolab
> user  href="mailto:harkin at domain.com">harkin at domain.com. Error
was:
> Unable to bind to the LDAP server as
> cn=nobody,cn=internal,dc=domain,dc=com!
>  New Roman">   face="Arial">I hope by
> mentioning the second issue (with LDAP) I'm not creating additional
> confusion, but I thought they might be related.   size="3"  
> face="Times New Roman">   size="2" face="Arial">
Roman">Any suggestions are appreciated.  Thank you.
>   size="3" face="Times New Roman">

-- 
______ http://kdab.com _______________ http://kolab-konsortium.com _

p at rdus Kolab work is funded in part by KDAB and the Kolab Konsortium

____ http://www.pardus.de _________________ http://gunnarwrobel.de _
E-mail : p at rdus.de                                 Dr. Gunnar Wrobel
Tel.   : +49 700 6245 0000                          Bundesstrasse 29
Fax    : +49 721 1513 52322                          D-20146 Hamburg
--------------------------------------------------------------------
    >> Mail at ease - Rent a kolab groupware server at p at rdus <<
--------------------------------------------------------------------

Links:
------
[1] mailto:harkin at example.com
[2] mailto:harkin at example.com
[3] http://smtp.example.com
[4] mailto:harkin at example.com
[5] http://smtp.example.com
[6] http://smtp.example.com
[7] http://smtp.example.com
[8] http://118-165-88-73.dynamic.hinet.net
[9] http://smtp.example.com
[10] http://73.dynamic.hinet.net
[11] http://smtp.example.com
[12] http://smtp.example.com
[13] http://118-165-88-73.dynamic.hinet.net
[14] http://smtp.example.com
[15] http://73.dynamic.hinet.net
[16] http://smtp.example.com
[17] http://smtp.example.com
[18] http://smtp.example.com
[19] http://smtp.example.com
[20] http://smtp.example.com
[21] http://smtp.example.com
[22] http://smtp.example.com
[23] http://smtp.example.com
[24] mailto:harkin at example.com
[25] http://smtp.example.com
[26] mailto:07aqp55a1mvk8 at example.com
[27] http://smtp.example.com
[28] mailto:harkin at example.com
[29] http://smtp.example.com
[30] http://smtp.example.com
[31] http://smtp.example.com
[32] http://smtp.example.com
[33] http://smtp.example.com
[34] http://smtp.example.com
[35] mailto:07aqp55a1mvk8 at example.com
[36] http://smtp.example.com
[37] mailto:harkin at example.com
[38] http://smtp.example.com
[39] http://smtp.example.com
[40] mailto:harkin at example.com
[41] http://smtp.example.com
[42] http://smtp.example.com
[43] http://smtp.example.com
[44] http://smtp.example.com
[45] http://smtp.example.com
[46] mailto:07aqp55a1mvk8 at example.com
[47] http://smtp.example.com
[48] mailto:harkin at example.com
[49] http://smtp.example.com
[50] http://smtp.example.com
[51] mailto:harkin at example.com
[52] http://smtp.example.com
[53] http://smtp.example.com
[54] mailto:harkin at example.com
[55] http://smtp.example.com
[56] mailto:605883DE at mail101.example.com
[57] http://smtp.example.com
[58] http://smtp.example.com
[59] http://smtp.example.com
[60] http://smtp.example.com
[61] mailto:harkin at example.com
[62] http://smtp.example.com
[63] http://smtp.example.com
[64] http://smtp.example.com
[65] http://smtp.example.com
[66] http://harkin.as
[67] http://gmail.com


----------------------------------------------------------------
This message was sent using IMP, the Internet Messaging Program.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 197 bytes
Desc: Digitale PGP-Unterschrift
URL: <http://lists.kolab.org/pipermail/users/attachments/20081220/01792eaf/attachment.sig>


More information about the users mailing list