Troubleshooting Stability Problems in Freenas 8.2

Status
Not open for further replies.

alfista

Cadet
Joined
Sep 6, 2012
Messages
6
Hi All,

I have a small form factor Atom freenas box. It has two gigs of ram, dual 1.5tb hard drives, and an external 2tb USB drive. It boots from a CF card and is running freeness 8.2.

I recently went through the pain of migrating off of ZFS to UFS due to memory limitations (2gb is the max) and I have the two internal drives mirrored.

I am mainly using this machine to act as a time machine server, as well as serve software ISOs, etc.

I am experiencing random crashes when I am not around to witness them, and I'm finding that even a clean shutdown will result in a long startup routine (it has been over 10 minutes now and I shut down before the hurricane).

What is the best way to get to the bottom of my problems? At what point do I consider the hardware?

Thanks,

Jason
Freenas N00b
 

cyberjock

Inactive Account
Joined
Mar 25, 2012
Messages
19,526
What CPU do you have? Some of the 1st generation Atoms just flat out suck badly. I'm using an Atom for my pfsense server, and it works great. But it is the latest generation Atom CPU too. I'm thinking you have one of the oldest Atoms because you are limited to 2GB. I remember reading about some of the first Atoms being very limited in their use because they could use so little RAM.

Some ideas off the top of my head:

1. Try booting up without the 2TB USB drive. I know USB has some performance issues, but I don't think that would account for a 10 minute bootup time.
2. Have you tried upgrading to FreeNAS 8.3? Perhaps the issue will be resolved with the upgrade.
3. Is there any particular step of the bootup that takes a very long time?
4. Can you post the output of "dmesg -a"?
 

alfista

Cadet
Joined
Sep 6, 2012
Messages
6
Hi noobsauce80 - thanks for the quick reply.

It took about 30 minutes before I was able to connect to the webgui.

Hostname mininas.local
Build FreeNAS-8.2.0-RELEASE-p1-x86 (r11950)
Platform Intel(R) Atom(TM) CPU 230 @ 1.60GHz
Memory 2027MB
System Time Wed Oct 31 07:15:35 EDT 2012
Uptime 7:15AM up 48 mins, 0 users
Load Average 0.41, 0.24, 0.23
Connected through mininas.local

here is the output of dmesg -a

It looks like the fs thinks it was improperly dismounted even though I shut the system down from the webgui.

Having no other system to compare to, I'm not sure what errors in this output are truly critical so I look forward to your analysis.

Cheers!

Copyright (c) 1992-2011 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 8.2-RELEASE-p9 #0: Thu Jul 19 16:02:19 PDT 2012
root@build.ixsystems.com:/build/home/jpaetzel/8.2.0/os-base/i386/i386/build/home/jpaetzel/8.2.0/FreeBSD/src/sys/FREENAS.i386 i386
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel(R) Atom(TM) CPU 230 @ 1.60GHz (1596.01-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x106c2 Family = 6 Model = 1c Stepping = 2
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=0x40e31d<SSE3,DTES64,MON,DS_CPL,TM2,SSSE3,CX16,xTPR,PDCM,MOVBE>
AMD Features=0x20100000<NX,LM>
AMD Features2=0x1<LAHF>
TSC: P-state invariant
real memory = 2147483648 (2048 MB)
avail memory = 2080681984 (1984 MB)
ACPI APIC Table: <111708 APIC1326>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 1 core(s) x 2 HTT threads
cpu0 (BSP): APIC ID: 0
cpu1 (AP/HT): APIC ID: 1
WARNING: VIMAGE (virtualized network stack) is a highly experimental feature.
ioapic0: Changing APIC ID to 2
ioapic0 <Version 2.0> irqs 0-23 on motherboard
kbd1 at kbdmux0
cryptosoft0: <software crypto> on motherboard
acpi0: <111708 XSDT1326> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7f700000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
cpu0: <ACPI CPU> on acpi0
cpu1: <ACPI CPU> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0xdc00-0xdc07 mem 0xfea80000-0xfeafffff,0xe0000000-0xefffffff,0xfea40000-0xfea7ffff irq 16 at device 2.0 on pci0
agp0: <Intel 82945G (945G GMCH) SVGA controller> on vgapci0
agp0: detected 7932k stolen memory
agp0: aperture size is 256M
pci0: <multimedia, HDA> 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
re0: <RealTek 8168/8111 B/C/CP/D/DP/E PCIe Gigabit Ethernet> port 0xe800-0xe8ff mem 0xfebff000-0xfebfffff,0xfdff0000-0xfdffffff irq 16 at device 0.0 on pci1
re0: Using 1 MSI-X message
re0: Chip rev. 0x3c000000
re0: MAC rev. 0x00400000
miibus0: <MII bus> on re0
rgephy0: <RTL8169S/8110S/8211B media interface> PHY 1 on miibus0
rgephy0: 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
re0: Ethernet address: 00:24:21:52:27:43
re0: [ITHREAD]
pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.1 on pci0
pci2: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> irq 18 at device 28.2 on pci0
pci3: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> irq 19 at device 28.3 on pci0
pci4: <ACPI PCI bus> on pcib4
uhci0: <Intel 82801G (ICH7) USB controller USB-A> port 0xd880-0xd89f irq 23 at device 29.0 on pci0
uhci0: [ITHREAD]
usbus0: <Intel 82801G (ICH7) USB controller USB-A> on uhci0
uhci1: <Intel 82801G (ICH7) USB controller USB-B> port 0xd800-0xd81f irq 19 at device 29.1 on pci0
uhci1: [ITHREAD]
usbus1: <Intel 82801G (ICH7) USB controller USB-B> on uhci1
uhci2: <Intel 82801G (ICH7) USB controller USB-C> port 0xd480-0xd49f irq 18 at device 29.2 on pci0
uhci2: [ITHREAD]
usbus2: <Intel 82801G (ICH7) USB controller USB-C> on uhci2
uhci3: <Intel 82801G (ICH7) USB controller USB-D> port 0xd400-0xd41f irq 16 at device 29.3 on pci0
uhci3: [ITHREAD]
usbus3: <Intel 82801G (ICH7) USB controller USB-D> on uhci3
ehci0: <Intel 82801GB/R (ICH7) USB 2.0 controller> mem 0xfea37c00-0xfea37fff irq 23 at device 29.7 on pci0
ehci0: [ITHREAD]
usbus4: EHCI version 1.0
usbus4: <Intel 82801GB/R (ICH7) USB 2.0 controller> on ehci0
pcib5: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci5: <ACPI PCI bus> on pcib5
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,0xffa0-0xffaf at device 31.1 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
atapci1: <Intel ICH7 SATA300 controller> port 0xd080-0xd087,0xd000-0xd003,0xcc00-0xcc07,0xc880-0xc883,0xc800-0xc80f 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)
acpi_button0: <Power Button> on acpi0
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 14318180 Hz quality 900
pmtimer0 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff 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.
coretemp0: <CPU On-Die Thermal Sensors> on cpu0
coretemp0: Can not get Tj(target) from your CPU, using 100C.
p4tcc0: <CPU Frequency Thermal Control> on cpu0
coretemp1: <CPU On-Die Thermal Sensors> on cpu1
coretemp1: Can not get Tj(target) from your CPU, using 100C.
p4tcc1: <CPU Frequency Thermal Control> on cpu1
fuse4bsd: version 0.3.9-pre1, FUSE ABI 7.8
Timecounters tick every 1.000 msec
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 12Mbps Full Speed USB v1.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 480Mbps High Speed USB v2.0
ugen0.1: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ata0: DMA limited to UDMA33, controller found non-ATA66 cable
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen4.1: <Intel> at usbus4
uhub4: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus4
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub2: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 8 ports with 8 removable, self powered
ugen4.2: <Sunplus Innovation Technology> at usbus4
umass0: <Bulk Only Interface> on usbus4
ada0 at ata0 bus 0 scbus0 target 0 lun 0
ada0: <CF CARD 4GB 20090216> ATA-0 device
ada0: 33.300MB/s transfers (UDMA2, PIO 512bytes)
ada0: 3831MB (7847280 512 byte sectors: 16H 63S/T 7785C)
ada1 at ata2 bus 0 scbus1 target 0 lun 0
ada1: <ST31500541AS CC32> ATA-8 SATA 2.x device
ada1: 150.000MB/s transfers (SATA, da0 at umass-sim0 bus 0 scbus3 target 0 lun 0
da0: <SAMSUNG HD204UI 0200> Fixed Direct Access SCSI-4 device
da0: 40.000MB/s transfers
da0: 1907729MB (3907029168 512 byte sectors: 255H 63S/T 243201C)
UDMA5, PIO 8192bytes)
ada1: 1430799MB (2930277168 512 byte sectors: 16H 63S/T 16383C)
ada2 at ata3 bus 0 scbus2 target 0 lun 0
ada2: <ST31500541AS CC34> ATA-8 SATA 2.x device
ada2: 150.000MB/s transfers (SATA, UDMA5, PIO 8192bytes)
ada2: 1430799MB (2930277168 512 byte sectors: 16H 63S/T 16383C)
SMP: AP CPU #1 Launched!
GEOM_MIRROR: Device mirror/mirror launched (2/2).
Trying to mount root from ufs:/dev/ufs/FreeNASs1a
WARNING: /data was not properly dismounted
Setting hostuuid: 00020003-0004-0005-0006-000700080009.
Setting hostid: 0x81f4ec68.
/dev/ufs/FreeNASs4: 10 files, 2417 used, 38110 free (30 frags, 4760 blocks, 0.1% fragmentation)
Entropy harvesting:
kickstart
.
Starting file system checks:
/dev/ufs/FreeNASs1a: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs1a: clean, 1219563 free (939 frags, 152328 blocks, 0.0% fragmentation)
/dev/ufs/FreeNASs4: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs4: clean, 38110 free (30 frags, 4760 blocks, 0.1% fragmentation)
/dev/ufs/FreeNASs3: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs3: clean, 2829 free (21 frags, 351 blocks, 0.7% fragmentation)
/dev/ufs/data: INCORRECT BLOCK COUNT I=29647360 (16 should be 8) (CORRECTED)
/dev/ufs/mirror: 971110 files, 322975408 used, 37553621 free (10077 frags, 4692943 blocks, 0.0% fragmentation)
/dev/ufs/data: FREE BLK COUNT(S) WRONG IN SUPERBLK (SALVAGED)
/dev/ufs/data: 1068287 files, 423205714 used, 56982412 free (30372 frags, 7119005 blocks, 0.0% fragmentation)
Mounting local file systems:
.
Setting hostname: mininas.local
.
re0: no link ...
.
.
got link
DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 3

DHCPOFFER from 192.168.0.1

DHCPREQUEST on re0 to 255.255.255.255 port 67

DHCPACK from 192.168.0.1

bound to 192.168.0.54 -- renewal in 43200 seconds.

Starting Network: lo0 re0.
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
options=3<RXCSUM,TXCSUM>
inet6 fe80::1%lo0 prefixlen 64 scopeid 0x2
inet6 ::1 prefixlen 128
inet 127.0.0.1 netmask 0xff000000
nd6 options=3<PERFORMNUD,ACCEPT_RTADV>
re0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=389b<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,VLAN_HWCSUM,WOL_UCAST,WOL_MCAST,WOL_MAGIC>
ether 00:24:21:52:27:43
inet 192.168.0.54 netmask 0xffffff00 broadcast 192.168.0.255
media: Ethernet autoselect (1000baseT <full-duplex>)
status: active
Starting devd.
Generating host.conf.

Creating and/or trimming log files
.
Starting syslogd.
No core dumps found.
ELF ldconfig path: /lib /usr/lib /usr/local/lib
a.out ldconfig path: /usr/lib/aout
ldconfig:
/usr/lib/aout
:
No such file or directory
Starting ntpd.
Mounting late file systems:
.
/etc/rc: WARNING: failed precmd routine for vmware_guestd
Updating motd:
.
16+0 records in
16+0 records out
16 bytes transferred in 0.000141 secs (113551 bytes/sec)
Generating a 1024 bit RSA private key
.
.
.
.
.
.
.
+
+
+
+
+
+

.
.
.
.
.
.
.
.
.
.
.
.
.
+
+
+
+
+
+

unable to write 'random state'
writing new private key to '/etc/ssl/freenas/CA/private/cakey.key'
-----
writing RSA key
Starting django.
Waiting for http...
Done.
Performing sanity check on nginx configuration:
nginx: the configuration file /usr/local/etc/nginx/nginx.conf syntax is ok
nginx: configuration file /usr/local/etc/nginx/nginx.conf test is successful
Starting nginx.
Starting dbus.
Starting avahi_daemon.
ZFS NOTICE: Prefetch is disabled by default on i386 -- to enable,
add "vfs.zfs.prefetch_disable=0" to /boot/loader.conf.
ZFS WARNING: Recommended minimum kmem_size is 512MB; expect unstable behavior.
Consider tuning vm.kmem_size and vm.kmem_size_max
in /boot/loader.conf.
ZFS filesystem version 4
ZFS storage pool version 15
Starting collectd.
Configuring syscons:
blanktime
.
Starting sshd.
Starting cron.
Starting rpcbind.
Starting mountd.
 

cyberjock

Inactive Account
Joined
Mar 25, 2012
Messages
19,526
Your aperture size is 256MB. That's HUGE for a non-3D machine. My understanding is that the aperture size is setaside in the system RAM and is not usable. So you really have 1.75GB of RAM usable. If you can change the setting in the BIOS to the smallest value, usually 64MB is the smallest I've seen, I'd recommend you do that. It will give you a little more RAM to play with.

One thing I'd check out, since your /data is claiming to have not been unmounted properly, would be to try a different CF drive. It could be that your CF drive is going bad and causing some of your problems.

You do have the ZFS warning, which is pretty much pointless since you don't use ZFS. But nothing really sticks out in the log. What takes the computer so long to boot? Some people claim their system boots up to "Starting cron." then sits there for 10+ minutes. Any place in the bootup cycle that is REALLY long?
 

alfista

Cadet
Joined
Sep 6, 2012
Messages
6
thanks again noobsauce80,

I lugged a keyboard and monitor over to the box and booted it into the bios and watched the boot sequence.

Of course - no ability to change aperture size in the bios, at least that I could see. And the system booted in about 90 seconds, or less. However, I could not log in to the webgui once the system got to the freenas menu prompt. I got this error returned to my browser.

Code:
Unhandled Exception

An unhandled exception was thrown by the application.

I just rebooted it again.

I guess I should install 8.3 on a USB drive and try booting from that?

Cheers,

Jason
 

cyberjock

Inactive Account
Joined
Mar 25, 2012
Messages
19,526
Yeah, that's about the best idea I have at the moment. I'd recommend you install FreeNAS to a new CF card or USB key, just to rule out the old CF. Although you could try running a memtest on the server to see if you have bad RAM.
 

alfista

Cadet
Joined
Sep 6, 2012
Messages
6
hi noobsauce80,

I built a USB stick with 8.3 and booted from it.

It hung once on the cron step, and another on the mountd step but after a couple reboots those went away.

I imported the UFS mirror, and removed the USB drive.

All seems to be working .....except.....very.....slowly. I shared a directory out with afp and it takes about 90 seconds to display the contents on a mac.
The webgui is rendering slowly as well, including commands run through the terminal page.

Here is my most recent boot sequence. My first thought was I had plugged the USB stick into a 1.1 port, but I don't think that is the case.


Copyright (c) 1992-2012 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 8.3-RELEASE-p4 #0 r241984M: Wed Oct 24 08:35:12 PDT 2012
root@build.ixsystems.com:/usr/home/jpaetzel/8.3.0-RELEASE/os-base/i386/i386/usr/home/jpaetzel/8.3.0-RELEASE/FreeBSD/src/sys/FREENAS.i386 i386
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel(R) Atom(TM) CPU 230 @ 1.60GHz (1596.01-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x106c2 Family = 6 Model = 1c Stepping = 2
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=0x40e31d<SSE3,DTES64,MON,DS_CPL,TM2,SSSE3,CX16,xTPR,PDCM,MOVBE>
AMD Features=0x20100000<NX,LM>
AMD Features2=0x1<LAHF>
TSC: P-state invariant
real memory = 2147483648 (2048 MB)
avail memory = 2076475392 (1980 MB)
ACPI APIC Table: <111708 APIC1326>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 1 core(s) x 2 HTT threads
cpu0 (BSP): APIC ID: 0
cpu1 (AP/HT): APIC ID: 1
WARNING: VIMAGE (virtualized network stack) is a highly experimental feature.
ioapic0: Changing APIC ID to 2
ioapic0 <Version 2.0> irqs 0-23 on motherboard
kbd1 at kbdmux0
hpt27xx: RocketRAID 27xx controller driver v1.0 (Oct 24 2012 08:35:04)
cryptosoft0: <software crypto> on motherboard
aesni0: No AESNI support.
acpi0: <111708 XSDT1326> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7f700000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
cpu0: <ACPI CPU> on acpi0
cpu1: <ACPI CPU> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0xdc00-0xdc07 mem 0xfea80000-0xfeafffff,0xe0000000-0xefffffff,0xfea40000-0xfea7ffff irq 16 at device 2.0 on pci0
agp0: <Intel 82945G (945G GMCH) SVGA controller> on vgapci0
agp0: aperture size is 256M, detected 7932k stolen memory
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
pci1: <ACPI PCI bus> on pcib1
re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F PCIe Gigabit Ethernet> port 0xe800-0xe8ff mem 0xfebff000-0xfebfffff,0xfdff0000-0xfdffffff irq 16 at device 0.0 on pci1
re0: Using 1 MSI-X message
re0: Chip rev. 0x3c000000
re0: MAC rev. 0x00400000
miibus0: <MII bus> on re0
rgephy0: <RTL8169S/8110S/8211B media interface> PHY 1 on miibus0
rgephy0: none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
re0: Ethernet address: 00:24:21:52:27:43
re0: [ITHREAD]
pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.1 on pci0
pci2: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> irq 18 at device 28.2 on pci0
pci3: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> irq 19 at device 28.3 on pci0
pci4: <ACPI PCI bus> on pcib4
uhci0: <Intel 82801G (ICH7) USB controller USB-A> port 0xd880-0xd89f irq 23 at device 29.0 on pci0
uhci0: [ITHREAD]
usbus0: <Intel 82801G (ICH7) USB controller USB-A> on uhci0
uhci1: <Intel 82801G (ICH7) USB controller USB-B> port 0xd800-0xd81f irq 19 at device 29.1 on pci0
uhci1: [ITHREAD]
usbus1: <Intel 82801G (ICH7) USB controller USB-B> on uhci1
uhci2: <Intel 82801G (ICH7) USB controller USB-C> port 0xd480-0xd49f irq 18 at device 29.2 on pci0
uhci2: [ITHREAD]
usbus2: <Intel 82801G (ICH7) USB controller USB-C> on uhci2
uhci3: <Intel 82801G (ICH7) USB controller USB-D> port 0xd400-0xd41f irq 16 at device 29.3 on pci0
uhci3: [ITHREAD]
usbus3: <Intel 82801G (ICH7) USB controller USB-D> on uhci3
ehci0: <Intel 82801GB/R (ICH7) USB 2.0 controller> mem 0xfea3bc00-0xfea3bfff irq 23 at device 29.7 on pci0
ehci0: [ITHREAD]
usbus4: EHCI version 1.0
usbus4: <Intel 82801GB/R (ICH7) USB 2.0 controller> on ehci0
pcib5: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci5: <ACPI PCI bus> on pcib5
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,0xffa0-0xffaf at device 31.1 on pci0
ata0: <ATA channel> at channel 0 on atapci0
ata0: [ITHREAD]
atapci1: <Intel ICH7 SATA300 controller> port 0xd080-0xd087,0xd000-0xd003,0xcc00-0xcc07,0xc880-0xc883,0xc800-0xc80f irq 19 at device 31.2 on pci0
atapci1: [ITHREAD]
ata2: <ATA channel> at channel 0 on atapci1
ata2: [ITHREAD]
ata3: <ATA channel> at channel 1 on atapci1
ata3: [ITHREAD]
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
acpi_button0: <Power Button> on acpi0
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 14318180 Hz quality 900
pmtimer0 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff 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.
coretemp0: <CPU On-Die Thermal Sensors> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
coretemp1: <CPU On-Die Thermal Sensors> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
fuse4bsd: version 0.3.9-pre1, FUSE ABI 7.8
Timecounters tick every 1.000 msec
hpt27xx: no controller detected.
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 12Mbps Full Speed USB v1.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 480Mbps High Speed USB v2.0
ugen0.1: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ata0: DMA limited to UDMA33, controller found non-ATA66 cable
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen4.1: <Intel> at usbus4
uhub4: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus4
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub2: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 8 ports with 8 removable, self powered
ugen4.2: <Kingston> at usbus4
umass0: <Kingston DT 101 G2, class 0/0, rev 2.00/1.00, addr 2> on usbus4
da0 at umass-sim0 bus 0 scbus3 target 0 lun 0
da0: <Kingston DT 101 G2 PMAP> Removable Direct Access SCSI-0 device
da0: 40.000MB/s transfers
da0: 14883MB (30481152 512 byte sectors: 255H 63S/T 1897C)
ada0 at ata0 bus 0 scbus0 target 0 lun 0
ada0: <CF CARD 4GB 20090216> ATA-0 device
ada0: 33.300MB/s transfers (UDMA2, PIO 512bytes)
ada0: 3831MB (7847280 512 byte sectors: 16H 63S/T 7785C)
ada1 at ata2 bus 0 scbus1 target 0 lun 0
ada1: <ST31500541AS CC32> ATA-8 SATA 2.x device
ada1: 150.000MB/s transfers (SATA, UDMA5, PIO 8192bytes)
ada1: 1430799MB (2930277168 512 byte sectors: 16H 63S/T 16383C)
ada2 at ata3 bus 0 scbus2 target 0 lun 0
ada2: <ST31500541AS CC34> ATA-8 SATA 2.x device
ada2: 150.000MB/s transfers (SATA, UDMA5, PIO 8192bytes)
ada2: 1430799MB (2930277168 512 byte sectors: 16H 63S/T 16383C)
SMP: AP CPU #1 Launched!
GEOM: da0s1: geometry does not match label (16h,63s != 255h,63s).
GEOM_MIRROR: Device mirror/mirror launched (2/2).
Trying to mount root from ufs:/dev/ufs/FreeNASs1a
Setting hostuuid: 00020003-0004-0005-0006-000700080009.
Setting hostid: 0x81f4ec68.
/dev/ufs/FreeNASs4: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs4: clean, 39637 free (29 frags, 4951 blocks, 0.1% fragmentation)
No suitable dump device was found.
Entropy harvesting:
kickstart
.
Starting file system checks:
/dev/ufs/FreeNASs1a: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs1a: clean, 1160503 free (1383 frags, 144890 blocks, 0.1% fragmentation)
/dev/ufs/FreeNASs4: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs4: clean, 39637 free (29 frags, 4951 blocks, 0.1% fragmentation)
/dev/ufs/FreeNASs3: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/FreeNASs3: clean, 2829 free (21 frags, 351 blocks, 0.7% fragmentation)
/dev/ufs/mirror: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/ufs/mirror: clean, 37551002 free (10098 frags, 4692613 blocks, 0.0% fragmentation)
Mounting local file systems:
.
Setting hostname: freenas.local
.
Starting dhclient.
re0: no link ...
.
.
got link
DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 8

DHCPOFFER from 192.168.0.1

DHCPREQUEST on re0 to 255.255.255.255 port 67

DHCPACK from 192.168.0.1

bound to 192.168.0.54 -- renewal in 43200 seconds.

Starting Network: lo0 re0.
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
options=3<RXCSUM,TXCSUM>
inet6 fe80::1%lo0 prefixlen 64 scopeid 0x7
inet6 ::1 prefixlen 128
inet 127.0.0.1 netmask 0xff000000
nd6 options=3<PERFORMNUD,ACCEPT_RTADV>
re0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=209b<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,VLAN_HWCSUM,WOL_MAGIC>
ether 00:24:21:52:27:43
inet 192.168.0.54 netmask 0xffffff00 broadcast 192.168.0.255
media: Ethernet autoselect (1000baseT <full-duplex>)
status: active
add net ::ffff:0.0.0.0: gateway ::1
add net ::0.0.0.0: gateway ::1
Starting devd.
Starting dhclient.
usbus0: not found

exiting.
/etc/rc.d/dhclient: WARNING: failed to start dhclient
Starting Network: usbus0.
Starting dhclient.
usbus1: not found

exiting.
/etc/rc.d/dhclient: WARNING: failed to start dhclient
Starting Network: usbus1.
Starting dhclient.
usbus2: not found

exiting.
/etc/rc.d/dhclient: WARNING: failed to start dhclient
Starting Network: usbus2.
Starting dhclient.
usbus3: not found

exiting.
/etc/rc.d/dhclient: WARNING: failed to start dhclient
Starting Network: usbus3.
Starting dhclient.
usbus4: not found

exiting.
/etc/rc.d/dhclient: WARNING: failed to start dhclient
Starting Network: usbus4.
dhclient already running? (pid=762).
Generating host.conf.

ELF ldconfig path: /lib /usr/lib /usr/local/lib
a.out ldconfig path: /usr/lib/aout
ldconfig:
/usr/lib/aout
:
No such file or directory
Creating and/or trimming log files
.
Starting syslogd.
/etc/rc: WARNING: Dump device does not exist. Savecore not run.
/etc/rc: WARNING: failed precmd routine for vmware_guestd
Updating motd:
.
Mounting late file systems:
.
Starting ntpd.
16+0 records in
16+0 records out
16 bytes transferred in 0.000187 secs (85598 bytes/sec)
Generating a 1024 bit RSA private key
.
.
.
.
.
+
+
+
+
+
+

.
.
.
.
.
.
.
.
+
+
+
+
+
+

writing new private key to '/etc/ssl/freenas/CA/private/cakey.key'
-----
writing RSA key
Starting smartd.
Starting django.
Waiting for http...
Done.
Performing sanity check on nginx configuration:
nginx: the configuration file /usr/local/etc/nginx/nginx.conf syntax is ok
nginx: configuration file /usr/local/etc/nginx/nginx.conf test is successful
Starting nginx.
Starting dbus.
Starting avahi-daemon.
ZFS NOTICE: Prefetch is disabled by default on i386 -- to enable,
add "vfs.zfs.prefetch_disable=0" to /boot/loader.conf.
ZFS WARNING: Recommended minimum kmem_size is 512MB; expect unstable behavior.
Consider tuning vm.kmem_size and vm.kmem_size_max
in /boot/loader.conf.
ZFS filesystem version 5
ZFS storage pool version 28
Starting collectd.
Starting bsdstats.
Posting monthly OS statistics disabled
set monthly_statistics_enable="YES" in /etc/periodic.conf
Configuring syscons:
blanktime
.
Starting sshd.
Starting cron.
Traceback (most recent call last):
File "/usr/local/www/freenasUI/tools/sync_disks.py", line 60, in <module>

main()
File "/usr/local/www/freenasUI/tools/sync_disks.py", line 57, in main

_notifier.sync_disks()
File "/usr/local/www/freenasUI/tools/../../freenasUI/middleware/notifier.py", line 3515, in sync_disks

disk.save()
File "/usr/local/www/freenasUI/tools/../../freenasUI/storage/models.py", line 440, in save

super(Disk, self).save(args, kwargs)
File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 463, in save

self.save_base(using=using, force_insert=force_insert, force_update=force_update)
File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 529, in save_base

rows = manager.using(using).filter(pk=pk_val)._update(values)
File "/usr/local/lib/python2.7/site-packages/django/db/models/query.py", line 557, in _update

return query.get_compiler(self.db).execute_sql(None)
File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 986, in execute_sql

cursor = super(SQLUpdateCompiler, self).execute_sql(result_type)
File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 818, in execute_sql

cursor.execute(sql, params)
File "/usr/local/lib/python2.7/site-packages/django/db/backends/sqlite3/base.py", line 337, in execute

return Database.Cursor.execute(self, query, params)
django.db.utils
.
IntegrityError
:
storage_disk.disk_identifier may not be NULL

Starting rpcbind.
Starting mountd.

Wed Oct 31 10:16:23 PDT 2012
 
Status
Not open for further replies.
Top