[nycbug-talk] ZFS and firewire - conditions for a perfect storm
Isaac Levy
ike at lesmuug.org
Sun Jun 29 19:40:59 EDT 2008
A quick adendum, for the record on list,
On Jun 29, 2008, at 7:11 PM, Isaac Levy wrote:
> Hi All,
>
> This story could have been avoided, but... I had a fairly 'perfect
> storm' of data loss at home, thought I'd share with the list.
>
> I figure perhaps someone here could suggest a plan of action to fix
> this long-term?
>
> If you don't care about firewire, feel free to skip this message- I've
> replicated *everything below* with a SATA controller and ZFS/RAID-Z
> works as expected, flawlessly. (ZFS is really astounding on FreeBSD!)
>
>
> --
> I've been a big fan (and little user) of ZFS for about a year now,
> it's of course excellent. With that, I built a *very* cheap 2nd file
> server from gear I had,
>
> - A mini-PC (no PCI slots, almost laptop specs)
> - 4 Firewire drives (long-time mac user, have the cases)
> - daisy-chained setup, no Firewire hub
> - ZFS RAID-Z (FreeBSD 7, was running HEAD now REL)
>
> This rig worked so well, that it quickly became my primary SMB
> workhorse. A mac with an apple software raid became the backup
> system, (rsync over SSH rocks for me). The firewire drives cases are
> metal, and act as a heat-sinc, so no noisy fans for home use :)
>
> The firewire drives seem to have issues hanging up ZFS if one fails,
> but I figured *what the heck, it's got a backup on an alltogether
> different filesystem*, and at least I don't ever have to fsck 4tb
> volumes! Whee! This has been a very productive tag-team for my uses-
> until now...
>
>
> --
> The perfect storm:
>
> I needed to pull 2 drives from the mac, therefore I shut down SMB on
> the ZFS server. I rebuilt the RAID on the mac, and brought it back
> online.
>
> - I went to immediately begin copying files back from the ZFS machine,
>
> - A physical drive enclosure power board shorted out (perhaps the
> fatal moment?)
>
> - The FreeBSD system stayed up, all I/O to the ZFS volumes would just
> hang-
> - df reported the volumes still mounted
> - saw the firewire drive dissappear in /var/log/messages, but,
> - 'zpool status' reported that ALL WAS FINE
>
> I freaked out, and did nothing to the system but watch it (and chain
> smoke) for 2 hours- hoping perhaps that something would move. All
> disk I/O to the ZFS volume stayed in a hung state.
>
> Finally, I rebooted the system- and it hung during the shutdown
> sequence. I sat with it for another 30 minutes, hoping something
> would change, to no avail.
> I finally power-cycled the machine, (perhaps the fatal moment?)
>
> Before it came back up, I pulled the dead drive out of the pool,
> expecting the ZFS volume to come back online in a RAID-Z degraded
> state-
> *I found the entire ZFS volume was hosed (insert wailing and gnashing
> of teeth here):
>
> [root at blackowl /usr/home/ike]# zpool status
> pool: Z
> state: FAULTED
> status: One or more devices could not be used because the label is
> missing
> or invalid. There are insufficient replicas for the pool to continue
> functioning.
> action: Destroy and re-create the pool from a backup source.
> see: http://www.sun.com/msg/ZFS-8000-5E
> scrub: none requested
> config:
>
> NAME STATE READ WRITE CKSUM
> Z FAULTED 0 0 0 corrupted data
> da0 FAULTED 0 0 0 corrupted data
> da1 FAULTED 0 0 0 corrupted data
> da2 FAULTED 0 0 0 corrupted data
> da3 ONLINE 0 0 0
> [root at blackowl /usr/home/ike]#
>
>
>
>
> Anyhow, I have a few theories about what happened, and some subsequent
> tests.
>
> 1) The firewire bus could possibly be loosing track of which device is
> which- and confusing ZFS. In my daisy-chain setup, when one drive in
> the chain dies, (say, da2), and it's removed from the chain, it seems
> to become the previous drive (e.g. da1).
Further experiments:
I tried creating and torturing a setup with the drives connected to a
firewire hub, and had *slightly* different results- (still not good):
- Taking 1 drive offline, simply hung *any* process trying to access
files on the ZFS volumes.
+ HOWEVER, contrary to the daisy-chain setup, bringing the drive back
online FREED THE HUNG SYSTEM and all went on normally! Samba
transfers in progress however, failed- but a client still had it's SMB
mount, and all continued to operate normally after the drive was back
in.
So it simply seems this part of the problem is a issue (er, bug?)
relating to a given Firewire device physical ID, and how it is
presented to the kernel. Yet, one more test:
- I took 1 drive from the RAID-Z pool offline, then rebooted the
system. My expected result was that it would come back like a
degraded (yet operational) RAID-Z volme, but instead I got:
[root at blackowl /usr/home/ike]# zpool status
pool: Z
state: UNAVAIL
status: One or more devices could not be opened. There are insufficient
replicas for the pool to continue functioning.
action: Attach the missing device and online it using 'zpool online'.
see: http://www.sun.com/msg/ZFS-8000-D3
scrub: none requested
config:
NAME STATE READ WRITE CKSUM
Z UNAVAIL 0 0 0 insufficient replicas
raidz1 UNAVAIL 0 0 0 insufficient replicas
da0 FAULTED 0 0 0 corrupted data
da1 ONLINE 0 0 0
da2 FAULTED 0 0 0 corrupted data
da3 UNAVAIL 0 0 0 cannot open
[root at blackowl /usr/home/ike]#
So, hrmph.
>
> However, this may not exactly be the case- because when I remove the
> last drive from the chain, I still destroy the RAID-Z pool, and it
> still hangs as expected above.
>
> ZFS definately gets confused when I take an existing daisy-chain ZFS
> pool, and reboot the machine with all drives plugged into a firewire
> hub:
>
> [root at blackowl /usr/home/ike]# zpool status
> pool: Z
> state: UNAVAIL
> status: One or more devices could not be used because the label is
> missing
> or invalid. There are insufficient replicas for the pool to continue
> functioning.
> action: Destroy and re-create the pool from a backup source.
> see: http://www.sun.com/msg/ZFS-8000-5E
> scrub: none requested
> config:
>
> NAME STATE READ WRITE CKSUM
> Z UNAVAIL 0 0 0 insufficient replicas
> raidz1 UNAVAIL 0 0 0 insufficient replicas
> da0 FAULTED 0 0 0 corrupted data
> da1 FAULTED 0 0 0 corrupted data
> da2 FAULTED 0 0 0 corrupted data
> da3 FAULTED 0 0 0 corrupted data
> [root at blackowl /usr/home/ike]#
>
> As an aside, for those not familiar with using Firewire and Apple/OSX
> stuff: this is not the case on a Mac with HFS+ volumes, they always
> retain their identity regardless of how/where they are plugged in...
> Stupid simple.
>
>
> 2) The firewire driver may not send the right signal in/to the kernel
> when a drive fails or is removed, or perhaps ZFS may not pick it up
> correctly. This scenario would explain the hanging and subsequent
> data corruption, when the last drive is simply unplugged from the
> chain.
> (My expected results would be simply an 'UNAVAIL' ZFS device, and
> proper RAID-Z degraded functionality).
>
>
> --
> Resolution/Conclusions:
>
> 1) Don't bank on Firewire/ZFS using FreeBSD. Apple hasn't even sorted
> the issues out for OSX... :) (Anyone know about OpenSolaris/Firewire/
> ZFS? How's that for esoteric :)
>
> ZFS ROCKS HARD in my experience using SATA and well-worn (and even
> cheap) SATA controllers, so for the future, I'll be sticking to the
> MASSIVE amount of that hardware out there.
>
> 2) Should I email a FreeBSD dev list with this, and if so, which one?
> Firewire? ZFS?
>
> 3) Should I email a Sun/ZFS list with this, and if so, does anyone
> know that scene?
>
>
> Rocket-
> .ike
What follows here are tailings of syslogs, for anyone who's as
interested in this issue as I am:
##
zpool status from just after the catastrophic moment described above:
[root at blackowl /usr/home/ike]# zpool status
pool: Z
state: FAULTED
status: One or more devices could not be used because the label is
missing
or invalid. There are insufficient replicas for the pool to continue
functioning.
action: Destroy and re-create the pool from a backup source.
see: http://www.sun.com/msg/ZFS-8000-5E
scrub: none requested
config:
NAME STATE READ WRITE CKSUM
Z FAULTED 0 0 0 corrupted data
da0 FAULTED 0 0 0 corrupted data
da1 FAULTED 0 0 0 corrupted data
da2 FAULTED 0 0 0 corrupted data
da3 ONLINE 0 0 0
[root at blackowl /usr/home/ike]#
##
tailings from /var/log/messages, from the moments before/after the
event described above:
(fwohci0, firewire0 are the firewire parts, da0-3 are the firewire
disks)
Jun 27 19:00:01 blackowl newsyslog[1385]: logfile turned over due to
size>100K
Jun 27 19:00:39 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared/
Movies ; USER=root ; COMMAND=/sbin/zfs list -r -t snapshot -o
name,creation Z/shared
Jun 27 19:01:12 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared/
Movies ; USER=root ; COMMAND=/sbin/zfs destroy Z/
shared at shuffle_ouroboros_disks
Jun 27 19:08:01 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared ;
USER=root ; COMMAND=/bin/mkdir CaseSensitive.txt
Jun 27 19:08:31 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared ;
USER=root ; COMMAND=/bin/rm -rf CaseSensitive.txt
Jun 27 19:08:37 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared ;
USER=root ; COMMAND=/bin/mkdir CaseSensitive
Jun 27 19:08:46 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/shared ;
USER=root ; COMMAND=/usr/sbin/chown ike:ike CaseSensitive
Jun 27 19:09:05 blackowl kernel: fwohci0: too many cycle lost, no
cycle master presents?
Jun 27 19:12:15 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/
Jun 27 19:24:29 blackowl sshd[1466]: error: ssh_msg_send: write
Jun 27 19:24:41 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/
Jun 27 19:28:12 blackowl sshd[1475]: error: PAM: authentication error
for ike from 192.168.1.87
Jun 27 19:28:15 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/
Jun 27 19:31:14 blackowl sudo: ike : TTY=ttyp0 ; PWD=/Z/jails ;
USER=root ; COMMAND=/bin/rm -rf supercore.local/COPYRIGHT
supercore.local/bin supercore.local/boot supercore.local/dev
supercore.local/etc supercore.local/home supercore.local/lib
supercore.local/libexec supercore.local/media supercore.local/mnt
supercore.local/proc supercore.local/rescue supercore.local/root
supercore.local/sbin supercore.local/sys supercore.local/tmp
supercore.local/usr supercore.local/var
Jun 27 19:31:48 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/
Jun 27 21:04:23 blackowl ntpd[998]: kernel time sync enabled 6001
Jun 27 21:08:47 blackowl ntpd[998]: kernel time sync enabled 2001
Jun 27 22:03:39 blackowl sudo: ike : TTY=ttyp2 ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/bin/vi /etc/ntp.conf
Jun 27 22:40:25 blackowl sshd[1886]: error: PAM: authentication error
for ike from 192.168.1.75
Jun 27 22:40:25 blackowl last message repeated 2 times
Jun 27 22:40:31 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/shared/
Jun 27 22:54:45 blackowl sudo: ike : TTY=ttyp3 ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/bash
Jun 28 04:43:09 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/
Jun 28 04:45:31 blackowl sudo: ike : TTY=ttyp5 ; PWD=/xsdisk ;
USER=root ; COMMAND=/usr/local/bin/bash
Jun 28 12:01:57 blackowl kernel: fwohci0: BUS reset
Jun 28 12:01:57 blackowl kernel: fwohci0: node_id=0xc800ffc2, gen=2,
CYCLEMASTER mode
Jun 28 12:01:57 blackowl kernel: fwohci0: txd err=14 ack busy_X
Jun 28 12:01:57 blackowl kernel: sbp_orb_pointer_callback: xfer->resp
= 16
Jun 28 12:01:57 blackowl kernel: fwohci0: txd err= 0 No stat
Jun 28 12:01:57 blackowl kernel: sbp_orb_pointer_callback: xfer->resp
= 22
Jun 28 12:01:57 blackowl kernel: firewire0: 3 nodes, maxhop <= 2,
cable IRM = 2 (me)
Jun 28 12:01:57 blackowl kernel: firewire0: bus manager 2 (me)
Jun 28 12:23:14 blackowl sudo: ike : TTY=unknown ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/local/bin/rsync --server --sender -
vlogDtprz . /Z/shared/books/
Jun 28 14:57:51 blackowl sudo: ike : TTY=ttyp1 ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/usr/bin/killall -9 rsync
Jun 28 14:57:54 blackowl last message repeated 3 times
Jun 28 14:58:08 blackowl acpi: resumed at 20080628 14:58:08
Jun 28 14:59:31 blackowl syslogd: kernel boot file is /boot/kernel/
kernel
Jun 28 14:59:31 blackowl kernel: Copyright (c) 1992-2008 The FreeBSD
Project.
Jun 28 14:59:31 blackowl kernel: Copyright (c) 1979, 1980, 1983, 1986,
1988, 1989, 1991, 1992, 1993, 1994
Jun 28 14:59:31 blackowl kernel: The Regents of the University of
California. All rights reserved.
Jun 28 14:59:31 blackowl kernel: FreeBSD is a registered trademark of
The FreeBSD Foundation.
Jun 28 14:59:31 blackowl kernel: FreeBSD 7.0-RELEASE-p2 #4: Wed Jun 25
16:38:06 EDT 2008
Jun 28 14:59:31 blackowl kernel: root at blackowl.local:/usr/obj/usr/src/
sys/IKEKERNEL-JAN-2008
Jun 28 14:59:31 blackowl kernel: Timecounter "i8254" frequency 1193182
Hz quality 0
Jun 28 14:59:31 blackowl kernel: CPU: Genuine Intel(R) CPU
T2500 @ 2.00GHz (2000.00-MHz 686-class CPU)
Jun 28 14:59:31 blackowl kernel: Origin = "GenuineIntel" Id = 0x6e8
Stepping = 8
Jun 28 14:59:31 blackowl kernel:
Features
=
0xbfe9fbff
<
FPU
,VME
,DE
,PSE
,TSC
,MSR
,PAE
,MCE
,CX8
,APIC
,SEP
,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Jun 28 14:59:31 blackowl kernel:
Features2=0xc1a9<SSE3,MON,VMX,EST,TM2,xTPR,PDCM>
Jun 28 14:59:31 blackowl kernel: AMD Features=0x100000<NX>
Jun 28 14:59:31 blackowl kernel: Cores per package: 2
Jun 28 14:59:31 blackowl kernel: real memory = 2137915392 (2038 MB)
Jun 28 14:59:31 blackowl kernel: avail memory = 2082533376 (1986 MB)
Jun 28 14:59:31 blackowl kernel: ACPI APIC Table: <AOpen AWRDACPI>
Jun 28 14:59:31 blackowl kernel: FreeBSD/SMP: Multiprocessor System
Detected: 2 CPUs
Jun 28 14:59:31 blackowl kernel: cpu0 (BSP): APIC ID: 0
Jun 28 14:59:31 blackowl kernel: cpu1 (AP): APIC ID: 1
Jun 28 14:59:31 blackowl kernel: ioapic0: Changing APIC ID to 2
Jun 28 14:59:31 blackowl kernel: ioapic0 <Version 2.0> irqs 0-23 on
motherboard
Jun 28 14:59:31 blackowl kernel: kbd1 at kbdmux0
Jun 28 14:59:31 blackowl kernel: ath_hal: 0.9.20.3 (AR5210, AR5211,
AR5212, RF5111, RF5112, RF2413, RF5413)
Jun 28 14:59:31 blackowl kernel: hptrr: HPT RocketRAID controller
driver v1.1 (Jun 25 2008 16:37:50)
Jun 28 14:59:31 blackowl kernel: acpi0: <AOpen AWRDACPI> on motherboard
Jun 28 14:59:31 blackowl kernel: acpi0: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: acpi0: Power Button (fixed)
Jun 28 14:59:31 blackowl kernel: acpi0: reservation of 0, a0000 (3)
failed
Jun 28 14:59:31 blackowl kernel: acpi0: reservation of 100000,
7f5e0000 (3) failed
Jun 28 14:59:31 blackowl kernel: Timecounter "ACPI-fast" frequency
3579545 Hz quality 1000
Jun 28 14:59:31 blackowl kernel: acpi_timer0: <24-bit timer at
3.579545MHz> port 0x408-0x40b on acpi0
Jun 28 14:59:31 blackowl kernel: cpu0: <ACPI CPU> on acpi0
Jun 28 14:59:31 blackowl kernel: ACPI Error (psparse-0626): Method
parse/execution failed [\_PR_.CPU0._OSC] (Node 0xc52013a0),
AE_ALREADY_EXISTS
Jun 28 14:59:31 blackowl kernel: est0: <Enhanced SpeedStep Frequency
Control> on cpu0
Jun 28 14:59:31 blackowl kernel: p4tcc0: <CPU Frequency Thermal
Control> on cpu0
Jun 28 14:59:31 blackowl kernel: cpu1: <ACPI CPU> on acpi0
Jun 28 14:59:31 blackowl kernel: ACPI Error (psparse-0626): Method
parse/execution failed [\_PR_.CPU1._OSC] (Node 0xc5201300),
AE_ALREADY_EXISTS
Jun 28 14:59:31 blackowl kernel: est1: <Enhanced SpeedStep Frequency
Control> on cpu1
Jun 28 14:59:31 blackowl kernel: p4tcc1: <CPU Frequency Thermal
Control> on cpu1
Jun 28 14:59:31 blackowl kernel: acpi_button0: <Power Button> on acpi0
Jun 28 14:59:31 blackowl kernel: pcib0: <ACPI Host-PCI bridge> port
0xcf8-0xcff on acpi0
Jun 28 14:59:31 blackowl kernel: pci0: <ACPI PCI bus> on pcib0
Jun 28 14:59:31 blackowl kernel: vgapci0: <VGA-compatible display>
port 0xff00-0xff07 mem 0xfde80000-0xfdefffff,0xd0000000-0xdfffffff,
0xfdf80000-0xfdfbffff irq 16 at device 2.0 on pci0
Jun 28 14:59:31 blackowl kernel: agp0: <Intel 82945GM (945GM GMCH)
SVGA controller> on vgapci0
Jun 28 14:59:31 blackowl kernel: agp0: detected 7932k stolen memory
Jun 28 14:59:31 blackowl kernel: agp0: aperture size is 256M
Jun 28 14:59:31 blackowl kernel: vgapci1: <VGA-compatible display> mem
0xfdf00000-0xfdf7ffff at device 2.1 on pci0
Jun 28 14:59:31 blackowl kernel: pci0: <multimedia> at device 27.0 (no
driver attached)
Jun 28 14:59:31 blackowl kernel: pcib1: <ACPI PCI-PCI bridge> irq 16
at device 28.0 on pci0
Jun 28 14:59:31 blackowl kernel: pci1: <ACPI PCI bus> on pcib1
Jun 28 14:59:31 blackowl kernel: pcib2: <ACPI PCI-PCI bridge> irq 17
at device 28.1 on pci0
Jun 28 14:59:31 blackowl kernel: pci2: <ACPI PCI bus> on pcib2
Jun 28 14:59:31 blackowl kernel: em0: <Intel(R) PRO/1000 Network
Connection Version - 6.7.3> port 0xef00-0xef1f mem
0xfdde0000-0xfddfffff irq 17 at device 0.0 on pci2
Jun 28 14:59:31 blackowl kernel: em0: Using MSI interrupt
Jun 28 14:59:31 blackowl kernel: em0: Ethernet address:
00:01:80:66:90:7a
Jun 28 14:59:31 blackowl kernel: em0: [FILTER]
Jun 28 14:59:31 blackowl kernel: uhci0: <UHCI (generic) USB
controller> port 0xfe00-0xfe1f irq 23 at device 29.0 on pci0
Jun 28 14:59:31 blackowl kernel: uhci0: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: uhci0: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: usb0: <UHCI (generic) USB controller>
on uhci0
Jun 28 14:59:31 blackowl kernel: usb0: USB revision 1.0
Jun 28 14:59:31 blackowl kernel: uhub0: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb0
Jun 28 14:59:31 blackowl kernel: uhub0: 2 ports with 2 removable, self
powered
Jun 28 14:59:31 blackowl kernel: uhci1: <UHCI (generic) USB
controller> port 0xfd00-0xfd1f irq 19 at device 29.1 on pci0
Jun 28 14:59:31 blackowl kernel: uhci1: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: uhci1: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: usb1: <UHCI (generic) USB controller>
on uhci1
Jun 28 14:59:31 blackowl kernel: usb1: USB revision 1.0
Jun 28 14:59:31 blackowl kernel: uhub1: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb1
Jun 28 14:59:31 blackowl kernel: uhub1: 2 ports with 2 removable, self
powered
Jun 28 14:59:31 blackowl kernel: uhci2: <UHCI (generic) USB
controller> port 0xfc00-0xfc1f irq 18 at device 29.2 on pci0
Jun 28 14:59:31 blackowl kernel: uhci2: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: uhci2: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: usb2: <UHCI (generic) USB controller>
on uhci2
Jun 28 14:59:31 blackowl kernel: usb2: USB revision 1.0
Jun 28 14:59:31 blackowl kernel: uhub2: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb2
Jun 28 14:59:31 blackowl kernel: uhub2: 2 ports with 2 removable, self
powered
Jun 28 14:59:31 blackowl kernel: uhci3: <UHCI (generic) USB
controller> port 0xfb00-0xfb1f irq 16 at device 29.3 on pci0
Jun 28 14:59:31 blackowl kernel: uhci3: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: uhci3: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: usb3: <UHCI (generic) USB controller>
on uhci3
Jun 28 14:59:31 blackowl kernel: usb3: USB revision 1.0
Jun 28 14:59:31 blackowl kernel: uhub3: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb3
Jun 28 14:59:31 blackowl kernel: uhub3: 2 ports with 2 removable, self
powered
Jun 28 14:59:31 blackowl kernel: ehci0: <Intel 82801GB/R (ICH7) USB
2.0 controller> mem 0xfdfff000-0xfdfff3ff irq 23 at device 29.7 on pci0
Jun 28 14:59:31 blackowl kernel: ehci0: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: ehci0: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: usb4: EHCI version 1.0
Jun 28 14:59:31 blackowl kernel: usb4: companion controllers, 2 ports
each: usb0 usb1 usb2 usb3
Jun 28 14:59:31 blackowl kernel: usb4: <Intel 82801GB/R (ICH7) USB 2.0
controller> on ehci0
Jun 28 14:59:31 blackowl kernel: usb4: USB revision 2.0
Jun 28 14:59:31 blackowl kernel: uhub4: <Intel EHCI root hub, class
9/0, rev 2.00/1.00, addr 1> on usb4
Jun 28 14:59:31 blackowl kernel: uhub4: 8 ports with 8 removable, self
powered
Jun 28 14:59:31 blackowl kernel: pcib3: <ACPI PCI-PCI bridge> at
device 30.0 on pci0
Jun 28 14:59:31 blackowl kernel: pci3: <ACPI PCI bus> on pcib3
Jun 28 14:59:31 blackowl kernel: fwohci0: <Lucent FW322/323> mem
0xfdaff000-0xfdafffff irq 19 at device 3.0 on pci3
Jun 28 14:59:31 blackowl kernel: fwohci0: [FILTER]
Jun 28 14:59:31 blackowl kernel: fwohci0: OHCI version 1.0 (ROM=1)
Jun 28 14:59:31 blackowl kernel: fwohci0: No. of Isochronous channels
is 8.
Jun 28 14:59:31 blackowl kernel: fwohci0: EUI64 00:01:80:13:94:66:90:7a
Jun 28 14:59:31 blackowl kernel: fwohci0: Phy 1394a available S400, 2
ports.
Jun 28 14:59:31 blackowl kernel: fwohci0: Link S400, max_rec 2048 bytes.
Jun 28 14:59:31 blackowl kernel: firewire0: <IEEE1394(FireWire) bus>
on fwohci0
Jun 28 14:59:31 blackowl kernel: dcons_crom0: <dcons configuration
ROM> on firewire0
Jun 28 14:59:31 blackowl kernel: dcons_crom0: bus_addr 0x7d018000
Jun 28 14:59:31 blackowl kernel: fwe0: <Ethernet over FireWire> on
firewire0
Jun 28 14:59:31 blackowl kernel: if_fwe0: Fake Ethernet address:
02:01:80:66:90:7a
Jun 28 14:59:31 blackowl kernel: fwe0: Ethernet address:
02:01:80:66:90:7a
Jun 28 14:59:31 blackowl kernel: fwip0: <IP over FireWire> on firewire0
Jun 28 14:59:31 blackowl kernel: fwip0: Firewire address:
00:01:80:13:94:66:90:7a @ 0xfffe00000000, S400, maxrec 2048
Jun 28 14:59:31 blackowl kernel: sbp0: <SBP-2/SCSI over FireWire> on
firewire0
Jun 28 14:59:31 blackowl kernel: fwohci0: Initiate bus reset
Jun 28 14:59:31 blackowl kernel: fwohci0: BUS reset
Jun 28 14:59:31 blackowl kernel: fwohci0: node_id=0xc800ffc2, gen=1,
CYCLEMASTER mode
Jun 28 14:59:31 blackowl kernel: pci3: <processor> at device 4.0 (no
driver attached)
Jun 28 14:59:31 blackowl kernel: isab0: <PCI-ISA bridge> at device
31.0 on pci0
Jun 28 14:59:31 blackowl kernel: isa0: <ISA bus> on isab0
Jun 28 14:59:31 blackowl kernel: atapci0: <Intel ICH7 UDMA100
controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xfa00-0xfa0f at
device 31.1 on pci0
Jun 28 14:59:31 blackowl kernel: ata0: <ATA channel 0> on atapci0
Jun 28 14:59:31 blackowl kernel: ata0: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: ata1: <ATA channel 1> on atapci0
Jun 28 14:59:31 blackowl kernel: ata1: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: atapci1: <Intel ICH7M SATA300
controller> port
0xf900-0xf907,0xf800-0xf803,0xf700-0xf707,0xf600-0xf603,0xf500-0xf50f
mem 0xfdffe000-0xfdffe3ff irq 19 at device 31.2 on pci0
Jun 28 14:59:31 blackowl kernel: atapci1: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: ata2: <ATA channel 0> on atapci1
Jun 28 14:59:31 blackowl kernel: ata2: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: ata3: <ATA channel 1> on atapci1
Jun 28 14:59:31 blackowl kernel: ata3: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: pci0: <serial bus, SMBus> at device
31.3 (no driver attached)
Jun 28 14:59:31 blackowl kernel: pmtimer0 on isa0
Jun 28 14:59:31 blackowl kernel: atkbdc0: <Keyboard controller
(i8042)> at port 0x60,0x64 on isa0
Jun 28 14:59:31 blackowl kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0
Jun 28 14:59:31 blackowl kernel: kbd0 at atkbd0
Jun 28 14:59:31 blackowl kernel: atkbd0: [GIANT-LOCKED]
Jun 28 14:59:31 blackowl kernel: atkbd0: [ITHREAD]
Jun 28 14:59:31 blackowl kernel: ppc0: parallel port not found.
Jun 28 14:59:31 blackowl kernel: sc0: <System console> at flags 0x100
on isa0
Jun 28 14:59:31 blackowl kernel: sc0: VGA <16 virtual consoles,
flags=0x300>
Jun 28 14:59:31 blackowl kernel: sio0: configured irq 4 not in bitmap
of probed irqs 0
Jun 28 14:59:31 blackowl kernel: sio0: port may not be enabled
Jun 28 14:59:31 blackowl kernel: sio0: configured irq 4 not in bitmap
of probed irqs 0
Jun 28 14:59:31 blackowl kernel: sio0: port may not be enabled
Jun 28 14:59:31 blackowl kernel: sio0 at port 0x3f8-0x3ff irq 4 flags
0x10 on isa0
Jun 28 14:59:31 blackowl kernel: sio0: type 8250 or not responding
Jun 28 14:59:31 blackowl kernel: sio0: [FILTER]
Jun 28 14:59:31 blackowl kernel: sio1: configured irq 3 not in bitmap
of probed irqs 0
Jun 28 14:59:31 blackowl kernel: sio1: port may not be enabled
Jun 28 14:59:31 blackowl kernel: vga0: <Generic ISA VGA> at port
0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Jun 28 14:59:31 blackowl kernel: Timecounters tick every 1.000 msec
Jun 28 14:59:31 blackowl kernel: firewire0: 3 nodes, maxhop <= 2,
cable IRM = 2 (me)
Jun 28 14:59:31 blackowl kernel: firewire0: bus manager 2 (me)
Jun 28 14:59:31 blackowl kernel: hptrr: no controller detected.
Jun 28 14:59:31 blackowl kernel: acd0: CDRW <MATSHITACD-RW CW-8124/
DZ13> at ata0-slave UDMA33
Jun 28 14:59:31 blackowl kernel: ad4: 114473MB <TOSHIBA MK1234GSX
AH001A> at ata2-master SATA150
Jun 28 14:59:31 blackowl kernel: firewire0: New S400 device ID:
00303c02e0126d83
Jun 28 14:59:31 blackowl kernel: firewire0: New S400 device ID:
00303c02e0126d6b
Jun 28 14:59:31 blackowl kernel: SMP: AP CPU #1 Launched!
Jun 28 14:59:31 blackowl kernel: da0 at sbp0 bus 0 target 0 lun 0
Jun 28 14:59:31 blackowl kernel: da0: <WiebeTec ToughTech XE 00 0100>
Fixed Direct Access SCSI-4 device
Jun 28 14:59:31 blackowl kernel: da0: 50.000MB/s transfers
Jun 28 14:59:31 blackowl kernel: da0: 953869MB (1953525168 512 byte
sectors: 255H 63S/T 121601C)
Jun 28 14:59:31 blackowl kernel: da1 at sbp0 bus 0 target 1 lun 0
Jun 28 14:59:31 blackowl kernel: da1: <WiebeTec ToughTech XE 00 0100>
Fixed Direct Access SCSI-4 device
Jun 28 14:59:31 blackowl kernel: da1: 50.000MB/s transfers
Jun 28 14:59:31 blackowl kernel: da1: 953869MB (1953525168 512 byte
sectors: 255H 63S/T 121601C)
Jun 28 14:59:31 blackowl kernel: Trying to mount root from ufs:/dev/
ad4s1a
Jun 28 14:59:31 blackowl kernel: WARNING: / was not properly dismounted
Jun 28 14:59:31 blackowl kernel: WARNING: /tmp was not properly
dismounted
Jun 28 14:59:31 blackowl kernel: WARNING: /usr was not properly
dismounted
Jun 28 14:59:31 blackowl kernel: WARNING: /var was not properly
dismounted
Jun 28 14:59:31 blackowl kernel: /var: mount pending error: blocks 16
files 6
Jun 28 14:59:31 blackowl kernel: WARNING: /xsdisk was not properly
dismounted
Jun 28 14:59:31 blackowl kernel: WARNING: ZFS is considered to be an
experimental feature in FreeBSD.
Jun 28 14:59:31 blackowl kernel: ZFS filesystem version 6
Jun 28 14:59:31 blackowl kernel: ZFS storage pool version 6
Jun 28 14:59:31 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 14:59:31 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 14:59:31 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 14:59:31 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 14:59:31 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 14:59:31 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 14:59:31 blackowl savecore: no dumps found
Jun 28 14:59:33 blackowl kernel: en0: link state changed to UP
Jun 28 14:59:36 blackowl acpi: resumed at 20080628 14:59:36
Jun 28 14:59:37 blackowl syslogd: exiting on signal 15
Jun 28 15:01:03 blackowl syslogd: kernel boot file is /boot/kernel/
kernel
Jun 28 15:01:03 blackowl kernel: Copyright (c) 1992-2008 The FreeBSD
Project.
Jun 28 15:01:03 blackowl kernel: Copyright (c) 1979, 1980, 1983, 1986,
1988, 1989, 1991, 1992, 1993, 1994
Jun 28 15:01:03 blackowl kernel: The Regents of the University of
California. All rights reserved.
Jun 28 15:01:03 blackowl kernel: FreeBSD is a registered trademark of
The FreeBSD Foundation.
Jun 28 15:01:03 blackowl kernel: FreeBSD 7.0-RELEASE-p2 #4: Wed Jun 25
16:38:06 EDT 2008
Jun 28 15:01:03 blackowl kernel: root at blackowl.local:/usr/obj/usr/src/
sys/IKEKERNEL-JAN-2008
Jun 28 15:01:03 blackowl kernel: Timecounter "i8254" frequency 1193182
Hz quality 0
Jun 28 15:01:03 blackowl kernel: CPU: Genuine Intel(R) CPU
T2500 @ 2.00GHz (2000.00-MHz 686-class CPU)
Jun 28 15:01:03 blackowl kernel: Origin = "GenuineIntel" Id = 0x6e8
Stepping = 8
Jun 28 15:01:03 blackowl kernel:
Features
=
0xbfe9fbff
<
FPU
,VME
,DE
,PSE
,TSC
,MSR
,PAE
,MCE
,CX8
,APIC
,SEP
,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Jun 28 15:01:03 blackowl kernel:
Features2=0xc1a9<SSE3,MON,VMX,EST,TM2,xTPR,PDCM>
Jun 28 15:01:03 blackowl kernel: AMD Features=0x100000<NX>
Jun 28 15:01:03 blackowl kernel: Cores per package: 2
Jun 28 15:01:03 blackowl kernel: real memory = 2137915392 (2038 MB)
Jun 28 15:01:03 blackowl kernel: avail memory = 2082533376 (1986 MB)
Jun 28 15:01:03 blackowl kernel: ACPI APIC Table: <AOpen AWRDACPI>
Jun 28 15:01:03 blackowl kernel: FreeBSD/SMP: Multiprocessor System
Detected: 2 CPUs
Jun 28 15:01:03 blackowl kernel: cpu0 (BSP): APIC ID: 0
Jun 28 15:01:03 blackowl kernel: cpu1 (AP): APIC ID: 1
Jun 28 15:01:03 blackowl kernel: ioapic0: Changing APIC ID to 2
Jun 28 15:01:03 blackowl kernel: ioapic0 <Version 2.0> irqs 0-23 on
motherboard
Jun 28 15:01:03 blackowl kernel: kbd1 at kbdmux0
Jun 28 15:01:03 blackowl kernel: ath_hal: 0.9.20.3 (AR5210, AR5211,
AR5212, RF5111, RF5112, RF2413, RF5413)
Jun 28 15:01:03 blackowl kernel: hptrr: HPT RocketRAID controller
driver v1.1 (Jun 25 2008 16:37:50)
Jun 28 15:01:03 blackowl kernel: acpi0: <AOpen AWRDACPI> on motherboard
Jun 28 15:01:03 blackowl kernel: acpi0: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: acpi0: Power Button (fixed)
Jun 28 15:01:03 blackowl kernel: acpi0: reservation of 0, a0000 (3)
failed
Jun 28 15:01:03 blackowl kernel: acpi0: reservation of 100000,
7f5e0000 (3) failed
Jun 28 15:01:03 blackowl kernel: Timecounter "ACPI-fast" frequency
3579545 Hz quality 1000
Jun 28 15:01:03 blackowl kernel: acpi_timer0: <24-bit timer at
3.579545MHz> port 0x408-0x40b on acpi0
Jun 28 15:01:03 blackowl kernel: cpu0: <ACPI CPU> on acpi0
Jun 28 15:01:03 blackowl kernel: ACPI Error (psparse-0626): Method
parse/execution failed [\_PR_.CPU0._OSC] (Node 0xc52013a0),
AE_ALREADY_EXISTS
Jun 28 15:01:03 blackowl kernel: est0: <Enhanced SpeedStep Frequency
Control> on cpu0
Jun 28 15:01:03 blackowl kernel: p4tcc0: <CPU Frequency Thermal
Control> on cpu0
Jun 28 15:01:03 blackowl kernel: cpu1: <ACPI CPU> on acpi0
Jun 28 15:01:03 blackowl kernel: ACPI Error (psparse-0626): Method
parse/execution failed [\_PR_.CPU1._OSC] (Node 0xc5201300),
AE_ALREADY_EXISTS
Jun 28 15:01:03 blackowl kernel: est1: <Enhanced SpeedStep Frequency
Control> on cpu1
Jun 28 15:01:03 blackowl kernel: p4tcc1: <CPU Frequency Thermal
Control> on cpu1
Jun 28 15:01:03 blackowl kernel: acpi_button0: <Power Button> on acpi0
Jun 28 15:01:03 blackowl kernel: pcib0: <ACPI Host-PCI bridge> port
0xcf8-0xcff on acpi0
Jun 28 15:01:03 blackowl kernel: pci0: <ACPI PCI bus> on pcib0
Jun 28 15:01:03 blackowl kernel: vgapci0: <VGA-compatible display>
port 0xff00-0xff07 mem 0xfde80000-0xfdefffff,0xd0000000-0xdfffffff,
0xfdf80000-0xfdfbffff irq 16 at device 2.0 on pci0
Jun 28 15:01:03 blackowl kernel: agp0: <Intel 82945GM (945GM GMCH)
SVGA controller> on vgapci0
Jun 28 15:01:03 blackowl kernel: agp0: detected 7932k stolen memory
Jun 28 15:01:03 blackowl kernel: agp0: aperture size is 256M
Jun 28 15:01:03 blackowl kernel: vgapci1: <VGA-compatible display> mem
0xfdf00000-0xfdf7ffff at device 2.1 on pci0
Jun 28 15:01:03 blackowl kernel: pci0: <multimedia> at device 27.0 (no
driver attached)
Jun 28 15:01:03 blackowl kernel: pcib1: <ACPI PCI-PCI bridge> irq 16
at device 28.0 on pci0
Jun 28 15:01:03 blackowl kernel: pci1: <ACPI PCI bus> on pcib1
Jun 28 15:01:03 blackowl kernel: pcib2: <ACPI PCI-PCI bridge> irq 17
at device 28.1 on pci0
Jun 28 15:01:03 blackowl kernel: pci2: <ACPI PCI bus> on pcib2
Jun 28 15:01:03 blackowl kernel: em0: <Intel(R) PRO/1000 Network
Connection Version - 6.7.3> port 0xef00-0xef1f mem
0xfdde0000-0xfddfffff irq 17 at device 0.0 on pci2
Jun 28 15:01:03 blackowl kernel: em0: Using MSI interrupt
Jun 28 15:01:03 blackowl kernel: em0: Ethernet address:
00:01:80:66:90:7a
Jun 28 15:01:03 blackowl kernel: em0: [FILTER]
Jun 28 15:01:03 blackowl kernel: uhci0: <UHCI (generic) USB
controller> port 0xfe00-0xfe1f irq 23 at device 29.0 on pci0
Jun 28 15:01:03 blackowl kernel: uhci0: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: uhci0: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: usb0: <UHCI (generic) USB controller>
on uhci0
Jun 28 15:01:03 blackowl kernel: usb0: USB revision 1.0
Jun 28 15:01:03 blackowl kernel: uhub0: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb0
Jun 28 15:01:03 blackowl kernel: uhub0: 2 ports with 2 removable, self
powered
Jun 28 15:01:03 blackowl kernel: uhci1: <UHCI (generic) USB
controller> port 0xfd00-0xfd1f irq 19 at device 29.1 on pci0
Jun 28 15:01:03 blackowl kernel: uhci1: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: uhci1: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: usb1: <UHCI (generic) USB controller>
on uhci1
Jun 28 15:01:03 blackowl kernel: usb1: USB revision 1.0
Jun 28 15:01:03 blackowl kernel: uhub1: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb1
Jun 28 15:01:03 blackowl kernel: uhub1: 2 ports with 2 removable, self
powered
Jun 28 15:01:03 blackowl kernel: uhci2: <UHCI (generic) USB
controller> port 0xfc00-0xfc1f irq 18 at device 29.2 on pci0
Jun 28 15:01:03 blackowl kernel: uhci2: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: uhci2: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: usb2: <UHCI (generic) USB controller>
on uhci2
Jun 28 15:01:03 blackowl kernel: usb2: USB revision 1.0
Jun 28 15:01:03 blackowl kernel: uhub2: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb2
Jun 28 15:01:03 blackowl kernel: uhub2: 2 ports with 2 removable, self
powered
Jun 28 15:01:03 blackowl kernel: uhci3: <UHCI (generic) USB
controller> port 0xfb00-0xfb1f irq 16 at device 29.3 on pci0
Jun 28 15:01:03 blackowl kernel: uhci3: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: uhci3: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: usb3: <UHCI (generic) USB controller>
on uhci3
Jun 28 15:01:03 blackowl kernel: usb3: USB revision 1.0
Jun 28 15:01:03 blackowl kernel: uhub3: <Intel UHCI root hub, class
9/0, rev 1.00/1.00, addr 1> on usb3
Jun 28 15:01:03 blackowl kernel: uhub3: 2 ports with 2 removable, self
powered
Jun 28 15:01:03 blackowl kernel: ehci0: <Intel 82801GB/R (ICH7) USB
2.0 controller> mem 0xfdfff000-0xfdfff3ff irq 23 at device 29.7 on pci0
Jun 28 15:01:03 blackowl kernel: ehci0: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: ehci0: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: usb4: EHCI version 1.0
Jun 28 15:01:03 blackowl kernel: usb4: companion controllers, 2 ports
each: usb0 usb1 usb2 usb3
Jun 28 15:01:03 blackowl kernel: usb4: <Intel 82801GB/R (ICH7) USB 2.0
controller> on ehci0
Jun 28 15:01:03 blackowl kernel: usb4: USB revision 2.0
Jun 28 15:01:03 blackowl kernel: uhub4: <Intel EHCI root hub, class
9/0, rev 2.00/1.00, addr 1> on usb4
Jun 28 15:01:03 blackowl kernel: uhub4: 8 ports with 8 removable, self
powered
Jun 28 15:01:03 blackowl kernel: pcib3: <ACPI PCI-PCI bridge> at
device 30.0 on pci0
Jun 28 15:01:03 blackowl kernel: pci3: <ACPI PCI bus> on pcib3
Jun 28 15:01:03 blackowl kernel: fwohci0: <Lucent FW322/323> mem
0xfdaff000-0xfdafffff irq 19 at device 3.0 on pci3
Jun 28 15:01:03 blackowl kernel: fwohci0: [FILTER]
Jun 28 15:01:03 blackowl kernel: fwohci0: OHCI version 1.0 (ROM=1)
Jun 28 15:01:03 blackowl kernel: fwohci0: No. of Isochronous channels
is 8.
Jun 28 15:01:03 blackowl kernel: fwohci0: EUI64 00:01:80:13:94:66:90:7a
Jun 28 15:01:03 blackowl kernel: fwohci0: Phy 1394a available S400, 2
ports.
Jun 28 15:01:03 blackowl kernel: fwohci0: Link S400, max_rec 2048 bytes.
Jun 28 15:01:03 blackowl kernel: firewire0: <IEEE1394(FireWire) bus>
on fwohci0
Jun 28 15:01:03 blackowl kernel: dcons_crom0: <dcons configuration
ROM> on firewire0
Jun 28 15:01:03 blackowl kernel: dcons_crom0: bus_addr 0x7d018000
Jun 28 15:01:03 blackowl kernel: fwe0: <Ethernet over FireWire> on
firewire0
Jun 28 15:01:03 blackowl kernel: if_fwe0: Fake Ethernet address:
02:01:80:66:90:7a
Jun 28 15:01:03 blackowl kernel: fwe0: Ethernet address:
02:01:80:66:90:7a
Jun 28 15:01:03 blackowl kernel: fwip0: <IP over FireWire> on firewire0
Jun 28 15:01:03 blackowl kernel: fwip0: Firewire address:
00:01:80:13:94:66:90:7a @ 0xfffe00000000, S400, maxrec 2048
Jun 28 15:01:03 blackowl kernel: sbp0: <SBP-2/SCSI over FireWire> on
firewire0
Jun 28 15:01:03 blackowl kernel: fwohci0: Initiate bus reset
Jun 28 15:01:03 blackowl kernel: fwohci0: BUS reset
Jun 28 15:01:03 blackowl kernel: fwohci0: node_id=0xc800ffc2, gen=1,
CYCLEMASTER mode
Jun 28 15:01:03 blackowl kernel: pci3: <processor> at device 4.0 (no
driver attached)
Jun 28 15:01:03 blackowl kernel: isab0: <PCI-ISA bridge> at device
31.0 on pci0
Jun 28 15:01:03 blackowl kernel: isa0: <ISA bus> on isab0
Jun 28 15:01:03 blackowl kernel: atapci0: <Intel ICH7 UDMA100
controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xfa00-0xfa0f at
device 31.1 on pci0
Jun 28 15:01:03 blackowl kernel: ata0: <ATA channel 0> on atapci0
Jun 28 15:01:03 blackowl kernel: ata0: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: ata1: <ATA channel 1> on atapci0
Jun 28 15:01:03 blackowl kernel: ata1: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: atapci1: <Intel ICH7M SATA300
controller> port
0xf900-0xf907,0xf800-0xf803,0xf700-0xf707,0xf600-0xf603,0xf500-0xf50f
mem 0xfdffe000-0xfdffe3ff irq 19 at device 31.2 on pci0
Jun 28 15:01:03 blackowl kernel: atapci1: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: ata2: <ATA channel 0> on atapci1
Jun 28 15:01:03 blackowl kernel: ata2: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: ata3: <ATA channel 1> on atapci1
Jun 28 15:01:03 blackowl kernel: ata3: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: pci0: <serial bus, SMBus> at device
31.3 (no driver attached)
Jun 28 15:01:03 blackowl kernel: pmtimer0 on isa0
Jun 28 15:01:03 blackowl kernel: atkbdc0: <Keyboard controller
(i8042)> at port 0x60,0x64 on isa0
Jun 28 15:01:03 blackowl kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0
Jun 28 15:01:03 blackowl kernel: kbd0 at atkbd0
Jun 28 15:01:03 blackowl kernel: atkbd0: [GIANT-LOCKED]
Jun 28 15:01:03 blackowl kernel: atkbd0: [ITHREAD]
Jun 28 15:01:03 blackowl kernel: ppc0: parallel port not found.
Jun 28 15:01:03 blackowl kernel: sc0: <System console> at flags 0x100
on isa0
Jun 28 15:01:03 blackowl kernel: sc0: VGA <16 virtual consoles,
flags=0x300>
Jun 28 15:01:03 blackowl kernel: sio0: configured irq 4 not in bitmap
of probed irqs 0
Jun 28 15:01:03 blackowl kernel: sio0: port may not be enabled
Jun 28 15:01:03 blackowl kernel: sio0: configured irq 4 not in bitmap
of probed irqs 0
Jun 28 15:01:03 blackowl kernel: sio0: port may not be enabled
Jun 28 15:01:03 blackowl kernel: sio0 at port 0x3f8-0x3ff irq 4 flags
0x10 on isa0
Jun 28 15:01:03 blackowl kernel: sio0: type 8250 or not responding
Jun 28 15:01:03 blackowl kernel: sio0: [FILTER]
Jun 28 15:01:03 blackowl kernel: sio1: configured irq 3 not in bitmap
of probed irqs 0
Jun 28 15:01:03 blackowl kernel: sio1: port may not be enabled
Jun 28 15:01:03 blackowl kernel: vga0: <Generic ISA VGA> at port
0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Jun 28 15:01:03 blackowl kernel: Timecounters tick every 1.000 msec
Jun 28 15:01:03 blackowl kernel: firewire0: 3 nodes, maxhop <= 2,
cable IRM = 2 (me)
Jun 28 15:01:03 blackowl kernel: firewire0: bus manager 2 (me)
Jun 28 15:01:03 blackowl kernel: hptrr: no controller detected.
Jun 28 15:01:03 blackowl kernel: acd0: CDRW <MATSHITACD-RW CW-8124/
DZ13> at ata0-slave UDMA33
Jun 28 15:01:03 blackowl kernel: ad4: 114473MB <TOSHIBA MK1234GSX
AH001A> at ata2-master SATA150
Jun 28 15:01:03 blackowl kernel: firewire0: New S400 device ID:
00303c02e0126d83
Jun 28 15:01:03 blackowl kernel: firewire0: New S400 device ID:
00303c02e0126d6b
Jun 28 15:01:03 blackowl kernel: SMP: AP CPU #1 Launched!
Jun 28 15:01:03 blackowl kernel: da0 at sbp0 bus 0 target 0 lun 0
Jun 28 15:01:03 blackowl kernel: da0: <WiebeTec ToughTech XE 00 0100>
Fixed Direct Access SCSI-4 device
Jun 28 15:01:03 blackowl kernel: da0: 50.000MB/s transfers
Jun 28 15:01:03 blackowl kernel: da0: 953869MB (1953525168 512 byte
sectors: 255H 63S/T 121601C)
Jun 28 15:01:03 blackowl kernel: da1 at sbp0 bus 0 target 1 lun 0
Jun 28 15:01:03 blackowl kernel: da1: <WiebeTec ToughTech XE 00 0100>
Fixed Direct Access SCSI-4 device
Jun 28 15:01:03 blackowl kernel: da1: 50.000MB/s transfers
Jun 28 15:01:03 blackowl kernel: da1: 953869MB (1953525168 512 byte
sectors: 255H 63S/T 121601C)
Jun 28 15:01:03 blackowl kernel: Trying to mount root from ufs:/dev/
ad4s1a
Jun 28 15:01:03 blackowl kernel: WARNING: / was not properly dismounted
Jun 28 15:01:03 blackowl kernel: WARNING: /tmp was not properly
dismounted
Jun 28 15:01:03 blackowl kernel: WARNING: /usr was not properly
dismounted
Jun 28 15:01:03 blackowl kernel: WARNING: /var was not properly
dismounted
Jun 28 15:01:03 blackowl kernel: /var: mount pending error: blocks 48
files 12
Jun 28 15:01:03 blackowl kernel: WARNING: /xsdisk was not properly
dismounted
Jun 28 15:01:03 blackowl kernel: WARNING: ZFS is considered to be an
experimental feature in FreeBSD.
Jun 28 15:01:03 blackowl kernel: ZFS filesystem version 6
Jun 28 15:01:03 blackowl kernel: ZFS storage pool version 6
Jun 28 15:01:03 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 15:01:03 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 15:01:03 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 15:01:03 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 15:01:03 blackowl kernel: link_elf: symbol atm_event undefined
Jun 28 15:01:03 blackowl kernel: KLD if_en.ko: depends on utopia - not
available
Jun 28 15:01:03 blackowl savecore: no dumps found
Jun 28 15:01:05 blackowl kernel: en0: link state changed to UP
Jun 28 15:01:15 blackowl ntpd[1064]: ntpd 4.2.0-a Wed Jun 25 15:59:39
EDT 2008 (1)
Jun 28 15:01:32 blackowl smbd[1204]: [2008/06/28 15:01:32, 0,
pid=1204] smbd/service.c:make_connection_snum(1003)
Jun 28 15:01:32 blackowl smbd[1204]: '/Z/shared' does not exist or
permission denied when connecting to [BIGSHARE] Error was No such file
or directory
Jun 28 15:01:42 blackowl sudo: ike : TTY=ttyp0 ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/sbin/zpool status
Jun 28 15:02:19 blackowl fsck: /dev/ad4s1e: 10 files, 7 used, 253808
free (40 frags, 31721 blocks, 0.0% fragmentation)
Jun 28 15:02:36 blackowl kernel: fwohci0: BUS reset
Jun 28 15:02:36 blackowl kernel: fwohci0: node_id=0xc800ffc1, gen=2,
CYCLEMASTER mode
Jun 28 15:02:36 blackowl kernel: firewire0: 2 nodes, maxhop <= 1,
cable IRM = 1 (me)
Jun 28 15:02:36 blackowl kernel: firewire0: bus manager 1 (me)
Jun 28 15:02:36 blackowl kernel: fwohci0: BUS reset
Jun 28 15:02:36 blackowl kernel: fwohci0: node_id=0xc800ffc0, gen=3,
CYCLEMASTER mode
Jun 28 15:02:36 blackowl kernel: firewire0: 1 nodes, maxhop <= 0,
cable IRM = 0 (me)
Jun 28 15:02:36 blackowl kernel: firewire0: bus manager 0 (me)
Jun 28 15:05:18 blackowl fsck: /dev/ad4s1f: 245109 files, 14348367
used, 5963031 free (80527 frags, 735313 blocks, 0.4% fragmentation)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=70668
OWNER=smmsp MODE=100600
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=50 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=329999
OWNER=root MODE=100600
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=3 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=330000
OWNER=root MODE=140666
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=0 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=330001
OWNER=root MODE=140600
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=0 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=330004
OWNER=root MODE=100644
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=5 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: UNREF FILE I=330005
OWNER=root MODE=100600
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: SIZE=79 MTIME=Jun 27 18:20
2008 (CLEARED)
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: Reclaimed: 0 directories,
18 files, 13 fragments
Jun 28 15:05:32 blackowl fsck: /dev/ad4s1d: 6514 files, 690270 used,
818908 free (948 frags, 102245 blocks, 0.1% fragmentation)
Jun 28 15:08:24 blackowl fsck: /dev/ad4s1g: 161522 files, 927711 used,
31501374 free (41102 frags, 3932534 blocks, 0.1% fragmentation)
Jun 28 15:09:53 blackowl ntpd[1064]: kernel time sync disabled 2041
Jun 28 15:14:09 blackowl ntpd[1064]: kernel time sync enabled 2001
Jun 28 19:41:39 blackowl ntpd[1064]: kernel time sync enabled 6001
Jun 28 20:32:54 blackowl ntpd[1064]: kernel time sync enabled 2001
Jun 28 21:51:12 blackowl sudo: ike : TTY=ttyp1 ; PWD=/usr/home/
ike ; USER=root ; COMMAND=/sbin/zpool status
##
Here's a dmesg from the system, basically the kernel is GENERIC with
PF, HTTP acceleration stuff, and SMP compiled in- it's otherwise stock:
##
Copyright (c) 1992-2008 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 7.0-RELEASE-p2 #4: Wed Jun 25 16:38:06 EDT 2008
root at blackowl.local:/usr/obj/usr/src/sys/IKEKERNEL-JAN-2008
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Genuine Intel(R) CPU T2500 @ 2.00GHz (2000.00-MHz 686-
class CPU)
Origin = "GenuineIntel" Id = 0x6e8 Stepping = 8
Features
=
0xbfe9fbff
<
FPU
,VME
,DE
,PSE
,TSC
,MSR
,PAE
,MCE
,CX8
,APIC
,SEP
,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Features2=0xc1a9<SSE3,MON,VMX,EST,TM2,xTPR,PDCM>
AMD Features=0x100000<NX>
Cores per package: 2
real memory = 2137915392 (2038 MB)
avail memory = 2082533376 (1986 MB)
ACPI APIC Table: <AOpen AWRDACPI>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
cpu0 (BSP): APIC ID: 0
cpu1 (AP): APIC ID: 1
ioapic0: Changing APIC ID to 2
ioapic0 <Version 2.0> irqs 0-23 on motherboard
kbd1 at kbdmux0
ath_hal: 0.9.20.3 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413,
RF5413)
hptrr: HPT RocketRAID controller driver v1.1 (Jun 25 2008 16:37:50)
acpi0: <AOpen AWRDACPI> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7f5e0000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
cpu0: <ACPI CPU> on acpi0
ACPI Error (psparse-0626): Method parse/execution failed [\
\_PR_.CPU0._OSC] (Node 0xc52013a0), AE_ALREADY_EXISTS
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
cpu1: <ACPI CPU> on acpi0
ACPI Error (psparse-0626): Method parse/execution failed [\
\_PR_.CPU1._OSC] (Node 0xc5201300), AE_ALREADY_EXISTS
est1: <Enhanced SpeedStep Frequency Control> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0xff00-0xff07 mem
0xfde80000-0xfdefffff,0xd0000000-0xdfffffff,0xfdf80000-0xfdfbffff irq
16 at device 2.0 on pci0
agp0: <Intel 82945GM (945GM GMCH) SVGA controller> on vgapci0
agp0: detected 7932k stolen memory
agp0: aperture size is 256M
vgapci1: <VGA-compatible display> mem 0xfdf00000-0xfdf7ffff at device
2.1 on pci0
pci0: <multimedia> at device 27.0 (no driver attached)
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
pci1: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.1 on pci0
pci2: <ACPI PCI bus> on pcib2
em0: <Intel(R) PRO/1000 Network Connection Version - 6.7.3> port
0xef00-0xef1f mem 0xfdde0000-0xfddfffff irq 17 at device 0.0 on pci2
em0: Using MSI interrupt
em0: Ethernet address: 00:01:80:66:90:7a
em0: [FILTER]
uhci0: <UHCI (generic) USB controller> port 0xfe00-0xfe1f irq 23 at
device 29.0 on pci0
uhci0: [GIANT-LOCKED]
uhci0: [ITHREAD]
usb0: <UHCI (generic) USB controller> on uhci0
usb0: USB revision 1.0
uhub0: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb0
uhub0: 2 ports with 2 removable, self powered
uhci1: <UHCI (generic) USB controller> port 0xfd00-0xfd1f irq 19 at
device 29.1 on pci0
uhci1: [GIANT-LOCKED]
uhci1: [ITHREAD]
usb1: <UHCI (generic) USB controller> on uhci1
usb1: USB revision 1.0
uhub1: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb1
uhub1: 2 ports with 2 removable, self powered
uhci2: <UHCI (generic) USB controller> port 0xfc00-0xfc1f irq 18 at
device 29.2 on pci0
uhci2: [GIANT-LOCKED]
uhci2: [ITHREAD]
usb2: <UHCI (generic) USB controller> on uhci2
usb2: USB revision 1.0
uhub2: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb2
uhub2: 2 ports with 2 removable, self powered
uhci3: <UHCI (generic) USB controller> port 0xfb00-0xfb1f irq 16 at
device 29.3 on pci0
uhci3: [GIANT-LOCKED]
uhci3: [ITHREAD]
usb3: <UHCI (generic) USB controller> on uhci3
usb3: USB revision 1.0
uhub3: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb3
uhub3: 2 ports with 2 removable, self powered
ehci0: <Intel 82801GB/R (ICH7) USB 2.0 controller> mem
0xfdfff000-0xfdfff3ff irq 23 at device 29.7 on pci0
ehci0: [GIANT-LOCKED]
ehci0: [ITHREAD]
usb4: EHCI version 1.0
usb4: companion controllers, 2 ports each: usb0 usb1 usb2 usb3
usb4: <Intel 82801GB/R (ICH7) USB 2.0 controller> on ehci0
usb4: USB revision 2.0
uhub4: <Intel EHCI root hub, class 9/0, rev 2.00/1.00, addr 1> on usb4
uhub4: 8 ports with 8 removable, self powered
pcib3: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci3: <ACPI PCI bus> on pcib3
fwohci0: <Lucent FW322/323> mem 0xfdaff000-0xfdafffff irq 19 at device
3.0 on pci3
fwohci0: [FILTER]
fwohci0: OHCI version 1.0 (ROM=1)
fwohci0: No. of Isochronous channels is 8.
fwohci0: EUI64 00:01:80:13:94:66:90:7a
fwohci0: Phy 1394a available S400, 2 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
dcons_crom0: <dcons configuration ROM> on firewire0
dcons_crom0: bus_addr 0x7d018000
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 02:01:80:66:90:7a
fwe0: Ethernet address: 02:01:80:66:90:7a
fwip0: <IP over FireWire> on firewire0
fwip0: Firewire address: 00:01:80:13:94:66:90:7a @ 0xfffe00000000,
S400, maxrec 2048
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwohci0: Initiate bus reset
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc4, gen=1, CYCLEMASTER mode
pci3: <processor> at device 4.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH7 UDMA100 controller> port
0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xfa00-0xfa0f at device 31.1 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
ata1: <ATA channel 1> on atapci0
ata1: [ITHREAD]
atapci1: <Intel ICH7M SATA300 controller> port
0xf900-0xf907,0xf800-0xf803,0xf700-0xf707,0xf600-0xf603,0xf500-0xf50f
mem 0xfdffe000-0xfdffe3ff irq 19 at device 31.2 on pci0
atapci1: [ITHREAD]
ata2: <ATA channel 0> on atapci1
ata2: [ITHREAD]
ata3: <ATA channel 1> on atapci1
ata3: [ITHREAD]
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
pmtimer0 on isa0
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
ppc0: parallel port not found.
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sio0: configured irq 4 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0: configured irq 4 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 8250 or not responding
sio0: [FILTER]
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on
isa0
Timecounters tick every 1.000 msec
firewire0: 5 nodes, maxhop <= 4, cable IRM = 4 (me)
firewire0: bus manager 4 (me)
hptrr: no controller detected.
acd0: CDRW <MATSHITACD-RW CW-8124/DZ13> at ata0-slave UDMA33
ad4: 114473MB <TOSHIBA MK1234GSX AH001A> at ata2-master SATA150
firewire0: New S400 device ID:00303c02e0126d83
firewire0: New S400 device ID:00303c02e0126d35
firewire0: New S400 device ID:00303c020012d1c4
da0 at sbp0 bus 0 target 0 lun 0
da0: <WiebeTec ToughTech XE 0100> Fixed Direct Access SCSI-4 device
da0: 50.000MB/s transfers
da0: 953869MB (1953525168 512 byte sectors: 255H 63S/T 121601C)
da1 at sbp0 bus 0 target 1 lun 0
da1: <WiebeTec ToughTech XE 00 0100> Fixed Direct Access SCSI-4 device
da1: 50.000MB/s transfers
da1: 953869MB (1953525168 512 byte sectors: 255H 63S/T 121601C)
da2 at sbp0 bus 0 target 2 lun 0
da2: <WiebeTec ToughTech XE 00 0100> Fixed Direct Access SCSI-4 device
da2: 50.000MB/s transfers
da2: 953869MB (1953525168 512 byte sectors: 255H 63S/T 121601C)
SMP: AP CPU #1 Launched!
Trying to mount root from ufs:/dev/ad4s1a
WARNING: / was not properly dismounted
WARNING: /tmp was not properly dismounted
WARNING: /usr was not properly dismounted
WARNING: /var was not properly dismounted
WARNING: /xsdisk was not properly dismounted
WARNING: ZFS is considered to be an experimental feature in FreeBSD.
ZFS filesystem version 6
ZFS storage pool version 6
link_elf: symbol atm_event undefined
KLD if_en.ko: depends on utopia - not available
link_elf: symbol atm_event undefined
KLD if_en.ko: depends on utopia - not available
link_elf: symbol atm_event undefined
KLD if_en.ko: depends on utopia - not available
en0: link state changed to UP
-- ikenote --
Let me point out one sidenote, for sanity sake, from the dmesg:
WARNING: ZFS is considered to be an experimental feature in FreeBSD.
But it's so good, I look foreword to the day that warning is gone :)
Rocket-
.ike
More information about the talk
mailing list