[prev in list] [next in list] [prev in thread] [next in thread] 

List:       bind9-users
Subject:    Strange behaviour of BIND 9.0.0b2 on Solaris 2.5.1
From:       Matti Saarinen <mjs () cc ! tut ! fi>
Date:       2000-04-25 5:53:30
[Download RAW message or body]


I've been testing BIND 9 on a Solaris 2.5.1 machine and there has been
no significant problems except twice. On those two times, named has
started to do zone transfers of the slave zones in a loop and the only
way to break the loop is to send SIGHUP to the process.

Is this a known bug or should I check my configuration file for some
serious mistakes or should I try a snapshot version and see what will
happen?

The logs of the master server look like this:

Apr 25 08:28:03 master named[157]: approved AXFR from slave.58545 for "zone1"
Apr 25 08:28:03 master named[157]: zone transfer (AXFR) of "zone1" (IN) to slave.58545
Apr 25 08:28:04 master named[157]: approved AXFR from slave.58546 for "zone2"
Apr 25 08:28:04 master named[157]: zone transfer (AXFR) of "zone2" (IN) to slave.58546
Apr 25 08:28:05 master named[157]: approved AXFR from slave.58547 for "zone1"
Apr 25 08:28:05 master named[157]: zone transfer (AXFR) of "zone1" (IN) to slave.58547
Apr 25 08:28:06 master named[157]: approved AXFR from slave.58548 for "zone2"
Apr 25 08:28:06 master named[157]: zone transfer (AXFR) of "zone2" (IN) to slave.58548
Apr 25 08:28:06 master named[157]: approved AXFR from slave.58549 for "zone1"
Apr 25 08:28:06 master named[157]: zone transfer (AXFR) of "zone1" (IN) to slave.58549


If I trace (with truss) the named process I see something like this:

2434:	    Received signal #14, SIGALRM, in lwp_sema_p() [caught]
2434:	lwp_sema_p(0xEF6B5C30)				Err#91 ERESTART
2434:	sigprocmask(SIG_SETMASK, 0xEEF05E34, 0x00000000) = 0
2434:	lwp_sema_v(0xEF109E80)				= 0
2434:	lwp_sema_p(0xEF109E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	setitimer(ITIMER_REAL, 0xEEF057A8, 0x00000000)	= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	setcontext(0xEEF05A30)
2434:	sigprocmask(SIG_BLOCK, 0xEF6B5C20, 0x00000000)	= 0
2434:	setitimer(ITIMER_REAL, 0xEEF05CD8, 0x00000000)	= 0
2434:	sigprocmask(SIG_UNBLOCK, 0xEF6B5C20, 0x00000000) = 0
2434:	    Received signal #14, SIGALRM, in lwp_sema_p() [caught]
2434:	lwp_sema_p(0xEF6B5C30)				Err#91 ERESTART
2434:	sigprocmask(SIG_SETMASK, 0xEEF05E34, 0x00000000) = 0
2434:	lwp_sema_v(0xEF109E80)				= 0
2434:	lwp_sema_p(0xEF109E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	open("/dev/tcp", O_RDWR)			= 16
2434:	ioctl(16, I_PUSH, "sockmod")			= 0
2434:	ioctl(16, I_STR, 0xEF20B7F0)			= 0
2434:	ioctl(16, I_SETCLTIME, 0xEF20B8A4)		= 0
2434:	ioctl(16, I_SWROPT, 0x00000002)			= 0
2434:	ioctl(16, I_STR, 0xEF20B708)			= 0
2434:	fcntl(16, F_GETFL, 0x00000000)			= 2
2434:	ioctl(16, I_FIND, "sockmod")			= 1
2434:	ioctl(16, I_FIND, "sockmod")			= 1
2434:	fcntl(16, F_SETFL, 0x00000082)			= 0
2434:	ioctl(16, I_STR, 0xEF20B810)			= 0
2434:	ioctl(16, I_STR, 0xEF20B6B8)			= 0
2434:	fcntl(16, F_GETFL, 0x00000000)			= 130
2434:	putmsg(16, 0xEF20B6D4, 0x00000000, 0)		= 0
2434:	fcntl(16, F_SETFL, 0x00000002)			= 0
2434:	getmsg(16, 0xEF20B6D4, 0x00000000, 0xEF20B5CC)	= 0
2434:	fcntl(16, F_SETFL, 0x00000082)			= 0
2434:	poll(0xEF005BE0, 7, -1)				= 1
2434:	write(9, "\0\0\010", 4)				= 4
2434:	setitimer(ITIMER_REAL, 0xEEF057A8, 0x00000000)	= 0
2434:	setcontext(0xEEF05A30)
2434:	sigprocmask(SIG_BLOCK, 0xEF6B5C20, 0x00000000)	= 0
2434:	setitimer(ITIMER_REAL, 0xEEF05CD8, 0x00000000)	= 0
2434:	sigprocmask(SIG_UNBLOCK, 0xEF6B5C20, 0x00000000) = 0
2434:	read(8, "\0\0\010", 4)				= 4
2434:	read(8, 0xEF007BD8, 4)				Err#11 EAGAIN
2434:	poll(0xEF005BE0, 8, -1)				= 1
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	ioctl(16, I_STR, 0xEF20BB80)			= 0
2434:	ioctl(16, I_STR, 0xEF20B728)			= 0
2434:	ioctl(16, I_GETSIG, 0xEF20B79C)			Err#22 EINVAL
2434:	write(16, "\0 &", 2)				= 2
2434:	write(16, " b 9\0\0\001\0\0\0\0\0\0".., 38)	= 38
2434:	getmsg(16, 0xEF20B9B0, 0xEF20B9BC, 0xEF20B99C)	Err#11 EAGAIN
2434:	fcntl(16, F_GETFL, 0x00000000)			= 130
2434:	write(9, "\0\0\010", 4)				= 4
2434:	poll(0xEF005BE0, 7, -1)				= 1
2434:	read(8, "\0\0\010", 4)				= 4
2434:	read(8, 0xEF007BD8, 4)				Err#11 EAGAIN
2434:	poll(0xEF005BE0, 8, -1)				= 1
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	getmsg(16, 0xEF20BAB0, 0xEF20BABC, 0xEF20BA9C)	= 2
[cut]
2434:	getmsg(17, 0xEF20BA20, 0xEF20BA2C, 0xEF20BA0C)	= 0
2434:	open("cache/zone1.cache", O_WRONLY|O_CREAT|O_TRUNC, 0666) = 18
2434:	fstat(18, 0xEF20A748)				= 0
2434:	ioctl(18, TCGETA, 0xEF20A6D4)			Err#25 ENOTTY
2434:	write(18, " $ O R I G I N   .\n $ T".., 4762)	= 4762
2434:	close(18)					= 0
2434:	lstat("cache/zone1.cache.jnl", 0xEF20BA08)	Err#2 ENOENT
2434:	lwp_sema_v(0xEF109E80)				= 0
2434:	lwp_sema_p(0xEF109E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF109E80)				= 0
2434:	lwp_sema_p(0xEF109E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF6B5C30)				= 0
2434:	lwp_sema_p(0xEF6B5C30)				= 0
2434:	sigprocmask(SIG_BLOCK, 0xEF6B5C20, 0x00000000)	= 0
2434:	setitimer(ITIMER_REAL, 0xEEF05CD8, 0x00000000)	= 0
2434:	sigprocmask(SIG_UNBLOCK, 0xEF6B5C20, 0x00000000) = 0
2434:	write(9, "\0\0\011", 4)				= 4
2434:	poll(0xEF005BE0, 7, -1)				= 1
2434:	lwp_sema_v(0xEF007E80)				= 0
2434:	lwp_sema_p(0xEF007E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	read(8, "\0\0\011", 4)				= 4
2434:	close(17)					= 0
2434:	read(8, 0xEF007BD8, 4)				Err#11 EAGAIN
2434:	getmsg(16, 0xEF20B990, 0xEF20B99C, 0xEF20B97C)	= 2
[cut]
2434:	getmsg(16, 0xEF20BA20, 0xEF20BA2C, 0xEF20BA0C)	= 0
2434:	open("cache/zone2", O_WRONLY|O_CREAT|O_TRUNC, 0666) = 17
2434:	fstat(17, 0xEF20A748)				= 0
2434:	ioctl(17, TCGETA, 0xEF20A6D4)			Err#25 ENOTTY
2434:	write(17, " $ O R I G I N   .\n 2 3".., 8192)	= 8192
2434:	write(17, "\n\t\t\t N S\t b u t l e".., 1893)	= 1893
2434:	close(17)					= 0
2434:	lstat("cache/zone2.jnl", 0xEF20BA08) Err#2 ENOENT
2434:	poll(0xEF005BE0, 7, -1)				= 1
2434:	write(9, "\0\0\010", 4)				= 4
2434:	read(8, "\0\0\010", 4)				= 4
2434:	close(16)					= 0
2434:	read(8, 0xEF007BD8, 4)				Err#11 EAGAIN
2434:	    Received signal #14, SIGALRM, in lwp_sema_p() [caught]
2434:	lwp_sema_p(0xEF6B5C30)				Err#91 ERESTART
2434:	sigprocmask(SIG_SETMASK, 0xEEF05E34, 0x00000000) = 0
2434:	lwp_sema_v(0xEF109E80)				= 0
2434:	lwp_sema_p(0xEF109E80)				= 0
2434:	lwp_mutex_unlock(0xEF6B81C8)			= 0
2434:	lwp_mutex_lock(0xEF6B81C8)			= 0
2434:	lwp_sema_v(0xEF20BE80)				= 0
2434:	lwp_sema_p(0xEF20BE80)				= 0


-- 
- Matti -

[prev in list] [next in list] [prev in thread] [next in thread] 

Configure | About | News | Add a list | Sponsored by KoreLogic