View Single Post
  #1  
Old 12th February 2011, 13:02
Ian Wilson Ian Wilson is offline
Junior Member
 
Join Date: Feb 2011
Posts: 19
Thanks: 0
Thanked 0 Times in 0 Posts
Exclamation Getting Email Working ISPConfig3 Squirrelmail and Courier etc

I have installed ISPConfig following the perfect server set up for Ubuntu 10.04 The Perfect Server – Ubuntu 10.04 [ISPConfig 3]
I have managed to get most things working apart from the email
As far as I can determine I have two separate but possibly interconnected problems.

Problem 1
I cannot login into Squirrelmail with a email address and password of a valid email account.
I get the Unknown user or password incorrect error message

Problem 2
When I try sending an email to a valid email account I get the following bounced email message

Code:
This is the mail system at host wsrv1.smallbizwebhosts.co.uk.

I'm sorry to have to inform you that your message could not
be delivered to one or more recipients. It's attached below.

For further assistance, please send mail to postmaster.

If you do so, please include this problem report. You can
delete your own text from the attached returned message.

                  The mail system

<webmaster@specialist-roofing-contractor.co.uk>: user unknown. Command output:
   ERR: authdaemon: s_connect() failed: Permission denied Invalid user
   specified.

Final-Recipient: rfc822; webmaster@specialist-roofing-contractor.co.uk
Original-Recipient: rfc822;webmaster@specialist-roofing-contractor.co.uk
Action: failed
Status: 5.1.1
Diagnostic-Code: x-unix; ERR: authdaemon: s_connect() failed: Permission denied
   Invalid user specified.
My Email log file shows

Code:
Feb 12 11:10:45 wsrv1 postfix/qmgr[13911]: 048D81D801: from=, size=1475, nrcpt=1 (queue active)
Feb 12 11:10:46 wsrv1 postfix/smtpd[14786]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:10:46 wsrv1 postfix/smtpd[14786]: 28CE61D808: client=localhost.localdomain[127.0.0.1]
Feb 12 11:10:46 wsrv1 postfix/cleanup[14773]: 28CE61D808: message-id=<213807380575234423.5398158.725@mta900.em.linkedin.com>
Feb 12 11:10:46 wsrv1 postfix/qmgr[13911]: 28CE61D808: from=, size=1965, nrcpt=1 (queue active)
Feb 12 11:10:46 wsrv1 postfix/smtpd[14786]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:10:46 wsrv1 amavis[14344]: (14344-02) Passed CLEAN, [117.200.132.47] [63.211.90.176] -> , Message-ID: <213807380575234423.5398158.725@mta900.em.linkedin.com>, mail_id: WhzroLdjMk9g, Hits: 14.19, size: 1473, queued_as: 28CE61D808, 292 ms
Feb 12 11:10:46 wsrv1 postfix/pipe[14780]: 28CE61D808: to=, relay=maildrop, delay=0.02, delays=0.01/0/0/0.01, dsn=5.1.1, status=bounced (user unknown. Command output: ERR: authdaemon: s_connect() failed: Permission denied Invalid user specified. )
Feb 12 11:10:46 wsrv1 postfix/smtp[14774]: 048D81D801: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=1.2, delays=0.87/0/0/0.29, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=14344-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 28CE61D808)
Feb 12 11:10:46 wsrv1 postfix/qmgr[13911]: 048D81D801: removed
Feb 12 11:10:46 wsrv1 postfix/cleanup[14773]: 2C7791D80D: message-id=<20110212111046.2C7791D80D@wsrv1.smallbizwebhosts.co.uk>
Feb 12 11:10:46 wsrv1 postfix/bounce[14782]: 28CE61D808: sender non-delivery notification: 2C7791D80D
Feb 12 11:10:46 wsrv1 postfix/qmgr[13911]: 2C7791D80D: from=<>, size=4111, nrcpt=1 (queue active)
Feb 12 11:10:46 wsrv1 postfix/qmgr[13911]: 28CE61D808: removed
Feb 12 11:10:46 wsrv1 postfix/smtpd[14758]: disconnect from unknown[117.200.132.47]
Feb 12 11:10:51 wsrv1 postfix/smtp[14783]: 2C7791D80D: to=, relay=mx01.1and1.fr[212.227.15.150]:25, delay=5.2, delays=0/0/0.12/5.1, dsn=5.0.0, status=bounced (host mx01.1and1.fr[212.227.15.150] said: 550 : invalid address (in reply to RCPT TO command))
Feb 12 11:10:51 wsrv1 postfix/qmgr[13911]: 2C7791D80D: removed
Feb 12 11:14:06 wsrv1 postfix/anvil[14772]: statistics: max connection rate 1/60s for (smtp:209.85.210.175) at Feb 12 11:10:09
Feb 12 11:14:06 wsrv1 postfix/anvil[14772]: statistics: max connection count 1 for (smtp:209.85.210.175) at Feb 12 11:10:09
Feb 12 11:14:06 wsrv1 postfix/anvil[14772]: statistics: max cache size 2 at Feb 12 11:10:44
Feb 12 11:15:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:15:01 wsrv1 imapd: Disconnected, ip=[::1], time=0
Feb 12 11:15:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:15:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:15:01 wsrv1 postfix/smtpd[14833]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:15:01 wsrv1 postfix/smtpd[14833]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:15:01 wsrv1 postfix/smtpd[14833]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:15:11 wsrv1 postfix/smtpd[14778]: timeout after END-OF-MESSAGE from localhost.localdomain[127.0.0.1]
Feb 12 11:15:11 wsrv1 postfix/smtpd[14778]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:20:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:20:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:20:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:20:01 wsrv1 imapd: Disconnected, ip=[::1], time=0
Feb 12 11:20:02 wsrv1 postfix/smtpd[14899]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:20:02 wsrv1 postfix/smtpd[14899]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:20:02 wsrv1 postfix/smtpd[14899]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:22:30 wsrv1 postfix/smtpd[14925]: connect from S010600259c0b6f6e.va.shawcable.net[70.69.247.15]
Feb 12 11:22:30 wsrv1 postfix/smtpd[14925]: NOQUEUE: reject: RCPT from S010600259c0b6f6e.va.shawcable.net[70.69.247.15]: 554 5.7.1 <26803ian@smallbizwebhosts.co.uk>: Relay access denied; from= to=<26803ian@smallbizwebhosts.co.uk> proto=SMTP helo=
Feb 12 11:22:30 wsrv1 postfix/smtpd[14925]: lost connection after RCPT from S010600259c0b6f6e.va.shawcable.net[70.69.247.15]
Feb 12 11:22:30 wsrv1 postfix/smtpd[14925]: disconnect from S010600259c0b6f6e.va.shawcable.net[70.69.247.15]
Feb 12 11:22:47 wsrv1 pop3d: Connection, ip=[::ffff:209.85.210.152]
Feb 12 11:22:48 wsrv1 pop3d: LOGIN FAILED, user=ian@smallbusinesswebservices.co.uk, ip=[::ffff:209.85.210.152]
Feb 12 11:22:53 wsrv1 pop3d: Disconnected, ip=[::ffff:209.85.210.152]
Feb 12 11:25:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:25:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:25:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:25:01 wsrv1 imapd: Disconnected, ip=[::1], time=0
Feb 12 11:25:01 wsrv1 postfix/smtpd[14964]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:25:01 wsrv1 postfix/smtpd[14964]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:25:01 wsrv1 postfix/smtpd[14964]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:25:50 wsrv1 postfix/anvil[14927]: statistics: max connection rate 1/60s for (smtp:70.69.247.15) at Feb 12 11:22:30
Feb 12 11:25:50 wsrv1 postfix/anvil[14927]: statistics: max connection count 1 for (smtp:70.69.247.15) at Feb 12 11:22:30
Feb 12 11:25:50 wsrv1 postfix/anvil[14927]: statistics: max cache size 1 at Feb 12 11:22:30
Feb 12 11:29:51 wsrv1 postfix/qmgr[13911]: A44C61D804: from=<>, size=5323, nrcpt=1 (queue active)
Feb 12 11:29:51 wsrv1 postfix/smtp[15003]: connect to promote-bz.net[111.224.250.132]:25: Connection refused
Feb 12 11:29:51 wsrv1 postfix/smtp[15003]: A44C61D804: to=, relay=none, delay=25300, delays=25300/0.03/0.41/0, dsn=4.4.1, status=deferred (connect to promote-bz.net[111.224.250.132]:25: Connection refused)
Feb 12 11:30:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:30:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:30:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:30:01 wsrv1 imapd: Disconnected, ip=[::1], time=0
Feb 12 11:30:01 wsrv1 postfix/smtpd[15032]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:30:01 wsrv1 postfix/smtpd[15032]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:30:01 wsrv1 postfix/smtpd[15032]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:31:57 wsrv1 pop3d: Connection, ip=[::ffff:209.85.214.138]
Feb 12 11:31:58 wsrv1 pop3d: LOGIN FAILED, user=info@dreamdiversltd.co.uk, ip=[::ffff:209.85.214.138]
Feb 12 11:32:03 wsrv1 pop3d: Disconnected, ip=[::ffff:209.85.214.138]
Feb 12 11:35:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:35:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:35:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:35:01 wsrv1 imapd: Disconnected, ip=[::1], time=0
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:56 wsrv1 postfix/smtpd[15122]: connect from mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:36:57 wsrv1 postfix/smtpd[15122]: D3DE81D801: client=mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: D3DE81D801: message-id=
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: D3DE81D801: from=, size=1850, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: 5DA9D1D808: client=localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: 5DA9D1D808: message-id=
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 5DA9D1D808: from=, size=2382, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 amavis[14358]: (14358-02) Passed CLEAN, [209.85.214.175] [209.85.214.175] -> , Message-ID: , mail_id: kiYdGvxHE27I, Hits: -0.699, size: 1850, queued_as: 5DA9D1D808, 286 ms
Feb 12 11:36:58 wsrv1 postfix/smtp[15127]: D3DE81D801: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=0.67, delays=0.37/0.01/0/0.29, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=14358-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5DA9D1D808)
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: D3DE81D801: removed
Feb 12 11:36:58 wsrv1 postfix/pipe[15132]: 5DA9D1D808: to=, relay=maildrop, delay=0.03, delays=0.01/0.01/0/0.01, dsn=5.1.1, status=bounced (user unknown. Command output: ERR: authdaemon: s_connect() failed: Permission denied Invalid user specified. )
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: 63FA41D80C: message-id=<20110212113658.63FA41D80C@wsrv1.smallbizwebhosts.co.uk>
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 63FA41D80C: from=<>, size=4638, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/bounce[15134]: 5DA9D1D808: sender non-delivery notification: 63FA41D80C
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 5DA9D1D808: removed
Feb 12 11:36:58 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:36:58 wsrv1 imapd: LOGIN FAILED, user=ian@smallbizwebhosts.co.uk, ip=[::1]
Feb 12 11:37:00 wsrv1 postfix/smtp[15135]: 63FA41D80C: to=, relay=ASPMX2.GOOGLEMAIL.COM[74.125.43.27]:25, delay=2.2, delays=0/0.01/0.17/2, dsn=2.0.0, status=sent (250 2.0.0 OK 1297510620 rc7si968760bkb.94)
Feb 12 11:37:00 wsrv1 postfix/qmgr[13911]: 63FA41D80C: removed
Feb 12 11:37:03 wsrv1 imapd: LOGOUT, ip=[::1], rcvd=64, sent=332
Feb 12 11:37:28 wsrv1 postfix/smtpd[15122]: disconnect from mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:40:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:40:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:40:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:40:01 wsrv1 imapd: Disconnected, ip=[::1], ti
with the relevant section being

Code:
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Feb 12 11:35:01 wsrv1 postfix/smtpd[15102]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:56 wsrv1 postfix/smtpd[15122]: connect from mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:36:57 wsrv1 postfix/smtpd[15122]: D3DE81D801: client=mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: D3DE81D801: message-id=
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: D3DE81D801: from=, size=1850, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: connect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: 5DA9D1D808: client=localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: 5DA9D1D808: message-id=
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 5DA9D1D808: from=, size=2382, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/smtpd[15130]: disconnect from localhost.localdomain[127.0.0.1]
Feb 12 11:36:58 wsrv1 amavis[14358]: (14358-02) Passed CLEAN, [209.85.214.175] [209.85.214.175] -> , Message-ID: , mail_id: kiYdGvxHE27I, Hits: -0.699, size: 1850, queued_as: 5DA9D1D808, 286 ms
Feb 12 11:36:58 wsrv1 postfix/smtp[15127]: D3DE81D801: to=, relay=127.0.0.1[127.0.0.1]:10024, delay=0.67, delays=0.37/0.01/0/0.29, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=14358-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5DA9D1D808)
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: D3DE81D801: removed
Feb 12 11:36:58 wsrv1 postfix/pipe[15132]: 5DA9D1D808: to=, relay=maildrop, delay=0.03, delays=0.01/0.01/0/0.01, dsn=5.1.1, status=bounced (user unknown. Command output: ERR: authdaemon: s_connect() failed: Permission denied Invalid user specified. )
Feb 12 11:36:58 wsrv1 postfix/cleanup[15126]: 63FA41D80C: message-id=<20110212113658.63FA41D80C@wsrv1.smallbizwebhosts.co.uk>
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 63FA41D80C: from=<>, size=4638, nrcpt=1 (queue active)
Feb 12 11:36:58 wsrv1 postfix/bounce[15134]: 5DA9D1D808: sender non-delivery notification: 63FA41D80C
Feb 12 11:36:58 wsrv1 postfix/qmgr[13911]: 5DA9D1D808: removed
Feb 12 11:36:58 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:36:58 wsrv1 imapd: LOGIN FAILED, user=ian@smallbizwebhosts.co.uk, ip=[::1]
Feb 12 11:37:00 wsrv1 postfix/smtp[15135]: 63FA41D80C: to=, relay=ASPMX2.GOOGLEMAIL.COM[74.125.43.27]:25, delay=2.2, delays=0/0.01/0.17/2, dsn=2.0.0, status=sent (250 2.0.0 OK 1297510620 rc7si968760bkb.94)
Feb 12 11:37:00 wsrv1 postfix/qmgr[13911]: 63FA41D80C: removed
Feb 12 11:37:03 wsrv1 imapd: LOGOUT, ip=[::1], rcvd=64, sent=332
Feb 12 11:37:28 wsrv1 postfix/smtpd[15122]: disconnect from mail-iw0-f175.google.com[209.85.214.175]
Feb 12 11:40:01 wsrv1 pop3d: Connection, ip=[::1]
Feb 12 11:40:01 wsrv1 pop3d: Disconnected, ip=[::1]
Feb 12 11:40:01 wsrv1 imapd: Connection, ip=[::1]
Feb 12 11:40:01 wsrv1 imapd: Disconnected, ip=[::1]
I have checked the mysql database and the user accounts are listed in the user_email table
the password field looks like the passwords are hashed

but when looking at the /etc/postfix/sasl/smtpd.conf file it is showing
Code:
pwcheck_method: saslauthd
mech_list : plain login
allow_plaintext: true
authprop_plugin:mysql
sql_hostnames: 127.0.0.1
sql_user: ispconfig
sqpasswd:*****************
sql_database:dbispconfig
sql_select: select password from mail_user where email = '%u'
where ******************** is the password

so is authorization failing because the authorization test is trying to compare a plain text password with the hashed password stored in the database?

or do I have other problems?

Last edited by Ian Wilson; 12th February 2011 at 13:09.
Reply With Quote
Sponsored Links