Dear Debian Sparc List, I have encountered a "Kernel panic - not syncing: Irrecoverable deferred error trap." on a Sun Fire V210 running Debian 7.2 as a bridged iptables firewall. Before we repurposed it as a firewall, Solaris 2.8 was running on it flawlessly for years except for fan replacements.
The machine has months of uptime, but every 2-4 months, it gives us a trap like this - usually when we log in to do some iptraf or to configure iptables. I have attached a syslog snippet that contains kernel error messages. When this happens, iptables seems to keep working but we can not log into the machine. Does anybody know if it is a aging HW problem or a Kernel problem? Thank you, Elemer K. Nagy
Oct 23 15:05:01 sun0 /USR/SBIN/CRON[5027]: (root) CMD (if [ -x /etc/munin/plugins/apt_all ]; then /etc/munin/plugins/apt_all update 7200 12 >/dev/null; elif [ -x /etc/munin/plugins/apt ]; then /etc/munin/plugins/apt update 7200 12 >/dev/null; fi) Oct 23 15:05:06 sun0 kernel: [330327.084655] ERROR(1): Cheetah error trap taken afsr[0010100000000000] afar[0000000040000000] TL1(0) Oct 23 15:05:06 sun0 kernel: [330327.204846] ERROR(1): TPC[407be8] TNPC[407bec] O7[448fdc] TSTATE[9980e01606] Oct 23 15:05:06 sun0 kernel: [330327.298615] ERROR(1): TPC<copy_tsb+0x68/0x80> Oct 23 15:05:06 sun0 kernel: [330327.356945] ERROR(1): M_SYND(0), E_SYND(0), Privileged Oct 23 15:05:06 sun0 kernel: [330327.426718] ERROR(1): Highest priority error (0000100000000000) "Unmapped error from system bus" Oct 23 15:05:06 sun0 kernel: [330327.543391] ERROR(1): D-cache idx[0] tag[0000000000000000] utag[0000000000000000] stag[0000000000000000] Oct 23 15:05:06 sun0 kernel: [330327.669204] ERROR(1): D-cache data0[0000000000000000] data1[0000000000000000] data2[0000000000000000] data3[0000000000000000] Oct 23 15:05:06 sun0 kernel: [330327.819040] ERROR(1): I-cache idx[0] tag[0000000000000000] utag[0000000000000000] stag[0000000000000000] u[0000000000000000] l[0000000000000000] Oct 23 15:05:06 sun0 kernel: [330327.990603] ERROR(1): I-cache INSN0[0000000000000000] INSN1[0000000000000000] INSN2[0000000000000000] INSN3[0000000000000000] Oct 23 15:05:06 sun0 kernel: [330328.140433] ERROR(1): I-cache INSN4[0000000000000000] INSN5[0000000000000000] INSN6[0000000000000000] INSN7[0000000000000000] Oct 23 15:05:06 sun0 kernel: [330328.290264] ERROR(1): E-cache idx[40000000] tag[0000000006040240] Oct 23 15:05:06 sun0 kernel: [330328.371464] ERROR(1): E-cache data0[0000000000000019] data1[0003a20000000009] data2[ff8a1afa00000000] data3[0000000000000019] Oct 23 15:05:06 sun0 kernel: [330328.521304] Kernel panic - not syncing: Irrecoverable deferred error trap. Oct 23 15:05:06 sun0 kernel: [330328.521309] Oct 23 15:05:06 sun0 kernel: [330328.633375] Call Trace: Oct 23 15:05:06 sun0 kernel: [330328.666554] [0000000000428e28] cheetah_deferred_handler+0x32c/0x364 Oct 23 15:05:06 sun0 kernel: [330328.751195] [0000000000405e50] c_deferred+0x18/0x24 Oct 23 15:05:06 sun0 kernel: [330328.817528] [0000000000407be8] copy_tsb+0x68/0x80 Oct 23 15:05:06 sun0 kernel: [330328.881580] [0000000000782904] do_sparc64_fault+0x5b4/0x7a4 Oct 23 15:05:06 sun0 kernel: [330328.957068] [00000000004079a8] sparc64_realfault_common+0x10/0x20 Oct 23 15:05:06 sun0 kernel: [330329.039421] [000000000045d208] schedule_tail+0x78/0x88 Oct 23 15:05:06 sun0 kernel: [330329.109186] [0000000000406050] ret_from_syscall+0x8/0x18 Oct 23 15:05:06 sun0 kernel: [330329.181250] Press Stop-A (L1-A) to return to the boot prom Oct 23 15:11:25 sun0 kernel: imklog 5.8.11, log source = /proc/kmsg started. Oct 23 15:11:25 sun0 rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="1965" x-info="http://www.rsyslog.com";] start Oct 23 15:11:25 sun0 kernel: [ 0.000000] PROMLIB: Sun IEEE Boot Prom 'OBP 4.30.4.a 2010/01/06 14:48' Oct 23 15:11:25 sun0 kernel: [ 0.000000] PROMLIB: Root node compatible: Oct 23 15:11:25 sun0 kernel: [ 0.000000] Initializing cgroup subsys cpuset Oct 23 15:11:25 sun0 kernel: [ 0.000000] Initializing cgroup subsys cpu Oct 23 15:11:25 sun0 kernel: [ 0.000000] Linux version 3.2.0-4-sparc64-smp (debian-ker...@lists.debian.org) (gcc version 4.6.3 (Debian 4.6.3-14) ) #1 SMP Debian 3.2.51-1 Oct 23 15:11:25 sun0 kernel: [ 0.000000] bootconsole [earlyprom0] enabled Oct 23 15:11:25 sun0 kernel: [ 0.000000] ARCH: SUN4U Oct 23 15:11:25 sun0 kernel: [ 0.000000] Ethernet address: 00:03:ba:54:09:d1 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Kernel: Using 2 locked TLB entries for main kernel image. Oct 23 15:11:25 sun0 kernel: [ 0.000000] Remapping the kernel... done. Oct 23 15:11:25 sun0 kernel: [ 0.000000] OF stdout device is: /pci@1e,600000/isa@7/serial@0,3f8 Oct 23 15:11:25 sun0 kernel: [ 0.000000] PROM: Built device tree with 77798 bytes of memory. Oct 23 15:11:25 sun0 kernel: [ 0.000000] Top of RAM: 0x103fedc000, Total RAM: 0x7fe96000 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Memory hole size: 64512MB Oct 23 15:11:25 sun0 kernel: [ 0.000000] [20000000-207fffff] on node 0 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Zone PFN ranges: Oct 23 15:11:25 sun0 kernel: [ 0.000000] Normal 0x00000000 -> 0x0081ff6e Oct 23 15:11:25 sun0 kernel: [ 0.000000] Movable zone start PFN for each node Oct 23 15:11:25 sun0 kernel: [ 0.000000] early_node_map[6] active PFN ranges Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x00000000 -> 0x00020000 Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x00800000 -> 0x0081f7ff Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x0081f800 -> 0x0081ff3f Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x0081ff47 -> 0x0081ff50 Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x0081ff68 -> 0x0081ff6b Oct 23 15:11:25 sun0 kernel: [ 0.000000] 0: 0x0081ff6d -> 0x0081ff6e Oct 23 15:11:25 sun0 kernel: [ 0.000000] On node 0 totalpages: 261963 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Normal zone: 66559 pages used for memmap Oct 23 15:11:25 sun0 kernel: [ 0.000000] Normal zone: 0 pages reserved Oct 23 15:11:25 sun0 kernel: [ 0.000000] Normal zone: 195404 pages, LIFO batch:15 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Booting Linux... Oct 23 15:11:25 sun0 kernel: [ 0.000000] CPU CAPS: [flush,stbar,swap,muldiv,v9,ultra3,mul32,div32] Oct 23 15:11:25 sun0 kernel: [ 0.000000] CPU CAPS: [v8plus,vis,vis2] Oct 23 15:11:25 sun0 kernel: [ 0.000000] PERCPU: Embedded 6 pages/cpu @fffff80001400000 s20416 r8192 d20544 u2097152 Oct 23 15:11:25 sun0 kernel: [ 0.000000] pcpu-alloc: s20416 r8192 d20544 u2097152 alloc=1*4194304 Oct 23 15:11:25 sun0 kernel: [ 0.000000] pcpu-alloc: [0] 0 1 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 195404 Oct 23 15:11:25 sun0 kernel: [ 0.000000] Kernel command line: root=/dev/md0 ro Oct 23 15:11:25 sun0 kernel: [ 0.000000] PID hash table entries: 4096 (order: 2, 32768 bytes) Oct 23 15:11:25 sun0 kernel: [ 0.000000] Dentry cache hash table entries: 262144 (order: 8, 2097152 bytes) Oct 23 15:11:25 sun0 kernel: [ 0.000000] Inode-cache hash table entries: 131072 (order: 7, 1048576 bytes) Oct 23 15:11:25 sun0 kernel: [ 0.000000] Memory: 2058992k available (3592k kernel code, 1488k data, 232k init) [fffff80000000000,000000103fedc000] Oct 23 15:11:25 sun0 kernel: [ 0.000000] Hierarchical RCU implementation. Oct 23 15:11:25 sun0 kernel: [ 0.000000] CONFIG_RCU_FANOUT set to non-default value of 32 Oct 23 15:11:25 sun0 kernel: [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. Oct 23 15:11:25 sun0 kernel: [ 0.000000] NR_IRQS:255 Oct 23 15:11:25 sun0 kernel: [ 0.000000] clocksource: mult[53555555] shift[24] Oct 23 15:11:25 sun0 kernel: [ 0.000000] clockevent: mult[3126e98] shift[32] Oct 23 15:11:25 sun0 kernel: [ 0.000000] Console: colour dummy device 80x25 Oct 23 15:11:25 sun0 kernel: [ 0.000000] console [tty0] enabled, bootconsole disabled Oct 23 15:11:25 sun0 kernel: [ 36.627505] Calibrating delay using timer specific routine.. 24.00 BogoMIPS (lpj=48014) Oct 23 15:11:25 sun0 kernel: [ 36.627529] pid_max: default: 32768 minimum: 301 Oct 23 15:11:25 sun0 kernel: [ 36.627691] Security Framework initialized Oct 23 15:11:25 sun0 kernel: [ 36.627719] AppArmor: AppArmor disabled by boot time parameter Oct 23 15:11:25 sun0 kernel: [ 36.627797] Mount-cache hash table entries: 512 Oct 23 15:11:25 sun0 kernel: [ 36.628413] Initializing cgroup subsys cpuacct Oct 23 15:11:25 sun0 kernel: [ 36.628455] Initializing cgroup subsys memory Oct 23 15:11:25 sun0 kernel: [ 36.628497] Initializing cgroup subsys devices Oct 23 15:11:25 sun0 kernel: [ 36.628510] Initializing cgroup subsys freezer Oct 23 15:11:25 sun0 kernel: [ 36.628522] Initializing cgroup subsys net_cls Oct 23 15:11:25 sun0 kernel: [ 36.628534] Initializing cgroup subsys blkio Oct 23 15:11:25 sun0 kernel: [ 36.628561] Initializing cgroup subsys perf_event Oct 23 15:11:25 sun0 kernel: [ 36.628785] Performance events: Supported PMU type is 'ultra3i' Oct 23 15:11:25 sun0 kernel: [ 36.630540] CPU 0: synchronized TICK with master CPU (last diff -1 cycles, maxerr 7 cycles) Oct 23 15:11:25 sun0 kernel: [ 36.630596] Brought up 2 CPUs Oct 23 15:11:25 sun0 kernel: [ 36.630627] Testing NMI watchdog ... OK. Oct 23 15:11:25 sun0 kernel: [ 36.711602] devtmpfs: initialized Oct 23 15:11:25 sun0 kernel: [ 36.712375] print_constraints: dummy: Oct 23 15:11:25 sun0 kernel: [ 36.712604] NET: Registered protocol family 16 Oct 23 15:11:25 sun0 kernel: [ 36.716689] /pci@1f,700000: TOMATILLO PCI Bus Module ver[4:0] Oct 23 15:11:25 sun0 kernel: [ 36.716712] /pci@1f,700000: PCI IO[7f601000000] MEM[7f700000000] Oct 23 15:11:25 sun0 kernel: [ 36.718975] PCI: Scanning PBM /pci@1f,700000 Oct 23 15:11:25 sun0 kernel: [ 36.719186] pci 0000:00:02.0: PME# supported from D3hot Oct 23 15:11:25 sun0 kernel: [ 36.719286] pci 0000:00:02.1: PME# supported from D3hot Oct 23 15:11:25 sun0 kernel: [ 36.719659] /pci@1e,600000: TOMATILLO PCI Bus Module ver[4:0] Oct 23 15:11:25 sun0 kernel: [ 36.719685] /pci@1e,600000: PCI IO[7fe01000000] MEM[7ff00000000] Oct 23 15:11:25 sun0 kernel: [ 36.721965] PCI: Scanning PBM /pci@1e,600000 Oct 23 15:11:25 sun0 kernel: [ 36.722206] pci 0001:00:06.0: quirk: [io 0x7fe01000800-0x7fe0100083f] claimed by ali7101 ACPI Oct 23 15:11:25 sun0 kernel: [ 36.722242] pci 0001:00:06.0: quirk: [io 0x7fe01000600-0x7fe0100061f] claimed by ali7101 SMB Oct 23 15:11:25 sun0 kernel: [ 36.722339] pci 0001:00:0a.0: PME# supported from D3cold Oct 23 15:11:25 sun0 kernel: [ 36.722929] /pci@1c,600000: TOMATILLO PCI Bus Module ver[4:0] Oct 23 15:11:25 sun0 kernel: [ 36.722954] /pci@1c,600000: PCI IO[7ce01000000] MEM[7cf00000000] Oct 23 15:11:25 sun0 kernel: [ 36.725270] PCI: Scanning PBM /pci@1c,600000 Oct 23 15:11:25 sun0 kernel: [ 36.725466] pci 0002:00:02.0: supports D1 D2 Oct 23 15:11:25 sun0 kernel: [ 36.725548] pci 0002:00:02.1: supports D1 D2 Oct 23 15:11:25 sun0 kernel: [ 36.725906] /pci@1d,700000: TOMATILLO PCI Bus Module ver[4:0] Oct 23 15:11:25 sun0 kernel: [ 36.725933] /pci@1d,700000: PCI IO[7c601000000] MEM[7c700000000] Oct 23 15:11:25 sun0 kernel: [ 36.728246] PCI: Scanning PBM /pci@1d,700000 Oct 23 15:11:25 sun0 kernel: [ 36.728469] pci 0003:00:02.0: PME# supported from D3hot Oct 23 15:11:25 sun0 kernel: [ 36.728578] pci 0003:00:02.1: PME# supported from D3hot Oct 23 15:11:25 sun0 kernel: [ 36.731348] bio: create slab <bio-0> at 0 Oct 23 15:11:25 sun0 kernel: [ 36.731849] vgaarb: loaded Oct 23 15:11:25 sun0 kernel: [ 36.732528] /pci@1e,600000/isa@7/rtc@0,70: RTC regs at 0x7fe01000070 Oct 23 15:11:25 sun0 kernel: [ 36.733234] Switching to clocksource stick Oct 23 15:11:25 sun0 kernel: [ 36.744341] NET: Registered protocol family 2 Oct 23 15:11:25 sun0 kernel: [ 36.744631] IP route cache hash table entries: 65536 (order: 6, 524288 bytes) Oct 23 15:11:25 sun0 kernel: [ 36.746109] TCP established hash table entries: 262144 (order: 9, 4194304 bytes) Oct 23 15:11:25 sun0 kernel: [ 36.756546] TCP bind hash table entries: 65536 (order: 7, 1048576 bytes) Oct 23 15:11:25 sun0 kernel: [ 36.760168] TCP: Hash tables configured (established 262144 bind 65536) Oct 23 15:11:25 sun0 kernel: [ 36.760195] TCP reno registered Oct 23 15:11:25 sun0 kernel: [ 36.760222] UDP hash table entries: 1024 (order: 2, 32768 bytes) Oct 23 15:11:25 sun0 kernel: [ 36.760360] UDP-Lite hash table entries: 1024 (order: 2, 32768 bytes) Oct 23 15:11:25 sun0 kernel: [ 36.760865] NET: Registered protocol family 1 Oct 23 15:11:25 sun0 kernel: [ 36.760945] pci 0001:00:07.0: Activating ISA DMA hang workarounds Oct 23 15:11:25 sun0 kernel: [ 36.760988] PCI: Enabling device: (0001:00:0a.0), cmd 2 Oct 23 15:11:25 sun0 kernel: [ 36.813344] PCI: CLS 64 bytes, default 64 Oct 23 15:11:25 sun0 kernel: [ 36.813539] Unpacking initramfs... Oct 23 15:11:25 sun0 kernel: [ 37.426228] Freeing initrd memory: 10571k freed Oct 23 15:11:25 sun0 kernel: [ 37.427307] power: Control reg at 7fe01000800 Oct 23 15:11:25 sun0 kernel: [ 37.427682] chmc: UltraSPARC-IIIi memory controller at /memory-controller@0,0 Oct 23 15:11:25 sun0 kernel: [ 37.427725] chmc: UltraSPARC-IIIi memory controller at /memory-controller@1,0 Oct 23 15:11:25 sun0 kernel: [ 37.428153] audit: initializing netlink socket (disabled) Oct 23 15:11:25 sun0 kernel: [ 37.428206] type=2000 audit(0.880:1): initialized Oct 23 15:11:25 sun0 kernel: [ 37.460264] HugeTLB registered 4 MB page size, pre-allocated 0 pages Oct 23 15:11:25 sun0 kernel: [ 37.461058] VFS: Disk quotas dquot_6.5.2 Oct 23 15:11:25 sun0 kernel: [ 37.461244] Dquot-cache hash table entries: 1024 (order 0, 8192 bytes) Oct 23 15:11:25 sun0 kernel: [ 37.461539] msgmni has been set to 4042 Oct 23 15:11:25 sun0 kernel: [ 37.462129] alg: No test for stdrng (krng) Oct 23 15:11:25 sun0 kernel: [ 37.462254] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) Oct 23 15:11:25 sun0 kernel: [ 37.462288] io scheduler noop registered Oct 23 15:11:25 sun0 kernel: [ 37.462305] io scheduler deadline registered Oct 23 15:11:25 sun0 kernel: [ 37.462362] io scheduler cfq registered (default) Oct 23 15:11:25 sun0 kernel: [ 37.463517] f00aba6c: ttyS0 at MMIO 0x7fe010003f8 (irq = 15) is a 16550A Oct 23 15:11:25 sun0 kernel: [ 37.463543] Console: ttyS0 (SU) Oct 23 15:11:25 sun0 kernel: [ 44.791783] console [ttyS0] enabled Oct 23 15:11:25 sun0 kernel: [ 44.837881] f00ad5ec: ttyS1 at MMIO 0x7fe010002e8 (irq = 15) is a 16550A Oct 23 15:11:25 sun0 kernel: [ 44.927960] mousedev: PS/2 mouse device common for all mice Oct 23 15:11:25 sun0 kernel: [ 45.001834] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0 Oct 23 15:11:25 sun0 kernel: [ 45.085401] rtc0: no alarms, 114 bytes nvram Oct 23 15:11:25 sun0 kernel: [ 45.142344] TCP cubic registered Oct 23 15:11:25 sun0 kernel: [ 45.184791] NET: Registered protocol family 10 Oct 23 15:11:25 sun0 kernel: [ 45.244852] Mobile IPv6 Oct 23 15:11:25 sun0 kernel: [ 45.276902] NET: Registered protocol family 17 Oct 23 15:11:25 sun0 kernel: [ 45.335342] Registering the dns_resolver key type Oct 23 15:11:25 sun0 kernel: [ 45.397282] registered taskstats version 1 Oct 23 15:11:25 sun0 kernel: [ 45.451499] rtc_cmos rtc_cmos: setting system clock to 2013-10-23 13:10:52 UTC (1382533852) Oct 23 15:11:25 sun0 kernel: [ 45.561429] Initializing network drop monitor service Oct 23 15:11:25 sun0 kernel: [ 45.921119] tg3.c:v3.121 (November 2, 2011) Oct 23 15:11:25 sun0 kernel: [ 45.976215] PCI: Enabling device: (0000:00:02.0), cmd 2 Oct 23 15:11:25 sun0 kernel: [ 46.022676] usbcore: registered new interface driver usbfs Oct 23 15:11:25 sun0 kernel: [ 46.096428] SCSI subsystem initialized Oct 23 15:11:25 sun0 kernel: [ 46.160076] PCI: Enabling device: (0002:00:02.0), cmd 147 Oct 23 15:11:25 sun0 kernel: [ 46.160669] sym0: <1010-66> rev 0x1 at pci 0002:00:02.0 irq 24 Oct 23 15:11:25 sun0 kernel: [ 46.241860] libata version 3.00 loaded. Oct 23 15:11:25 sun0 kernel: [ 46.242633] PCI: Enabling device: (0001:00:0d.0), cmd 5 Oct 23 15:11:25 sun0 kernel: [ 46.246051] usbcore: registered new interface driver hub Oct 23 15:11:25 sun0 kernel: [ 46.259952] tg3 0000:00:02.0: (unregistered net_device): Cannot get nvram lock, tg3_nvram_init failed Oct 23 15:11:25 sun0 kernel: [ 46.437582] scsi1 : pata_ali Oct 23 15:11:25 sun0 kernel: [ 46.475399] usbcore: registered new device driver usb Oct 23 15:11:25 sun0 kernel: [ 46.543569] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Oct 23 15:11:25 sun0 kernel: [ 46.592176] sym0: No NVRAM, ID 7, Fast-80, LVD, parity checking Oct 23 15:11:25 sun0 kernel: [ 46.632607] sym0: SCSI BUS has been reset. Oct 23 15:11:25 sun0 kernel: [ 46.632631] scsi0 : sym-2.2.3 Oct 23 15:11:25 sun0 kernel: [ 46.802178] tg3 0000:00:02.0: eth0: Tigon3 [partno(none) rev 2003] (PCI:66MHz:64-bit) MAC address 00:03:ba:54:09:d1 Oct 23 15:11:25 sun0 kernel: [ 46.806609] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Oct 23 15:11:25 sun0 kernel: [ 47.020750] tg3 0000:00:02.0: eth0: attached PHY is 5704 (10/100/1000Base-T Ethernet) (WireSpeed[1], EEE[0]) Oct 23 15:11:25 sun0 kernel: [ 47.021627] PCI: Enabling device: (0002:00:02.1), cmd 147 Oct 23 15:11:25 sun0 kernel: [ 47.022222] sym1: <1010-66> rev 0x1 at pci 0002:00:02.1 irq 25 Oct 23 15:11:25 sun0 kernel: [ 47.022538] scsi2 : pata_ali Oct 23 15:11:25 sun0 kernel: [ 47.022661] ata1: PATA max UDMA/100 cmd 0x7fe01000900 ctl 0x7fe01000918 bmdma 0x7fe01000920 irq 18 Oct 23 15:11:25 sun0 kernel: [ 47.022672] ata2: PATA max UDMA/100 cmd 0x7fe01000910 ctl 0x7fe01000908 bmdma 0x7fe01000928 irq 18 Oct 23 15:11:25 sun0 kernel: [ 47.023116] ohci_hcd 0001:00:0a.0: OHCI Host Controller Oct 23 15:11:25 sun0 kernel: [ 47.023221] ohci_hcd 0001:00:0a.0: new USB bus registered, assigned bus number 1 Oct 23 15:11:25 sun0 kernel: [ 47.023295] ohci_hcd 0001:00:0a.0: irq 17, io mem 0x7ff01000000 Oct 23 15:11:25 sun0 kernel: [ 47.025761] sym1: No NVRAM, ID 7, Fast-80, LVD, parity checking Oct 23 15:11:25 sun0 kernel: [ 47.066376] sym1: SCSI BUS has been reset. Oct 23 15:11:25 sun0 kernel: [ 47.066396] scsi3 : sym-2.2.3 Oct 23 15:11:25 sun0 kernel: [ 47.914114] tg3 0000:00:02.0: eth0: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] TSOcap[1] Oct 23 15:11:25 sun0 kernel: [ 47.921494] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 Oct 23 15:11:25 sun0 kernel: [ 47.921506] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 23 15:11:25 sun0 kernel: [ 47.921515] usb usb1: Product: OHCI Host Controller Oct 23 15:11:25 sun0 kernel: [ 47.921521] usb usb1: Manufacturer: Linux 3.2.0-4-sparc64-smp ohci_hcd Oct 23 15:11:25 sun0 kernel: [ 47.921529] usb usb1: SerialNumber: 0001:00:0a.0 Oct 23 15:11:25 sun0 kernel: [ 48.412878] tg3 0000:00:02.0: eth0: dma_rwctrl[763f0000] dma_mask[32-bit] Oct 23 15:11:25 sun0 kernel: [ 48.413593] ata1.00: ATAPI: DV-28E-C, 1.4B, max UDMA/33 Oct 23 15:11:25 sun0 kernel: [ 48.413608] ata1.00: WARNING: ATAPI DMA disabled for reliability issues. It can be enabled Oct 23 15:11:25 sun0 kernel: [ 48.413618] ata1.00: WARNING: via pata_ali.atapi_dma modparam or corresponding sysfs node. Oct 23 15:11:25 sun0 kernel: [ 48.414325] hub 1-0:1.0: USB hub found Oct 23 15:11:25 sun0 kernel: [ 48.414346] hub 1-0:1.0: 2 ports detected Oct 23 15:11:25 sun0 kernel: [ 48.891110] sched: RT throttling activated Oct 23 15:11:25 sun0 kernel: [ 48.944977] PCI: Enabling device: (0000:00:02.1), cmd 2 Oct 23 15:11:25 sun0 kernel: [ 48.945269] ata1.00: configured for UDMA/33 Oct 23 15:11:25 sun0 kernel: [ 49.004090] scsi: waiting for bus probes to complete ... Oct 23 15:11:25 sun0 kernel: [ 49.118336] tg3 0000:00:02.1: (unregistered net_device): Cannot get nvram lock, tg3_nvram_init failed Oct 23 15:11:25 sun0 kernel: [ 49.578587] tg3 0000:00:02.1: eth1: Tigon3 [partno(none) rev 2003] (PCI:66MHz:64-bit) MAC address 00:03:ba:54:09:d2 Oct 23 15:11:25 sun0 kernel: [ 49.715948] tg3 0000:00:02.1: eth1: attached PHY is 5704 (10/100/1000Base-T Ethernet) (WireSpeed[1], EEE[0]) Oct 23 15:11:25 sun0 kernel: [ 49.845281] tg3 0000:00:02.1: eth1: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] TSOcap[1] Oct 23 15:11:25 sun0 kernel: [ 49.949469] tg3 0000:00:02.1: eth1: dma_rwctrl[763f0000] dma_mask[32-bit] Oct 23 15:11:25 sun0 kernel: [ 50.038832] PCI: Enabling device: (0003:00:02.0), cmd 2 Oct 23 15:11:25 sun0 kernel: [ 50.046039] scsi 0:0:0:0: Direct-Access IBM IC35L036UCD210-0 S5BS PQ: 0 ANSI: 3 Oct 23 15:11:25 sun0 kernel: [ 50.152461] scsi target0:0:0: tagged command queuing enabled, command queue depth 16. Oct 23 15:11:25 sun0 kernel: [ 50.210280] tg3 0003:00:02.0: (unregistered net_device): Cannot get nvram lock, tg3_nvram_init failed Oct 23 15:11:25 sun0 kernel: [ 50.376884] scsi target0:0:0: Beginning Domain Validation Oct 23 15:11:25 sun0 kernel: [ 50.450492] scsi target0:0:0: FAST-80 WIDE SCSI 160.0 MB/s DT (12.5 ns, offset 31) Oct 23 15:11:25 sun0 kernel: [ 50.562789] scsi target0:0:0: Ending Domain Validation Oct 23 15:11:25 sun0 kernel: [ 50.631549] scsi 0:0:1:0: Direct-Access COMPAQ BB01813467 3B07 PQ: 0 ANSI: 2 Oct 23 15:11:25 sun0 kernel: [ 50.642042] tg3 0003:00:02.0: eth2: Tigon3 [partno(none) rev 2003] (PCI:66MHz:64-bit) MAC address 00:03:ba:54:09:d3 Oct 23 15:11:25 sun0 kernel: [ 50.642056] tg3 0003:00:02.0: eth2: attached PHY is 5704 (10/100/1000Base-T Ethernet) (WireSpeed[1], EEE[0]) Oct 23 15:11:25 sun0 kernel: [ 50.642069] tg3 0003:00:02.0: eth2: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] TSOcap[1] Oct 23 15:11:25 sun0 kernel: [ 50.642079] tg3 0003:00:02.0: eth2: dma_rwctrl[763f0000] dma_mask[32-bit] Oct 23 15:11:25 sun0 kernel: [ 50.642128] PCI: Enabling device: (0003:00:02.1), cmd 2 Oct 23 15:11:25 sun0 kernel: [ 50.814266] tg3 0003:00:02.1: (unregistered net_device): Cannot get nvram lock, tg3_nvram_init failed Oct 23 15:11:25 sun0 kernel: [ 51.146228] tg3 0003:00:02.1: eth3: Tigon3 [partno(none) rev 2003] (PCI:66MHz:64-bit) MAC address 00:03:ba:54:09:d4 Oct 23 15:11:25 sun0 kernel: [ 51.146242] tg3 0003:00:02.1: eth3: attached PHY is 5704 (10/100/1000Base-T Ethernet) (WireSpeed[1], EEE[0]) Oct 23 15:11:25 sun0 kernel: [ 51.146255] tg3 0003:00:02.1: eth3: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] TSOcap[1] Oct 23 15:11:25 sun0 kernel: [ 51.146266] tg3 0003:00:02.1: eth3: dma_rwctrl[763f0000] dma_mask[32-bit] Oct 23 15:11:25 sun0 kernel: [ 51.781329] scsi target0:0:1: tagged command queuing enabled, command queue depth 16. Oct 23 15:11:25 sun0 kernel: [ 51.884980] scsi target0:0:1: Beginning Domain Validation Oct 23 15:11:25 sun0 kernel: [ 51.962793] scsi target0:0:1: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 31) Oct 23 15:11:25 sun0 kernel: [ 52.062266] scsi target0:0:1: Domain Validation skipping write tests Oct 23 15:11:25 sun0 kernel: [ 52.145781] scsi target0:0:1: Ending Domain Validation Oct 23 15:11:25 sun0 kernel: [ 56.035940] scsi 1:0:0:0: CD-ROM TEAC DV-28E-C 1.4B PQ: 0 ANSI: 5 Oct 23 15:11:25 sun0 kernel: [ 56.144963] sd 0:0:0:0: [sda] 71096640 512-byte logical blocks: (36.4 GB/33.9 GiB) Oct 23 15:11:25 sun0 kernel: [ 56.245185] sd 0:0:1:0: [sdb] 35565080 512-byte logical blocks: (18.2 GB/16.9 GiB) Oct 23 15:11:25 sun0 kernel: [ 56.344818] sd 0:0:0:0: [sda] Write Protect is off Oct 23 15:11:25 sun0 kernel: [ 56.407764] sd 0:0:0:0: [sda] Mode Sense: cb 00 00 08 Oct 23 15:11:25 sun0 kernel: [ 56.408869] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 23 15:11:25 sun0 kernel: [ 56.529017] sd 0:0:1:0: [sdb] Write Protect is off Oct 23 15:11:25 sun0 kernel: [ 56.591995] sd 0:0:1:0: [sdb] Mode Sense: f7 00 10 08 Oct 23 15:11:25 sun0 kernel: [ 56.595375] sd 0:0:1:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 23 15:11:25 sun0 kernel: [ 56.722639] sda: sda1 sda2 sda3 sda4 Oct 23 15:11:25 sun0 kernel: [ 56.770837] sdb: sdb1 sdb2 sdb3 Oct 23 15:11:25 sun0 kernel: [ 56.817412] sd 0:0:0:0: [sda] Attached SCSI disk Oct 23 15:11:25 sun0 kernel: [ 56.880635] sd 0:0:1:0: [sdb] Attached SCSI disk Oct 23 15:11:25 sun0 kernel: [ 56.960009] sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 23 15:11:25 sun0 kernel: [ 57.030831] sd 0:0:1:0: Attached scsi generic sg1 type 0 Oct 23 15:11:25 sun0 kernel: [ 57.033525] sr0: scsi3-mmc drive: 24x/24x cd/rw xa/form2 cdda tray Oct 23 15:11:25 sun0 kernel: [ 57.033537] cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 23 15:11:25 sun0 kernel: [ 57.250823] sr 1:0:0:0: Attached scsi CD-ROM sr0 Oct 23 15:11:25 sun0 kernel: [ 57.251546] sr 1:0:0:0: Attached scsi generic sg2 type 5 Oct 23 15:11:25 sun0 kernel: [ 59.163657] md: md0 stopped. Oct 23 15:11:25 sun0 kernel: [ 59.212816] md: bind<sdb1> Oct 23 15:11:25 sun0 kernel: [ 59.266966] md: bind<sda1> Oct 23 15:11:25 sun0 kernel: [ 59.307728] md: raid1 personality registered for level 1 Oct 23 15:11:25 sun0 kernel: [ 59.378346] bio: create slab <bio-1> at 1 Oct 23 15:11:25 sun0 kernel: [ 59.431250] md/raid1:md0: not clean -- starting background reconstruction Oct 23 15:11:25 sun0 kernel: [ 59.520548] md/raid1:md0: active with 2 out of 2 mirrors Oct 23 15:11:25 sun0 kernel: [ 59.590484] md0: detected capacity change from 0 to 17179803648 Oct 23 15:11:25 sun0 kernel: [ 59.676596] md0: unknown partition table Oct 23 15:11:25 sun0 kernel: [ 59.850915] md: md1 stopped. Oct 23 15:11:25 sun0 kernel: [ 59.950225] md: bind<sdb2> Oct 23 15:11:25 sun0 kernel: [ 59.987792] md: bind<sda2> Oct 23 15:11:25 sun0 kernel: [ 60.027137] md/raid1:md1: active with 2 out of 2 mirrors Oct 23 15:11:25 sun0 kernel: [ 60.097098] md1: detected capacity change from 0 to 893845504 Oct 23 15:11:25 sun0 kernel: [ 60.181867] md1: unknown partition table Oct 23 15:11:25 sun0 kernel: [ 60.457299] EXT3-fs (md0): recovery required on readonly filesystem Oct 23 15:11:25 sun0 kernel: [ 60.548688] EXT3-fs (md0): write access will be enabled during recovery Oct 23 15:11:25 sun0 kernel: [ 61.543258] md: resync of RAID array md0 Oct 23 15:11:25 sun0 kernel: [ 61.594796] md: minimum _guaranteed_ speed: 1000 KB/sec/disk. Oct 23 15:11:25 sun0 kernel: [ 61.671517] md: using maximum available idle IO bandwidth (but not more than 200000 KB/sec) for resync. Oct 23 15:11:25 sun0 kernel: [ 61.795059] md: using 256k window, over a total of 16777152k. Oct 23 15:11:25 sun0 kernel: [ 62.710465] kjournald starting. Commit interval 5 seconds Oct 23 15:11:25 sun0 kernel: [ 62.710572] EXT3-fs (md0): recovery complete Oct 23 15:11:25 sun0 kernel: [ 62.780945] EXT3-fs (md0): mounted filesystem with ordered data mode Oct 23 15:11:25 sun0 kernel: [ 67.123832] Adding 872880k swap on /dev/md1. Priority:-1 extents:1 across:872880k Oct 23 15:11:25 sun0 kernel: [ 67.824369] EXT3-fs (md0): using internal journal Oct 23 15:11:25 sun0 kernel: [ 68.297305] loop: module loaded Oct 23 15:11:25 sun0 kernel: [ 69.713239] EXT4-fs (sda4): recovery complete Oct 23 15:11:25 sun0 kernel: [ 69.770447] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) Oct 23 15:11:25 sun0 kernel: [ 70.682852] tg3 0000:00:02.0: firmware: agent aborted loading tigon/tg3_tso.bin (not found?) Oct 23 15:11:25 sun0 kernel: [ 70.794019] tg3 0000:00:02.0: eth0: TSO capability disabled Oct 23 15:11:25 sun0 kernel: [ 72.152919] tg3 0000:00:02.0: eth0: No firmware running Oct 23 15:11:25 sun0 kernel: [ 72.422092] ADDRCONF(NETDEV_UP): eth0: link is not ready Oct 23 15:11:25 sun0 kernel: [ 72.607299] tg3 0003:00:02.0: firmware: agent aborted loading tigon/tg3_tso.bin (not found?) Oct 23 15:11:25 sun0 kernel: [ 72.718476] tg3 0003:00:02.0: eth2: TSO capability disabled Oct 23 15:11:25 sun0 kernel: [ 74.076309] tg3 0003:00:02.0: eth2: No firmware running Oct 23 15:11:25 sun0 kernel: [ 74.345046] ADDRCONF(NETDEV_UP): eth2: link is not ready Oct 23 15:11:25 sun0 kernel: [ 74.427834] tg3 0003:00:02.1: firmware: agent aborted loading tigon/tg3_tso.bin (not found?) Oct 23 15:11:25 sun0 kernel: [ 74.540117] tg3 0003:00:02.1: eth3: TSO capability disabled Oct 23 15:11:25 sun0 kernel: [ 75.900327] tg3 0003:00:02.1: eth3: No firmware running Oct 23 15:11:25 sun0 kernel: [ 76.166940] tg3 0000:00:02.0: eth0: Link is up at 1000 Mbps, full duplex Oct 23 15:11:25 sun0 kernel: [ 76.255028] tg3 0000:00:02.0: eth0: Flow control is off for TX and off for RX Oct 23 15:11:25 sun0 kernel: [ 76.351640] ADDRCONF(NETDEV_UP): eth3: link is not ready Oct 23 15:11:25 sun0 kernel: [ 76.425640] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 23 15:11:25 sun0 kernel: [ 76.602226] Bridge firewalling registered Oct 23 15:11:25 sun0 kernel: [ 76.662779] device eth2 entered promiscuous mode Oct 23 15:11:25 sun0 kernel: [ 76.726432] device eth3 entered promiscuous mode Oct 23 15:11:25 sun0 kernel: [ 76.816475] ADDRCONF(NETDEV_UP): br0: link is not ready Oct 23 15:11:25 sun0 kernel: [ 77.565291] tg3 0003:00:02.0: eth2: Link is up at 1000 Mbps, full duplex Oct 23 15:11:25 sun0 kernel: [ 77.653410] tg3 0003:00:02.0: eth2: Flow control is off for TX and off for RX Oct 23 15:11:25 sun0 kernel: [ 77.750237] ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready Oct 23 15:11:25 sun0 kernel: [ 77.825948] br0: port 1(eth2) entering forwarding state Oct 23 15:11:25 sun0 kernel: [ 77.894635] br0: port 1(eth2) entering forwarding state Oct 23 15:11:25 sun0 kernel: [ 77.965244] ADDRCONF(NETDEV_CHANGE): br0: link becomes ready Oct 23 15:11:26 sun0 /usr/sbin/cron[2065]: (CRON) INFO (pidfile fd = 3) Oct 23 15:11:26 sun0 /usr/sbin/cron[2074]: (CRON) STARTUP (fork ok) Oct 23 15:11:26 sun0 /usr/sbin/cron[2074]: (CRON) INFO (Running @reboot jobs) Oct 23 15:11:26 sun0 logger: Starting firewall /etc/init.d/firewall. Oct 23 15:11:26 sun0 kernel: [ 79.416257] ip_tables: (C) 2000-2006 Netfilter Core Team Oct 23 15:11:26 sun0 kernel: [ 79.502603] tg3 0003:00:02.1: eth3: Link is up at 1000 Mbps, full duplex Oct 23 15:11:26 sun0 kernel: [ 79.606665] tg3 0003:00:02.1: eth3: Flow control is off for TX and off for RX Oct 23 15:11:26 sun0 kernel: [ 79.712375] ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready Oct 23 15:11:26 sun0 kernel: [ 79.788198] br0: port 2(eth3) entering forwarding state Oct 23 15:11:26 sun0 kernel: [ 79.856926] br0: port 2(eth3) entering forwarding state Oct 23 15:11:27 sun0 kernel: [ 80.512062] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Oct 23 15:11:27 sun0 ntpd[2360]: ntpd 4.2.6p5@1.2349-o Sat May 12 10:03:52 UTC 2012 (1) Oct 23 15:11:27 sun0 ntpd[2391]: proto: precision = 1.416 usec Oct 23 15:11:27 sun0 ntpd[2391]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen and drop on 1 v6wildcard :: UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 2 lo 127.0.0.1 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 3 eth0 152.66.114.247 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 4 lo ::1 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 5 eth0 fe80::203:baff:fe54:9d1 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 6 br0 fe80::203:baff:fe54:9d3 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: Listen normally on 7 eth2 fe80::203:baff:fe54:9d3 UDP 123 Oct 23 15:11:27 sun0 ntpd[2391]: peers refreshed Oct 23 15:11:27 sun0 ntpd[2391]: Listening on routing socket on fd #24 for interface updates
