I ran bonnie++ sessions on the same hardware with no other load. When kernel 3.14 is used, ZFS always uses a significantly higher amount of CPU time. The block writes higher CPU usage is not as significant as the block reads higher CPU usage. When running on sys-kernel/gentoo-sources-3.12.20: # bonnie++ -u nobody:nobody -d /mnt/shares/Unsorted/ Using uid:65534, gid:65534. Writing a byte at a time...done Writing intelligently...done Rewriting...done Reading a byte at a time...done Reading intelligently...done start 'em...done...done...done...done...done... Create files in sequential order...done. Stat files in sequential order...done. Delete files in sequential order...done. Create files in random order...done. Stat files in random order...done. Delete files in random order...done. Version 1.97 ------Sequential Output------ --Sequential Input- --Random- Concurrency 1 -Per Chr- --Block-- -Rewrite- -Per Chr- --Block-- --Seeks-- Machine Size K/sec %CP K/sec %CP K/sec %CP K/sec %CP K/sec %CP /sec %CP raptor 63G 262 99 383409 26 191400 18 641 98 416550 15 207.4 6 Latency 43499us 10945us 1142ms 56359us 138ms 204ms Version 1.97 ------Sequential Create------ --------Random Create-------- raptor -Create-- --Read--- -Delete-- -Create-- --Read--- -Delete-- files /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP 16 27609 65 +++++ +++ +++++ +++ 30715 90 +++++ +++ 11182 98 Latency 16613us 195us 212us 27817us 34us 1374us 1.97,1.97,raptor,1,1401392075,63G,,262,99,383409,26,191400,18,641,98,416550,15,207.4,6,16,,,,,27609,65,+++++,+++,+++++,+++,30715,90,+++++,+++,11182,98,43499us,10945us,1142ms,56359us,138ms,204ms,16613us,195us,212us,27817us,34us,1374us When running on sys-kernel/gentoo-sources-3.14.4: # bonnie++ -u nobody:nobody -d /mnt/shares/Unsorted/ Using uid:65534, gid:65534. Writing a byte at a time...done Writing intelligently...done Rewriting...done Reading a byte at a time...done Reading intelligently...done start 'em...done...done...done...done...done... Create files in sequential order...done. Stat files in sequential order...done. Delete files in sequential order...done. Create files in random order...done. Stat files in random order...done. Delete files in random order...done. Version 1.97 ------Sequential Output------ --Sequential Input- --Random- Concurrency 1 -Per Chr- --Block-- -Rewrite- -Per Chr- --Block-- --Seeks-- Machine Size K/sec %CP K/sec %CP K/sec %CP K/sec %CP K/sec %CP /sec %CP raptor 63G 240 99 380045 35 157276 39 636 97 392856 51 203.0 16 Latency 90298us 12378us 1056ms 102ms 129ms 208ms Version 1.97 ------Sequential Create------ --------Random Create-------- raptor -Create-- --Read--- -Delete-- -Create-- --Read--- -Delete-- files /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP 16 13630 79 +++++ +++ 29622 89 27174 84 +++++ +++ 20515 92 Latency 25627us 606us 447us 20030us 11us 229us 1.97,1.97,raptor,1,1401388077,63G,,240,99,380045,35,157276,39,636,97,392856,51,203.0,16,16,,,,,13630,79,+++++,+++,29622,89,27174,84,+++++,+++,20515,92,90298us,12378us,1056ms,102ms,129ms,208ms,25627us,606us,447us,20030us,11us,229us Reproducible: Always
# emerge --info Portage 2.2.10 (default/linux/amd64/13.0/no-multilib, gcc-4.8.2, glibc-2.19, 3.12.20-gentoo x86_64) ================================================================= System uname: Linux-3.12.20-gentoo-x86_64-Intel-R-_Xeon-R-_CPU_E3-1240_v3_@_3.40GHz-with-gentoo-2.2 KiB Mem: 32929260 total, 11232772 free KiB Swap: 15625212 total, 15625212 free Timestamp of tree: Thu, 29 May 2014 19:00:01 +0000 ld GNU ld (GNU Binutils) 2.24 app-shells/bash: 4.2_p47 dev-lang/python: 2.7.6-r1, 3.3.5, 3.4.0 dev-util/pkgconfig: 0.28-r1 sys-apps/baselayout: 2.2 sys-apps/openrc: 0.12.4 sys-apps/sandbox: 2.6-r1 sys-devel/autoconf: 2.13, 2.69 sys-devel/automake: 1.11.6, 1.14.1 sys-devel/binutils: 2.24-r2 sys-devel/gcc: 4.8.2 sys-devel/gcc-config: 1.8 sys-devel/libtool: 2.4.2-r1 sys-devel/make: 4.0-r1 sys-kernel/linux-headers: 3.12 (virtual/os-headers) sys-libs/glibc: 2.19 Repositories: gentoo Personal_Portage ACCEPT_KEYWORDS="amd64 ~amd64" ACCEPT_LICENSE="* -@EULA" CBUILD="x86_64-pc-linux-gnu" CFLAGS="-march=native -O2 -pipe" CHOST="x86_64-pc-linux-gnu" CONFIG_PROTECT="/etc /usr/share/gnupg/qualified.txt" CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/env.d /etc/gconf /etc/gentoo-release /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo" CXXFLAGS="-march=native -O2 -pipe" DISTDIR="/usr/portage/distfiles" FCFLAGS="-O2 -pipe" FEATURES="assume-digests binpkg-logs config-protect-if-modified distlocks ebuild-locks fixlafiles merge-sync news parallel-fetch preserve-libs protect-owned sandbox sfperms strict unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync" FFLAGS="-O2 -pipe" GENTOO_MIRRORS="http://gentoo.channelx.biz/ http://gentoo.gg3.net/ http://ftp.iij.ad.jp/pub/linux/gentoo/ http://ftp.jaist.ac.jp/pub/Linux/Gentoo/" LANG="en_US" LDFLAGS="-Wl,-O1 -Wl,--as-needed" MAKEOPTS="-j5" PKGDIR="/usr/portage/packages" PORTAGE_CONFIGROOT="/" PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --omit-dir-times --compress --force --whole-file --delete --stats --human-readable --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages" PORTAGE_TMPDIR="/var/tmp" PORTDIR="/usr/portage" PORTDIR_OVERLAY="/var/lib/personal_portage" SYNC="rsync://nexus/gentoo-portage" USE="acl acpi amd64 berkdb bzip2 cli cracklib crypt cxx dri fortran gdbm iconv ipv6 lm_sensors mmx modules ncurses nls nptl openmp pam pcre readline samba session sse sse2 sse3 ssl ssse3 tcpd threads unicode zlib" ABI_X86="64" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" APACHE2_MODULES="authn_core authz_core socache_shmcb unixd actions alias auth_basic authn_alias authn_anon authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache cgi cgid dav dav_fs dav_lock deflate dir disk_cache env expires ext_filter file_cache filter headers include info log_config logio mem_cache mime mime_magic negotiation rewrite setenvif speling status unique_id userdir usertrack vhost_alias" CALLIGRA_FEATURES="kexi words flow plan sheets stage tables krita karbon braindump author" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf superstar2 timing tsip tripmate tnt ublox ubx" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LIBREOFFICE_EXTENSIONS="presenter-console presenter-minimizer" OFFICE_IMPLEMENTATION="libreoffice" PHP_TARGETS="php5-5" PYTHON_SINGLE_TARGET="python2_7" PYTHON_TARGETS="python2_7 python3_3" RUBY_TARGETS="ruby19 ruby20" USERLAND="GNU" VIDEO_CARDS="fbdev glint intel mach64 mga nouveau nv r128 radeon savage sis tdfx trident vesa via vmware dummy v4l" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account" Unset: CPPFLAGS, CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, LC_ALL, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS, USE_PYTHON
I just realised I didn't show what my ZFS pool is like... # zpool status pool: shares state: ONLINE scan: scrub repaired 0 in 7h16m with 0 errors on Sun Jun 1 12:47:14 2014 config: NAME STATE READ WRITE CKSUM shares ONLINE 0 0 0 raidz1-0 ONLINE 0 0 0 ata-WDC_WD4000F9YZ-09N20L0_WD-WCC130745847 ONLINE 0 0 0 ata-WDC_WD4000F9YZ-09N20L0_WD-WCC130685393 ONLINE 0 0 0 ata-WDC_WD4000F9YZ-09N20L0_WD-WCC130745037 ONLINE 0 0 0 ata-WDC_WD4000F9YZ-09N20L0_WD-WCC130681302 ONLINE 0 0 0 ata-WDC_WD4000F9YZ-09N20L0_WD-WCC1F0040763 ONLINE 0 0 0 errors: No known data errors
Performance numbers using zfs-0.63 with Kernel 3.15.1 is a lot better. # bonnie++ -u nobody:nobody -d /mnt/shares/Unsorted/ Using uid:65534, gid:65534. Writing a byte at a time...done Writing intelligently...done Rewriting...done Reading a byte at a time...done Reading intelligently...done start 'em...done...done...done...done...done... Create files in sequential order...done. Stat files in sequential order...done. Delete files in sequential order...done. Create files in random order...done. Stat files in random order...done. Delete files in random order...done. Version 1.97 ------Sequential Output------ --Sequential Input- --Random- Concurrency 1 -Per Chr- --Block-- -Rewrite- -Per Chr- --Block-- --Seeks-- Machine Size K/sec %CP K/sec %CP K/sec %CP K/sec %CP K/sec %CP /sec %CP raptor 63G 273 99 519141 42 205483 20 684 99 579338 20 259.9 7 Latency 41524us 8002us 747ms 35577us 136ms 173ms Version 1.97 ------Sequential Create------ --------Random Create-------- raptor -Create-- --Read--- -Delete-- -Create-- --Read--- -Delete-- files /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP /sec %CP 16 +++++ +++ +++++ +++ +++++ +++ 24224 88 +++++ +++ +++++ +++ Latency 21284us 236us 267us 31764us 12us 144us 1.97,1.97,raptor,1,1403434203,63G,,273,99,519141,42,205483,20,684,99,579338,20,259.9,7,16,,,,,+++++,+++,+++++,+++,+++++,+++,24224,88,+++++,+++,+++++,+++,41524us,8002us,747ms,35577us,136ms,173ms,21284us,236us,267us,31764us,12us,144us
Unfortunately, this issue fell through the cracks. I just tagged 0.6.3-r1, which has some fixes that should help further. I am closing this as obsolete. Feel free to reopen it if it is still a problem.
