thr3ads.net - freebsd stable - my kernel spews out a lot of garbage to syslog (long /var/log/messages) [Sep 2006]

If this information is useful, please help other people find it:
Share via:

Klaus Robert Suetterlin

2006-Sep-06 13:48 UTC

my kernel spews out a lot of garbage to syslog (long /var/log/messages)

Hi,

I have FreeBSD6.1-p5 (PAE+SMP+COMPAT_LINUX+LINPROCFS) system running  
that is producing a lot of guberish in /var/log/messages through  
syslog (about 100-1000 bytes per second), I give example below.  Any  
ideas on this are welcome, especially any hints on diagnostics...

The giberish started 11:30 local time and still (15:46) continues...

Here is some example giberish:

Sep  6 10:48:51 circe kernel: Trying to mount root from ufs:/dev/da0s1a
Sep  6 11:02:30 circe su: krs to root on /dev/ttyp1
Sep  6 11:30:19 circe kernel: <NNM2M>NIIMNIM  II IS ISAISA SA  A  
302202,00,,  ,
EE EIIEISSISAASA  Aff fff
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: ff
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: <<22>>f
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: NMNINM MNIIMI  IISSIA AS I AS A32 00,2 , 
0 ,EE2 IIE
S0SIA,AS   fAfEf fIf
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: Sf
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: <<22>>A
Sep  6 11:30:19 circe kernel:
Sep  6 11:30:19 circe kernel: ff
Sep  6 11:30:20 circe kernel: <N<2M2>I> NNIMSMIAIk  I eI3SSrA0An ,
eE33l00,I  ,
SE tIASE ArfI fafSf

And here the full bootup (dmesg) stuff:

Sep  6 10:48:51 circe syslogd: kernel boot file is /boot/kernel/kernel
Sep  6 10:48:51 circe kernel: Copyright (c) 1992-2006 The FreeBSD  
Project.
Sep  6 10:48:51 circe kernel: Copyright (c) 1979, 1980, 1983, 1986,  
1988, 1989,
1991, 1992, 1993, 1994
Sep  6 10:48:51 circe kernel: The Regents of the University of  
California. All r
ights reserved.
Sep  6 10:48:51 circe kernel: FreeBSD 6.1-RELEASE-p5 #0: Wed Sep  6  
10:39:40 CES
T 2006
Sep  6 10:48:51 circe kernel: krs@circe.mpe-garching.mpg.de:/usr/obj/ 
usr/src/sys
/CIRCE
Sep  6 10:48:51 circe kernel: acpi_alloc_wakeup_handler: can't alloc  
wake memory
Sep  6 10:48:51 circe kernel: ACPI APIC Table: <INTEL  SKA4    >
Sep  6 10:48:51 circe kernel: Timecounter "i8254" frequency 1193182  
Hz quality 0
Sep  6 10:48:51 circe kernel: CPU: Intel Pentium III Xeon (697.68-MHz  
686-class
CPU)
Sep  6 10:48:51 circe kernel: Origin = "GenuineIntel"  Id = 0x6a1   
Stepping = 1
Sep  6 10:48:51 circe kernel:  
Features=0x387fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,
CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,PN,MMX,FXSR,SSE>
Sep  6 10:48:51 circe kernel: real memory  = 17112760320 (16320 MB)
Sep  6 10:48:51 circe kernel: avail memory = 16698773504 (15925 MB)
Sep  6 10:48:51 circe kernel: FreeBSD/SMP: Multiprocessor System  
Detected: 4 CPU
s
Sep  6 10:48:51 circe kernel: cpu0 (BSP): APIC ID:  3
Sep  6 10:48:51 circe kernel: cpu1 (AP): APIC ID:  0
Sep  6 10:48:51 circe kernel: cpu2 (AP): APIC ID:  1
Sep  6 10:48:51 circe kernel: cpu3 (AP): APIC ID:  2
Sep  6 10:48:51 circe kernel: MADT: Forcing active-low polarity and  
level trigge
r for SCI
Sep  6 10:48:51 circe kernel: ioapic0 <Version 1.1> irqs 0-15 on  
motherboard
Sep  6 10:48:51 circe kernel: ioapic1 <Version 1.1> irqs 16-31 on  
motherboard
Sep  6 10:48:51 circe kernel: kbd1 at kbdmux0
Sep  6 10:48:51 circe kernel: acpi0: <INTEL SKA4> on motherboard
Sep  6 10:48:51 circe kernel: acpi0: Power Button (fixed)
Sep  6 10:48:51 circe kernel: acpi0: Sleep Button (fixed)
Sep  6 10:48:51 circe kernel: Timecounter "ACPI-safe" frequency  
3579545 Hz quali
ty 1000
Sep  6 10:48:51 circe kernel: acpi_timer0: <32-bit timer at  
3.579545MHz> port 0x
508-0x50b on acpi0
Sep  6 10:48:51 circe kernel: cpu0: <ACPI CPU> on acpi0
Sep  6 10:48:51 circe kernel: cpu1: <ACPI CPU> on acpi0
Sep  6 10:48:51 circe kernel: cpu2: <ACPI CPU> on acpi0
Sep  6 10:48:51 circe kernel: cpu3: <ACPI CPU> on acpi0
Sep  6 10:48:51 circe kernel: pcib0: <ACPI Host-PCI bridge> port  
0xcf8-0xcff on
acpi0
Sep  6 10:48:51 circe kernel: pci0: <ACPI PCI bus> on pcib0
Sep  6 10:48:51 circe kernel: fxp0: <Intel 82559 Pro/100 Ethernet>  
port 0x1400-0
x143f mem 0xfc100000-0xfc100fff,0xfc000000-0xfc0fffff irq 31 at  
device 4.0 on pc
i0
Sep  6 10:48:51 circe kernel: miibus0: <MII bus> on fxp0
Sep  6 10:48:51 circe kernel: inphy0: <i82555 10/100 media interface>  
on miibus0
Sep  6 10:48:51 circe kernel: inphy0:  10baseT, 10baseT-FDX,  
100baseTX, 100baseT
X-FDX, auto
Sep  6 10:48:51 circe kernel: fxp0: Ethernet address: 00:d0:b7:a8:8d:33
Sep  6 10:48:51 circe kernel: pci0: <display, VGA> at device 12.0 (no  
driver att
ached)
Sep  6 10:48:51 circe kernel: isab0: <PCI-ISA bridge> port  
0x580-0x58f at device
15.0 on pci0
Sep  6 10:48:51 circe kernel: isa0: <ISA bus> on isab0
Sep  6 10:48:51 circe kernel: atapci0: <ServerWorks ROSB4 UDMA33  
controller> por
t 0x1f0-0x1f7,0x3f6,0x1458-0x145f,0x1450-0x1453,0x1440-0x144f at  
device 15.1 on
pci0
Sep  6 10:48:51 circe kernel: ata0: <ATA channel 0> on atapci0
Sep  6 10:48:51 circe kernel: ata2: <ATA channel 1> on atapci0
Sep  6 10:48:51 circe kernel: pci0: <serial bus, USB> at device 15.2  
(no driver
attached)
Sep  6 10:48:51 circe kernel: pcib1: <ACPI Host-PCI bridge> on acpi0
Sep  6 10:48:51 circe kernel: pci1: <ACPI PCI bus> on pcib1
Sep  6 10:48:51 circe kernel: pci1: <base peripheral, PCI hot-plug  
controller> a
t device 5.0 (no driver attached)
Sep  6 10:48:51 circe kernel: pcib2: <ACPI Host-PCI bridge> on acpi0
Sep  6 10:48:51 circe kernel: pci2: <ACPI PCI bus> on pcib2
Sep  6 10:48:51 circe kernel: pci2: <base peripheral, PCI hot-plug  
controller> a
t device 7.0 (no driver attached)
Sep  6 10:48:51 circe kernel: iir0: <ICP Disk Array Controller> mem  
0xfcc00000-0
xfcc03fff irq 17 at device 13.0 on pci2
Sep  6 10:48:51 circe kernel: iir0: [GIANT-LOCKED]
Sep  6 10:48:51 circe kernel: em0: <Intel(R) PRO/1000 Network  
Connection Version
- 3.2.18> mem 0xfc800000-0xfc81ffff irq 19 at device 14.0 on pci2
Sep  6 10:48:51 circe kernel: em0: Ethernet address: 00:03:47:08:f2:12
Sep  6 10:48:51 circe kernel: sio0: <16550A-compatible COM port> port  
0x3f8-0x3f
f irq 4 flags 0x10 on acpi0
Sep  6 10:48:51 circe kernel: sio0: type 16550A
Sep  6 10:48:51 circe kernel: sio1: <16550A-compatible COM port> port  
0x2f8-0x2f
f irq 3 on acpi0
Sep  6 10:48:51 circe kernel: sio1: type 16550A
Sep  6 10:48:51 circe kernel: ppc0: <Standard parallel printer port>  
port 0x378-
0x37f irq 7 on acpi0
Sep  6 10:48:51 circe kernel: ppc0: Generic chipset (NIBBLE-only) in  
COMPATIBLE
mode
Sep  6 10:48:51 circe kernel: ppbus0: <Parallel port bus> on ppc0
Sep  6 10:48:51 circe kernel: plip0: <PLIP network interface> on ppbus0
Sep  6 10:48:51 circe kernel: lpt0: <Printer> on ppbus0
Sep  6 10:48:51 circe kernel: lpt0: Interrupt-driven port
Sep  6 10:48:51 circe kernel: ppi0: <Parallel I/O> on ppbus0
Sep  6 10:48:51 circe kernel: fdc0: <floppy drive controller> port  
0x3f0-0x3f5,0
x3f7 irq 6 drq 2 on acpi0
Sep  6 10:48:51 circe kernel: fdc0: [FAST]
Sep  6 10:48:51 circe kernel: fd0: <1440-KB 3.5" drive> on fdc0 drive
0
Sep  6 10:48:51 circe kernel: atkbdc0: <Keyboard controller (i8042)>  
port 0x60,0
x64 irq 1 on acpi0
Sep  6 10:48:51 circe kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0
Sep  6 10:48:51 circe kernel: kbd0 at atkbd0
Sep  6 10:48:51 circe kernel: atkbd0: [GIANT-LOCKED]
Sep  6 10:48:51 circe kernel: psm0: <PS/2 Mouse> irq 12 on atkbdc0
Sep  6 10:48:51 circe kernel: psm0: [GIANT-LOCKED]
Sep  6 10:48:51 circe kernel: psm0: model Generic PS/2 mouse, device  
ID 0
Sep  6 10:48:51 circe kernel: pmtimer0 on isa0
Sep  6 10:48:51 circe kernel: orm0: <ISA Option ROMs> at iomem  
0xc0000-0xc7fff,0
xc8000-0xc8fff on isa0
Sep  6 10:48:51 circe kernel: ata1 at port 0x170-0x177,0x376 irq 15  
on isa0
Sep  6 10:48:51 circe kernel: sc0: <System console> at flags 0x100 on  
isa0
Sep  6 10:48:51 circe kernel: sc0: VGA <16 virtual consoles,  
flags=0x300>
Sep  6 10:48:51 circe kernel: vga0: <Generic ISA VGA> at port  
0x3c0-0x3df iomem
0xa0000-0xbffff on isa0
Sep  6 10:48:51 circe kernel: Timecounters tick every 1.000 msec
Sep  6 10:48:51 circe kernel: module_register_init: MOD_LOAD  
(amr_linux, 0xc02cc
80c, 0) error 6
Sep  6 10:48:51 circe kernel: acd0: DVDROM <Pioneer DVD-ROM  
ATAPIModel DVD-105S
0122/E1.22> at ata0-master UDMA33
Sep  6 10:48:51 circe kernel: acd1: CDROM <CD-540E/1.0A> at ata0- 
slave UDMA33
Sep  6 10:48:51 circe kernel: Waiting 5 seconds for SCSI devices to  
settle
Sep  6 10:48:51 circe kernel: ses0 at iir0 bus 2 target 6 lun 0
Sep  6 10:48:51 circe kernel: ses0: <ESG-SHV SCA HSBP M9 0.10> Fixed  
Processor S
CSI-2 device
Sep  6 10:48:51 circe kernel: ses0: SAF-TE Compliant Device
Sep  6 10:48:51 circe kernel: ses1 at iir0 bus 3 target 6 lun 0
Sep  6 10:48:51 circe kernel: ses1: <ESG-SHV SCA HSBP M9 0.10> Fixed  
Processor S
CSI-2 device
Sep  6 10:48:51 circe kernel: ses1: SAF-TE Compliant Device
Sep  6 10:48:51 circe kernel: da0 at iir0 bus 4 target 0 lun 0
Sep  6 10:48:51 circe kernel: da0: <ICP Host Drive   #00 > Fixed  
Direct Access S
CSI-2 device
Sep  6 10:48:51 circe kernel: da0: Tagged Queueing Enabled
Sep  6 10:48:51 circe kernel: da0: 175012MB (358426215 512 byte  
sectors: 255H 63
S/T 22311C)
Sep  6 10:48:51 circe kernel: SMP: AP CPU #3 Launched!
Sep  6 10:48:51 circe kernel: SMP: AP CPU #1 Launched!
Sep  6 10:48:51 circe kernel: SMP: AP CPU #2 Launched!
Sep  6 10:48:51 circe kernel: Trying to mount root from ufs:/dev/da0s1a
Sep  6 11:02:30 circe su: krs to root on /dev/ttyp1
Sep  6 11:30:19 circe kernel: <NNM2M>NIIMNIM  II IS ISAISA SA  A  
302202,00,,  ,
EE EIIEISSISAASA  Aff fff

Gavin Atkinson

2006-Sep-06 16:34 UTC

head link

my kernel spews out a lot of garbage to syslog (long /var/log/messages)

On Wed, 2006-09-06 at 15:47 +0200, Klaus Robert Suetterlin
wrote:> Hi,
> 
> I have FreeBSD6.1-p5 (PAE+SMP+COMPAT_LINUX+LINPROCFS) system running  
> that is producing a lot of guberish in /var/log/messages through  
> syslog (about 100-1000 bytes per second), I give example below.  Any  
> ideas on this are welcome, especially any hints on diagnostics...
> 
> The giberish started 11:30 local time and still (15:46) continues...
> 
> Here is some example giberish:
> 
> Sep  6 10:48:51 circe kernel: Trying to mount root from ufs:/dev/da0s1a
> Sep  6 11:02:30 circe su: krs to root on /dev/ttyp1
> Sep  6 11:30:19 circe kernel: <NNM2M>NIIMNIM  II IS ISAISA SA  A  
> 302202,00,,  ,
> EE EIIEISSISAASA  Aff fff
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: ff
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: <<22>>f
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: NMNINM MNIIMI  IISSIA AS I AS A32 00,2 , 
> 0 ,EE2 IIE
> S0SIA,AS   fAfEf fIf
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: Sf
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: <<22>>A
> Sep  6 11:30:19 circe kernel:
> Sep  6 11:30:19 circe kernel: ff
> Sep  6 11:30:20 circe kernel: <N<2M2>I> NNIMSMIAIk  I
eI3SSrA0An ,
> eE33l00,I  ,
> SE tIASE ArfI fafSf
It's not exactly garbage, it's four copies of (almost) the same message
being printed at the same time.  What's happening is that you are
receiving an NMI, and all four processors are acting on it.  The default
handler simply prints out a message and ignores the interrupt.  I
believe the actual messages are multiple copies each of:

NMI ISA 30, EISA ff\n
NMI ISA 20, EISA ff\n

Also looks like there's the start of a "kernel trap" message in
the
11:30:20 message.

As to what's causing it, it's hard to say, but given they have only just
started, the hardware is trying to tell you something.  My money is on
either overheating or failing RAM (Failed parity checks usually manifest
itself as an NMI, but looking at the code in /usr/src/sys/i386/isa/nmi.c
an attempt is made to try and detect them).  It may be worth trying to
find the rest of that "kernel trap" message.

Gavin

freebsd stable - Sep 2006 - my kernel spews out a lot of garbage to syslog (long /var/log/messages)

my kernel spews out a lot of garbage to syslog (long /var/log/messages)

my kernel spews out a lot of garbage to syslog (long /var/log/messages)