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

Fri Dec 19 18:19:30 CET 2008

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 to harkin at example.com).  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):

Dec 19 16:55:39 smtp.example.com <info> postfix/pipe[17699]: 8A0A45883DA:
to=<harkin at example.com>, 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 <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 <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 <info> postfix/smtpd[11718]: connect from
118-165-88-73.dynamic.hinet.net[
118.165.88.73]
Dec 19 16:52:21 smtp.example.com <info> postfix/smtpd[11718]: lost
connection after CONNECT from 118-165-88
-73.dynamic.hinet.net[118.165.88.73]
Dec 19 16:52:21 smtp.example.com <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 <info> postfix/smtpd[11717]: connect from
118-165-88-73.dynamic.hinet.net[
118.165.88.73]
Dec 19 16:52:21 smtp.example.com <info> postfix/smtpd[11717]: lost
connection after CONNECT from 118-165-88
-73.dynamic.hinet.net[118.165.88.73]
Dec 19 16:52:21 smtp.example.com <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 <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 <info> postfix/smtpd[17625]: connect from
localhost.localdomain[127.0.0.1]
Dec 19 16:55:35 smtp.example.com <info> postfix/smtpd[17625]: setting up TLS
connection from localhost.loca
ldomain[127.0.0.1]
Dec 19 16:55:35 smtp.example.com <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 <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 <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 <info> postfix/smtpd[17625]: 78B865883DA:
client=localhost.localdomain[127
.0.0.1], sasl_method=LOGIN, sasl_username=harkin at example.com
Dec 19 16:55:35 smtp.example.com <info> postfix/cleanup[17653]: 78B865883DA:
message-id=<20081219165533.170
07aqp55a1mvk8 at example.com>
Dec 19 16:55:35 smtp.example.com <info> postfix/qmgr[30201]: 78B865883DA:
from=<harkin at example.com>, siz
e=1029, nrcpt=1 (queue active)
Dec 19 16:55:35 smtp.example.com <info> postfix/smtpd[17625]: disconnect
from localhost.localdomain[127.0.0
.1]
Dec 19 16:55:37 smtp.example.com <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 <info> postfix/smtpd[17680]: connect from
localhost.localdomain[127.0.0.1]
Dec 19 16:55:37 smtp.example.com <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 <info> postfix/smtpd[17680]: 13DCB5883DC:
client=localhost.localdomain[127
.0.0.1]
Dec 19 16:55:37 smtp.example.com <info> postfix/cleanup[17682]: 13DCB5883DC:
message-id=<20081219165533.170
07aqp55a1mvk8 at example.com>
Dec 19 16:55:37 smtp.example.com <info> postfix/qmgr[30201]: 13DCB5883DC:
from=<harkin at example.com>, siz
e=1224, nrcpt=1 (queue active)
Dec 19 16:55:37 smtp.example.com <info> postfix/smtpd[17680]: disconnect
from localhost.localdomain[127.0.0
.1]
Dec 19 16:55:37 smtp.example.com <info> postfix/pipe[17660]: 78B865883DA:
to=<harkin at example.com>, 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 <info> postfix/qmgr[30201]: 78B865883DA:
removed
Dec 19 16:55:38 smtp.example.com <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 <info> postfix/smtpd[17696]: connect from
localhost.localdomain[127.0.0.1]
Dec 19 16:55:38 smtp.example.com <info> postfix/smtpd[17696]: 8A0A45883DA:
client=localhost.localdomain[127
.0.0.1]
Dec 19 16:55:38 smtp.example.com <info> postfix/cleanup[17682]: 8A0A45883DA:
message-id=<20081219165533.170
07aqp55a1mvk8 at example.com>
Dec 19 16:55:38 smtp.example.com <info> postfix/qmgr[30201]: 8A0A45883DA:
from=<harkin at example.com>, siz
e=1699, nrcpt=1 (queue active)
Dec 19 16:55:38 smtp.example.com <info> postfix/smtpd[17696]: disconnect
from localhost.localdomain[127.0.0
.1]
Dec 19 16:55:38 smtp.example.com <info> postfix/smtp[17684]: 13DCB5883DC:
to=<harkin at example.com>, 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 <info> postfix/qmgr[30201]: 13DCB5883DC:
removed
Dec 19 16:55:39 smtp.example.com <info> postfix/pipe[17699]: 8A0A45883DA:
to=<harkin at example.com>, 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 <info> postfix/cleanup[17653]: 141605883DE:
message-id=<20081219165539.141
605883DE at mail101.example.com>
Dec 19 16:55:39 smtp.example.com <info> postfix/qmgr[30201]: 141605883DE:
from=<>, size=3599, nrcpt=1 (queu
e active)
Dec 19 16:55:39 smtp.example.com <info> postfix/bounce[17707]: 8A0A45883DA:
sender non-delivery notificatio
n: 141605883DE
Dec 19 16:55:39 smtp.example.com <info> postfix/qmgr[30201]: 8A0A45883DA:
removed
Dec 19 16:55:40 smtp.example.com <info> postfix/pipe[17699]: 141605883DE:
to=<harkin at example.com>, 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 <info> postfix/qmgr[30201]: 141605883DE:
removed
Dec 19 16:55:41 smtp.example.com <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 <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 <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 at gmail.com> wrote:

>  I just installed Kolab on a server with a fresh install of CentOS 5.2.  I
> got the source rpm's from
> http://ftp.belnet.be/packages/kolab/server/beta/kolab-server-2.2.1-beta-1/
>
> .  I did the install according to
> 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 (user1 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.
>
> 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)):
> Failed identifying the UID of the Kolab user harkin at domain.com. Error was:
> Unable to bind to the LDAP server as cn=nobody,cn=internal,dc=domain,dc=com!
>
> I hope by mentioning the second issue (with LDAP) I'm not creating
> additional confusion, but I thought they might be related.
>
> Any suggestions are appreciated.  Thank you.
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kolab.org/pipermail/users/attachments/20081219/3d823ea4/attachment.html>