saslauthd -a pam + 'imap' service name = dead saslauthd

Jeff Blaine jblaine at mitre.org
Wed Mar 29 19:53:22 EST 2006


Hi Nico,

Comments below.

Nicolas Williams wrote:
 > On Wed, Mar 29, 2006 at 06:25:01PM -0500, Jeff Blaine wrote:
 >> Is this a known issue?  What am I doing wrong?
 >>
 >> Solaris 9 SPARC
 >> Cyrus IMAPd 2.2.12
 >> Cyrus SASL 2.1.20
 >>
 >> 1.  saslauthd -a pam
 >>
 >> 2.  'imap' used as PAM service name (below snippet).
 >>
 >> RESULT: saslauthd promptly crashes.  Zero logins.
 >>          Can repeat.
 >>
 >> #------------ BEGIN /etc/pam.conf imap lines ------------------
 >> imap  auth requisite          pam_authtok_get.so.1
 >> imap  auth required           pam_dhkeys.so.1
 >> imap  auth sufficient /usr/lib/security/pam_afs.so.1 ignore_root
 >> setenv_password_expires
 >> imap  auth required           pam_unix_auth.so.1
 >> #------------ END /etc/pam.conf imap lines --------------------
 >>
 >> Attached output from 'truss -f -p <parent_saslauthd_PID>'
 >> during a connection under the situation above.
 >>
 >> I see nothing interesting :(
 >
 > For one you've truncated the truss, so I one can't tell what file
 > descriptor 9 is (it's a door, but to what?  nscd I bet).

Hmm.  I'll try again.  Looking at the file I attached, it
is not truncated.  It ends where saslauthd exit()s and I
get my shell prompt back.

Reattached.

 > For another, something's forking, and the child is sendign SIGTERM to
 > the parent for no apparent reason.
 >
 > Try apptrace(1).

apptrace -f -o ~/Private/apptrace.out /linus/mail/cyrus/sbin/saslauthd 
-a pam

Gives me: unexpected version 3

 > Also, it's not even clear if this is happening in PAM or not; apptrace
 > will help.  Logs would too (add 'debug' to all those PAM modules'
 > arguments, configure syslog to save debug logs, create the debug log,
 > restart syslogd).

debug info included in pam.log attachment
-------------- next part --------------
Mar 29 19:36:43 HOST.mitre.org saslauthd[16559]: [ID 634615 auth.debug] pam_authtok_get:pam_sm_authenticate: flags = -2147483648
Mar 29 19:36:43 HOST.mitre.org saslauthd[16559]: [ID 237441 auth.debug] AFS Options: nowarn=0, use_first_pass=0, try_first_pass=0
Mar 29 19:36:43 HOST.mitre.org saslauthd[16559]: [ID 617489 auth.debug] AFS Username = `jblaine'
Mar 29 19:36:43 HOST.mitre.org saslauthd[16559]: [ID 114272 auth.debug] AFS Trying first password for user jblaine
Mar 29 19:36:43 HOST.mitre.org saslauthd[16559]: [ID 896952 auth.debug] pam_unix_auth: entering pam_sm_authenticate()
Mar 29 19:37:32 HOST.mitre.org saslauthd[16556]: [ID 285309 auth.info] server_exit     : master exited: 16556
Mar 29 19:37:32 HOST.mitre.org imap[16818]: [ID 702911 auth.notice] size read failed
Mar 29 19:37:34 HOST.mitre.org imap[16793]: [ID 702911 auth.notice] cannot connect to saslauthd server: No such file or directory
-------------- next part --------------
10282:	fcntl(6, F_SETLKW, 0xFFBFFA78)	(sleeping...)
10282:	fcntl(6, F_SETLKW, 0xFFBFFA78)			= 0
10282:	accept(5, 0x000291D6, 0x00029244, 1)		= 7
10282:	fcntl(6, F_SETLKW, 0xFFBFFA78)			= 0
10282:	read(7, "\007", 2)				= 2
10282:	read(7, " i n f o s y s", 7)			= 7
10282:	read(7, "\007", 2)				= 2
10282:	read(7, " i 8 m y h a t", 7)			= 7
10282:	read(7, "\004", 2)				= 2
10282:	read(7, " i m a p", 4)				= 4
10282:	read(7, "\0\0", 2)				= 2
10282:	open("/etc/pam_debug", O_RDONLY)		Err#2 ENOENT
10282:	stat64("/etc/pam.conf", 0xFFBFF128)		= 0
10282:	open("/etc/pam.conf", O_RDONLY)			= 10
10282:	mmap(0x00000000, 4576, PROT_READ, MAP_PRIVATE, 10, 0) = 0xFF3A0000
10282:	munmap(0xFF3A0000, 4576)			= 0
10282:	close(10)					= 0
10282:	stat64("/usr/lib/security/pam_authtok_get.so.1", 0xFFBFF0E8) = 0
10282:	stat("/usr/lib/security/pam_authtok_get.so.1", 0xFFBFEC30) = 0
10282:	resolvepath("/usr/lib/security/pam_authtok_get.so.1", "/usr/lib/security/pam_authtok_get.so.1", 1023) = 38
10282:	open("/usr/lib/security/pam_authtok_get.so.1", O_RDONLY) = 10
10282:	mmap(0x00010000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_ALIGN, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 81920, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFF0E0000
10282:	mmap(0xFF0E0000, 5322, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF0E0000
10282:	mmap(0xFF0F2000, 2103, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 8192) = 0xFF0F2000
10282:	munmap(0xFF0E2000, 65536)			= 0
10282:	memcntl(0xFF0E0000, 2740, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	stat("/usr/lib/passwdutil.so.1", 0xFFBFEAF0)	= 0
10282:	resolvepath("/usr/lib/passwdutil.so.1", "/usr/lib/passwdutil.so.1", 1023) = 24
10282:	open("/usr/lib/passwdutil.so.1", O_RDONLY)	= 10
10282:	mmap(0xFF3A0000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 122880, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFF0B0000
10282:	mmap(0xFF0B0000, 46602, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF0B0000
10282:	mmap(0xFF0CC000, 3875, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 49152) = 0xFF0CC000
10282:	munmap(0xFF0BC000, 65536)			= 0
10282:	memcntl(0xFF0B0000, 10316, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	stat("/usr/lib/libsldap.so.1", 0xFFBFEAF0)	= 0
10282:	resolvepath("/usr/lib/libsldap.so.1", "/usr/lib/libsldap.so.1", 1023) = 22
10282:	open("/usr/lib/libsldap.so.1", O_RDONLY)	= 10
10282:	mmap(0xFF3A0000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 147456, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFF080000
10282:	mmap(0xFF080000, 130256, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF080000
10282:	mmap(0xFF0A0000, 10745, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 131072) = 0xFF0A0000
10282:	memcntl(0xFF080000, 20040, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	stat("/usr/lib/libdoor.so.1", 0xFFBFEAF0)	= 0
10282:	resolvepath("/usr/lib/libdoor.so.1", "/usr/lib/libdoor.so.1", 1023) = 21
10282:	open("/usr/lib/libdoor.so.1", O_RDONLY)		= 10
10282:	mmap(0xFF3A0000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 81920, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFF060000
10282:	mmap(0xFF060000, 3655, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF060000
10282:	mmap(0xFF072000, 616, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 8192) = 0xFF072000
10282:	munmap(0xFF062000, 65536)			= 0
10282:	memcntl(0xFF060000, 2528, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	munmap(0xFF3A0000, 8192)			= 0
10282:	stat64("/usr/lib/security/pam_dhkeys.so.1", 0xFFBFF0E8) = 0
10282:	stat("/usr/lib/security/pam_dhkeys.so.1", 0xFFBFEC30) = 0
10282:	resolvepath("/usr/lib/security/pam_dhkeys.so.1", "/usr/lib/security/pam_dhkeys.so.1", 1023) = 33
10282:	open("/usr/lib/security/pam_dhkeys.so.1", O_RDONLY) = 10
10282:	mmap(0x00010000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_ALIGN, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 90112, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFEEC0000
10282:	mmap(0xFEEC0000, 12385, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFEEC0000
10282:	mmap(0xFEED4000, 2936, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 16384) = 0xFEED4000
10282:	munmap(0xFEEC4000, 65536)			= 0
10282:	memcntl(0xFEEC0000, 4672, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	munmap(0xFF3A0000, 8192)			= 0
10282:	stat64("/usr/lib/security/pam_afs.so.1", 0xFFBFF0E8) = 0
10282:	stat("/usr/lib/security/pam_afs.so.1", 0xFFBFEC30) = 0
10282:	resolvepath("/usr/lib/security/pam_afs.so.1", "/usr/lib/security/pam_afs.so.1", 1023) = 30
10282:	open("/usr/lib/security/pam_afs.so.1", O_RDONLY) = 10
10282:	mmap(0x00010000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_ALIGN, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 417792, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFEE50000
10282:	mmap(0xFEE50000, 307621, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFEE50000
10282:	mmap(0xFEEAA000, 19212, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 303104) = 0xFEEAA000
10282:	mmap(0xFEEB0000, 19168, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANON, -1, 0) = 0xFEEB0000
10282:	munmap(0xFEE9C000, 57344)			= 0
10282:	memcntl(0xFEE50000, 68152, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	stat("/usr/lib/libm.so.1", 0xFFBFEAF0)		= 0
10282:	resolvepath("/usr/lib/libm.so.1", "/usr/lib/libm.so.1", 1023) = 18
10282:	open("/usr/lib/libm.so.1", O_RDONLY)		= 10
10282:	mmap(0xFF3A0000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF3A0000
10282:	mmap(0x00010000, 294912, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFEDC0000
10282:	mmap(0xFEDC0000, 225034, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFEDC0000
10282:	mmap(0xFEE06000, 6972, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 221184) = 0xFEE06000
10282:	munmap(0xFEDF8000, 57344)			= 0
10282:	memcntl(0xFEDC0000, 10304, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	stat("/usr/lib/libintl.so.1", 0xFFBFEAF0)	= 0
10282:	resolvepath("/usr/lib/libintl.so.1", "/usr/lib/libintl.so.1", 1023) = 21
10282:	open("/usr/lib/libintl.so.1", O_RDONLY)		= 10
10282:	mmap(0xFF3A0000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFF3A0000
10282:	close(10)					= 0
10282:	mprotect(0xFEE50000, 307621, PROT_READ|PROT_WRITE|PROT_EXEC) = 0
10282:	mprotect(0xFEE50000, 307621, PROT_READ|PROT_EXEC) = 0
10282:	stat64("/usr/lib/security/pam_unix_auth.so.1", 0xFFBFF0E8) = 0
10282:	stat("/usr/lib/security/pam_unix_auth.so.1", 0xFFBFEC30) = 0
10282:	resolvepath("/usr/lib/security/pam_unix_auth.so.1", "/usr/lib/security/pam_unix_auth.so.1", 1023) = 36
10282:	open("/usr/lib/security/pam_unix_auth.so.1", O_RDONLY) = 10
10282:	mmap(0x00010000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_ALIGN, 10, 0) = 0xFF010000
10282:	mmap(0x00010000, 81920, PROT_NONE, MAP_PRIVATE|MAP_NORESERVE|MAP_ANON|MAP_ALIGN, -1, 0) = 0xFEE30000
10282:	mmap(0xFEE30000, 4113, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 0) = 0xFEE30000
10282:	mmap(0xFEE42000, 1926, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED, 10, 8192) = 0xFEE42000
10282:	munmap(0xFEE32000, 65536)			= 0
10282:	memcntl(0xFEE30000, 2444, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
10282:	close(10)					= 0
10282:	munmap(0xFF010000, 8192)			= 0
10282:	open("/etc/shadow", O_RDONLY)			= 10
10282:	fstat64(10, 0xFFBFE5F8)				= 0
10282:	fstat64(10, 0xFFBFE4A0)				= 0
10282:	ioctl(10, TCGETA, 0xFFBFE584)			Err#25 ENOTTY
10282:	read(10, " r o o t : Q K P G v r W".., 8192)	= 354
10282:	read(10, 0x00044B94, 8192)			= 0
10282:	llseek(10, 0, SEEK_CUR)				= 354
10282:	close(10)					= 0
10282:	getpid()					= 10282 [1]
10282:	fstat(8, 0xFFBFEED0)				= 0
10282:	fstat(8, 0xFFBFEC58)				= 0
10282:	putmsg(8, 0xFFBFEDF4, 0xFFBFEF14, 0)		= 0
10282:	poll(0x0003D7A4, 1, 15000)			= 1
10282:	fstat(8, 0xFFBFEC50)				= 0
10282:	getmsg(8, 0xFFBFEDF0, 0x0002DC10, 0xFFBFEE1C)	= 0
10282:	door_info(9, 0xFFBFEAE8)			= 0
10282:	door_call(9, 0xFFBFEAD0)			= 0
10282:	open("/etc/shadow", O_RDONLY)			= 10
10282:	fstat64(10, 0xFFBFE430)				= 0
10282:	fstat64(10, 0xFFBFE2D8)				= 0
10282:	ioctl(10, TCGETA, 0xFFBFE3BC)			Err#25 ENOTTY
10282:	read(10, " r o o t : Q K P G v r W".., 8192)	= 354
10282:	read(10, 0x00044F9C, 8192)			= 0
10282:	llseek(10, 0, SEEK_CUR)				= 354
10282:	close(10)					= 0
10282:	getpid()					= 10282 [1]
10282:	fstat(8, 0xFFBFED08)				= 0
10282:	fstat(8, 0xFFBFEA90)				= 0
10282:	putmsg(8, 0xFFBFEC2C, 0xFFBFED4C, 0)		= 0
10282:	poll(0x0003D7A4, 1, 15000)			= 1
10282:	fstat(8, 0xFFBFEA88)				= 0
10282:	getmsg(8, 0xFFBFEC28, 0x0002DC10, 0xFFBFEC54)	= 0
10282:	door_info(9, 0xFFBFE2F0)			= 0
10282:	door_call(9, 0xFFBFE2D8)			= 0
10282:	sigaction(SIGCLD, 0xFFBFE788, 0xFFBFE818)	= 0
10282:	fork()						= 10868
10868:	fork()		(returning as child ...)	= 10282
10868:	open("/usr/vice/etc/ThisCell", O_RDONLY)	= 10
10868:	fstat64(10, 0xFFBFDB38)				= 0
10868:	fstat64(10, 0xFFBFD9E0)				= 0
10868:	ioctl(10, TCGETA, 0xFFBFDAC4)			Err#25 ENOTTY
10868:	read(10, " r c f . m i t r e . o r".., 8192)	= 14
10868:	llseek(10, 0xFFFFFFFFFFFFFFFF, SEEK_CUR)	= 13
10868:	close(10)					= 0
10868:	stat("/usr/vice/etc/CellServDB", 0xFFBFE16C)	= 0
10868:	open("/usr/vice/etc/CellServDB", O_RDONLY)	= 10
10868:	fstat64(10, 0xFFBFDFD0)				= 0
10868:	fstat64(10, 0xFFBFDE78)				= 0
10868:	ioctl(10, TCGETA, 0xFFBFDF5C)			Err#25 ENOTTY
10868:	read(10, " > r c f . m i t r e . o".., 8192)	= 165
10868:	read(10, 0x0004478C, 8192)			= 0
10868:	llseek(10, 0, SEEK_CUR)				= 165
10868:	close(10)					= 0
10868:	open("/usr/vice/etc/KeyFile", O_RDONLY)		Err#2 ENOENT
10868:	stat("/usr/vice/etc/CellServDB", 0xFFBFE474)	= 0
10868:	alarm(0)					= 0
10868:	sigaction(SIGSYS, 0xFFBFE610, 0xFFBFE690)	= 0
10868:	time()						= 1143667816
10868:	open("/usr/vice/etc/ThisCell", O_RDONLY)	= 10
10868:	fstat64(10, 0xFFBFD5B0)				= 0
10868:	fstat64(10, 0xFFBFD458)				= 0
10868:	ioctl(10, TCGETA, 0xFFBFD53C)			Err#25 ENOTTY
10868:	read(10, " r c f . m i t r e . o r".., 8192)	= 14
10868:	llseek(10, 0xFFFFFFFFFFFFFFFF, SEEK_CUR)	= 13
10868:	close(10)					= 0
10868:	stat("/usr/vice/etc/CellServDB", 0xFFBFDBE4)	= 0
10868:	open("/usr/vice/etc/CellServDB", O_RDONLY)	= 10
10868:	fstat64(10, 0xFFBFDA48)				= 0
10868:	fstat64(10, 0xFFBFD8F0)				= 0
10868:	ioctl(10, TCGETA, 0xFFBFD9D4)			Err#25 ENOTTY
10868:	read(10, " > r c f . m i t r e . o".., 8192)	= 165
10868:	read(10, 0x00046A74, 8192)			= 0
10868:	llseek(10, 0, SEEK_CUR)				= 165
10868:	close(10)					= 0
10868:	open("/usr/vice/etc/KeyFile", O_RDONLY)		Err#2 ENOENT
10868:	stat("/usr/vice/etc/CellServDB", 0xFFBFDE3C)	= 0
10868:	open("/etc/netconfig", O_RDONLY|O_LARGEFILE)	= 10
10868:	fcntl(10, F_DUPFD, 0x00000100)			Err#22 EINVAL
10868:	read(10, " # p r a g m a   i d e n".., 1024)	= 1024
10868:	read(10, " t s           t p i _ c".., 1024)	= 215
10868:	read(10, 0x00045510, 1024)			= 0
10868:	lseek(10, 0, SEEK_SET)				= 0
10868:	read(10, " # p r a g m a   i d e n".., 1024)	= 1024
10868:	read(10, " t s           t p i _ c".., 1024)	= 215
10868:	read(10, 0x00045510, 1024)			= 0
10868:	close(10)					= 0
10868:	open("/dev/udp", O_RDONLY)			= 10
10868:	ioctl(10, 0xC00C6982, 0xFFBFDE04)		= 0
10868:	close(10)					= 0
10868:	open("/etc/services", O_RDONLY|O_LARGEFILE)	= 10
10868:	fcntl(10, F_DUPFD, 0x00000100)			Err#22 EINVAL
10868:	read(10, " # i d e n t\t " @ ( # )".., 1024)	= 1024
10868:	read(10, " o t o c o l   v 2\n l d".., 1024)	= 1024
10868:	read(10, " n d o w   S y s t e m\n".., 1024)	= 1024
10868:	read(10, " t e   D y n a m i c   R".., 1024)	= 1024
10868:	read(10, "       5 8 7 / u d p    ".., 1024)	= 77
10868:	read(10, 0x00046080, 1024)			= 0
10868:	close(10)					= 0
10868:	getpid()					= 10868 [10282]
10868:	fstat(8, 0xFFBFD9A8)				= 0
10868:	fstat(8, 0xFFBFD818)				= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD9A0, 0xFFBFD990) = 0
10868:	close(8)					= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD990, 0x00000000) = 0
10868:	open("/var/yp/binding/rcf.mitre.org/cache_binding", O_RDONLY|O_LARGEFILE) = 8
10868:	fcntl(8, F_DUPFD, 0x00000100)			Err#22 EINVAL
10868:	fcntl(8, F_GETLK, 0xFFBFD8BC)			= 0
10868:	read(8, "\0\0\001\0\0\001\0\0\001".., 1024)	= 132
10868:	close(8)					= 0
10868:	open("/dev/udp", O_RDWR)			= 8
10868:	ioctl(8, I_FIND, "timod")			= 0
10868:	ioctl(8, I_PUSH, "timod")			= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD924, 0xFFBFD914) = 0
10868:	ioctl(8, I_STR, 0xFFBFD798)			= 0
10868:	fstat(8, 0xFFBFD720)				= 0
10868:	ioctl(8, I_STR, 0xFFBFD798)			= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD914, 0x00000000) = 0
10868:	ioctl(8, I_FLUSH, FLUSHRW)			= 0
10868:	fstat(8, 0xFFBFD788)				= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD928, 0xFFBFD918) = 0
10868:	ioctl(8, I_STR, 0xFFBFD890)			= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD918, 0x00000000) = 0
10868:	fstat(8, 0xFFBFD6B0)				= 0
10868:	ioctl(8, I_STR, 0xFFBFD7B8)			= 0
10868:	fstat(8, 0xFFBFD5B0)				= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD750, 0xFFBFD740) = 0
10868:	ioctl(8, I_STR, 0xFFBFD6B8)			= 0
10868:	sigprocmask(SIG_SETMASK, 0xFFBFD740, 0x00000000) = 0
10868:	getpid()					= 10868 [10282]
10868:	fstat(8, 0xFFBFD688)				= 0
10868:	ioctl(8, I_STR, 0xFFBFD804)			= 0
10868:	fstat(8, 0xFFBFD994)				= 0
10868:	fcntl(8, F_SETFD, 0x00000001)			= 0
10868:	fstat(8, 0xFFBFDA68)				= 0
10868:	fstat(8, 0xFFBFD7E0)				= 0
10868:	putmsg(8, 0xFFBFD97C, 0xFFBFDA9C, 0)		= 0
10868:	poll(0x0003D7A4, 1, 15000)			= 1
10868:	fstat(8, 0xFFBFD7D8)				= 0
10868:	getmsg(8, 0xFFBFD978, 0x0002E500, 0xFFBFD9A4)	= 0
10868:	brk(0x00049340)					= 0
10868:	brk(0x00053340)					= 0
10868:	so_socket(PF_INET, SOCK_DGRAM, IPPROTO_IP, "", 1) = 10
10868:	bind(10, 0xFFBFE0B0, 16, 3)			= 0
10868:	fcntl(10, F_SETFD, 0x00000001)			= 0
10868:	setsockopt(10, SOL_SOCKET, SO_RCVBUF, 0xFFBFE0A8, 4, 1) = 0
10868:	setsockopt(10, SOL_SOCKET, SO_SNDBUF, 0xFFBFE0AC, 4, 1) = 0
10868:	setsockopt(10, SOL_SOCKET, SO_RCVBUF, 0xFFBFE0A8, 4, 1) = 0
10868:	fcntl(10, F_SETFL, 0x00000004)			= 0
10868:	brk(0x00053340)					= 0
10868:	brk(0x00067340)					= 0
10868:	brk(0x00067340)					= 0
10868:	brk(0x00073340)					= 0
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	poll(0x00072938, 1, 0)				= 0
10868:	time()						= 1143667816
10868:	so_socket(PF_INET, SOCK_DGRAM, IPPROTO_IP, "", 1) = 11
10868:	ioctl(11, 0xC0086914, 0xFFBFE000)		= 0
10868:	ioctl(11, 0xC020690D, 0xFFBFDE00)		= 0
10868:	ioctl(11, 0xC020690D, 0xFFBFDE20)		= 0
10868:	ioctl(11, 0xC0206911, 0xFFBFDE20)		= 0
10868:	sigaction(SIGSYS, 0xFFBFDC70, 0xFFBFDCF0)	= 0
10868:	sys#65(0x0000001C, 0x00000014, 0x81530A01, 0xFEEAF810, 0x00000000, 0xFF3F1648) = 0x00000000 [0x01449CF8]
10868:	sigaction(SIGSYS, 0xFFBFDC70, 0xFFBFDCF0)	= 0
10868:	sigaction(SIGSYS, 0xFFBFDC70, 0xFFBFDCF0)	= 0
10868:	sys#65(0x0000001C, 0x0000002A, 0x81530A01, 0xFEEAF7D0, 0x00000000, 0xFF3F1648) = 0x00000000 [0x01449CF8]
10868:	sigaction(SIGSYS, 0xFFBFDC70, 0xFFBFDCF0)	= 0
10868:	ioctl(11, 0xC0206916, 0xFFBFDE20)		= 0
10868:	ioctl(11, 0xC0206919, 0xFFBFDE20)		= 0
10868:	close(11)					= 0
10868:	brk(0x00073340)					= 0
10868:	brk(0x00085340)					= 0
10868:	time()						= 1143667816
10868:	getpid()					= 10868 [10282]
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	sendmsg(10, 0xFFBFD910, 0)			= 76
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	poll(0x000528F8, 1, 2000)			= 1
10868:	recvmsg(10, 0x00072984, 0)			= 172
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	sendmsg(10, 0xFFBFE1B8, 0)			= 28
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	time()						= 1143667816
10868:	kill(-10282, SIGTERM)				= 0
10868:	    Received signal #15, SIGTERM [caught]
10282:	    Received signal #15, SIGTERM, in waitid() [caught]
10868:	kill(-10282, SIGTERM)				= 0
10868:	getpid()					= 10868 [10282]
10282:	waitid(P_PID, 10868, 0xFFBFE728, WEXITED|WTRAPPED) Err#4 EINTR
10868:	close(4)					= 0
10868:	fcntl(6, F_SETLK, 0xFFBFE208)			= 0
10282:	kill(-10282, SIGTERM)				= 0
10868:	close(6)					= 0
10282:	getpid()					= 10282 [1]
10868:	unlink("/var/run/mux.accept")			= 0
10282:	unlink("/var/run/saslauthd.pid")		= 0
10868:	close(5)					= 0
10282:	close(4)					= 0
10868:	unlink("/var/run/mux")				= 0
10282:	fcntl(6, F_SETLK, 0xFFBFE280)			= 0
10868:	fstat(3, 0xFFBFDB78)				= 0
10282:	close(6)					= 0
10868:	time()						= 1143667816
10282:	unlink("/var/run/mux.accept")			Err#2 ENOENT
10868:	getpid()					= 10868 [10282]
10868:	putmsg(3, 0xFFBFD230, 0xFFBFD224, 0)		= 0
10868:	open("/var/run/syslog_door", O_RDONLY)		= 4
10282:	close(5)					= 0
10868:	door_info(4, 0xFFBFD168)			= 0
10868:	getpid()					= 10868 [10282]
10282:	unlink("/var/run/mux")				Err#2 ENOENT
10282:	fstat(3, 0xFFBFDBF0)				= 0
10868:	door_call(4, 0xFFBFD150)			= 0
10282:	time()						= 1143667816
10868:	close(4)					= 0
10282:	getpid()					= 10282 [1]
10868:	_exit(0)
10282:	putmsg(3, 0xFFBFD2A8, 0xFFBFD29C, 0)		= 0
10282:	open("/var/run/syslog_door", O_RDONLY)		= 4
10282:	door_info(4, 0xFFBFD1E0)			= 0
10282:	getpid()					= 10282 [1]
10282:	door_call(4, 0xFFBFD1C8)			= 0
10282:	close(4)					= 0
10282:	_exit(0)


More information about the Cyrus-sasl mailing list