Hello everyone. I've done some testing on my new ZFS build.
Since I'm not as well versed in ZFS as many others here, I'd like to get some thoughts.
System Hardware Specs
1 x Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
1 x ASRock Z87E-ITX
2 x 8GB Crucial Ballistix Sport DDR3-1600
6 x Seagate 4TB NAS HDD (ST4000VN000)
1 x SILVERSTONE ST55F-G 550W
1 x Fractal Design Node 304
1 x Cooler Master Hyper 212 EVO
Using the onboard Intel I217V NIC and 6 SATAIII ports.
System Software Specs
FreeNAS-9.1.0-BETA-a83fe91-x64
1 x RaidZ2
Network
Tests were either performed directly on the server via SSH or from a Windows 7 machine connected to the same 1Gbps router as the server. Tests done with Iperf appear to show expected speeds.
Testing
I did some diskinfo -t that I won't bother posting unless someone wants them. I didn't see anything wrong.
Ran SMART short test, no issues their either.
CrystalDiskMark
ATTO
Write/Read of 30GB file.
If I'm understanding correctly, when you write/read files smaller then your RAM you will get extremely fast speeds due to caching.
To see what real disk performance is like we should test with writes/reads of data above that amount?
IOZONE (pastbin)
I can perform other tests if it helps anyone diagnose potential performance issues.
What I am experiencing in real world file/folder copies of CIFS shares is as follows. Copying 2GB worth of video (4x500MB) windows file transfer shows ~120MB/s and then starts to drop down to 90...80..70MB/s half way through. I'm not sure if that is the expected behavior. When I transfer anything really large I tend to see transfer speeds cut in half to about 60MB/s. I believe this has something to do with the RAM amount and caching, but not sure.
Also, it appears that if I set my drives to advanced power management "level 64" that I am experiencing delays/pauses and low transfer speeds. When I look at top I can see that when these issues occur RAM has been released (14GB free) and when the transfer is started it slowly attempts to start using more of it (speed increases but not to expected levels). When I disable advanced power management, I do not see these issues and free RAM shows 7.8GB instead.
A side note, even though I'm not going to bother with HDD standby, I noticed that if I didn't comment out certain logs in /etc/syslog.conf I could not get them to spin down. I didn't go a ton of investigating but I know I had commented out maillog and cron (cron alone didn't resolve it, so it might not have been my issue). Just something I figured I'd mention.
Since I'm not as well versed in ZFS as many others here, I'd like to get some thoughts.
System Hardware Specs
1 x Intel(R) Core(TM) i5-4570 CPU @ 3.20GHz
1 x ASRock Z87E-ITX
2 x 8GB Crucial Ballistix Sport DDR3-1600
6 x Seagate 4TB NAS HDD (ST4000VN000)
1 x SILVERSTONE ST55F-G 550W
1 x Fractal Design Node 304
1 x Cooler Master Hyper 212 EVO
Using the onboard Intel I217V NIC and 6 SATAIII ports.
System Software Specs
FreeNAS-9.1.0-BETA-a83fe91-x64
1 x RaidZ2
Network
Tests were either performed directly on the server via SSH or from a Windows 7 machine connected to the same 1Gbps router as the server. Tests done with Iperf appear to show expected speeds.
Testing
I did some diskinfo -t that I won't bother posting unless someone wants them. I didn't see anything wrong.
Ran SMART short test, no issues their either.
CrystalDiskMark
Code:
-----------------------------------------------------------------------
CrystalDiskMark 3.0.2 x64 (C) 2007-2013 hiyohiyo
Crystal Dew World : http://crystalmark.info/
-----------------------------------------------------------------------
* MB/s = 1,000,000 byte/s [SATA/300 = 300,000,000 byte/s]
Sequential Read : 109.845 MB/s
Sequential Write : 100.679 MB/s
Random Read 512KB : 105.917 MB/s
Random Write 512KB : 97.526 MB/s
Random Read 4KB (QD=1) : 10.451 MB/s [ 2551.5 IOPS]
Random Write 4KB (QD=1) : 8.888 MB/s [ 2170.0 IOPS]
Random Read 4KB (QD=32) : 111.510 MB/s [ 27224.1 IOPS]
Random Write 4KB (QD=32) : 25.104 MB/s [ 6128.9 IOPS]
Test : 1000 MB [Z: 0.1% (20.5/14601.7 GB)] (x5)
Date : 2013/07/07 23:11:32
OS : Windows 7 Ultimate Edition SP1 [6.1 Build 7601] (x64)
ATTO
Write/Read of 30GB file.
If I'm understanding correctly, when you write/read files smaller then your RAM you will get extremely fast speeds due to caching.
To see what real disk performance is like we should test with writes/reads of data above that amount?
Code:
[root@freenas] /mnt/store# dd if=/dev/zero of=/mnt/store/zerofile.000 bs=10M count=3200
3200+0 records in
3200+0 records out
33554432000 bytes transferred in 62.317696 secs (538441472 bytes/sec)
[root@freenas] /mnt/store# dd if=/mnt/store/zerofile.000 of=/dev/zero bs=10M count=3200
3200+0 records in
3200+0 records out
33554432000 bytes transferred in 63.747565 secs (526364136 bytes/sec)
IOZONE (pastbin)
Code:
Iozone: Performance Test of File I/O
Version $Revision: 3.397 $
Compiled for 64 bit mode.
Build: freebsd
Contributors:William Norcott, Don Capps, Isom Crawford, Kirby Collins
Al Slater, Scott Rhine, Mike Wisner, Ken Goss
Steve Landherr, Brad Smith, Mark Kelly, Dr. Alain CYR,
Randy Dunlap, Mark Montague, Dan Million, Gavin Brebner,
Jean-Marc Zucconi, Jeff Blomberg, Benny Halevy, Dave Boone,
Erik Habbinga, Kris Strecker, Walter Wong, Joshua Root,
Fabrice Bacchella, Zhenghua Xue, Qin Li, Darren Sawyer.
Ben England.
Run began: Sat Jul 6 21:42:49 2013
Machine = FreeBSD freenas.local 9.1-STABLE FreeBSD 9.1-STABLE #0 r+7f710c8: Tu Include fsync in write timing
Include fsync in write timing
CPU utilization Resolution = 0.000 seconds.
CPU utilization Excel chart enabled
Record Size 128 KB
File size set to 40960 KB
Percent read in mix test is 70
Command line used: iozone -M -e -+u -T -t 32 -r 128k -s 40960 -i 0 -i 1 -i 2 -i 8 -+p 70 -C
Output is in Kbytes/sec
Time Resolution = 0.000001 seconds.
Processor cache size set to 1024 Kbytes.
Processor cache line size set to 32 bytes.
File stride size set to 17 * record size.
Throughput test with 32 threads
Each thread writes a 40960 Kbyte file in 128 Kbyte records
Children see throughput for 32 initial writers = 488913.72 KB/sec
Parent sees throughput for 32 initial writers = 172126.88 KB/sec
Min throughput per thread = 7703.42 KB/sec
Max throughput per thread = 59566.02 KB/sec
Avg throughput per thread = 15278.55 KB/sec
Min xfer = 8064.00 KB
CPU Utilization: Wall time 2.687 CPU time 9.738 CPU utilization 362.35 %
Child[0] xfer count = 4352.00 KB, Throughput = 4009.30 KB/sec, wall= 2.687, cpu= 0.372, %= 13.84
Child[1] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.660, cpu= 0.253, %= 9.50
Child[2] xfer count = 25344.00 KB, Throughput = 24240.02 KB/sec, wall= 2.686, cpu= 0.352, %= 13.12
Child[3] xfer count = 4352.00 KB, Throughput = 4160.65 KB/sec, wall= 2.687, cpu= 0.372, %= 13.83
Child[4] xfer count = 32896.00 KB, Throughput = 31312.02 KB/sec, wall= 2.679, cpu= 0.331, %= 12.35
Child[5] xfer count = 4352.00 KB, Throughput = 4110.85 KB/sec, wall= 2.687, cpu= 0.372, %= 13.84
Child[6] xfer count = 8192.00 KB, Throughput = 7886.55 KB/sec, wall= 2.686, cpu= 0.353, %= 13.12
Child[7] xfer count = 8192.00 KB, Throughput = 7785.44 KB/sec, wall= 2.686, cpu= 0.353, %= 13.12
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.660, cpu= 0.252, %= 9.49
Child[9] xfer count = 29312.00 KB, Throughput = 27995.29 KB/sec, wall= 2.686, cpu= 0.352, %= 13.12
Child[10] xfer count = 4224.00 KB, Throughput = 3989.86 KB/sec, wall= 2.687, cpu= 0.372, %= 13.84
Child[11] xfer count = 33792.00 KB, Throughput = 32310.91 KB/sec, wall= 2.684, cpu= 0.348, %= 12.95
Child[12] xfer count = 12544.00 KB, Throughput = 12099.72 KB/sec, wall= 2.684, cpu= 0.348, %= 12.97
Child[13] xfer count = 37888.00 KB, Throughput = 36161.47 KB/sec, wall= 2.676, cpu= 0.318, %= 11.87
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.660, cpu= 0.252, %= 9.49
Child[15] xfer count = 12416.00 KB, Throughput = 11724.95 KB/sec, wall= 2.680, cpu= 0.339, %= 12.65
Child[16] xfer count = 16640.00 KB, Throughput = 15981.28 KB/sec, wall= 2.675, cpu= 0.316, %= 11.80
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.641, cpu= 0.245, %= 9.26
Child[18] xfer count = 33280.00 KB, Throughput = 32310.78 KB/sec, wall= 2.671, cpu= 0.298, %= 11.17
Child[19] xfer count = 15488.00 KB, Throughput = 15334.30 KB/sec, wall= 2.663, cpu= 0.271, %= 10.16
Child[20] xfer count = 3968.00 KB, Throughput = 3857.63 KB/sec, wall= 2.670, cpu= 0.294, %= 11.02
Child[21] xfer count = 8064.00 KB, Throughput = 7819.04 KB/sec, wall= 2.677, cpu= 0.326, %= 12.17
Child[22] xfer count = 8192.00 KB, Throughput = 7950.84 KB/sec, wall= 2.667, cpu= 0.286, %= 10.72
Child[23] xfer count = 40960.00 KB, Throughput = 39844.32 KB/sec, wall= 1.028, cpu= 0.086, %= 8.35
Child[24] xfer count = 23936.00 KB, Throughput = 23092.88 KB/sec, wall= 2.665, cpu= 0.278, %= 10.44
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.641, cpu= 0.245, %= 9.27
Child[26] xfer count = 37504.00 KB, Throughput = 35693.14 KB/sec, wall= 2.679, cpu= 0.331, %= 12.36
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.659, cpu= 0.252, %= 9.48
Child[28] xfer count = 15360.00 KB, Throughput = 59566.02 KB/sec, wall= 1.104, cpu= 0.252, %= 22.82
Child[29] xfer count = 8192.00 KB, Throughput = 7703.42 KB/sec, wall= 2.665, cpu= 0.278, %= 10.45
Child[30] xfer count = 8064.00 KB, Throughput = 7756.73 KB/sec, wall= 2.681, cpu= 0.344, %= 12.83
Child[31] xfer count = 25088.00 KB, Throughput = 24216.30 KB/sec, wall= 2.671, cpu= 0.298, %= 11.16
Children see throughput for 32 rewriters = 754212.42 KB/sec
Parent sees throughput for 32 rewriters = 523181.80 KB/sec
Min throughput per thread = 16350.46 KB/sec
Max throughput per thread = 239399.38 KB/sec
Avg throughput per thread = 23569.14 KB/sec
Min xfer = 40960.00 KB
CPU utilization: Wall time 2.505 CPU time 7.190 CPU utilization 287.00 %
Child[0] xfer count = 40960.00 KB, Throughput = 16351.31 KB/sec, wall= 2.505, cpu= 0.326, %= 13.00
Child[1] xfer count = 40960.00 KB, Throughput = 16849.61 KB/sec, wall= 2.431, cpu= 0.109, %= 4.47
Child[2] xfer count = 40960.00 KB, Throughput = 16863.51 KB/sec, wall= 2.429, cpu= 0.095, %= 3.93
Child[3] xfer count = 40960.00 KB, Throughput = 16479.75 KB/sec, wall= 2.485, cpu= 0.286, %= 11.51
Child[4] xfer count = 40960.00 KB, Throughput = 239399.38 KB/sec, wall= 0.298, cpu= 0.298, %=100.00
Child[5] xfer count = 40960.00 KB, Throughput = 16351.37 KB/sec, wall= 2.505, cpu= 0.326, %= 13.00
Child[6] xfer count = 40960.00 KB, Throughput = 16350.46 KB/sec, wall= 2.505, cpu= 0.326, %= 13.02
Child[7] xfer count = 40960.00 KB, Throughput = 16377.42 KB/sec, wall= 2.501, cpu= 0.314, %= 12.57
Child[8] xfer count = 40960.00 KB, Throughput = 16604.74 KB/sec, wall= 2.467, cpu= 0.225, %= 9.12
Child[9] xfer count = 40960.00 KB, Throughput = 16541.75 KB/sec, wall= 2.476, cpu= 0.256, %= 10.35
Child[10] xfer count = 40960.00 KB, Throughput = 16479.23 KB/sec, wall= 2.486, cpu= 0.286, %= 11.50
Child[11] xfer count = 40960.00 KB, Throughput = 16535.55 KB/sec, wall= 2.477, cpu= 0.262, %= 10.58
Child[12] xfer count = 40960.00 KB, Throughput = 16470.42 KB/sec, wall= 2.487, cpu= 0.288, %= 11.57
Child[13] xfer count = 40960.00 KB, Throughput = 16479.49 KB/sec, wall= 2.486, cpu= 0.286, %= 11.51
Child[14] xfer count = 40960.00 KB, Throughput = 16532.66 KB/sec, wall= 2.478, cpu= 0.264, %= 10.67
Child[15] xfer count = 40960.00 KB, Throughput = 16541.44 KB/sec, wall= 2.476, cpu= 0.257, %= 10.38
Child[16] xfer count = 40960.00 KB, Throughput = 16569.05 KB/sec, wall= 2.472, cpu= 0.245, %= 9.91
Child[17] xfer count = 40960.00 KB, Throughput = 16541.87 KB/sec, wall= 2.476, cpu= 0.256, %= 10.35
Child[18] xfer count = 40960.00 KB, Throughput = 16683.12 KB/sec, wall= 2.455, cpu= 0.189, %= 7.71
Child[19] xfer count = 40960.00 KB, Throughput = 16620.32 KB/sec, wall= 2.464, cpu= 0.224, %= 9.08
Child[20] xfer count = 40960.00 KB, Throughput = 16701.86 KB/sec, wall= 2.452, cpu= 0.177, %= 7.21
Child[21] xfer count = 40960.00 KB, Throughput = 16683.49 KB/sec, wall= 2.455, cpu= 0.189, %= 7.71
Child[22] xfer count = 40960.00 KB, Throughput = 16622.70 KB/sec, wall= 2.464, cpu= 0.224, %= 9.08
Child[23] xfer count = 40960.00 KB, Throughput = 16700.32 KB/sec, wall= 2.453, cpu= 0.178, %= 7.24
Child[24] xfer count = 40960.00 KB, Throughput = 16779.61 KB/sec, wall= 2.441, cpu= 0.145, %= 5.93
Child[25] xfer count = 40960.00 KB, Throughput = 16738.12 KB/sec, wall= 2.447, cpu= 0.156, %= 6.38
Child[26] xfer count = 40960.00 KB, Throughput = 16750.31 KB/sec, wall= 2.445, cpu= 0.153, %= 6.25
Child[27] xfer count = 40960.00 KB, Throughput = 16680.00 KB/sec, wall= 2.456, cpu= 0.199, %= 8.09
Child[28] xfer count = 40960.00 KB, Throughput = 16683.35 KB/sec, wall= 2.455, cpu= 0.189, %= 7.72
Child[29] xfer count = 40960.00 KB, Throughput = 16822.26 KB/sec, wall= 2.435, cpu= 0.116, %= 4.78
Child[30] xfer count = 40960.00 KB, Throughput = 16627.70 KB/sec, wall= 2.463, cpu= 0.215, %= 8.74
Child[31] xfer count = 40960.00 KB, Throughput = 16800.26 KB/sec, wall= 2.438, cpu= 0.130, %= 5.35
Children see throughput for 32 readers = 14485534.00 KB/sec
Parent sees throughput for 32 readers = 14110598.57 KB/sec
Min throughput per thread = 0.00 KB/sec
Max throughput per thread = 3676190.50 KB/sec
Avg throughput per thread = 452672.94 KB/sec
Min xfer = 0.00 KB
CPU utilization: Wall time 0.045 CPU time 0.147 CPU utilization 326.44 %
Child[0] xfer count = 39552.00 KB, Throughput = 3541561.00 KB/sec, wall= 0.034, cpu= 0.034, %=100.00
Child[1] xfer count = 40832.00 KB, Throughput = 3659456.00 KB/sec, wall= 0.034, cpu= 0.034, %=100.00
Child[2] xfer count = 40320.00 KB, Throughput = 3608326.50 KB/sec, wall= 0.045, cpu= 0.045, %=100.00
Child[3] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[4] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[5] xfer count = 40960.00 KB, Throughput = 3676190.50 KB/sec, wall= 0.034, cpu= 0.034, %=100.00
Child[6] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[7] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[9] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 85.44
Child[10] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[11] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[12] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 91.18
Child[13] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 43.39
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[15] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[16] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[18] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 99.34
Child[19] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 79.89
Child[20] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 50.33
Child[21] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[22] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[23] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[24] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 49.34
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[26] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[28] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[29] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 79.89
Child[30] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[31] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Children see throughput for 32 re-readers = 14484478.75 KB/sec
Parent sees throughput for 32 re-readers = 14098699.61 KB/sec
Min throughput per thread = 0.00 KB/sec
Max throughput per thread = 3694055.00 KB/sec
Avg throughput per thread = 452639.96 KB/sec
Min xfer = 0.00 KB
CPU utilization: Wall time 0.044 CPU time 0.141 CPU utilization 319.24 %
Child[0] xfer count = 40064.00 KB, Throughput = 3607739.75 KB/sec, wall= 0.033, cpu= 0.033, %=100.00
Child[1] xfer count = 40320.00 KB, Throughput = 3628455.25 KB/sec, wall= 0.044, cpu= 0.044, %=100.00
Child[2] xfer count = 40960.00 KB, Throughput = 3694055.00 KB/sec, wall= 0.030, cpu= 0.030, %=100.00
Child[3] xfer count = 39424.00 KB, Throughput = 3554228.75 KB/sec, wall= 0.033, cpu= 0.033, %=100.00
Child[4] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[5] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[6] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[7] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 50.33
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 93.21
Child[9] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 55.92
Child[10] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[11] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 74.02
Child[12] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[13] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[15] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[16] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 59.92
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 44.15
Child[18] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[19] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[20] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[21] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 59.92
Child[22] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[23] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 49.34
Child[24] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 98.69
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[26] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 74.02
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 74.02
Child[28] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[29] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 79.89
Child[30] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[31] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Children see throughput for 32 random readers = 14411108.50 KB/sec
Parent sees throughput for 32 random readers = 13713301.84 KB/sec
Min throughput per thread = 0.00 KB/sec
Max throughput per thread = 3679497.25 KB/sec
Avg throughput per thread = 450347.14 KB/sec
Min xfer = 0.00 KB
CPU utilization: Wall time 0.045 CPU time 0.158 CPU utilization 352.35 %
Child[0] xfer count = 40832.00 KB, Throughput = 3659065.00 KB/sec, wall= 0.045, cpu= 0.045, %=100.00
Child[1] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[2] xfer count = 39680.00 KB, Throughput = 3556211.00 KB/sec, wall= 0.045, cpu= 0.045, %=100.00
Child[3] xfer count = 39168.00 KB, Throughput = 3516335.25 KB/sec, wall= 0.034, cpu= 0.034, %=100.00
Child[4] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[5] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 56.87
Child[6] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[7] xfer count = 40960.00 KB, Throughput = 3679497.25 KB/sec, wall= 0.034, cpu= 0.034, %=100.00
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[9] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[10] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[11] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[12] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[13] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 54.37
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[15] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[16] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[18] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[19] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 79.89
Child[20] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 69.91
Child[21] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[22] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[23] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 66.23
Child[24] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[26] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 82.06
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 83.89
Child[28] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[29] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[30] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[31] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Children see throughput for 32 mixed workload = 14513196.25 KB/sec
Parent sees throughput for 32 mixed workload = 160698.87 KB/sec
Min throughput per thread = 0.00 KB/sec
Max throughput per thread = 3670613.75 KB/sec
Avg throughput per thread = 453537.38 KB/sec
Min xfer = 0.00 KB
CPU utilization: Wall time 0.998 CPU time 1.218 CPU utilization 122.12 %
Child[0] xfer count = 40064.00 KB, Throughput = 3582588.00 KB/sec, wall= 0.039, cpu= 0.039, %=100.00
Child[1] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.269, cpu= 0.053, %= 19.72
Child[2] xfer count = 40576.00 KB, Throughput = 3629997.25 KB/sec, wall= 0.039, cpu= 0.039, %=100.00
Child[3] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[4] xfer count = 40576.00 KB, Throughput = 3629997.25 KB/sec, wall= 0.044, cpu= 0.044, %=100.00
Child[5] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[6] xfer count = 40960.00 KB, Throughput = 3670613.75 KB/sec, wall= 0.039, cpu= 0.039, %=100.00
Child[7] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 42.65
Child[9] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %= 42.65
Child[10] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[11] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[12] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[13] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[15] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.035, cpu= 0.012, %= 33.98
Child[16] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[18] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.269, cpu= 0.053, %= 19.72
Child[19] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[20] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.000, cpu= 0.000, %=100.00
Child[21] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.251, cpu= 0.014, %= 5.43
Child[22] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.269, cpu= 0.053, %= 19.77
Child[23] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.398, cpu= 0.092, %= 23.07
Child[24] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.998, cpu= 0.098, %= 9.85
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.279, cpu= 0.092, %= 33.00
Child[26] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.997, cpu= 0.098, %= 9.84
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.997, cpu= 0.098, %= 9.84
Child[28] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.998, cpu= 0.098, %= 9.85
Child[29] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.997, cpu= 0.098, %= 9.84
Child[30] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.997, cpu= 0.098, %= 9.84
Child[31] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 0.998, cpu= 0.099, %= 9.90
Children see throughput for 32 random writers = 549492.20 KB/sec
Parent sees throughput for 32 random writers = 58424.30 KB/sec
Min throughput per thread = 0.00 KB/sec
Max throughput per thread = 280223.31 KB/sec
Avg throughput per thread = 17171.63 KB/sec
Min xfer = 0.00 KB
CPU utilization: Wall time 2.716 CPU time 9.476 CPU utilization 348.88 %
Child[0] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.45
Child[1] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.46
Child[2] xfer count = 39424.00 KB, Throughput = 89756.22 KB/sec, wall= 2.442, cpu= 0.338, %= 13.85
Child[3] xfer count = 39808.00 KB, Throughput = 90630.07 KB/sec, wall= 0.507, cpu= 0.293, %= 57.77
Child[4] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.432, cpu= 0.302, %= 12.42
Child[5] xfer count = 39040.00 KB, Throughput = 88882.59 KB/sec, wall= 2.421, cpu= 0.335, %= 13.85
Child[6] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[7] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.716, cpu= 0.309, %= 11.38
Child[8] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.433, cpu= 0.309, %= 12.69
Child[9] xfer count = 40960.00 KB, Throughput = 280223.31 KB/sec, wall= 0.146, cpu= 0.036, %= 24.90
Child[10] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.412, cpu= 0.306, %= 12.68
Child[11] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.708, cpu= 0.302, %= 11.16
Child[12] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.45
Child[13] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.411, cpu= 0.299, %= 12.41
Child[14] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.430, cpu= 0.302, %= 12.43
Child[15] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.46
Child[16] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.45
Child[17] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[18] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.433, cpu= 0.302, %= 12.43
Child[19] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.44
Child[20] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[21] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.432, cpu= 0.302, %= 12.42
Child[22] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.46
Child[23] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.432, cpu= 0.302, %= 12.42
Child[24] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[25] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.45
Child[26] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.46
Child[27] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.433, cpu= 0.302, %= 12.43
Child[28] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.427, cpu= 0.302, %= 12.44
Child[29] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[30] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.425, cpu= 0.302, %= 12.45
Child[31] xfer count = 0.00 KB, Throughput = 0.00 KB/sec, wall= 2.715, cpu= 0.302, %= 11.14
iozone test complete.
I can perform other tests if it helps anyone diagnose potential performance issues.
What I am experiencing in real world file/folder copies of CIFS shares is as follows. Copying 2GB worth of video (4x500MB) windows file transfer shows ~120MB/s and then starts to drop down to 90...80..70MB/s half way through. I'm not sure if that is the expected behavior. When I transfer anything really large I tend to see transfer speeds cut in half to about 60MB/s. I believe this has something to do with the RAM amount and caching, but not sure.
Also, it appears that if I set my drives to advanced power management "level 64" that I am experiencing delays/pauses and low transfer speeds. When I look at top I can see that when these issues occur RAM has been released (14GB free) and when the transfer is started it slowly attempts to start using more of it (speed increases but not to expected levels). When I disable advanced power management, I do not see these issues and free RAM shows 7.8GB instead.
A side note, even though I'm not going to bother with HDD standby, I noticed that if I didn't comment out certain logs in /etc/syslog.conf I could not get them to spin down. I didn't go a ton of investigating but I know I had commented out maillog and cron (cron alone didn't resolve it, so it might not have been my issue). Just something I figured I'd mention.
Last edited: