Mails doesn't appears in squirelmail INBOX folder

Discussion in 'Installation/Configuration' started by cubitus, Nov 7, 2009.

  1. cubitus

    cubitus New Member

    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
    Code:
    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 :)
     
  2. falko

    falko Super Moderator Howtoforge Staff

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

    cubitus New Member

    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 [email protected]).

    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 [email protected], 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
     
  4. cubitus

    cubitus New Member

    Sorry, my mistake,

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

    Code:
     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
     
  5. till

    till Super Moderator Staff Member ISPConfig Developer

    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.
     
  6. cubitus

    cubitus New Member

    Next is the logfile.

    Just 2 notes:

    1) At about 21:33, I've created two mailbox ([email protected] and [email protected]

    2) At about 21:37 and 21:39, I've send a mail from [email protected] to [email protected] and relied back to [email protected]

    3) The server url is vidy.XXX.com

    Hope you can find some interesting stuff in the log

    Code:
    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=<[email protected]>
    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=<[email protected]>
    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=<[email protected]>
    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: <[email protected]>, 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=<[email protected]>
    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: <[email protected]>, 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, [email protected], ip=[::ffff:127.0.0.1], port=[48653], protocol=IMAP
    Nov 9 21:36:17 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48654], protocol=IMAP
    Nov 9 21:36:17 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48655], protocol=IMAP
    Nov 9 21:36:18 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48656], protocol=IMAP
    Nov 9 21:36:45 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48657], protocol=IMAP
    Nov 9 21:36:46 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48658], protocol=IMAP
    Nov 9 21:36:46 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48659], protocol=IMAP
    Nov 9 21:37:01 vidy imapd: LOGOUT, [email protected], 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, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48661], protocol=IMAP
    Nov 9 21:37:02 vidy imapd: LOGOUT, [email protected], ip=[::ffff:127.0.0.1], headers=0, body=0, rcvd=119, sent=900, time=0
    Nov 9 21:37:02 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48670], protocol=IMAP
    Nov 9 21:39:00 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48672], protocol=IMAP
    Nov 9 21:39:00 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48673], protocol=IMAP
    Nov 9 21:39:04 vidy imapd: LOGOUT, [email protected], 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, [email protected], ip=[::ffff:127.0.0.1], port=[48680], protocol=IMAP
    Nov 9 21:39:07 vidy imapd: LOGOUT, [email protected], 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 :):)
     
  7. cubitus

    cubitus New Member

    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
     
  8. till

    till Super Moderator Staff Member ISPConfig Developer

    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.
     
  9. cubitus

    cubitus New Member

    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 ?
     
  10. till

    till Super Moderator Staff Member ISPConfig Developer

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

    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.
     
  11. cubitus

    cubitus New Member

    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
    Code:
    Data from: 2009-11-27 13:40
    -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient-------
    2DDA513C3C* 626 Fri Nov 27 13:40:02 [email protected]
    [email protected]
    
    -- 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
     
  12. falko

    falko Super Moderator Howtoforge Staff

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

    cubitus New Member

    Hi Falko,

    Thanks for your help.

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

    Code:
    /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 :)
     

Share This Page