saslauthd and dovecot failure

Sat Dec 23 05:14:07 EST 2006

Hi,

I have problem with saslauthd keeps killing its child one at a time after dovecot sends back an OK IMAP response message.

My Dovecot is running on an email proxy that communicates to my internal email server.
As far as I can tell, dovecot has responded positively on the login attemp. 
I also checked the raw IP data and it shows the IMAP OK response packet.

Here's the dovecot log:
Dec 21 17:13:53 lawang dovecot: auth(default): client in: AUTH^I4^IPLAIN^Iservice=IMAP^Ilip=10.11.3.1^Irip=10.11.3.2^Iresp=<hidden>
Dec 21 17:13:53 lawang dovecot: auth(default): cache(careercenter at mach.ac.id,10.11.3.2): NULL password access
Dec 21 17:13:53 lawang dovecot: auth(default): client out: OK^I4^Iuser=careercenter at mach.ac.id^Ihost=10.22.18.3^Idestuser=careercenter^Inologin^Iproxy^Ipass=<hidden>
Dec 21 17:13:53 lawang dovecot: imap-login: proxy(careercenter at mach.ac.id): started: user=<careercenter at mach.ac.id>, method=PLAIN, rip=10.11.3.2, lip=10.11.3.1
Dec 21 17:13:53 lawang dovecot: imap-login: proxy(careercenter at mach.ac.id): disconnecting 10.11.3.2

Here's the saslauthd log:
[root at bear ~]# saslauthd -a rimap -O 10.11.3.1 -r -d -V
saslauthd[30701] :main            : num_procs  : 5
saslauthd[30701] :main            : mech_option: 10.11.3.1
saslauthd[30701] :main            : run_path   : /var/run/saslauthd
saslauthd[30701] :main            : auth_mech  : rimap
saslauthd[30701] :ipc_init        : using accept lock file: /var/run/saslauthd/mux.accept
saslauthd[30701] :detach_tty      : master pid is: 0
saslauthd[30701] :ipc_init        : listening on socket: /var/run/saslauthd/mux
saslauthd[30701] :main            : using process model
saslauthd[30702] :get_accept_lock : acquired accept lock
saslauthd[30701] :have_baby       : forked child: 30702
saslauthd[30701] :have_baby       : forked child: 30703
saslauthd[30701] :have_baby       : forked child: 30704
saslauthd[30701] :have_baby       : forked child: 30705

saslauthd[30702] :rel_accept_lock : released accept lock
saslauthd[30703] :get_accept_lock : acquired accept lock
saslauthd[30702] :do_auth         : auth failure: [user=careercenter at mach.ac.id] [service=smtp] [realm=mach.ac.id] [mech=rimap] [reason=[ALERT] Unexpected response from remote authentication server]
*** glibc detected *** saslauthd: free(): invalid pointer: 0x08057234 ***
======= Backtrace: =========
/lib/libc.so.6[0xb96f18]
/lib/libc.so.6(__libc_free+0x79)[0xb9a41d]
saslauthd[0x8053703]
saslauthd[0x8053a21]
saslauthd[0x805570e]
/lib/libc.so.6(__libc_start_main+0xdc)[0xb487e4]
saslauthd[0x804aa11]
======= Memory map: ========
00111000-00184000 r-xp 00000000 03:42 2158542    /usr/lib/libkrb5.so.3.2
00184000-00186000 rwxp 00073000 03:42 2158542    /usr/lib/libkrb5.so.3.2
0022b000-00236000 r-xp 00000000 03:41 32077      /lib/libgcc_s-4.1.0-20060304.so.1
00236000-00237000 rwxp 0000a000 03:41 32077      /lib/libgcc_s-4.1.0-20060304.so.1
002a6000-002bf000 r-xp 00000000 03:41 192262     /lib/ld-2.4.so
002bf000-002c0000 r-xp 00018000 03:41 192262     /lib/ld-2.4.so
002c0000-002c1000 rwxp 00019000 03:41 192262     /lib/ld-2.4.so
002da000-002e9000 r-xp 00000000 03:41 32080      /lib/libresolv-2.4.so
002e9000-002ea000 r-xp 0000e000 03:41 32080      /lib/libresolv-2.4.so
002ea000-002eb000 rwxp 0000f000 03:41 32080      /lib/libresolv-2.4.so
002eb000-002ed000 rwxp 002eb000 00:00 0
002ef000-0040e000 r-xp 00000000 03:41 32096      /lib/libcrypto.so.0.9.8a
0040e000-00421000 rwxp 0011e000 03:41 32096      /lib/libcrypto.so.0.9.8a
00421000-00424000 rwxp 00421000 00:00 0
00426000-00428000 r-xp 00000000 03:41 32095      /lib/libcom_err.so.2.1
00428000-00429000 rwxp 00001000 03:41 32095      /lib/libcom_err.so.2.1
0042b000-0044f000 r-xp 00000000 03:42 2158541    /usr/lib/libk5crypto.so.3.0
0044f000-00450000 rwxp 00024000 03:42 2158541    /usr/lib/libk5crypto.so.3.0
00452000-00455000 r-xp 00000000 03:42 2158540    /usr/lib/libkrb5support.so.0.0
00455000-00456000 rwxp 00002000 03:42 2158540    /usr/lib/libkrb5support.so.0.0
004a2000-004a3000 r-xp 004a2000 00:00 0          [vdso]
004ed000-004fc000 r-xp 00000000 03:41 32105      /lib/libaudit.so.0.0.0
004fc000-004fe000 rwxp 0000e000 03:41 32105      /lib/libaudit.so.0.0.0
00500000-0050c000 r-xp 00000000 03:41 32106      /lib/libpam.so.0.81.2
0050c000-0050d000 rwxp 0000b000 03:41 32106      /lib/libpam.so.0.81.2
0051c000-00534000 r-xp 00000000 03:42 2158543    /usr/lib/libgssapi_krb5.so.2.2
00534000-00535000 rwxp 00017000 03:42 2158543    /usr/lib/libgssapi_krb5.so.2.2
00537000-00578000 r-xp 00000000 03:41 32097      /lib/libssl.so.0.9.8a
00578000-0057c000 rwxp 00040000 03:41 32097      /lib/libssl.so.0.9.8a
006b2000-006bb000 r-xp 00000000 03:41 191466     /lib/libnss_files-2.4.so
006bb000-006bc000 r-xp 00008000 03:41 191466     /lib/libnss_files-2.4.so
006bc000-006bd000 rwxp 00009000 03:41 191466     /lib/libnss_files-2.4.so
007a2000-007b9000 r-xp 00000000 03:42 1497044    /usr/lib/libsasl2.so.2.0.21
007b9000-007ba000 rwxp 00016000 03:42 1497044    /usr/lib/libsasl2.so.2.0.21
007bc000-007c9000 r-xp 00000000 03:42 1497106    /usr/lib/liblber-2.3.so.0.2.7
007c9000-007ca000 rwxp 0000c000 03:42 1497106    /usr/lib/liblber-2.3.so.0.2.7
00958000-00991000 r-xp 00000000 03:42 1497107    /usr/lib/libldap-2.3.so.0.2.7
00991000-00992000 rwxp 00039000 03:42 1497107    /usr/lib/libldap-2.3.so.0.2.7
00b33000-00c5f000 r-xp 00000000 03:41 192264     /lib/libc-2.4.so
00c5f000-00c62000 r-xp 0012b000 03:41 192264     /lib/libc-2.4.so
00c62000-00c63000 rwxp 0012e000 03:41 192264     /lib/libc-2.4.so
00c63000-00c66000 rwxp 00c63000 00:00 0
00c8f000-00c91000 r-xp 00000000 03:41 192267     /lib/libdl-2.4.so
00c91000-00c92000 r-xp 00001000 03:41 192267     /lib/libdl-2.4.so
00c92000-00c93000 rwxp 00002000 03:41 192267     /lib/libdl-2.4.so
00c95000-00ca7000 r-xp 00000000 03:42 2158510    /usr/lib/libz.so.1.2.3
00ca7000-00ca8000 rwxp 00011000 03:42 2158510    /usr/lib/libz.so.1.2.3
034b5000-034ba000 r-xp 00000000 03:41 32100      /lib/libcrypt-2.4.so
034ba000-034bb000 r-xp 00004000 03:41 32100      /lib/libcrypt-2.4.so
034bb000-034bc000 rwxp 00005000 03:41 32100      /lib/libcrypt-2.4.so
034bc000-034e3000 rwxp 034bc000 00:00 0
08048000-0805b000 r-xp 00000000 03:42 1041876    /usr/sbin/saslauthd
0805b000-0805d000 rw-p 00013000 03:42 1041876    /usr/sbin/saslauthd
0879c000-087bd000 rw-p 0879c000 00:00 0          [heap]
b7esaslauthd[30701] :handle_sigchld  : child exited: 30702

Has anyone run into similar problem as above?

Any help is appreciated.

Thanks,
Junaidi
_________________________________________________________________
Type your favorite song.  Get a customized station.  Try MSN Radio powered by Pandora.
http://radio.msn.com