From owner-freebsd-stable Wed Jan 24 15:22:59 2001 Delivered-To: freebsd-stable@freebsd.org Received: from mail.merrion.nua.net (mail.merrion.nua.net [195.7.46.14]) by hub.freebsd.org (Postfix) with ESMTP id ABBA537B401 for ; Wed, 24 Jan 2001 15:22:36 -0800 (PST) Received: from thoth (cerberus.merrion.nua.net [195.7.46.125]) by mail.merrion.nua.net (8.10.1/8.10.1) with SMTP id f0ONJjo19077; Wed, 24 Jan 2001 23:19:45 GMT From: Tony Byrne To: David Malone Cc: Andrew J Caines , freebsd-stable@freebsd.org Subject: Re: syslogd throwing a hissy fit Date: Wed, 24 Jan 2001 23:27:01 +0000 Organization: Nua Ltd. Message-ID: <5qou6tkiba9jk6nbjepe9hn2g45hq560ep@4ax.com> References: <20010121021039.A442@hal9000.bsdonline.org> <7q9u6t0tkj5ktplad6k8468koar3j8jvi9@4ax.com> <20010124223705.A92362@gosset.maths.tcd.ie> In-Reply-To: <20010124223705.A92362@gosset.maths.tcd.ie> X-Mailer: Forte Agent 1.8/32.548 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: quoted-printable Sender: owner-freebsd-stable@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG On Wed, 24 Jan 2001 22:37:05 +0000, you wrote: >On Wed, Jan 24, 2001 at 09:43:59PM +0000, Tony Byrne wrote: > >> My investigations using "syslogd -v" show that the all of the looped = and >> corrupt log lines that I am seeing are of the LOG_CONSOLE facility = with >> the 'info' priority. (console.info) (14.6) >>=20 >> It's my guess that syslogd is entering a tight loop whenever = syslog.conf >> is configured so that a log message with facility LOG_CONSOLE is >> directed to /dev/console. The committer seems to have been aware of = the >> problem, but maybe an incorrectly configured syslog.conf would >> circumvent the checks within the code? > >There should be code to prevent this looping, as you point out. >Can you send me the output of syslogd -d while it's looping and >I'll try to figure out what is going on. > > David. Here is the output that is going to the console and to my messages file: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:4 9 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan=20 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /ke rnel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 ce rberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 2 3:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel : Jan 24 23:14:47 cerberus /kernel: Jan 24 23:14:47 cerberus /kernel: Jan 24 23:14:47 cerberus /kernel: Jan 24 23:14:47 cerber us /kernel: Jan 24 23:14:47 cerberus /ker Note the lack of linefeeds and the corruption. The following is the output of 'syslogd -d'. The log messages are interspersed with the debug output: cerberus{tony}/var/log# syslogd -d off & running.... init cfline("*.* /var/log/messages", f, "*", "*") cfline("*.* @gamekeeper.merrion.nua.net", f, "*", "*") cfline("*.* /var/log/syslog.debug", f, "*", "*") cfline("*.info /dev/console", f, "*", "*") cfline("security.* /var/log/security", f, "*", "*") cfline("mail.info /var/log/maillog", f, "*", "*") cfline("lpr.info /var/log/lpd-errs", f, "*", "*") cfline("cron.* /var/log/cron", f, "*", "*") cfline("*.err root", f, "*", "*") cfline("*.notice;news.err root", f, "*", "*") cfline("*.alert root", f, "*", "*") cfline("*.emerg *", f, "*", "*") cfline("*.* /var/log/slip.log", f, "startslip", "*") cfline("*.* /var/log/ppp.log", f, "ppp", "*") 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 X FILE: /var/log/messages 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 X FORW: gamekeeper.merrion.nua.net 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 X FILE: /var/log/syslog.debug 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 X CONSOLE: /dev/console X X X X X X X X X X X X X 8 X X X X X X X X X X X FILE: /var/log/security X X 6 X X X X X X X X X X X X X X X X X X X X X X FILE: /var/log/maillog X X X X X X 6 X X X X X X X X X X X X X X X X X X FILE: /var/log/lpd-errs X X X X X X X X X 8 X X X X X X X X X X X X X X X FILE: /var/log/cron 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 X USERS: root,=20 5 5 5 5 5 5 5 3 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 X USERS: root,=20 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 X USERS: root,=20 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 X WALL:=20 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 X FILE: /var/log/slip.log (startslip) 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 X FILE: /var/log/ppp.log (ppp) logmsg: pri 56, flags 4, from cerberus, msg syslogd: restart Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to CONSOLE /dev/console syslogd: restarted logmsg: pri 2, flags 17, from cerberus, msg :17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus syslogd: restart Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to USERS Logging to USERS logmsg: pri 166, flags 16, from cerberus, msg Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to CONSOLE /dev/console logmsg: pri 2, flags 17, from cerberus, msg : Jan 24 23:14:48 cerberus /ker Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to USERS Logging to USERS logmsg: pri 166, flags 16, from cerberus, msg Jan 24 23:17:23 cerberus syslogd: exiting on signal 2 Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to CONSOLE /dev/console logmsg: pri 166, flags 16, from cerberus, msg Jan 24 23:17:50 cerberus syslogd: restart Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to CONSOLE /dev/console logmsg: pri 166, flags 16, from cerberus, msg Jan 24 23:17:50 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:17:23 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:49 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel: Jan 24 23:14:48 cerberus /kernel Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug Logging to CONSOLE /dev/console logmsg: pri 2, flags 17, from cerberus, msg : Jan 24 23:14:48 cerberus /ker Logging to FILE /var/log/messages Logging to FORW gamekeeper.merrion.nua.net Logging to FILE /var/log/syslog.debug msg repeated 1 times, 0 sec of 30 msg repeated 1 times, 0 sec of 30 Regards, Tony. To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-stable" in the body of the message