Mails doesn't appears in squirelmail INBOX folder

[cubitus]

Hello,

This is my first post here at howtoforge. I've installed ISPConfig3 on my new server and everything in running smoothly except on thing.

But first of all, I would like to congratulate all people that made ISPConfig :-)

Well, now my concern :

I've created an email domain and add to it an email Mailbox. I can send email to the outside, but I don't see incoming mails in the INBOX.
I digg in a bit (I'm not expert of linux) and found the mail are successfully reaching my server as the are tailed to the file /var/vmail.
What I miss is why they do not appears in the INBOX ?

Here a fragment of the mail log

Nov 7 19:04:48 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 7 19:04:48 vidy imapd: LOGIN, user=new@####.com, ip=[::ffff:127.0.0.1], port=[40827], protocol=IMAP
Nov 7 19:04:48 vidy imapd: LOGOUT, user=new@####.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=87, sent=391, time=0
Nov 7 19:05:01 vidy postfix/smtpd[4976]: connect from localhost.localdomain[127.0.0.1]
Nov 7 19:05:01 vidy postfix/smtpd[4976]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Nov 7 19:05:01 vidy postfix/smtpd[4976]: disconnect from localhost.localdomain[127.0.0.1]
Nov 7 19:05:01 vidy pop3d: Connection, ip=[::ffff:127.0.0.1]
Nov 7 19:05:01 vidy pop3d: Disconnected, ip=[::ffff:127.0.0.1]
Nov 7 19:05:01 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 7 19:05:01 vidy imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0


Any help will be appreciated. Thanks in advance :-)

[falko]

Are there any errors in your mail log when you send a mail to that server?

[cubitus]

Hi Falko,

Thanks for your reply.

No they are no error logged in either the Mail-Log, the Mail warn-Log or the Mail err-Log (all files located in Monitor/Logfiles of ISPConfig).

Just one thing. In my previous post, I did an error. The mail are tailed in the file /var/mail/vmail (not /var/vmail) . Nothing appear in subfolder new,cur or temp located in /var/vmail/XXX.com/YYY (email address is YYY@XXX.com).

For your info /var/vmail in a link to /srv/d_vidy/vmail.

If I try to send a mail to a non existing Email Mailbox (on that ISPConfig server), in exemple TOTO@XXX.com, the mail doesn't appear in the file /var/mail/vmail and is not sent back to the original sender (with maybe an error).

Strange. I don't know if it is an issue with postfix or courrier ?

Thanks again for your help

[cubitus]

Sorry, my mistake,

The mail send to an invalid email mailbox are correctly send back to the sender with the following message:

Recipient address rejected: User unknown in
virtual mailbox table (in reply to RCPT TO command)

They were captured by the antispam system.

The mail (send to a valid email mailbox) still doesn't appears in the squirelmail INBOX of that mailbox.

Thanks for your help

[till]

Please post the exat messages from the mail log that appera when you send thie email from a valid local mailbox to another valid local mailbox.

[cubitus]

Next is the logfile.

Just 2 notes:

1) At about 21:33, I've created two mailbox (one@YYY.com and two@YYY.com

2) At about 21:37 and 21:39, I've send a mail from one@YYY.com to two@YYY.com and relied back to one@YYY.com

3) The server url is vidy.XXX.com

Hope you can find some interesting stuff in the log


Nov 9 21:33:54 vidy postfix/pickup[12551]: E0C7913B8E: uid=33 from=
Nov 9 21:33:55 vidy postfix/cleanup[14935]: E0C7913B8E: message-id=<20091109213354.E0C7913B8E@vidy.XXX.com>
Nov 9 21:33:55 vidy postfix/qmgr[2868]: E0C7913B8E: from=, size=512, nrcpt=1 (queue active)
Nov 9 21:33:59 vidy amavis[2202]: (02202-05) NOTICE: reconnecting in response to: err=2006, HY000, DBD::mysql::st execute failed: MySQL server has gone away at (eval 98) line 166, line 3.
Nov 9 21:34:10 vidy postfix/pickup[12551]: 4FD8113B8F: uid=33 from=
Nov 9 21:34:10 vidy postfix/cleanup[14935]: 4FD8113B8F: message-id=<20091109213410.4FD8113B8F@vidy.XXX.com>
Nov 9 21:34:10 vidy postfix/qmgr[2868]: 4FD8113B8F: from=, size=512, nrcpt=1 (queue active)
Nov 9 21:34:10 vidy postfix/smtpd[15001]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:34:11 vidy postfix/smtpd[15001]: F40D213B8D: client=localhost.localdomain[127.0.0.1]
Nov 9 21:34:11 vidy postfix/cleanup[14935]: F40D213B8D: message-id=<20091109213354.E0C7913B8E@vidy.XXX.com>
Nov 9 21:34:11 vidy postfix/smtpd[15001]: disconnect from localhost.localdomain[127.0.0.1]
Nov 9 21:34:11 vidy postfix/qmgr[2868]: F40D213B8D: from=, size=964, nrcpt=1 (queue active)
Nov 9 21:34:11 vidy amavis[2202]: (02202-05) Passed CLEAN, -> , Message-ID: <20091109213354.E0C7913B8E@vidy.XXX.com>, mail_id: gUQ+MD1YcxYz, Hits: -0.001, size: 512, queued_as: F40D213B8D, 14187 ms
Nov 9 21:34:11 vidy postfix/pipe[15007]: F40D213B8D: to=, relay=maildrop, delay=0.65, delays=0.21/0.04/0/0.4, dsn=2.0.0, status=sent (delivered via maildrop service)
Nov 9 21:34:11 vidy postfix/qmgr[2868]: F40D213B8D: removed
Nov 9 21:34:11 vidy postfix/smtp[14938]: E0C7913B8E: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=17, delays=0.36/0.09/4/12, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=02202-05, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as F40D213B8D)
Nov 9 21:34:11 vidy postfix/qmgr[2868]: E0C7913B8E: removed
Nov 9 21:34:13 vidy amavis[2204]: (02204-05) NOTICE: reconnecting in response to: err=2006, HY000, DBD::mysql::st execute failed: MySQL server has gone away at (eval 98) line 166, line 3.
Nov 9 21:34:20 vidy postfix/smtpd[15001]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:34:21 vidy postfix/smtpd[15001]: B679313B8D: client=localhost.localdomain[127.0.0.1]
Nov 9 21:34:21 vidy postfix/cleanup[14935]: B679313B8D: message-id=<20091109213410.4FD8113B8F@vidy.XXX.com>
Nov 9 21:34:21 vidy postfix/smtpd[15001]: disconnect from localhost.localdomain[127.0.0.1]
Nov 9 21:34:21 vidy postfix/qmgr[2868]: B679313B8D: from=, size=964, nrcpt=1 (queue active)
Nov 9 21:34:22 vidy amavis[2204]: (02204-05) Passed CLEAN, -> , Message-ID: <20091109213410.4FD8113B8F@vidy.XXX.com>, mail_id: dgQPBybB5cdX, Hits: -0.001, size: 512, queued_as: B679313B8D, 10152 ms
Nov 9 21:34:22 vidy postfix/pipe[15007]: B679313B8D: to=, relay=maildrop, delay=1.4, delays=1.1/0.02/0/0.29, dsn=2.0.0, status=sent (delivered via maildrop service)
Nov 9 21:34:22 vidy postfix/qmgr[2868]: B679313B8D: removed
Nov 9 21:34:22 vidy postfix/smtp[15006]: 4FD8113B8F: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=12, delays=0.3/0.03/2.4/9.1, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=02204-05, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as B679313B8D)
Nov 9 21:34:22 vidy postfix/qmgr[2868]: 4FD8113B8F: removed
Nov 9 21:35:05 vidy pop3d: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:35:05 vidy pop3d: Disconnected, ip=[::ffff:127.0.0.1]
Nov 9 21:35:05 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:35:05 vidy imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
Nov 9 21:35:06 vidy postfix/smtpd[15048]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:35:06 vidy postfix/smtpd[15048]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Nov 9 21:35:06 vidy postfix/smtpd[15048]: disconnect from localhost.localdomain[127.0.0.1]
Nov 9 21:36:16 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:17 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48653], protocol=IMAP
Nov 9 21:36:17 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=30, sent=238, time=0
Nov 9 21:36:17 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:17 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48654], protocol=IMAP
Nov 9 21:36:17 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=472, sent=1066, time=0
Nov 9 21:36:17 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:17 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48655], protocol=IMAP
Nov 9 21:36:18 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=1
Nov 9 21:36:45 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:45 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48656], protocol=IMAP
Nov 9 21:36:45 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=30, sent=238, time=0
Nov 9 21:36:46 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:46 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48657], protocol=IMAP
Nov 9 21:36:46 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=431, sent=1438, time=0
Nov 9 21:36:46 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:36:46 vidy imapd: LOGIN, user=one@YYY.com, ip=[::ffff:127.0.0.1], port=[48658], protocol=IMAP
Nov 9 21:36:46 vidy imapd: LOGOUT, user=one@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
Nov 9 21:37:01 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:37:01 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48659], protocol=IMAP
Nov 9 21:37:01 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=30, sent=238, time=0
Nov 9 21:37:02 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:37:02 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48660], protocol=IMAP
Nov 9 21:37:02 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:37:02 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48661], protocol=IMAP
Nov 9 21:37:02 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
Nov 9 21:37:02 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=472, sent=1066, time=0
Nov 9 21:38:59 vidy postfix/smtpd[15176]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:39:00 vidy postfix/smtpd[15176]: 0764D13B92: client=localhost.localdomain[127.0.0.1]
Nov 9 21:39:00 vidy postfix/cleanup[15181]: 0764D13B92: message-id=
Nov 9 21:39:00 vidy postfix/qmgr[2868]: 0764D13B92: from=, size=735, nrcpt=1 (queue active)
Nov 9 21:39:00 vidy postfix/smtpd[15176]: disconnect from localhost.localdomain[127.0.0.1]
Nov 9 21:39:00 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:39:00 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48670], protocol=IMAP
Nov 9 21:39:00 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=625, sent=203, time=0
Nov 9 21:39:00 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:39:00 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48672], protocol=IMAP
Nov 9 21:39:00 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
Nov 9 21:39:04 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:39:04 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48673], protocol=IMAP
Nov 9 21:39:04 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
Nov 9 21:39:06 vidy postfix/smtpd[15231]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:39:06 vidy postfix/smtpd[15231]: 4ED2713BE1: client=localhost.localdomain[127.0.0.1]
Nov 9 21:39:06 vidy postfix/cleanup[15181]: 4ED2713BE1: message-id=
Nov 9 21:39:06 vidy postfix/smtpd[15231]: disconnect from localhost.localdomain[127.0.0.1]
Nov 9 21:39:06 vidy postfix/qmgr[2868]: 4ED2713BE1: from=, size=1187, nrcpt=1 (queue active)
Nov 9 21:39:06 vidy amavis[2202]: (02202-06) Passed CLEAN, LOCAL [127.0.0.1] [89.217.12.106] -> , Message-ID: , mail_id: BhI9fGHW8pMi, Hits: 1.459, size: 735, queued_as: 4ED2713BE1, 5287 ms
Nov 9 21:39:06 vidy postfix/smtp[15182]: 0764D13B92: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=6.6, delays=0.34/0.03/2/4.3, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=02202-06, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 4ED2713BE1)
Nov 9 21:39:06 vidy postfix/qmgr[2868]: 0764D13B92: removed
Nov 9 21:39:06 vidy postfix/pipe[15234]: 4ED2713BE1: to=, relay=maildrop, delay=0.38, delays=0.05/0.04/0/0.3, dsn=2.0.0, status=sent (delivered via maildrop service)
Nov 9 21:39:06 vidy postfix/qmgr[2868]: 4ED2713BE1: removed
Nov 9 21:39:07 vidy imapd: Connection, ip=[::ffff:127.0.0.1]
Nov 9 21:39:07 vidy imapd: LOGIN, user=two@YYY.com, ip=[::ffff:127.0.0.1], port=[48680], protocol=IMAP
Nov 9 21:39:07 vidy imapd: LOGOUT, user=two@YYY.com, ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
Nov 9 21:40:04 vidy postfix/smtpd[15176]: connect from localhost.localdomain[127.0.0.1]
Nov 9 21:40:04 vidy postfix/smtpd[15176]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Nov 9 21:40:04 vidy postfix/smtpd[15176]: disconnect from localhost.localdomain[127.0.0.1]

Thanks again for your help and your cooperation :):)

[cubitus]

Hi,

Sorry to come back with my problem.

I still connot get any incoming email in the squiremail inbox.

Please can you give me some feedback concerning the log file I've did post on the 9th of november.

Thank you for your help, for any help you can provide me

[till]

Did you really created the domain name in uppercaes? Then thats your problem. Domain names have to be always lowercase as the mails will be stored by postfix in a uppercase directory otherwise and the imap daemon will look for a lowercase directory.

[cubitus]

Hi till and thank you for your reply.

No, the YYY.com desn't exist in my configuration. I've replaces the real name with YYY in order to obfuscate it. I've set the realname of the domain in lowercase.

Just to give you some info, the yyy.com domain is not the same as the one of the server running ISP. Not sure that help us.

Is there any other log files, error files or configuration's files that I can check ?

[till]

The mails have been delivered correctly according to the mail log.

For your info /var/vmail in a link to /srv/d_vidy/vmail.

It is possible that symlinks are not supported by maildrop. Please reconfigure your system so that the setup is a real folder as required by ispconfig.

[cubitus]

Hi,

I did reconfigure the ISP "Server Config" with the two following values in the Mail's tab:

Maildir Path : /var/vmail/[domain]/[localpart]
Homedir Path : /var/vmail

Mailuser Name and Group have not been changed since the installation (vmail).
When I create a now Domail (email) with two mailbox, corresponding folder are correctly create in /var/vmail

No errors are logged in either ispconfig.log or cron.log

I have now an entry in the log Mail-queue

Data from: 2009-11-27 13:40
-Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient-------
2DDA513C3C* 626 Fri Nov 27 13:40:02 getmail@vidy.xxx.com
getmail@vidy.xxx.com

-- 0 Kbytes in 1 Request.

but nothing in squirel mail.

Btw, I haven't create any DNS Zone. Maybe that could be the problem ?

Thanks for your help

[falko]

Any errors in your mail log?
Does the MX record of your domain point to the correct server?

[cubitus]

Hi Falko,

Thanks for your help.

I realized that I get a lot of segmentation fault in the cron.log

/usr/local/ispconfig/server/server.sh: line 7: 14363 Segmentation fault /usr/bin/php -q /usr/local/ispconfig/server/server.php

I decided to reinstall ISP on a new vps machine with Debian 5 as OS, and now it works like a charm :)

Now the next step is to migrate websites from the old hosting company to that new ISP machine.

Thanks again for doing ISP Config and for the help users can get on this forum :)