Too many levels of symbolic links ?? Why ?
Darryl Harvey
I am installing postfix, courier-imap and SASL support from MYSQL.
I have postfix working, I have courier working, I have MySQL working.
I can pop into the server, it works fine (So MySQL auth is ok)
I can IMAP into the server, so same applies.
I can receive smtp via the MTA and it gets delivered to local users and
virtual users, so thats ok and proves postfix is talking to the DB via
MySQL.
What is failing is SASL Auth.. I get an error saying;
postfix/smtpd[4994]: warning: SASL authentication failure: cannot
connect to Courier authdaemond: Too many levels of symbolic links
postconf -n output;
----------------------
alias_database = hash:/etc/postfix/aliases
alias_maps = hash:/etc/aliases hash:/etc/postfix/aliases
always_bcc = mai...@av.tdj.com.au
append_dot_mydomain = no
biff = no
broken_sasl_auth_clients = yes
config_directory = /etc/postfix
content_filter = smtp-amavis:[127.0.0.1]:10024
header_checks = regexp:/etc/postfix/header_checks
home_mailbox = Maildir/
inet_interfaces = all
mailbox_size_limit = 51200000
maps_rbl_domains = bl.spamcop.net dynablock.wirehub.net
blackholes.wirehub.net relays.ordb.org list.dsbl.org
message_size_limit = 10240000
mydestination = av.tdj.com.au, localhost.tdj.com.au, localhost
myhostname = av.tdj.com.au
mynetworks = 10.43.1.0/24, 127.0.0.0/8
myorigin = /etc/mailname
recipient_delimiter = +
relay_domains = proxy:mysql:/etc/postfix/mysql_relay_domains_maps.cf
relayhost =
smtpd_banner = $myhostname ESMTP $mail_name (Debian/GNU)
smtpd_recipient_restrictions = permit_mynetworks
permit_sasl_authenticated check_sender_access
hash:/etc/postfix/deny_user check_client_access
hash:/etc/postfix/deny_user check_recipient_access
hash:/etc/postfix/deny_user check_recipient_access
regexp:/etc/postfix/regexp_access reject_non_fqdn_sender
reject_unknown_sender_domain reject_unauth_destination
smtpd_sasl_auth_enable = yes
smtpd_sasl_local_domain = $mydomain
smtpd_sasl_security_options = noanonymous
smtpd_sender_restrictions = check_sender_access
hash:/etc/postfix/deny_user check_client_access
hash:/etc/postfix/deny_user reject_non_fqdn_sender
reject_unknown_sender_domain
transport_maps = hash:/etc/postfix/transport.cf
virtual_alias_maps = proxy:mysql:/etc/postfix/mysql_virtual_alias_maps.cf
virtual_gid_maps = static:113
virtual_mailbox_base = /usr/local/virtual
virtual_mailbox_domains =
proxy:mysql:/etc/postfix/mysql_virtual_domains_maps.cf
virtual_mailbox_limit = 51200000
virtual_mailbox_maps =
proxy:mysql:/etc/postfix/mysql_virtual_mailbox_maps.cf
virtual_minimum_uid = 106
virtual_transport = virtual
virtual_uid_maps = static:106
--------------
contents of /etc/postfix/sasl/smtpd.conf
pwcheck_method: authdaemond
log_level: 7
mech_list: PLAIN LOGIN
authdaemond_Path:/var/run/courier/authdaemon/socket
--------------
Because postfix is chroot'ed, I have a symlink from;
darryl@av:~$ ls -ld /var/spool/postfix/var/run/courier/authdaemon/socket
lrwxrwxrwx 1 root root 34 2005-10-06 04:26
/var/spool/postfix/var/run/courier/authdaemon/socket ->
/var/run/courier/authdaemon/socket
darryl@av:~$ ls -ld /var/spool/postfix/var/run/courier/authdaemon/
drwxr-xr-x 2 daemon daemon 4096 2005-10-06 04:26
/var/spool/postfix/var/run/courier/authdaemon/
darryl@av:~$ ls -ld /var/run/courier/authdaemon/socket
srwxrwxrwx 1 root root 0 2005-10-06 09:34
/var/run/courier/authdaemon/socket
Log output with smtpd -vvv
---------------------------
Oct 6 09:58:00 av postfix/smtpd[4994]: connection established
Oct 6 09:58:00 av postfix/smtpd[4994]: master_notify: status 0
Oct 6 09:58:00 av postfix/smtpd[4994]: name_mask: resource
Oct 6 09:58:00 av postfix/smtpd[4994]: name_mask: software
Oct 6 09:58:00 av postfix/smtpd[4994]: name_mask: noanonymous
Oct 6 09:58:00 av postfix/smtpd[4994]: warning: 203.46.67.125: hostname
visp.irrrr.net verification failed: Name or service not known
Oct 6 09:58:00 av postfix/smtpd[4994]: connect from unknown[203.46.67.125]
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: unknown: no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: 203.46.67.125:
no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: unknown: no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: 203.46.67.125:
no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_hostname: unknown ~?
10.43.1.0/24
Oct 6 09:58:00 av postfix/smtpd[4994]: match_hostaddr: 203.46.67.125 ~?
10.43.1.0/24
Oct 6 09:58:00 av postfix/smtpd[4994]: match_hostname: unknown ~?
127.0.0.0/8
Oct 6 09:58:00 av postfix/smtpd[4994]: match_hostaddr: 203.46.67.125 ~?
127.0.0.0/8
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: unknown: no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: 203.46.67.125:
no match
Oct 6 09:58:00 av postfix/smtpd[4994]: attr_clnt_connect: connected to
private/anvil
Oct 6 09:58:00 av postfix/smtpd[4994]: event_enable_read: fd 17
Oct 6 09:58:00 av postfix/smtpd[4994]: send attr request = connect
Oct 6 09:58:00 av postfix/smtpd[4994]: send attr ident = smtp:203.46.67.125
Oct 6 09:58:00 av postfix/smtpd[4994]: vstream_fflush_some: fd 17 flush 42
Oct 6 09:58:00 av postfix/smtpd[4994]: private/anvil: wanted attribute:
status
Oct 6 09:58:00 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 17 got 25
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute name: status
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute value: 0
Oct 6 09:58:00 av postfix/smtpd[4994]: private/anvil: wanted attribute:
count
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute name: count
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute value: 1
Oct 6 09:58:00 av postfix/smtpd[4994]: private/anvil: wanted attribute:
rate
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute name: rate
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute value: 1
Oct 6 09:58:00 av postfix/smtpd[4994]: private/anvil: wanted attribute:
(list terminator)
Oct 6 09:58:00 av postfix/smtpd[4994]: input attribute name: (end)
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 220
av.tdj.com.au ESMTP Postfix (Debian/GNU)
Oct 6 09:58:00 av postfix/smtpd[4994]: watchdog_pat: 0x807bd90
Oct 6 09:58:00 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 46
Oct 6 09:58:00 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 10 got 21
Oct 6 09:58:00 av postfix/smtpd[4994]: < unknown[203.46.67.125]: EHLO
[192.168.1.43]
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]:
250-av.tdj.com.au
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]:
250-PIPELINING
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]:
250-SIZE 10240000
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 250-VRFY
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 250-ETRN
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]:
250-AUTH LOGIN PLAIN
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: unknown: no match
Oct 6 09:58:00 av postfix/smtpd[4994]: match_list_match: 203.46.67.125:
no match
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]:
250-AUTH=LOGIN PLAIN
Oct 6 09:58:00 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 250
8BITMIME
Oct 6 09:58:00 av postfix/smtpd[4994]: watchdog_pat: 0x807bd90
Oct 6 09:58:00 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 132
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 10 got 49
Oct 6 09:58:04 av postfix/smtpd[4994]: < unknown[203.46.67.125]: AUTH
PLAIN AGRhc[Hidden]
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate:
sasl_method PLAIN, init_response AGRhc[Hidden]
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate: decoded
initial response
Oct 6 09:58:04 av postfix/smtpd[4994]: warning: SASL authentication
failure: cannot connect to Courier authdaemond: Too many levels of
symbolic links
Oct 6 09:58:04 av postfix/smtpd[4994]: warning: SASL authentication
failure: Password verification failed
Oct 6 09:58:04 av postfix/smtpd[4994]: warning: unknown[203.46.67.125]:
SASL PLAIN authentication failed
Oct 6 09:58:04 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 535
Error: authentication failed
Oct 6 09:58:04 av postfix/smtpd[4994]: watchdog_pat: 0x807bd90
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 34
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 10 got 12
Oct 6 09:58:04 av postfix/smtpd[4994]: < unknown[203.46.67.125]: AUTH LOGIN
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate:
sasl_method LOGIN
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate: uncoded
challenge: Username:
Oct 6 09:58:04 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 334
VXNlcm5hbWU6
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 18
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 10 got 26
Oct 6 09:58:04 av postfix/smtpd[4994]: < unknown[203.46.67.125]:
ZGFycnlsQHRkai5jb20uYXU=
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate: decoded
response: [hidden]@[hidden]
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate: uncoded
challenge: Password:
Oct 6 09:58:04 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 334
UGFzc3dvcmQ6
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 18
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 10 got 14
Oct 6 09:58:04 av postfix/smtpd[4994]: < unknown[203.46.67.125]:
bWNuaW9uMDA=
Oct 6 09:58:04 av postfix/smtpd[4994]: smtpd_sasl_authenticate: decoded
response: [hidden]
Oct 6 09:58:04 av postfix/smtpd[4994]: warning: SASL authentication
failure: cannot connect to Courier authdaemond: Too many levels of
symbolic links
Oct 6 09:58:04 av postfix/smtpd[4994]: warning: unknown[203.46.67.125]:
SASL LOGIN authentication failed
Oct 6 09:58:04 av postfix/smtpd[4994]: > unknown[203.46.67.125]: 535
Error: authentication failed
Oct 6 09:58:04 av postfix/smtpd[4994]: watchdog_pat: 0x807bd90
Oct 6 09:58:04 av postfix/smtpd[4994]: vstream_fflush_some: fd 10 flush 34
Oct 6 09:58:06 av postfix/smtpd[4994]: smtp_get: EOF
Oct 6 09:58:06 av postfix/smtpd[4994]: match_hostname: unknown ~?
10.43.1.0/24
Oct 6 09:58:06 av postfix/smtpd[4994]: match_hostaddr: 203.46.67.125 ~?
10.43.1.0/24
Oct 6 09:58:06 av postfix/smtpd[4994]: match_hostname: unknown ~?
127.0.0.0/8
Oct 6 09:58:06 av postfix/smtpd[4994]: match_hostaddr: 203.46.67.125 ~?
127.0.0.0/8
Oct 6 09:58:06 av postfix/smtpd[4994]: match_list_match: unknown: no match
Oct 6 09:58:06 av postfix/smtpd[4994]: match_list_match: 203.46.67.125:
no match
Oct 6 09:58:06 av postfix/smtpd[4994]: send attr request = disconnect
Oct 6 09:58:06 av postfix/smtpd[4994]: send attr ident = smtp:203.46.67.125
Oct 6 09:58:06 av postfix/smtpd[4994]: vstream_fflush_some: fd 17 flush 45
Oct 6 09:58:06 av postfix/smtpd[4994]: private/anvil: wanted attribute:
status
Oct 6 09:58:06 av postfix/smtpd[4994]: vstream_buf_get_ready: fd 17 got 10
Oct 6 09:58:06 av postfix/smtpd[4994]: input attribute name: status
Oct 6 09:58:06 av postfix/smtpd[4994]: input attribute value: 0
Oct 6 09:58:06 av postfix/smtpd[4994]: private/anvil: wanted attribute:
(list terminator)
Oct 6 09:58:06 av postfix/smtpd[4994]: input attribute name: (end)
Oct 6 09:58:06 av postfix/smtpd[4994]: lost connection after AUTH from
unknown[203.46.67.125]
Oct 6 09:58:06 av postfix/smtpd[4994]: disconnect from
unknown[203.46.67.125]
Oct 6 09:58:06 av postfix/smtpd[4994]: master_notify: status 1
Oct 6 09:58:06 av postfix/smtpd[4994]: connection closed
-----------------------------------------
I am running out of ideas or things to try.. Can anyone shed some light?
Thanks
Darryl
Alex Satrapa
> Because postfix is chroot'ed, I have a symlink from;
>
> darryl@av:~$ ls -ld
> /var/spool/postfix/var/run/courier/authdaemon/socket
> lrwxrwxrwx 1 root root 34 2005-10-06 04:26
> /var/spool/postfix/var/run/courier/authdaemon/socket ->
> /var/run/courier/authdaemon/socket
Try swapping those around - put the socket in
/var/spool/postfix/var/run/courier/authdaemon/socket, with
/var/run/courier/authdaemon/socket being a symlink to it.
Alex Satrapa
IT Support
Australian Phenomics Facility
Building 117, Garran Road
Australian National University