Errors Installing Postfix, PostfixAdmin, Courier

[asyadiqin]

Hi. Hope someone can help me fix this.

I've followed the guide "Virtual Users With Postfix, PostfixAdmin, Courier, Mailscanner, ClamAV On CentOS" to install on my server running Fedora Core 5. Postfix seems to be running fine and Postfixadmin installed without any major problem.

However, there is some errors in the /var/log/mailllog and /var/log/messages that I cannot seem to fix.

Whenever I created a mailbox in postfixadmin, I get the following error.

Apr 26 10:09:43 fh-1 maildrop[902]: Unable to change to home directory.
Apr 26 10:09:43 fh-1 postfix/pipe[857]: 9D9D0FA3F: to=<user1@domain1.com>, relay=maildrop, delay=0, status=deferred (temporary failure. Command output: /usr/bin/maildrop: Unable to change to home directory. )


In /var/log/messages, i keep getting these errors

Apr 26 10:02:41 fh-1 postfix/smtpd[639]: sql_select option missing
Apr 26 10:02:41 fh-1 postfix/smtpd[639]: auxpropfunc error no mechanism available
Apr 26 10:06:59 fh-1 postfix/smtpd[755]: sql_select option missing
Apr 26 10:06:59 fh-1 postfix/smtpd[755]: auxpropfunc error no mechanism available
Apr 26 10:08:27 fh-1 postfix/smtpd[847]: sql_select option missing
Apr 26 10:08:27 fh-1 postfix/smtpd[847]: auxpropfunc error no mechanism available


Another issue I am having is with the creation of the mailbox. I've followed the guide and created the shell scripts to create and delete the maildir and modified the 4 files in postfixadmin but no maildir was created in /opt/mail/ folder whenever I created a mailbox.

Anyone have any idea? Thanks.

[falko]

What's in /usr/lib/sasl2/smtpd.conf?

[asyadiqin]

What's in /usr/lib/sasl2/smtpd.conf?

Content of /usr/lib/sasl2/smtpd.conf


pwcheck_method: authdaemond
log_level: 3
mech_list: PLAIN LOGIN
authdaemond_path:/var/spool/authdaemon/socket

[falko]

Content of /usr/lib/sasl2/smtpd.conf


pwcheck_method: authdaemond
log_level: 3
mech_list: PLAIN LOGIN
authdaemond_path:/var/spool/authdaemon/socket

Please try this instead:

pwcheck_method: authdaemond
log_level: 3
mech_list: PLAIN LOGIN
authdaemond_path:/var/spool/authdaemon/socket
allow_plaintext: true
auxprop_plugin: mysql
sql_hostnames: 127.0.0.1
sql_user: mail_admin
sql_passwd: mail_admin_password
sql_database: mail
sql_select: select password from users where email = '%u'

[asyadiqin]

Please try this instead:

pwcheck_method: authdaemond
log_level: 3
mech_list: PLAIN LOGIN
authdaemond_path:/var/spool/authdaemon/socket
allow_plaintext: true
auxprop_plugin: mysql
sql_hostnames: 127.0.0.1
sql_user: mail_admin
sql_passwd: mail_admin_password
sql_database: mail
sql_select: select password from users where email = '%u'

I've made the changes to the smtpd.conf and I don't see the error in /var/log/messages anymore.

However, I still get error in /var/log/maillog


Apr 29 11:29:08 fh-1 maildrop[23597]: Unable to change to home directory.
Apr 29 11:29:08 fh-1 postfix/pipe[23596]: 9C056FA53: to=<evelyn@domain1.com>, relay=maildrop, delay=0, status=deferred (temporary failure. Command output: /usr/bin/maildrop: Unable to change to home directory. )

[falko]

However, I still get error in /var/log/maillog
Something seems to be wrong with your maildrop setup. What's evelyn@domain1.com's homedir? Can you post the output of ls -la homedir?

[asyadiqin]

Something seems to be wrong with your maildrop setup. What's evelyn@domain1.com's homedir? Can you post the output of ls -la homedir?


[root@fh-1 ~]# ls -la /opt/mail
total 12
drwx------ 2 vmail vmail 4096 Apr 26 09:59 .
drwxr-xr-x 4 root root 4096 Apr 24 12:26 ..
[root@fh-1 ~]#


Maildir should be in /opt/mail/ and in the case for evelyn@domain1.com, it should be in

/opt/mail/domain1.com/evelyn

However, no such folders were created when I added the mailbox using postfixadmin.

However, if I change the transport type in /etc/postfix/main.cf from maildrop to virtual, the folders will be created.

As I have mentioned in previous post, I've added the shell scripts to create and delete maildir and modified 4 php files in the postfixadmin folders, so I am not sure why the folders were not created.

[JonMarkGo]

I'm going to bump this topic, since I'm having the same problem. I'm using CENTOs 5.2, and the installation went fine. I can send email unauthenticated through telnet, and it gets sent from the server and is received at the server in the proper maildir (only from telnet).

But I cannot authenticate remotely using thunderbird... I get these errors:
Nov 10 19:29:56 newinstance postfix/smtpd[7323]: sql_select option missing
Nov 10 19:29:56 newinstance postfix/smtpd[7323]: auxpropfunc error no mechanism available
Nov 10 19:33:58 newinstance saslauthd[7377]: do_auth : auth failure: [user=jon] [service=smtp] [realm=mydomain.com] [mech=pam] [reason=PAM auth error]

I updated my smtpd.conf file with the one posted here, but it did not help.

[JonMarkGo]

If it helps at all, I used this tutorial: http://www.howtoforge.com/virtual-users-and-domains-postfix-courier-mysql-centos5.1

I have postfix 2.3.3 and CENTOs 5.2

[falko]

What's the output of uname -a?

Please try this:

pwcheck_method: authdaemond
log_level: 3
mech_list: PLAIN LOGIN
authdaemond_path:/var/spool/authdaemon/socket

[JonMarkGo]

Linux mail1.mydomain.com 2.6.21-2952xen #1 SMP Tue Feb 12 09:11:36 EST 2008 x86_64 x86_64 x86_64 GNU/Linux

That is what I have in smtpd.conf already.

[JonMarkGo]

mydomain.com is my masked FQDN
1.2.3.4 is my personal IP address

There is nothing from authdaemond in my mysql query log.

Here is my messages log:
Nov 11 16:38:28 newinstance saslauthd[7377]: do_auth : auth failure: [user=jon] [service=smtp] [realm=mydomain.com] [mech=pam] [reason=PAM auth error]
Nov 11 16:38:30 newinstance saslauthd[7379]: do_auth : auth failure: [user=jon] [service=smtp] [realm=mydomain.com] [mech=pam] [reason=PAM auth error]

Here is my maillog:
Nov 11 16:37:11 newinstance authdaemond: stopping authdaemond children
Nov 11 16:37:14 newinstance authdaemond: modules="authmysql", daemons=5
Nov 11 16:37:14 newinstance authdaemond: Installing libauthmysql
Nov 11 16:37:14 newinstance authdaemond: Installation complete: authmysql
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const mail
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const ipv4
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: name_mask: ipv4
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const Postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const postdrop
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const mail1.mydomain.com, localhost, localhost.localdomain
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $myhostname -> mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const /usr/libexec/postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const /usr/sbin
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const /var/spool/postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const pid
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const all
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const double-bounce
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const nobody
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const hash:/etc/aliases
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 200600825
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 2.3.3
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const hash
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const deferred, defer
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $mydestination -> mail1.mydomain.com, localhost, localhost.localdomain
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $relay_domains -> mail1.mydomain.com, localhost, localhost.localdomain
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const TZ MAIL_CONFIG LANG
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY LANG=C
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const subnet
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const +=
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const -=+
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const debug_peer_list,fast_flush_domains,mynetworks,perm it_mx_backup_networks,qmqpd_authorized_clients,rel ay_domains,smtpd_access_maps
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const bounce
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const cleanup
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const defer
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const pickup
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const qmgr
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const rewrite
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const showq
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const error
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const flush
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const verify
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const trace
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 2
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance last message repeated 3 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3600s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3600s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 10s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 10s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1s
Nov 11 16:38:24 newinstance last message repeated 3 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 500s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 500s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 18000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 18000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 127.0.0.0/8
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: inet_addr_local: configured 2 IPv4 addresses
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 550
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $myhostname ESMTP $mail_name -> mail1.mydomain.com ESMTP Postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const resource, software
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 2 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const permit_mynetworks, permit_sasl_authenticated, reject_unauth_destination
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 4 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const postmaster
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 2 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const proxy:mysql:/etc/postfix/mysql-virtual_forwardings.cf, mysql:/etc/postfix/mysql-virtual_email2email.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const hash:/etc/aliases
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand proxy:unix:passwd.byname $alias_maps -> proxy:unix:passwd.byname hash:/etc/aliases
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const noanonymous
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const smtpd
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const amavis:[127.0.0.1]:10024
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 2 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const CONNECT GET POST
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const <>
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const postmaster
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $authorized_verp_clients ->
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $myhostname -> mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const no_address_mappings
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand ${smtpd_client_connection_limit_exceptions:$mynetw orks} -> 127.0.0.0/8
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const permit_inet_interfaces
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 2 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $smtpd_sasl_security_options -> noanonymous
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const /etc/postfix/smtpd.cert
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const /etc/postfix/smtpd.key
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $smtpd_tls_dcert_file ->
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const medium
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const !EXPORT:!LOW:!MEDIUM:ALL:+RC4:@STRENGTH
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const !EXPORT:!LOW:ALL:+RC4:@STRENGTH
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const !EXPORT:ALL:+RC4:@STRENGTH
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const ALL:+RC4:@STRENGTH
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const !aNULL:eNULL+kRSA
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const SSLv3, TLSv1
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance last message repeated 2 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const cyrus
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const j {daemon_name} v
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const {tls_version} {cipher} {cipher_bits} {cert_subject} {cert_issuer}
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const i {auth_type} {auth_authen} {auth_author} {mail_addr}
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const i {rcpt_addr}
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const i
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const i
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 2
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const tempfail
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $myhostname -> mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: expand $mail_name $mail_version -> Postfix 2.3.3
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const yes
Nov 11 16:38:24 newinstance last message repeated 3 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 100s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 1000s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3600s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 3600s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 30s
Nov 11 16:38:24 newinstance last message repeated 3 times
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_eval: const 300s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: process generation: 63 (63)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: mynetworks ~? debug_peer_list
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: mynetworks ~? fast_flush_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: mynetworks ~? mynetworks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? debug_peer_list
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? fast_flush_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? mynetworks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? permit_mx_backup_networks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? qmqpd_authorized_clients
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: relay_domains ~? relay_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: permit_mx_backup_networks ~? debug_peer_list
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: permit_mx_backup_networks ~? fast_flush_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: permit_mx_backup_networks ~? mynetworks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: permit_mx_backup_networks ~? permit_mx_backup_networks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: connect to subsystem private/proxymap
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = open
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr table = unix:passwd.byname
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr flags = 16448
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 16464
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_proxy_open: connect to map=unix:passwd.byname status=0 server_flags=fixed|lock|fold_fix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_open: proxy:unix:passwd.byname
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: Compiled against Berkeley DB: 4.3.29?
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: Run-time linked against Berkeley DB: 4.3.29?
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_open: hash:/etc/aliases
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = open
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr table = mysql:/etc/postfix/mysql-virtual_forwardings.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr flags = 16448
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 16464
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_proxy_open: connect to map=mysql:/etc/postfix/mysql-virtual_forwardings.cf status=0 server_flags=fixed|lock|fold_fix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_forwardings.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: user = mail_admin
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: password = 6gf535F43f!r
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: dbname = mail
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: result_format = %s
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_int: /etc/postfix/mysql-virtual_email2email.cf: expansion_limit = 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: query = SELECT email FROM users WHERE email='%s'
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: domain =
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: hosts = 127.0.0.1
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_open: mysql:/etc/postfix/mysql-virtual_email2email.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = open
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr table = mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr flags = 16448
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: flags
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 16464
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/proxymap socket: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_proxy_open: connect to map=mysql:/etc/postfix/mysql-virtual_mailboxes.cf status=0 server_flags=fixed|lock|fold_fix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? debug_peer_list
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? fast_flush_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? mynetworks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? permit_mx_backup_networks
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? qmqpd_authorized_clients
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? relay_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: smtpd_access_maps ~? smtpd_access_maps
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_init: SASL config file is smtpd.conf
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: auto_clnt_create: transport=local endpoint=private/tlsmgr
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: auto_clnt_open: connected to private/tlsmgr
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr size = 32
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: NsNFFy4tKg/arXlnKO+0GMaCKg7veoYm2qFtqr3U2/U=
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = policy
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr cache_type = smtpd
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: cachable
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: cachable
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: fast_flush_domains ~? debug_peer_list
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_string: fast_flush_domains ~? fast_flush_domains
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: name_mask: no_address_mappings
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: auto_clnt_create: transport=local endpoint=private/anvil
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: connection established
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: master_notify: status 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: name_mask: resource
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: name_mask: software
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: name_mask: noanonymous
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: warning: 1.2.3.4: address not listed for hostname plastimach.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: connect from unknown[1.2.3.4]
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_hostname: unknown ~? 127.0.0.0/8
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_hostaddr: 1.2.3.4 ~? 127.0.0.0/8
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: auto_clnt_open: connected to private/anvil
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = connect
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr ident = smtp:1.2.3.4
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: count
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: count
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 1
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: rate
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: rate
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 1
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 220 mail1.mydomain.com ESMTP Postfix
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: EHLO [127.0.0.1]
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-PIPELINING
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-SIZE 10240000
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-VRFY
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-ETRN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-STARTTLS
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-AUTH PLAIN LOGIN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-AUTH=PLAIN LOGIN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-ENHANCEDSTATUSCODES
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-8BITMIME
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250 DSN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: STARTTLS
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 220 2.0.0 Ready to start TLS
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr request = seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: send attr size = 32
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: seed
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute value: zlv6lvV7mutwdSSGio6NaXKRKgUpKk51W6tnG91zWqc=
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: private/tlsmgr: wanted attribute: (list terminator)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: EHLO [127.0.0.1]
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-mail1.mydomain.com
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-PIPELINING
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-SIZE 10240000
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-VRFY
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-ETRN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-AUTH PLAIN LOGIN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-AUTH=PLAIN LOGIN
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-ENHANCEDSTATUSCODES
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250-8BITMIME
Nov 11 16:38:24 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 250 DSN
Nov 11 16:38:27 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: AUTH PLAIN AGpvbkB3ZS1jYXJlLmNvbQBzZWNyZXQ=
Nov 11 16:38:27 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_first: sasl_method PLAIN, init_response AGpvbkB3ZS1jYXJlLmNvbQBzZWNyZXQ=
Nov 11 16:38:27 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_first: decoded initial response
Nov 11 16:38:28 newinstance postfix/smtpd[23539]: warning: SASL authentication failure: Password verification failed
Nov 11 16:38:28 newinstance postfix/smtpd[23539]: warning: unknown[1.2.3.4]: SASL PLAIN authentication failed: authentication failure
Nov 11 16:38:28 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 535 5.7.0 Error: authentication failed: authentication failure
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: AUTH LOGIN
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_first: sasl_method LOGIN
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_auth_response: uncoded server challenge: Username:
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 334 VXNlcm5hbWU6
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: am9uQHdlLWNhcmUuY29t
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_next: decoded response: jon@mydomain.com
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_auth_response: uncoded server challenge: Password:
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 334 UGFzc3dvcmQ6
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: < unknown[1.2.3.4]: c2VjcmV0
Nov 11 16:38:29 newinstance postfix/smtpd[23539]: xsasl_cyrus_server_next: decoded response: mypassword
Nov 11 16:38:30 newinstance postfix/smtpd[23539]: warning: unknown[1.2.3.4]: SASL LOGIN authentication failed: authentication failure
Nov 11 16:38:30 newinstance postfix/smtpd[23539]: > unknown[1.2.3.4]: 535 5.7.0 Error: authentication failed: authentication failure
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: smtp_get: EOF
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: match_hostname: unknown ~? 127.0.0.0/8
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: match_hostaddr: 1.2.3.4 ~? 127.0.0.0/8
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: match_list_match: unknown: no match
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: match_list_match: 1.2.3.4: no match
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: send attr request = disconnect
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: send attr ident = smtp:1.2.3.4
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: status
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: input attribute name: status
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: input attribute value: 0
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: private/anvil: wanted attribute: (list terminator)
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: input attribute name: (end)
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: lost connection after AUTH from unknown[1.2.3.4]
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: disconnect from unknown[1.2.3.4]
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: master_notify: status 1
Nov 11 16:39:31 newinstance postfix/smtpd[23539]: connection closed

[falko]

You're using a 64bit system, so you must put this in /usr/lib64/sasl2/smtpd.conf, not /usr/lib/sasl2/smtpd.conf.

[JonMarkGo]

I fixed the smtp issue, it was trying to use saslauthd, not authdaemond. Now courier-imap still can't authenticate:

Nov 12 04:03:35 mail1 imapd-ssl: Connection, ip=[::ffff:96.56.27.82]
Nov 12 04:03:35 mail1 imapd-ssl: authdaemon: s_connect() failed: Connection refused
Nov 12 04:03:35 mail1 imapd-ssl: [Hint: perhaps authdaemond is not running?]
Nov 12 04:03:35 mail1 imapd-ssl: LOGIN FAILED, method=PLAIN, ip=[::ffff:96.56.27.82]
Nov 12 04:03:35 mail1 imapd-ssl: authentication error: Connection refused

[falko]

Can you restart courier-authdaemond?

[JonMarkGo]

I fixed it by creating a symlink to the proper socket.