This is the old XigmaNAS forum in read only mode,
it will taken offline by the end of march 2021!



I like to aks Users and Admins to rewrite/take over important post from here into the new fresh main forum!
Its not possible for us to export from here and import it to the main forum!

Performance drop in ZFS file transfer

Forum rules
Set-Up GuideFAQsForum Rules
Post Reply
Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Performance drop in ZFS file transfer

Post by Krautmaster »

Dear all,

any how, I face a weird issue on my RaidZ device with 3x3 TB discs. System as a 2 core Celeron (not Atom based) and 8 GB memory.
Image

I always face a huge performance drop on copy processes, mostly on small files like MP3s. It starts from 50-65MB/s and falls to 1-10MB/s - not within 8Gb like expected, slowly but continuously.

Image

I really can't imagine that <5MB/s on MP3 files is a reasonable performance for a 3x3TB RaidZ device, I could do a Windows Raid 5 with a better performance.

When coping large files i reach ~95MB/s with standard compression, but I also noticed that every ~2 min the transfer completely stucks @ 0 MB, 1 core went to max and 10 s later it continues and full speed again. But with MP3/s - no matter if I user or disable compression, the performance drops to a few MB/s.

I wonder that the reported Pool size = 3x3Tb and not 2x3TB like it should be in a single parity raid.

Beside I noticed a buggy ZFS webgui configuration - same I had in Nas4Free years ago already. Pools / Dataset failed to delete, where removed from gui, new datasets stuck on initializing, nop devices (4k sectors) caused weird errors in syslog, still pretty uncomfortable - sadly, together with a weird performance.
In pool management I always get the message that a change was made and a error count 1, same as years ago - that message never disappears. I can reinstall nas4free and play with zfs again, I will be able to get the same weird message within some trys.
I used to format my HDD to zfs, then i create a volume on (RaidZ). Then I create a pool and after I create a dataset. Wonder what I can do wrong here. And the deletion of a dataset pool and all stucks in that buggy "still in use" fault.

If I remember correctly, that was the reason why I switched to FreeNas ~2-3 Years ago as the copy performance was more or less constant.

Actually, the NAS is completely for testing purposes - I can change whatever you want me to change or test.

Help would be appreciated =)

Best Regards

Kraut


Edit:
The problems beside the performance aren't that critical, I just mentioned them. Regarding to performance - it still crap like hell. If I cancle the file copy of 100000 files and start the copy again, windows takes 20 min to "calculate" - wtf. Its a standard ZFS with 3x3 TB in RAIDZ1...
Last edited by Krautmaster on 18 Mar 2016 16:07, edited 2 times in total.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

If I copy smaller folders with lot of Images (RAW & JPEG) in then the speed is constant and fast for my impressions:
Image

Simply wonder why MP3s are so awesome bad as the filesize is still around 5MB/ each. Any how, it drops from 70-75MB/s on images to 0-10 MB/s on MP3s which is crazy slow as it begings to copy with 50MB/s the first 10GB.

User avatar
crowi
Forum Moderator
Forum Moderator
Posts: 1176
Joined: 21 Feb 2013 16:18
Location: Munich, Germany
Status: Offline

Re: Performance drop in ZFS file transfer

Post by crowi »

In order to help you, please post your dmesg output (System log) and your Samba configuration. Then go through this list:
http://n4f.siftusystems.com/index.php/2 ... eshooting/
and report if you encounter any issues.
NAS 1: Milchkuh: Asrock C2550D4I, Intel Avoton C2550 Quad-Core, 16GB DDR3 ECC, 5x3TB WD Red RaidZ1 +60 GB SSD for ZIL/L2ARC, APC-Back UPS 350 CS, NAS4Free 11.0.0.4.3460 embedded
NAS 2: Backup: HP N54L, 8 GB ECC RAM, 4x4 TB WD Red, RaidZ1, NAS4Free 11.0.0.4.3460 embedded
NAS 3: Office: HP N54L, 8 GB ECC RAM, 2x3 TB WD Red, ZFS Mirror, APC-Back UPS 350 CS NAS4Free 11.0.0.4.3460 embedded

User avatar
b0ssman
Forum Moderator
Forum Moderator
Posts: 2438
Joined: 14 Feb 2013 08:34
Location: Munich, Germany
Status: Offline

Re: Performance drop in ZFS file transfer

Post by b0ssman »

du hast komprimierung an. welches level?

der celeron 847 ist kein leistungswunder und ist nur auf dem level aktueller atom prozessoren.
Nas4Free 11.1.0.4.4517. Supermicro X10SLL-F, 16gb ECC, i3 4130, IBM M1015 with IT firmware. 4x 3tb WD Red, 4x 2TB Samsung F4, both GEOM AES 256 encrypted.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

Erstmal vielen Dank!

Ich weiß dass der kein Leistungswunder ist, bietet aber bessere IPC als jeder ATOM.

Kompression hab ich erst ohne, dann mit lzjb und danach mit lz4 versucht, jeweils den Pool / Dataset neu erstellt. lz4 sollte wohl fixer sein, wars auch, gerad ebei großen Files ziemlich genau 100 MB/s schreibend, ohne 110 und mit lzjb ~90 MB/s.

Das Komische ist aber dass er auch bei Kompression = aus sehr stark einbricht. Kopier ich nur große Dateien ist alles wunderbar, durchweg 100 MB/s, aber das MP3s nach ~20 Min copy nur noch mit ~5 MB/s reinpuzeln is heftig find ich, zumal er ja mit ~50 MB/s loslegt.

An der Quelle kanns kaum liegen, da werkelt n LSI 9260 und 6x4TB.


Edit:
Habs auch mal mit einer Optimierung auf 8Gb Ram versucht

http://www.hardwareluxx.de/community/f2 ... 35734.html
mfsroot_load="NO"
mfsroot_type="mfs_root"
mfsroot_name="/mfsroot"
hw.est.msr_info="0"
hw.hptrr.attach_generic="0"
hw.msk.msi_disable="1"
kern.maxfiles="65536"
kern.maxfilesperproc="60000"
kern.cam.boot_delay="8000"
kern.cam.ada.legacy_aliases="0"
kern.geom.label.disk_ident.enable="0"
hint.acpi_throttle.0.disabled="0"
hint.p4tcc.0.disabled="0"
autoboot_delay="3"
isboot_load="YES"
zfs_load="YES"
xmfsroot_load="YES"
xmfsroot_type="xmd_root"
xmfsroot_name="/mfsroot.uzip"
geom_xmd_load="YES"
mlxen_load="YES"
vm.kmem_size="7G"
vfs.zfs.arc_max="6144M"
vfs.zfs.arc_min="6144M"
vfs.zfs.txg.timeout="5"
vfs.zfs.txg.write_limit_override="805306368"
vfs.zfs.vdev.min_pending="2"
vfs.zfs.vdev.max_pending="10"
vfs.zfs.prefetch_disable="0"
bringt aber nich wirklich viel. Man kann regelrecht zuschauen wie der Durchsatz zum NAS bei kleinen Files nach und nach langsamer wird

-> aktuell mit neuem Pool, 4k nop drives und Kompression = aus.

Image Image

und er sinkt weiter.


Edit2: könnte es auch am Samba liegen? Ich lass ihn jetzt mal ne STunde MP3s kopieren, mal sehn was am Ende bei rauskommt. Aktuell is er bei ~10MB/s.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

crowi wrote:In order to help you, please post your dmesg output (System log) and your Samba configuration. Then go through this list:
http://n4f.siftusystems.com/index.php/2 ... eshooting/
and report if you encounter any issues.
sry just saw ur post ;) will do so. Can I grab that from a ssh shell? Or should i simply copy the system log from weg guy + place a samba screen?

Edit:

-> Syslog
http://pastebin.com/eGERhjcy

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

Samba:
Image Image


Edit:
-> Still dropping:

Image

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

Raw Performance

Image

= 260/ 283,844674 Mbyte/s

Edit:

nas4free: ~# zdb | grep ashift
ashift: 12
= 4k formated


Edit2:
Maybe its really related to many files in 1 folder and Samba doesnt deal well? I mean we talk abt. 160k MP3s in a single folder. :?:

Edit3: If I pause my file copy of homeserver (server 2008 R2) -> NAS and continue after that I get the same <5 MB/s, if I keep it paused and start a second copy process from my Homeserver -> Workstation (windows 10) -> NAS it looks like this:
Image

Starting directly at 50 MB/s and now slowly dropping again.

Edit3: I cancled the process and started to copy again, Windows is now calculating and the problem seems to be SAMBA
last pid: 25690; load averages: 1.40, 1.13, 1.04 up 0+08:25:11 17:11:44
30 processes: 1 running, 29 sleeping

Mem: 16M Active, 281M Inact, 7361M Wired, 139M Buf, 135M Free
ARC: 6144M Total, 318M MFU, 5760M MRU, 16K Anon, 29M Header, 36M Other
Swap:


PID USERNAME THR PRI NICE SIZE RES STATE C TIME WCPU COMMAND
24247 root 2 20 0 290M 25616K kqread 1 5:53 100.00% smbd
23071 root 1 21 0 124M 23164K piperd 1 0:03 0.78% php-cgi
67100 root 1 52 0 124M 23100K accept 1 0:22 0.00% php-cgi
2231 root 1 21 0 124M 23856K accept 0 0:13 0.00% php-cgi
2210 root 1 20 0 48900K 6152K kqread 1 0:05 0.00% lighttpd
20604 root 1 52 0 124M 22880K accept 1 0:04 0.00% php-cgi
1514 root 1 20 0 13624K 5268K select 1 0:00 0.00% devd
12295 root 1 20 0 21500K 2892K kqread 0 0:00 0.00% netatalk
1631 root 1 20 0 14548K 2064K select 1 0:00 0.00% syslogd
20005 root 1 20 0 57556K 5596K select 1 0:00 0.00% sshd
20007 root 1 20 0 25644K 3680K ttyin 1 0:00 0.00% csh
21707 root 1 20 0 14488K 1936K select 1 0:00 0.00% powerd
1919 root 1 20 0 30704K 4168K nanslp 1 0:00 0.00% smartd
2261 root 1 52 0 118M 18612K wait 1 0:00 0.00% php-cgi
2212 root 1 24 0 118M 18612K wait 1 0:00 0.00% php-cgi
24208 root 1 20 0 275M 23556K select 1 0:00 0.00% smbd
2301 root 1 52 0 25644K 3524K pause 0 0:00 0.00% csh
24205 root 1 20 0 212M 15628K select 0 0:00 0.00% nmbd
18751 root 1 20 0 16612K 2212K nanslp 1 0:00 0.00% cron
2298 root 1 52 0 47744K 2708K wait 0 0:00 0.00% login

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

I just found this:

https://forums.freebsd.org/threads/49732/

Again, SAMBA sucks.

Edit: case sensitive = Yes will not fix it. I can't continue to copy a 100k files folder as the win calculation seems to take ages. But its definitly SAMBA which seems to be the bottleneck here.

User avatar
crowi
Forum Moderator
Forum Moderator
Posts: 1176
Joined: 21 Feb 2013 16:18
Location: Munich, Germany
Status: Offline

Re: Performance drop in ZFS file transfer

Post by crowi »

Dein Syslog ist voll mit Samba Fehlern, stell e smal um auf SMB2
NAS 1: Milchkuh: Asrock C2550D4I, Intel Avoton C2550 Quad-Core, 16GB DDR3 ECC, 5x3TB WD Red RaidZ1 +60 GB SSD for ZIL/L2ARC, APC-Back UPS 350 CS, NAS4Free 11.0.0.4.3460 embedded
NAS 2: Backup: HP N54L, 8 GB ECC RAM, 4x4 TB WD Red, RaidZ1, NAS4Free 11.0.0.4.3460 embedded
NAS 3: Office: HP N54L, 8 GB ECC RAM, 2x3 TB WD Red, ZFS Mirror, APC-Back UPS 350 CS NAS4Free 11.0.0.4.3460 embedded

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

ich hab eben das Logging des SAMBA auf "Normal" gestellt + SMB2, hatte ich davor aber auch schon versucht.

http://pastebin.com/rdP1hUpt

Das ist das System Log nach Samba Neustart.

http://pastebin.com/Ct21pBKg

Das ist das Daemon Log. Der ARP Fehler sollte nicht das Problem sein.

:) Ich dank dir vielmals. Leider verhält es sich mit SBM2 genau gleich.

Edit:

Im Debug Log kommt übrigens folgendes:
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: Annihilator - In Command_ Live 1989-1990 - 05 - Word Salad.mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307370, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307405, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: smbd_dirptr_get_entry: dirptr 0x8125db4a0 now at offset 6816
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307549, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: Annihilator - Double Live Annihilation - 03 - The Box.mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307592, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307626, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: smbd_dirptr_get_entry: dirptr 0x8125db4a0 now at offset 6817
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307769, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: All Saints - Big Night Out - 05 - Rock Steady.mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307811, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307846, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.307988, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: Aerosmith - Woodstock 94 - 15 - Draw The Line _ F.I.N.E..mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308031, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308065, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308208, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: ACDC - '74 Jailbreak - 02 - You Ain't Got A Hold On Me.mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308250, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308284, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: smbd_dirptr_get_entry: dirptr 0x8125db4a0 now at offset 6820
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308426, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: Anna - Desert Rose - 01 - Desert Rose (Radio Version 1).mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308469, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308507, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308649, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:998(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: Adoro - Für Immer Und Dich - 10 - Schlaflied (Kein Stern Der Fällt).mp3
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308694, 8, pid=29075, effective(21, 50), real(0, 0)] ../source3/lib/util.c:1022(is_in_path)
Mar 17 17:41:31 nas4free smbd[29075]: is_in_path: match not found
Mar 17 17:41:31 nas4free smbd[29075]: [2016/03/17 17:41:31.308728, 6, pid=29075, effective(21, 50), real(0, 0)] ../source3/smbd/dir.c:1098(smbd_dirptr_get_entry)
Mar 17 17:41:31 nas4free smbd[29075]: smbd_dirptr_get_entry: dirptr 0x8125db4a0 now at offset 6822
vergleicht halt wie ein irrer Filenames.

In dem anderen Thread wird ja auch angesprochen dass Samba ein Problem hat mit großen Ordnern & vielen Files. Dieser MP3 Ordner mit 100k+ MP3s ist ja auch der einzige der bei 3MB/s kopiert und auch quasi nicht fortzzusetzen ist da SAMBA dann auf 100% geht und dort Stunden verharrt.

:-/

Edit:

Ich starte das NAS nochmal mit SMB2 durch und schick dir ggf n Syslog. CIFS Settings sind komplett auf NAS4FREE default.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

Nach Umstellung SMB3 -> SMB2 kann ich nicht zugreifen wie es scheint, nicht anonym.
Mar 17 18:04:14 nas4free syslogd: kernel boot file is /boot/kernel/kernel
Mar 17 18:04:14 nas4free kernel: Waiting (max 60 seconds) for system process `vnlru' to stop...done
Mar 17 18:04:14 nas4free kernel: Waiting (max 60 seconds) for system process `bufdaemon' to stop...done
Mar 17 18:04:14 nas4free kernel: Waiting (max 60 seconds) for system process `syncer' to stop...
Mar 17 18:04:14 nas4free kernel: Syncing disks, vnodes remaining...4 2 1 0 0 0 done
Mar 17 18:04:14 nas4free kernel: All buffers synced.
Mar 17 18:04:14 nas4free kernel: Uptime: 9h14m5s
Mar 17 18:04:14 nas4free kernel: Copyright (c) 1992-2015 The FreeBSD Project.
Mar 17 18:04:14 nas4free kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
Mar 17 18:04:14 nas4free kernel: The Regents of the University of California. All rights reserved.
Mar 17 18:04:14 nas4free kernel: FreeBSD is a registered trademark of The FreeBSD Foundation.
Mar 17 18:04:14 nas4free kernel: FreeBSD 10.2-RELEASE-p13 #0 r296565M: Wed Mar 9 15:25:43 CET 2016
Mar 17 18:04:14 nas4free kernel: root@dev.nas4free.org:/usr/obj/nas4free/usr/src/sys/NAS4FREE-amd64 amd64
Mar 17 18:04:14 nas4free kernel: FreeBSD clang version 3.4.1 (tags/RELEASE_34/dot1-final 208032) 20140512
Mar 17 18:04:14 nas4free kernel: CPU: Intel(R) Celeron(R) CPU 847 @ 1.10GHz (1097.53-MHz K8-class CPU)
Mar 17 18:04:14 nas4free kernel: Origin="GenuineIntel" Id=0x206a7 Family=0x6 Model=0x2a Stepping=7
Mar 17 18:04:14 nas4free kernel: Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Mar 17 18:04:14 nas4free kernel: Features2=0xdbae3bf<SSE3,PCLMULQDQ,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,TSCDLT,XSAVE,OSXSAVE>
Mar 17 18:04:14 nas4free kernel: AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM>
Mar 17 18:04:14 nas4free kernel: AMD Features2=0x1<LAHF>
Mar 17 18:04:14 nas4free kernel: XSAVE Features=0x1<XSAVEOPT>
Mar 17 18:04:14 nas4free kernel: VT-x: (disabled in BIOS) PAT,HLT,MTF,PAUSE,EPT,UG,VPID
Mar 17 18:04:14 nas4free kernel: TSC: P-state invariant, performance statistics
Mar 17 18:04:14 nas4free kernel: real memory = 8589934592 (8192 MB)
Mar 17 18:04:14 nas4free kernel: avail memory = 8120324096 (7744 MB)
Mar 17 18:04:14 nas4free kernel: Event timer "LAPIC" quality 600
Mar 17 18:04:14 nas4free kernel: ACPI APIC Table: <ALASKA A M I>
Mar 17 18:04:14 nas4free kernel: FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
Mar 17 18:04:14 nas4free kernel: FreeBSD/SMP: 1 package(s) x 2 core(s)
Mar 17 18:04:14 nas4free kernel: cpu0 (BSP): APIC ID: 0
Mar 17 18:04:14 nas4free kernel: cpu1 (AP): APIC ID: 2
Mar 17 18:04:14 nas4free kernel: WARNING: VIMAGE (virtualized network stack) is a highly experimental feature.
Mar 17 18:04:14 nas4free kernel: ioapic0 <Version 2.0> irqs 0-23 on motherboard
Mar 17 18:04:14 nas4free kernel: kbd1 at kbdmux0
Mar 17 18:04:14 nas4free kernel: random: <Software, Yarrow> initialized
Mar 17 18:04:14 nas4free kernel: cryptosoft0: <software crypto> on motherboard
Mar 17 18:04:14 nas4free kernel: acpi0: <ALASKA A M I> on motherboard
Mar 17 18:04:14 nas4free kernel: acpi0: Power Button (fixed)
Mar 17 18:04:14 nas4free kernel: cpu0: <ACPI CPU> on acpi0
Mar 17 18:04:14 nas4free kernel: cpu1: <ACPI CPU> on acpi0
Mar 17 18:04:14 nas4free kernel: hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Mar 17 18:04:14 nas4free kernel: Timecounter "HPET" frequency 14318180 Hz quality 950
Mar 17 18:04:14 nas4free kernel: Event timer "HPET" frequency 14318180 Hz quality 550
Mar 17 18:04:14 nas4free kernel: Event timer "HPET1" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: Event timer "HPET2" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: Event timer "HPET3" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: Event timer "HPET4" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: Event timer "HPET5" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: Event timer "HPET6" frequency 14318180 Hz quality 440
Mar 17 18:04:14 nas4free kernel: atrtc0: <AT realtime clock> port 0x70-0x77 irq 8 on acpi0
Mar 17 18:04:14 nas4free kernel: atrtc0: Warning: Couldn't map I/O.
Mar 17 18:04:14 nas4free kernel: Event timer "RTC" frequency 32768 Hz quality 0
Mar 17 18:04:14 nas4free kernel: attimer0: <AT timer> port 0x40-0x43,0x50-0x53 irq 0 on acpi0
Mar 17 18:04:14 nas4free kernel: Timecounter "i8254" frequency 1193182 Hz quality 0
Mar 17 18:04:14 nas4free kernel: Event timer "i8254" frequency 1193182 Hz quality 100
Mar 17 18:04:14 nas4free kernel: Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
Mar 17 18:04:14 nas4free kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
Mar 17 18:04:14 nas4free kernel: pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
Mar 17 18:04:14 nas4free kernel: pci0: <ACPI PCI bus> on pcib0
Mar 17 18:04:14 nas4free kernel: pcib1: <ACPI PCI-PCI bridge> irq 16 at device 1.0 on pci0
Mar 17 18:04:14 nas4free kernel: pci1: <ACPI PCI bus> on pcib1
Mar 17 18:04:14 nas4free kernel: pcib2: <ACPI PCI-PCI bridge> irq 16 at device 1.2 on pci0
Mar 17 18:04:14 nas4free kernel: pci2: <ACPI PCI bus> on pcib2
Mar 17 18:04:14 nas4free kernel: vgapci0: <VGA-compatible display> port 0xf000-0xf03f mem 0xf7800000-0xf7bfffff,0xc0000000-0xdfffffff irq 16 at device 2.0 on pci0
Mar 17 18:04:14 nas4free kernel: agp0: <SandyBridge mobile GT1 IG> on vgapci0
Mar 17 18:04:14 nas4free kernel: agp0: aperture size is 512M, detected 65532k stolen memory
Mar 17 18:04:14 nas4free kernel: vgapci0: Boot video device
Mar 17 18:04:14 nas4free kernel: pci0: <simple comms> at device 22.0 (no driver attached)
Mar 17 18:04:14 nas4free kernel: ehci0: <Intel Panther Point USB 2.0 controller> mem 0xf7c08000-0xf7c083ff irq 16 at device 26.0 on pci0
Mar 17 18:04:14 nas4free kernel: usbus0: EHCI version 1.0
Mar 17 18:04:14 nas4free kernel: usbus0 on ehci0
Mar 17 18:04:14 nas4free kernel: hdac0: <Intel Panther Point HDA Controller> mem 0xf7c00000-0xf7c03fff irq 22 at device 27.0 on pci0
Mar 17 18:04:14 nas4free kernel: pcib3: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
Mar 17 18:04:14 nas4free kernel: pci3: <ACPI PCI bus> on pcib3
Mar 17 18:04:14 nas4free kernel: pcib4: <ACPI PCI-PCI bridge> irq 17 at device 28.1 on pci0
Mar 17 18:04:14 nas4free kernel: pci4: <ACPI PCI bus> on pcib4
Mar 17 18:04:14 nas4free kernel: re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F/G PCIe Gigabit Ethernet> port 0xe000-0xe0ff mem 0xe0004000-0xe0004fff,0xe0000000-0xe0003fff irq 17 at device 0.0 on pci4
Mar 17 18:04:14 nas4free kernel: re0: Using 1 MSI-X message
Mar 17 18:04:14 nas4free kernel: re0: Chip rev. 0x2c800000
Mar 17 18:04:14 nas4free kernel: re0: MAC rev. 0x00100000
Mar 17 18:04:14 nas4free kernel: miibus0: <MII bus> on re0
Mar 17 18:04:14 nas4free kernel: rgephy0: <RTL8169S/8110S/8211 1000BASE-T media interface> PHY 1 on miibus0
Mar 17 18:04:14 nas4free kernel: 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
Mar 17 18:04:14 nas4free kernel: re0: Using defaults for TSO: 65518/35/2048
Mar 17 18:04:14 nas4free kernel: re0: Ethernet address: b8:97:5a:2a:e4:0c
Mar 17 18:04:14 nas4free kernel: ehci1: <Intel Panther Point USB 2.0 controller> mem 0xf7c07000-0xf7c073ff irq 23 at device 29.0 on pci0
Mar 17 18:04:14 nas4free kernel: usbus1: EHCI version 1.0
Mar 17 18:04:14 nas4free kernel: usbus1 on ehci1
Mar 17 18:04:14 nas4free kernel: isab0: <PCI-ISA bridge> at device 31.0 on pci0
Mar 17 18:04:14 nas4free kernel: isa0: <ISA bus> on isab0
Mar 17 18:04:14 nas4free kernel: ahci0: <Intel Panther Point AHCI SATA controller> port 0xf0b0-0xf0b7,0xf0a0-0xf0a3,0xf090-0xf097,0xf080-0xf083,0xf060-0xf07f mem 0xf7c06000-0xf7c067ff irq 19 at device 31.2 on pci0
Mar 17 18:04:14 nas4free kernel: ahci0: AHCI v1.30 with 4 6Gbps ports, Port Multiplier not supported
Mar 17 18:04:14 nas4free kernel: ahcich0: <AHCI channel> at channel 0 on ahci0
Mar 17 18:04:14 nas4free kernel: ahcich2: <AHCI channel> at channel 2 on ahci0
Mar 17 18:04:14 nas4free kernel: ahcich4: <AHCI channel> at channel 4 on ahci0
Mar 17 18:04:14 nas4free kernel: ahcich5: <AHCI channel> at channel 5 on ahci0
Mar 17 18:04:14 nas4free kernel: ahciem0: <AHCI enclosure management bridge> on ahci0
Mar 17 18:04:14 nas4free kernel: acpi_button0: <Power Button> on acpi0
Mar 17 18:04:14 nas4free kernel: acpi_tz0: <Thermal Zone> on acpi0
Mar 17 18:04:14 nas4free kernel: acpi_tz1: <Thermal Zone> on acpi0
Mar 17 18:04:14 nas4free kernel: acpi_tz2: <Thermal Zone> on acpi0
Mar 17 18:04:14 nas4free kernel: ppc1: <Parallel port> port 0x378-0x37f irq 5 on acpi0
Mar 17 18:04:14 nas4free kernel: ppc1: Generic chipset (NIBBLE-only) in COMPATIBLE mode
Mar 17 18:04:14 nas4free kernel: ppbus0: <Parallel port bus> on ppc1
Mar 17 18:04:14 nas4free kernel: lpt0: <Printer> on ppbus0
Mar 17 18:04:14 nas4free kernel: lpt0: Interrupt-driven port
Mar 17 18:04:14 nas4free kernel: ppi0: <Parallel I/O> on ppbus0
Mar 17 18:04:14 nas4free kernel: uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Mar 17 18:04:14 nas4free kernel: orm0: <ISA Option ROM> at iomem 0xc0000-0xcefff on isa0
Mar 17 18:04:14 nas4free kernel: sc0: <System console> at flags 0x100 on isa0
Mar 17 18:04:14 nas4free kernel: sc0: VGA <16 virtual consoles, flags=0x300>
Mar 17 18:04:14 nas4free kernel: vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Mar 17 18:04:14 nas4free kernel: atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
Mar 17 18:04:14 nas4free kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0
Mar 17 18:04:14 nas4free kernel: kbd0 at atkbd0
Mar 17 18:04:14 nas4free kernel: atkbd0: [GIANT-LOCKED]
Mar 17 18:04:14 nas4free kernel: ppc0: cannot reserve I/O port range
Mar 17 18:04:14 nas4free kernel: coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Mar 17 18:04:14 nas4free kernel: est0: <Enhanced SpeedStep Frequency Control> on cpu0
Mar 17 18:04:14 nas4free kernel: p4tcc0: <CPU Frequency Thermal Control> on cpu0
Mar 17 18:04:14 nas4free kernel: coretemp1: <CPU On-Die Thermal Sensors> on cpu1
Mar 17 18:04:14 nas4free kernel: est1: <Enhanced SpeedStep Frequency Control> on cpu1
Mar 17 18:04:14 nas4free kernel: p4tcc1: <CPU Frequency Thermal Control> on cpu1
Mar 17 18:04:14 nas4free kernel: xmd version 0.5 by NAS4Free Project
Mar 17 18:04:14 nas4free kernel: xmd: default blocklen 32KB, compression LZ4/LZ4HC, level 6
Mar 17 18:04:14 nas4free kernel: xmd0: Loaded preload cloop image </mfsroot.uzip> 21681664 bytes at 0xffffffff8211ac60 (128MB disk w/LZ4)
Mar 17 18:04:14 nas4free kernel: random: unblocking device.
Mar 17 18:04:14 nas4free kernel: usbus0: 480Mbps High Speed USB v2.0
Mar 17 18:04:14 nas4free kernel: ZFS filesystem version: 5
Mar 17 18:04:14 nas4free kernel: ZFS storage pool version: features support (5000)
Mar 17 18:04:14 nas4free kernel: Timecounters tick every 1.000 msec
Mar 17 18:04:14 nas4free kernel: iSCSI boot driver version 0.2.13
Mar 17 18:04:14 nas4free kernel: hdacc0: <Realtek ALC662 HDA CODEC> at cad 2 on hdac0
Mar 17 18:04:14 nas4free kernel: hdaa0: <Realtek ALC662 Audio Function Group> at nid 1 on hdacc0
Mar 17 18:04:14 nas4free kernel: pcm0: <Realtek ALC662 (Analog 2.0+HP/2.0)> at nid 20,27 and 24,25,26 on hdaa0
Mar 17 18:04:14 nas4free kernel: pcm1: <Realtek ALC662 (Rear Digital)> at nid 30 on hdaa0
Mar 17 18:04:14 nas4free kernel: hdacc1: <Intel Panther Point HDA CODEC> at cad 3 on hdac0
Mar 17 18:04:14 nas4free kernel: hdaa1: <Intel Panther Point Audio Function Group> at nid 1 on hdacc1
Mar 17 18:04:14 nas4free kernel: pcm2: <Intel Panther Point (HDMI/DP 8ch)> at nid 5 on hdaa1
Mar 17 18:04:14 nas4free kernel: usbus1: 480Mbps High Speed USB v2.0
Mar 17 18:04:14 nas4free kernel: ugen0.1: <Intel> at usbus0
Mar 17 18:04:14 nas4free kernel: uhub0: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus0
Mar 17 18:04:14 nas4free kernel: ugen1.1: <Intel> at usbus1
Mar 17 18:04:14 nas4free kernel: uhub1: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus1
Mar 17 18:04:14 nas4free kernel: uhub1: 2 ports with 2 removable, self powered
Mar 17 18:04:14 nas4free kernel: uhub0: 2 ports with 2 removable, self powered
Mar 17 18:04:14 nas4free kernel: ugen1.2: <vendor 0x8087> at usbus1
Mar 17 18:04:14 nas4free kernel: uhub2: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus1
Mar 17 18:04:14 nas4free kernel: ugen0.2: <vendor 0x8087> at usbus0
Mar 17 18:04:14 nas4free kernel: uhub3: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus0
Mar 17 18:04:14 nas4free kernel: uhub2: 4 ports with 4 removable, self powered
Mar 17 18:04:14 nas4free kernel: uhub3: 4 ports with 4 removable, self powered
Mar 17 18:04:14 nas4free kernel: ugen0.3: <vendor 0x048d> at usbus0
Mar 17 18:04:14 nas4free kernel: umass0: <vendor 0x048d product 0x1172, class 0/0, rev 2.00/1.00, addr 3> on usbus0
Mar 17 18:04:14 nas4free kernel: umass0: SCSI over Bulk-Only; quirks = 0x8100
Mar 17 18:04:14 nas4free kernel: umass0:5:0:-1: Attached to scbus5
Mar 17 18:04:14 nas4free kernel: ses0 at ahciem0 bus 0 scbus4 target 0 lun 0
Mar 17 18:04:14 nas4free kernel: ses0: <AHCI SGPIO Enclosure 1.00 0001> SEMB S-E-S 2.00 device
Mar 17 18:04:14 nas4free kernel: ses0: SEMB SES Device
Mar 17 18:04:14 nas4free kernel: da0 at umass-sim0 bus 0 scbus5 target 0 lun 0
Mar 17 18:04:14 nas4free kernel: da0: < 0.00> Removable Direct Access SPC-2 SCSI device
Mar 17 18:04:14 nas4free kernel: da0: Serial Number B131003000018E
Mar 17 18:04:14 nas4free kernel: da0: 40.000MB/s transfers
Mar 17 18:04:14 nas4free kernel: da0: 7662MB (15692835 512 byte sectors: 255H 63S/T 976C)
Mar 17 18:04:14 nas4free kernel: da0: quirks=0x2<NO_6_BYTE>
Mar 17 18:04:14 nas4free kernel: ada0 at ahcich0 bus 0 scbus0 target 0 lun 0
Mar 17 18:04:14 nas4free kernel: ada0: <WDC WD30EZRX-00D8PB0 80.00A80> ACS-2 ATA SATA 3.x device
Mar 17 18:04:14 nas4free kernel: ada0: Serial Number WD-WMC4N1886175
Mar 17 18:04:14 nas4free kernel: ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes)
Mar 17 18:04:14 nas4free kernel: ada0: Command Queueing enabled
Mar 17 18:04:14 nas4free kernel: ada0: 2861588MB (5860533168 512 byte sectors: 16H 63S/T 16383C)
Mar 17 18:04:14 nas4free kernel: ada0: quirks=0x1<4K>
Mar 17 18:04:14 nas4free kernel: ada1 at ahcich2 bus 0 scbus1 target 0 lun 0
Mar 17 18:04:14 nas4free kernel: ada1: <WDC WD30EZRX-00D8PB0 80.00A80> ACS-2 ATA SATA 3.x device
Mar 17 18:04:14 nas4free kernel: ada1: Serial Number WD-WMC4N1863502
Mar 17 18:04:14 nas4free kernel: ada1: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
Mar 17 18:04:14 nas4free kernel: ada1: Command Queueing enabled
Mar 17 18:04:14 nas4free kernel: ada1: 2861588MB (5860533168 512 byte sectors: 16H 63S/T 16383C)
Mar 17 18:04:14 nas4free kernel: ada1: quirks=0x1<4K>
Mar 17 18:04:14 nas4free kernel: ada2 at ahcich4 bus 0 scbus2 target 0 lun 0
Mar 17 18:04:14 nas4free kernel: ada2: <WDC WD30EZRX-00D8PB0 80.00A80> ACS-2 ATA SATA 3.x device
Mar 17 18:04:14 nas4free kernel: ada2: Serial Number WD-WCC4N3UJE4KJ
Mar 17 18:04:14 nas4free kernel: ada2: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
Mar 17 18:04:14 nas4free kernel: ada2: Command Queueing enabled
Mar 17 18:04:14 nas4free kernel: ada2: 2861556MB (5860467633 512 byte sectors: 16H 63S/T 16383C)
Mar 17 18:04:14 nas4free kernel: ada2: quirks=0x1<4K>
Mar 17 18:04:14 nas4free kernel: SMP: AP CPU #1 Launched!
Mar 17 18:04:14 nas4free kernel: Timecounter "TSC" frequency 1097533748 Hz quality 1000
Mar 17 18:04:14 nas4free kernel: Trying to mount root from ufs:/dev/xmd0 []...
Mar 17 18:04:14 nas4free kernel: GEOM_NOP: Device ada0.nop created.
Mar 17 18:04:14 nas4free kernel: GEOM_NOP: Device ada1.nop created.
Mar 17 18:04:14 nas4free kernel: GEOM_NOP: Device ada2.nop created.
Mar 17 18:04:21 nas4free kernel: ada0
Mar 17 18:04:21 nas4free kernel: ada1
Mar 17 18:04:22 nas4free kernel: ada2
Mar 17 18:04:23 nas4free nmbd[1880]: [2016/03/17 18:04:23.262631, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Mar 17 18:04:23 nas4free nmbd[1880]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Mar 17 18:04:23 nas4free nmbd[1880]: [2016/03/17 18:04:23.263329, 0] ../source3/nmbd/nmbd_become_dmb.c:294(become_domain_master_browser_bcast)
Mar 17 18:04:23 nas4free nmbd[1880]: become_domain_master_browser_bcast:
Mar 17 18:04:23 nas4free nmbd[1880]: Attempting to become domain master browser on workgroup WORKGROUP on subnet 192.168.2.51
Mar 17 18:04:23 nas4free nmbd[1880]: [2016/03/17 18:04:23.263647, 0] ../source3/nmbd/nmbd_become_dmb.c:307(become_domain_master_browser_bcast)
Mar 17 18:04:23 nas4free nmbd[1880]: become_domain_master_browser_bcast: querying subnet 192.168.2.51 for domain master browser on workgroup WORKGROUP
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.431428, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 1 (min password length), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.432121, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 2 (password history), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.432338, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 3 (user must logon to change password), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.432550, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 4 (maximum password age), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.433271, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 5 (minimum password age), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.433504, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 6 (lockout duration), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.433717, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 7 (reset count minutes), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.433932, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 8 (bad lockout attempt), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.434144, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 9 (disconnect time), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.434359, 1] ../source3/passdb/account_pol.c:354(account_policy_get)
Mar 17 18:04:23 nas4free smbd[1882]: account_policy_get: tdb_fetch_uint32 failed for type 10 (refuse machine password change), returning 0
Mar 17 18:04:23 nas4free smbd[1882]: [2016/03/17 18:04:23.520176, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Mar 17 18:04:23 nas4free smbd[1882]: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Mar 17 18:04:25 nas4free kernel: .
Mar 17 18:04:25 nas4free netatalk[2113]: Netatalk AFP server starting
Mar 17 18:04:25 nas4free netatalk[2113]: Registered with Zeroconf
Mar 17 18:04:25 nas4free cnid_metad[2128]: CNID Server listening on localhost:4700
Mar 17 18:04:25 nas4free afpd[2127]: Netatalk AFP/TCP listening on 192.168.2.51:548
Mar 17 18:04:25 nas4free mDNSResponderPosix: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 nas4free.local. (Addr) that's already in the list
Mar 17 18:04:25 nas4free mDNSResponderPosix: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 51.2.168.192.in-addr.arpa. (PTR) that's already in the list
Mar 17 18:04:25 nas4free lighttpd[2238]: (log.c.194) server started
Mar 17 18:04:26 nas4free login: login on ttyv0 as root
Mar 17 18:04:31 nas4free nmbd[1880]: [2016/03/17 18:04:31.518201, 0] ../source3/nmbd/nmbd_become_dmb.c:112(become_domain_master_stage2)
Mar 17 18:04:31 nas4free nmbd[1880]: *****
Mar 17 18:04:31 nas4free nmbd[1880]:
Mar 17 18:04:31 nas4free nmbd[1880]: Samba server NAS4FREE is now a domain master browser for workgroup WORKGROUP on subnet 192.168.2.51
Mar 17 18:04:31 nas4free nmbd[1880]:
Mar 17 18:04:31 nas4free nmbd[1880]: *****
Mar 17 18:04:46 nas4free nmbd[1880]: [2016/03/17 18:04:46.579681, 0] ../source3/nmbd/nmbd_become_lmb.c:397(become_local_master_stage2)
Mar 17 18:04:46 nas4free nmbd[1880]: *****
Mar 17 18:04:46 nas4free nmbd[1880]:
Mar 17 18:04:46 nas4free nmbd[1880]: Samba name server NAS4FREE is now a local master browser for workgroup WORKGROUP on subnet 192.168.2.51
Mar 17 18:04:46 nas4free nmbd[1880]:
Mar 17 18:04:46 nas4free nmbd[1880]: *****

Edit: Okay hatte sich iwi komplett verabschiedet. Hing. Ich versuchs nochmal. Strange. Hat sich iwi beim aktualisieren des Logs aufgehängt.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

Ok nun hat er gebootet und SBM2 scheint zu laufen, komm normal drauf

Image

Edit: Wie schon davor legt er jetzt mit 50 Mb/s los den großen Ordner zu kopieren. Muss ihn aber an eine andere Stelle kopieren da das SAMBA das nicht gebacken bekommt mit dem Compare bzw zu Analysieren was es noch kopieren muss und was nicht.

Mal sehen ob SMB2 nun die 50 MB/s hält oder binnen 10 min wieder auf <10 MB/s abfällt.

Edit2. Er droppt wieder, aktuell 40MB/s, nich mehr lang und er is wieder bei 5 MB/s ^^ son Dreck. :roll: :?

Ich kann gern nochmal Werksreset machen, wird aber kaum was ändern. Ich kann auch testen ob FreeNas Embedded das Problem nicht hat & mal die smb4.confs vergleichen.

SMB Setting Nas4Free
[global]
server role = standalone
encrypt passwords = yes
netbios name = nas4free
workgroup = WORKGROUP
server string = NAS4Free Server
security = user
max protocol = SMB2
client max protocol = SMB2
dns proxy = no
# Settings to enhance performance:
strict locking = no
read raw = yes
write raw = yes
oplocks = yes
max xmit = 65535
deadtime = 15
getwd cache = yes
socket options = TCP_NODELAY SO_SNDBUF=128480 SO_RCVBUF=128480
# End of performance section
unix charset = UTF-8
local master = yes
domain master = yes
preferred master = yes
os level = 35
time server = yes
guest account = ftp
map to guest = Bad User
max log size = 100
syslog only = yes
syslog = 2
load printers = no
printing = bsd
printcap cache time = 0
printcap name = /dev/null
disable spoolss = yes
log level = 2
dos charset = CP437
smb passwd file = /var/etc/private/smbpasswd
private dir = /var/etc/private
passdb backend = tdbsam
idmap config * : backend = tdb
idmap config * : range = 10000-39999

[NAS]
comment = NasShare
path = /mnt/PoolParity/Share
writeable = yes
printable = no
veto files = /.snap/.sujournal/
hide dot files = yes
guest ok = yes
inherit permissions = yes
inherit acls = yes
vfs objects = shadow_copy2 zfsacl aio_pthread
nfs4:mode = special
nfs4:acedup = merge
nfs4:chown = yes
shadow:format = auto-%Y%m%d-%H%M%S
shadow:snapdir = .zfs/snapshot
shadow:sort = desc
shadow:localtime = yes
veto files = /.zfs/

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

-> FreeNas 9.3 installiert (selber Stick)
-> ZFS direkt importiert
-> SMB3 Share erstellt

Ausgangsgeschwindigkeit beim Kopieren ist schon mal quasi identisch. Jetzt bin ich gespannt ob das auch wegbricht.


Edit: Sieht nach 5 Min in der Tat besser aus. Er schwankt zwar ab und an kurz auf 35MB/s, is im Schnitt aber auch nach 5 min >45 MB/s und hält sich. :shock:

Im Prinzip ist es genau dasselbe wie das was mich vor 2 Jahren von Nas4Free abgewandt hat. Interessant wäre was den Unterschied macht. ZFS oder SAMBA. Das ZFS ist ja eigentlich unverändert eingebunden. SAMBA wurde eig jeweils auch nur nach Standard aktiviert.

Image

Zum Vergleich die smb4.conf bei Freenas
[root@freenas] /usr/local/etc# cat smb4.conf
[global]
server max protocol = SMB3
encrypt passwords = yes
dns proxy = no
strict locking = no
oplocks = yes
deadtime = 15
max log size = 51200
max open files = 232022
load printers = no
printing = bsd
printcap name = /dev/null
disable spoolss = yes
getwd cache = yes
guest account = nobody
map to guest = Bad User
obey pam restrictions = yes
directory name cache size = 0
kernel change notify = no
panic action = /usr/local/libexec/samba/samba-backtrace
nsupdate command = /usr/local/bin/samba-nsupdate -g
server string = FreeNAS Server
ea support = yes
store dos attributes = yes
lm announce = yes
hostname lookups = yes
time server = yes
acl allow execute always = true
acl check permissions = true
dos filemode = yes
multicast dns register = yes
domain logons = no
local master = yes
idmap config *: backend = tdb
idmap config *: range = 90000001-100000000
server role = standalone
netbios name = FREENAS
workgroup = WORKGROUP
security = user
pid directory = /var/run/samba
create mask = 0666
directory mask = 0777
client ntlmv2 auth = yes
dos charset = CP437
unix charset = UTF-8
log level = 1


[Share]
path = /mnt/PoolParity/Share
printable = no
veto files = /.snapshot/.windows/.mac/.zfs/
writeable = yes
browseable = yes
vfs objects = zfs_space zfsacl aio_pthread streams_xattr
hide dot files = yes
guest ok = yes
nfs4:mode = special
nfs4:acedup = merge
nfs4:chown = true
zfsacl:acesort = dontcare
Okay... welche der 1 Mio SAMBA Parameter könnte es denn sein ? ;)

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

-> NAS4FREE neu installiert, ZFS wie gehabt eingebunden. Standard SMB3 Share drauf gemacht:

Image

Fazit des Abends. Etwas passt bei Nas4Free nicht ;)

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »


Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

That finally solved it WTF!

Image

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: [SOLVED!] Performance drop in ZFS file transfer

Post by Krautmaster »

-> So nochmal Nas4Free (release von heute) neu installiert da nicht Updatebar (boot Part zu klein wenn mans über die embedded image macht... super)
-> Live USB geladen, 2. USB Key gemacht, damit auf den eigentlichen installiert. Passt nun:
Image

Läuft soweit 1a und is schneller als FreeNas.

User avatar
crowi
Forum Moderator
Forum Moderator
Posts: 1176
Joined: 21 Feb 2013 16:18
Location: Munich, Germany
Status: Offline

Re: [SOLVED!] Performance drop in ZFS file transfer

Post by crowi »

welche Einstellungen haben jetzt das Problem gelöst?
da nicht Updatebar (boot Part zu klein wenn mans über die embedded image macht...
Update über die Webgui funktioniert aber problemlos
NAS 1: Milchkuh: Asrock C2550D4I, Intel Avoton C2550 Quad-Core, 16GB DDR3 ECC, 5x3TB WD Red RaidZ1 +60 GB SSD for ZIL/L2ARC, APC-Back UPS 350 CS, NAS4Free 11.0.0.4.3460 embedded
NAS 2: Backup: HP N54L, 8 GB ECC RAM, 4x4 TB WD Red, RaidZ1, NAS4Free 11.0.0.4.3460 embedded
NAS 3: Office: HP N54L, 8 GB ECC RAM, 2x3 TB WD Red, ZFS Mirror, APC-Back UPS 350 CS NAS4Free 11.0.0.4.3460 embedded

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: [SOLVED!] Performance drop in ZFS file transfer

Post by Krautmaster »

-> im Samba den Parameter "Case sensitive = yes" hinzufügen.

Das hats gefixed.

@ Boot Partition.

Wenn man mit dem WinDiskImager oder so den USB erstellt, dann legt er eine zu kleine Partition an. Nas4Free rennt dann zwar, aber die Webgui sagt beim Update "BootPartition zu klein".

Eine richtige Embedded Installation via LiveUSB stick legt eine 800 MB Partition an, nicht 500 MB wie der WinDiskImager

Siehe:
viewtopic.php?t=8195

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: [SOLVED!] Performance drop in ZFS file transfer

Post by Krautmaster »

Edit:

Nächstes Problem: Ich kann den Ordnern nun fix draufkopieren, auch getan, aber nicht öffnen lol ^^

smbd[14578]: Too many open files, unable to open more! smbd's max open files = 59017

Ergo muss ich mal noch den Parameter MaxOpen Files hochdrehen wie es scheint.

Edit2:

schade, mit
max open files = 232022
ist es leider nicht getan.Im Ordner sind nur 160k files, 2016 sollte das kaum ein Limit darstellen (bin mir fast sicher das geht schon mit Win XP problemlos auf).
Kommt halt ne Fehlermeldung mit
smbd[14578]: Too many open files, unable to open more! smbd's max open files = 232022
-> wieder das netz nach möglichen Lösungen absuchen :-/

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: [SOLVED!] Performance drop in ZFS file transfer

Post by Krautmaster »

kern.maxfiles="655360"
kern.maxfilesperproc="600000"
hab da mal testweise ne 0 angehängt in der loader.conf

Zusammen mit Samba Max Open Files = 200k kann ich den Ordner nun über die Share zugreifen.

Samba braucht zwar um die 15s bis er aufgeht (geht in bei der Windows Server Share quasi Echtzeit) aber immerhin.

Sind nur 135k files, drin, was soll daran auch so schwer sein :-/


Edit: Ach f_*. Einmal nachm Reboot vom NAS komm ich rein, dann kommt wieder:
Image

Tolle Wurst.

Krautmaster
Starter
Starter
Posts: 35
Joined: 19 Dec 2013 09:58
Status: Offline

Re: Performance drop in ZFS file transfer

Post by Krautmaster »

did a thread for as no ZFS but samba issue

viewtopic.php?f=21&t=10656&p=66729#p66729

Post Reply

Return to “ZFS (only!)”