This thread has been locked.
If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.
Tool/software: Linux
We have Beaglbone black based custom boards with AM3352.
REV1 has following memory
DDR : 256MB (MT41K128M16JT-125:K TR) -- Data rate - 1600 MT/s
eMMC : 4GB
REV2 has following memory
DDR : 512MB ( MT41K256M16TW-107:P TR ) - Data rate - 1866 MT/s
eMMC : 4GB ( actual size is 8GB however using enhanced mode so effective size is ~4GB)
We have same clock settings on both boards. On both the boards DDR is running at 400Mhz.
When I ran following two bench marking tools on both the boards. And found out that RAM speed is consistently low on REV2 Which I was expecting it to be high.
1. STREAM
2. lmbench
Below are the test results of STREAM
For REV1
------------------------------------------------------------- STREAM version $Revision: 5.10 $ ------------------------------------------------------------- This system uses 8 bytes per array element. ------------------------------------------------------------- Array size = 9000000 (elements), Offset = 0 (elements) Memory per array = 68.7 MiB (= 0.1 GiB). Total memory required = 206.0 MiB (= 0.2 GiB). Each kernel will be executed 10 times. The *best* time for each kernel (excluding the first iteration) will be used to compute the reported bandwidth. ------------------------------------------------------------- Your clock granularity/precision appears to be 3 microseconds. Each test below will take on the order of 836646 microseconds. (= 278882 clock ticks) Increase the size of the arrays if this shows that you are not getting at least 20 clock ticks per test. ------------------------------------------------------------- WARNING -- The above is only a rough guideline. For best results, please be sure you know the precision of your system timer. ------------------------------------------------------------- Function Best Rate MB/s Avg time Min time Max time Copy: 243.1 0.592447 0.592237 0.592682 Scale: 314.5 0.458120 0.457861 0.458296 Add: 400.9 0.538948 0.538737 0.539077 Triad: 310.5 0.695861 0.695728 0.696141 ------------------------------------------------------------- Solution Validates: avg error less than 1.000000e-13 on all three arrays -------------------------------------------------------------
For REV2
# /home/stream_9M_c.exe ------------------------------------------------------------- STREAM version $Revision: 5.10 $ ------------------------------------------------------------- This system uses 8 bytes per array element. ------------------------------------------------------------- Array size = 9000000 (elements), Offset = 0 (elements) Memory per array = 68.7 MiB (= 0.1 GiB). Total memory required = 206.0 MiB (= 0.2 GiB). Each kernel will be executed 10 times. The *best* time for each kernel (excluding the first iteration) will be used to compute the reported bandwidth. ------------------------------------------------------------- Your clock granularity/precision appears to be 3 microseconds. Each test below will take on the order of 842498 microseconds. (= 280832 clock ticks) Increase the size of the arrays if this shows that you are not getting at least 20 clock ticks per test. ------------------------------------------------------------- WARNING -- The above is only a rough guideline. For best results, please be sure you know the precision of your system timer. ------------------------------------------------------------- Function Best Rate MB/s Avg time Min time Max time Copy: 237.9 0.605575 0.605404 0.605755 Scale: 305.5 0.471573 0.471323 0.471777 Add: 393.7 0.548840 0.548639 0.549065 Triad: 304.7 0.709157 0.709009 0.709323 ------------------------------------------------------------- Solution Validates: avg error less than 1.000000e-13 on all three arrays -------------------------------------------------------------
If you see REV2 rates are lower than REV1. I don't understand this.
lmbench also gives similar results I have attached lmbench results log ( I had run lmbench script from ltp-ddt and ran the test) for REV1 and REV2.
Now question is why DDR on the REV2 is slow compared to DDR on REV1 ?
|TRACE LOG| ***** STARTING LMBENCH SCRIPT ***** | |TRACE LOG| CREATING THE FILE OF 16MB SIZE FOR BENCHMARKING| 16+0 records in 16+0 records out 16777216 bytes (16.0MB) copied, 7.503526 seconds, 2.1MB/s |TRACE LOG| ***** STARTING BANDWIDTH BENCHMARKS ***** | |TRACE LOG| MEMORY BANDWIDTH BENCHMARKS | |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 1M| 1.05 270.67 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 2M| 2.10 255.69 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 4M| 4.19 252.14 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 8M| 8.39 250.98 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 16M| 16.78 251.57 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 1M| 1.05 1085.48 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 2M| 2.10 1086.23 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 4M| 4.19 1084.92 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 8M| 8.39 1085.48 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 16M| 16.78 1086.04 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 1M| 1.05 225.84 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 2M| 2.10 223.05 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 4M| 4.19 223.18 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 8M| 8.39 222.40 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 16M| 16.78 222.94 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 1M| 1.05 213.17 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 2M| 2.10 206.86 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 4M| 4.19 209.95 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 8M| 8.39 212.31 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 16M| 16.78 213.04 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 1M| 1.05 1088.68 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 2M| 2.10 1088.86 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 4M| 4.19 1089.15 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 8M| 8.39 301.43 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 16M| 16.78 1089.71 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 1M| 1.05 247.25 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 2M| 2.10 234.48 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 4M| 4.19 232.18 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 8M| 8.39 231.48 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 16M| 16.78 232.07 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 1M| 1.05 192.82 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 2M| 2.10 190.05 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 4M| 4.19 190.19 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 8M| 8.39 191.08 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 16M| 16.78 192.37 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 1M| 1.05 1074.91 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 2M| 2.10 1075.10 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 4M| 4.19 1076.01 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 8M| 8.39 1076.01 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 16M| 16.78 1075.53 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 1M| 1.05 187.98 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 2M| 2.10 187.95 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 4M| 4.19 187.91 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 8M| 8.39 189.64 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 16M| 16.78 190.13 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TRACE LOG| BANDWIDTH OF PIPE COMMUNICATION BETWEEN PARENT AND CHILD | |TEST START|bw_unix| |TRACE LOG| Parameters : none| AF_UNIX sock stream bandwidth: 194.31 MB/sec |TEST RESULT|PASS|bw_unix| |TEST END|bw_unix| |TRACE LOG| BANDWIDTH OF FILE READ| |TEST START|bw_file_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - open2close| |TRACE LOG| File - test1.txt| 1.05 163.89 |TEST RESULT|PASS|bw_file_rd| |TEST END|bw_file_rd| |TEST START|bw_file_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - io_only| |TRACE LOG| File - test1.txt| 1.05 61.67 |TEST RESULT|PASS|bw_file_rd| |TEST END|bw_file_rd| |TRACE LOG| BANDWIDTH OF CREATING A MEMORY MAPPING TO A FILE AND READING| |TEST START|bw_mmap_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - open2close| |TRACE LOG| File - test1.txt| 1.05 205.93 |TEST RESULT|PASS|bw_mmap_rd| |TEST END|bw_mmap_rd| |TEST START|bw_mmap_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - mmap_only| |TRACE LOG| File - test1.txt| 1.05 246.43 |TEST RESULT|PASS|bw_mmap_rd| |TEST END|bw_mmap_rd| |TRACE LOG| BANDWIDTH OF CREATING A UNIX PIPE BETWEEN TWO PROCESS AND MOVEING 50 MB DATA THROUGH PIPE IN 64 KB CHUNKS| |TEST START|bw_pipe| |TRACE LOG| Parameters : none| Pipe bandwidth: 218.65 MB/sec |TEST RESULT|PASS|bw_pipe| |TEST END|bw_pipe| |TRACE LOG| BANDWIDTH OF TCP/IP SOCKET ( DATA IS MOVED IN 48KB CHUNKS )| |TEST START|bw_tcp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| |TRACE LOG| transfersize - none(default 3MB )| socket connection: Connection refused |TEST RESULT|PASS|bw_tcp| |TEST END|bw_tcp| |TRACE LOG| ***** EXITING THE BANDWIDTH BENCHMARKING ***** | |TRACE LOG| ***** STARTING LATENCY BENCHMARKS ***** | |TRACE LOG| LATENCY OF INTERPROCESS CONNECTION VIA TCP/IP | |TEST START|lat_connect| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| TCP/IP connection cost to localhost: 108.0600 microseconds |TEST RESULT|PASS|lat_connect| |TEST END|lat_connect| |TRACE LOG| LATENCY OF FILE CREATION AND DELETION | |TEST START|lat_fs| |TRACE LOG| Parameters : | |TRACE LOG| dir - none(default current working directory)| 0k 280 49348 79085 1k 144 26111 59078 4k 145 24970 60655 10k 85 15299 42438 |TEST RESULT|PASS|lat_fs| |TEST END|lat_fs| |TRACE LOG|Syncing filesystem and waiting few seconds prior to starting context switching latency tests| |TRACE LOG| LATENCY OF CONTEXT SWITCHING | |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 2| |TRACE LOG| size_in_kbytes - 128K| |TRACE LOG| repetitions - 100| "size=128k ovr=162.16 2 140.89 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 2| |TRACE LOG| size_in_kbytes - 256K| |TRACE LOG| repetitions - 100| "size=256k ovr=576.56 2 450.44 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 4| |TRACE LOG| size_in_kbytes - 128K| |TRACE LOG| repetitions - 100| "size=128k ovr=162.97 4 382.62 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 4| |TRACE LOG| size_in_kbytes - 256K| |TRACE LOG| repetitions - 100| "size=256k ovr=522.30 4 652.83 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TRACE LOG| LATENCY OF COMMAND | |TEST START|lat_cmd| |TRACE LOG| Parameters : | |TRACE LOG| command - ls| |TEST RESULT|PASS|lat_cmd| |TEST END|lat_cmd| |TRACE LOG| LATENCY OF COMMAND | |TEST START|lat_cmd| |TRACE LOG| Parameters : | |TRACE LOG| command - pwd| |TEST RESULT|PASS|lat_cmd| |TEST END|lat_cmd| |TRACE LOG| LATENCY OF MEMORY READ | |TEST START|lat_mem_rd| |TRACE LOG| Parameters : | |TRACE LOG| size_in_mbytes - 1M| |TRACE LOG| stride - 128| "stride=128 0.00049 5.521 0.00098 5.519 0.00195 5.519 0.00293 5.522 0.00391 5.521 0.00586 5.519 0.00781 5.520 0.00977 5.520 0.01172 5.519 0.01367 5.492 0.01562 5.492 0.01758 5.493 0.01953 5.494 0.02148 5.493 0.02344 5.493 0.02539 5.484 0.02734 5.484 0.02930 5.485 0.03125 5.487 0.03516 8.966 0.03906 12.039 0.04297 12.530 0.04688 14.016 0.05078 15.543 0.05469 15.193 0.05859 15.587 0.06250 16.633 0.07031 16.639 0.07812 17.385 0.08594 17.954 0.09375 17.958 0.10156 18.399 0.10938 18.401 0.11719 18.750 0.12500 21.630 0.14062 21.530 0.15625 22.159 0.17188 23.654 0.18750 23.707 0.20312 38.669 0.21875 55.047 0.23438 65.451 0.25000 80.262 0.28125 89.177 0.31250 102.055 0.34375 127.820 0.37500 148.110 0.40625 163.048 0.43750 175.127 0.46875 185.099 0.50000 191.620 0.56250 203.040 0.62500 210.929 0.68750 214.998 0.75000 220.222 0.81250 223.134 0.87500 225.871 0.93750 227.202 1.00000 229.386 |TEST RESULT|PASS|lat_mem_rd| |TEST END|lat_mem_rd| |TRACE LOG| LATENCY OF MAPPING AND UNMAPPING | |TEST START|lat_mmap| |TRACE LOG| Parameters : | |TRACE LOG| size - 1M| |TRACE LOG| file - test1.txt| 1.048576 73 |TEST RESULT|PASS|lat_mmap| |TEST END|lat_mmap| |TRACE LOG| LATENCY OF BASIC CPU OPERATIONS | |TEST START|lat_ops| |TRACE LOG| Parameters : none| integer bit: 1.83 nanoseconds integer add: 0.19 nanoseconds integer mul: 11.10 nanoseconds integer div: 105.77 nanoseconds integer mod: 43.59 nanoseconds int64 bit: 1.85 nanoseconds int64 add: 0.21 nanoseconds int64 mul: 20.14 nanoseconds int64 div: 448.70 nanoseconds int64 mod: 155.07 nanoseconds float add: 16.23 nanoseconds float mul: 18.22 nanoseconds float div: 60.13 nanoseconds double add: 16.24 nanoseconds double mul: 20.05 nanoseconds double div: 103.83 nanoseconds float bogomflops: 177.48 nanoseconds double bogomflops: 217.54 nanoseconds |TEST RESULT|PASS|lat_ops| |TEST END|lat_ops| |TRACE LOG| LATENCY OF PAGE FAULT | |TEST START|lat_pagefault| |TRACE LOG| Parameters : | |TRACE LOG| file - test1.txt| Pagefaults on test1.txt: 1.8442 microseconds |TEST RESULT|PASS|lat_pagefault| |TEST END|lat_pagefault| |TRACE LOG| LATENCY INTERPROCESS COMMUNICATION THROUGH PIPES | |TEST START|lat_pipe| |TRACE LOG| Parameters : none| Pipe latency: 39.9448 microseconds |TEST RESULT|PASS|lat_pipe| |TEST END|lat_pipe| |TRACE LOG| LATENCY OF PROCESS CREATION | |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - fork| Process fork+exit: 1180.4000 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - exec| Process fork+execve: 1450.2500 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - shell| sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found Process fork+/bin/sh -c: 8219.0000 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - procedure| Procedure call: 0.0328 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TRACE LOG| LATENCY OF SEMAPHORE | |TEST START|lat_sem| |TRACE LOG| Parameters : none| Semaphore latency: 5.7048 microseconds |TEST RESULT|PASS|lat_sem| |TEST END|lat_sem| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA TCP/IP | |TEST START|lat_tcp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| TCP latency using localhost: 1.8467 microseconds |TEST RESULT|PASS|lat_tcp| |TEST END|lat_tcp| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA UDP | |TEST START|lat_udp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| lat_udp client: recv failed: Connection refused |TEST RESULT|PASS|lat_udp| |TEST END|lat_udp| |TRACE LOG| LATENCY OF PERFORM SELECT | |TEST START|lat_select| |TRACE LOG| Parameters : | |TRACE LOG| type - tcp| Select on 200 tcp fd's: 69.4000 microseconds |TEST RESULT|PASS|lat_select| |TEST END|lat_select| |TRACE LOG| LATENCY TO INSTALL AND CATCH SIGNALS | |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - install| Signal handler installation: 2.4178 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - catch| Signal handler overhead: 21.7325 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - prot| Protection fault: 1.4397 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA UNIX SOCKETS | |TEST START|lat_unix| |TRACE LOG| Parameters : none| AF_UNIX sock stream latency: 47.5259 microseconds |TEST RESULT|PASS|lat_unix| |TEST END|lat_unix| |TRACE LOG| LATENCY OF SYS CALL | |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - fstat| |TRACE LOG| file - test1.txt| Simple fstat: 1.8824 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - stat| |TRACE LOG| file - test1.txt| Simple stat: 4.7288 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - open| |TRACE LOG| file - test1.txt| Simple open/close: 8.6207 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - write| |TRACE LOG| file - test1.txt| Simple write: 1.1588 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - read| |TRACE LOG| file - test1.txt| Simple read: 1.7699 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - null| Simple syscall: 0.7767 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TRACE LOG| ***** EXITING THE LATENCY BENCHMARKING ***** | |TRACE LOG| DELETING THE FILE | |TRACE LOG| done.| |TRACE LOG|| |TRACE LOG| ***** EXITING THE LMBENCH SCRIPT ***** | |TRACE LOG|-------- TEST SUMMARY --------| |TRACE LOG| Total Test Case executed: 83| |TRACE LOG| Total Test Case Passed : 83| |TRACE LOG| Total Test Case failed : 0| |TRACE LOG|-------- TEST SUMMARY --------|
|TRACE LOG| ***** STARTING LMBENCH SCRIPT ***** | |TRACE LOG| CREATING THE FILE OF 16MB SIZE FOR BENCHMARKING| 16+0 records in 16+0 records out 16777216 bytes (16.0MB) copied, 7.494613 seconds, 2.1MB/s |TRACE LOG| ***** STARTING BANDWIDTH BENCHMARKS ***** | |TRACE LOG| MEMORY BANDWIDTH BENCHMARKS | |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 1M| 1.05 260.52 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 2M| 2.10 245.88 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 4M| 4.19 244.57 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 8M| 8.39 242.96 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rd| |TRACE LOG| Memory Blk Size - 16M| 16.78 243.70 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 1M| 1.05 1088.49 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 2M| 2.10 1071.98 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 4M| 4.19 1088.16 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 8M| 8.39 1089.43 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - wr| |TRACE LOG| Memory Blk Size - 16M| 16.78 1088.37 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 1M| 1.05 220.50 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 2M| 2.10 217.82 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 4M| 4.19 217.74 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 8M| 8.39 216.84 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - rdwr| |TRACE LOG| Memory Blk Size - 16M| 16.78 217.37 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 1M| 1.05 193.97 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 2M| 2.10 201.98 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 4M| 4.19 202.92 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 8M| 8.39 208.40 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - cp| |TRACE LOG| Memory Blk Size - 16M| 16.78 209.43 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 1M| 1.05 1079.34 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 2M| 2.10 1079.15 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 4M| 4.19 1079.89 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 8M| 8.39 1079.61 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fwr| |TRACE LOG| Memory Blk Size - 16M| 16.78 1079.34 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 1M| 1.05 239.43 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 2M| 2.10 228.60 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 4M| 4.19 226.60 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 8M| 8.39 225.63 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - frd| |TRACE LOG| Memory Blk Size - 16M| 16.78 225.89 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 1M| 1.05 181.57 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 2M| 2.10 184.67 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 4M| 4.19 182.54 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 8M| 8.39 187.20 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - fcp| |TRACE LOG| Memory Blk Size - 16M| 16.78 187.52 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 1M| 1.05 1067.43 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 2M| 2.10 1066.89 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 4M| 4.19 1066.98 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 8M| 8.39 1067.52 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bzero| |TRACE LOG| Memory Blk Size - 16M| 16.78 1067.32 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 1M| 1.05 182.23 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 2M| 2.10 182.35 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 4M| 4.19 182.08 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 8M| 8.39 184.53 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TEST START|bw_mem| |TRACE LOG| Parameters : | |TRACE LOG| Operation - bcopy| |TRACE LOG| Memory Blk Size - 16M| 16.78 184.89 |TEST RESULT|PASS|bw_mem| |TEST END|bw_mem| |TRACE LOG| BANDWIDTH OF PIPE COMMUNICATION BETWEEN PARENT AND CHILD | |TEST START|bw_unix| |TRACE LOG| Parameters : none| AF_UNIX sock stream bandwidth: 194.31 MB/sec |TEST RESULT|PASS|bw_unix| |TEST END|bw_unix| |TRACE LOG| BANDWIDTH OF FILE READ| |TEST START|bw_file_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - open2close| |TRACE LOG| File - test1.txt| 1.05 159.84 |TEST RESULT|PASS|bw_file_rd| |TEST END|bw_file_rd| |TEST START|bw_file_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - io_only| |TRACE LOG| File - test1.txt| 1.05 162.02 |TEST RESULT|PASS|bw_file_rd| |TEST END|bw_file_rd| |TRACE LOG| BANDWIDTH OF CREATING A MEMORY MAPPING TO A FILE AND READING| |TEST START|bw_mmap_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - open2close| |TRACE LOG| File - test1.txt| 1.05 199.46 |TEST RESULT|PASS|bw_mmap_rd| |TEST END|bw_mmap_rd| |TEST START|bw_mmap_rd| |TRACE LOG| Parameters : | |TRACE LOG| Size - 1MB| |TRACE LOG| operation - mmap_only| |TRACE LOG| File - test1.txt| 1.05 242.59 |TEST RESULT|PASS|bw_mmap_rd| |TEST END|bw_mmap_rd| |TRACE LOG| BANDWIDTH OF CREATING A UNIX PIPE BETWEEN TWO PROCESS AND MOVEING 50 MB DATA THROUGH PIPE IN 64 KB CHUNKS| |TEST START|bw_pipe| |TRACE LOG| Parameters : none| Pipe bandwidth: 220.78 MB/sec |TEST RESULT|PASS|bw_pipe| |TEST END|bw_pipe| |TRACE LOG| BANDWIDTH OF TCP/IP SOCKET ( DATA IS MOVED IN 48KB CHUNKS )| |TEST START|bw_tcp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| |TRACE LOG| transfersize - none(default 3MB )| socket connection: Connection refused |TEST RESULT|PASS|bw_tcp| |TEST END|bw_tcp| |TRACE LOG| ***** EXITING THE BANDWIDTH BENCHMARKING ***** | |TRACE LOG| ***** STARTING LATENCY BENCHMARKS ***** | |TRACE LOG| LATENCY OF INTERPROCESS CONNECTION VIA TCP/IP | |TEST START|lat_connect| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| TCP/IP connection cost to localhost: 109.4898 microseconds |TEST RESULT|PASS|lat_connect| |TEST END|lat_connect| |TRACE LOG| LATENCY OF FILE CREATION AND DELETION | |TEST START|lat_fs| |TRACE LOG| Parameters : | |TRACE LOG| dir - none(default current working directory)| 0k 274 49891 78511 1k 143 25682 59891 4k 141 25224 59524 10k 84 15292 42242 |TEST RESULT|PASS|lat_fs| |TEST END|lat_fs| |TRACE LOG|Syncing filesystem and waiting few seconds prior to starting context switching latency tests| |TRACE LOG| LATENCY OF CONTEXT SWITCHING | |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 2| |TRACE LOG| size_in_kbytes - 128K| |TRACE LOG| repetitions - 100| "size=128k ovr=165.42 2 140.41 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 2| |TRACE LOG| size_in_kbytes - 256K| |TRACE LOG| repetitions - 100| "size=256k ovr=457.46 2 609.37 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 4| |TRACE LOG| size_in_kbytes - 128K| |TRACE LOG| repetitions - 100| "size=128k ovr=162.48 4 399.02 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TEST START|lat_ctx| |TRACE LOG| Parameters : | |TRACE LOG| procs - 4| |TRACE LOG| size_in_kbytes - 256K| |TRACE LOG| repetitions - 100| "size=256k ovr=482.73 4 722.15 |TEST RESULT|PASS|lat_ctx| |TEST END|lat_ctx| |TRACE LOG| LATENCY OF COMMAND | |TEST START|lat_cmd| |TRACE LOG| Parameters : | |TRACE LOG| command - ls| |TEST RESULT|PASS|lat_cmd| |TEST END|lat_cmd| |TRACE LOG| LATENCY OF COMMAND | |TEST START|lat_cmd| |TRACE LOG| Parameters : | |TRACE LOG| command - pwd| |TEST RESULT|PASS|lat_cmd| |TEST END|lat_cmd| |TRACE LOG| LATENCY OF MEMORY READ | |TEST START|lat_mem_rd| |TRACE LOG| Parameters : | |TRACE LOG| size_in_mbytes - 1M| |TRACE LOG| stride - 128| "stride=128 0.00049 5.505 0.00098 5.505 0.00195 5.507 0.00293 5.505 0.00391 5.506 0.00586 5.505 0.00781 5.505 0.00977 5.509 0.01172 5.505 0.01367 5.486 0.01562 5.485 0.01758 5.486 0.01953 5.485 0.02148 5.486 0.02344 5.485 0.02539 5.479 0.02734 5.482 0.02930 5.481 0.03125 5.481 0.03516 8.962 0.03906 12.033 0.04297 12.540 0.04688 14.008 0.05078 15.542 0.05469 15.194 0.05859 15.588 0.06250 16.628 0.07031 16.638 0.07812 17.386 0.08594 17.948 0.09375 17.957 0.10156 18.390 0.10938 18.409 0.11719 18.756 0.12500 21.258 0.14062 34.600 0.15625 33.676 0.17188 33.006 0.18750 40.646 0.20312 39.491 0.21875 47.338 0.23438 68.756 0.25000 72.299 0.28125 98.304 0.31250 117.673 0.34375 137.808 0.37500 152.354 0.40625 164.302 0.43750 177.440 0.46875 188.149 0.50000 194.950 0.56250 206.285 0.62500 214.744 0.68750 220.271 0.75000 225.069 0.81250 227.874 0.87500 230.246 0.93750 232.316 1.00000 233.567 |TEST RESULT|PASS|lat_mem_rd| |TEST END|lat_mem_rd| |TRACE LOG| LATENCY OF MAPPING AND UNMAPPING | |TEST START|lat_mmap| |TRACE LOG| Parameters : | |TRACE LOG| size - 1M| |TRACE LOG| file - test1.txt| 1.048576 73 |TEST RESULT|PASS|lat_mmap| |TEST END|lat_mmap| |TRACE LOG| LATENCY OF BASIC CPU OPERATIONS | |TEST START|lat_ops| |TRACE LOG| Parameters : none| integer bit: 1.83 nanoseconds integer add: 0.19 nanoseconds integer mul: 11.10 nanoseconds integer div: 105.77 nanoseconds integer mod: 43.62 nanoseconds int64 bit: 1.85 nanoseconds int64 add: 0.21 nanoseconds int64 mul: 20.19 nanoseconds int64 div: 450.73 nanoseconds int64 mod: 155.00 nanoseconds float add: 16.24 nanoseconds float mul: 18.23 nanoseconds float div: 60.13 nanoseconds double add: 16.24 nanoseconds double mul: 20.07 nanoseconds double div: 103.82 nanoseconds float bogomflops: 177.68 nanoseconds double bogomflops: 218.54 nanoseconds |TEST RESULT|PASS|lat_ops| |TEST END|lat_ops| |TRACE LOG| LATENCY OF PAGE FAULT | |TEST START|lat_pagefault| |TRACE LOG| Parameters : | |TRACE LOG| file - test1.txt| Pagefaults on test1.txt: 1.8816 microseconds |TEST RESULT|PASS|lat_pagefault| |TEST END|lat_pagefault| |TRACE LOG| LATENCY INTERPROCESS COMMUNICATION THROUGH PIPES | |TEST START|lat_pipe| |TRACE LOG| Parameters : none| Pipe latency: 39.5540 microseconds |TEST RESULT|PASS|lat_pipe| |TEST END|lat_pipe| |TRACE LOG| LATENCY OF PROCESS CREATION | |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - fork| Process fork+exit: 1339.5000 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - exec| Process fork+execve: 1343.2500 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - shell| sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found sh: /tmp/hello: not found Process fork+/bin/sh -c: 8316.0000 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TEST START|lat_proc| |TRACE LOG| Parameters : | |TRACE LOG| type - procedure| Procedure call: 0.0328 microseconds |TEST RESULT|PASS|lat_proc| |TEST END|lat_proc| |TRACE LOG| LATENCY OF SEMAPHORE | |TEST START|lat_sem| |TRACE LOG| Parameters : none| Semaphore latency: 6.3224 microseconds |TEST RESULT|PASS|lat_sem| |TEST END|lat_sem| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA TCP/IP | |TEST START|lat_tcp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| TCP latency using localhost: 1.8417 microseconds |TEST RESULT|PASS|lat_tcp| |TEST END|lat_tcp| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA UDP | |TEST START|lat_udp| |TRACE LOG| Parameters : | |TRACE LOG| hostname - localhost| lat_udp client: recv failed: Connection refused |TEST RESULT|PASS|lat_udp| |TEST END|lat_udp| |TRACE LOG| LATENCY OF PERFORM SELECT | |TEST START|lat_select| |TRACE LOG| Parameters : | |TRACE LOG| type - tcp| Select on 200 tcp fd's: 69.3797 microseconds |TEST RESULT|PASS|lat_select| |TEST END|lat_select| |TRACE LOG| LATENCY TO INSTALL AND CATCH SIGNALS | |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - install| Signal handler installation: 2.3439 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - catch| Signal handler overhead: 21.7113 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TEST START|lat_sig| |TRACE LOG| Parameters : | |TRACE LOG| type - prot| Protection fault: 1.4957 microseconds |TEST RESULT|PASS|lat_sig| |TEST END|lat_sig| |TRACE LOG| LATENCY OF INTERPROCESS COMMUNICATION VIA UNIX SOCKETS | |TEST START|lat_unix| |TRACE LOG| Parameters : none| AF_UNIX sock stream latency: 47.8762 microseconds |TEST RESULT|PASS|lat_unix| |TEST END|lat_unix| |TRACE LOG| LATENCY OF SYS CALL | |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - fstat| |TRACE LOG| file - test1.txt| Simple fstat: 1.8789 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - stat| |TRACE LOG| file - test1.txt| Simple stat: 4.8132 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - open| |TRACE LOG| file - test1.txt| Simple open/close: 8.7898 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - write| |TRACE LOG| file - test1.txt| Simple write: 1.1594 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - read| |TRACE LOG| file - test1.txt| Simple read: 1.9053 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TEST START|lat_syscall| |TRACE LOG| Parameters : | |TRACE LOG| syscall - null| Simple syscall: 0.7764 microseconds |TEST RESULT|PASS|lat_syscall| |TEST END|lat_syscall| |TRACE LOG| ***** EXITING THE LATENCY BENCHMARKING ***** | |TRACE LOG| DELETING THE FILE | |TRACE LOG| done.| |TRACE LOG|| |TRACE LOG| ***** EXITING THE LMBENCH SCRIPT ***** | |TRACE LOG|-------- TEST SUMMARY --------| |TRACE LOG| Total Test Case executed: 83| |TRACE LOG| Total Test Case Passed : 83| |TRACE LOG| Total Test Case failed : 0| |TRACE LOG|-------- TEST SUMMARY --------|
Hi Ankur
I know the first thing they will ask is the DDR configurations please add that to the e2e post. I wonder if the refresh cycle is different between the 2 system? the bandwidth for the transfers should be the same between your rev1 board and rev2 board because they are running at 400Mhz. The question becomes in the CAS and RAS delay i wonder if we set it up differently between the rev1 and rev2 board?
Thanks
Hi Mohsen,
Thank you for reply,
below are EMIF dump from both the boards
For REV1
# ./omapconf dump emif OMAPCONF (rev v1.73-19-gbe8626b built Wed Sep 13 10:18:44 EDT 2017) HW Platform: Generic AM33XX (Flattened Device Tree) AM3352 ES2.1 GP Device (UNKNOWN performance ZCE package (600MHz)) UNKNOWN POWER IC UNKNOWN AUDIO IC SW Build Details: Build: release_details_get(): could not open /etc/issue.net file?! Version: UNKNOWN Kernel: Version: 4.4.32-001-ts-armv7l Author: ankur@ankur-HP-Z440-Workstation Toolchain: gcc version 4.9.4 (Timesys 20170906) Type: #6 Date: Wed Apr 4 15:50:52 EDT 2018 |--------------------------------------------------------| | EMIF4D Reg. Name | Reg. Address | Reg. Value | |--------------------------------------------------------| | EMIF4D_EMIF_MOD_ID_REV | 0x4C000000 | 0x40443403 | | EMIF4D_STATUS | 0x4C000004 | 0x40000000 | | EMIF4D_SDRAM_CONFIG | 0x4C000008 | 0x61C04BB2 | | EMIF4D_SDRAM_CONFIG_2 | 0x4C00000C | 0x00000000 | | EMIF4D_SDRAM_REF_CTRL | 0x4C000010 | 0x0000093B | | EMIF4D_SDRAM_REF_CTRL_SHDW | 0x4C000014 | 0x0000093B | | EMIF4D_SDRAM_TIM_1 | 0x4C000018 | 0x0AAAD4DB | | EMIF4D_SDRAM_TIM_1_SHDW | 0x4C00001C | 0x0AAAD4DB | | EMIF4D_SDRAM_TIM_2 | 0x4C000020 | 0x2C437FDA | | EMIF4D_SDRAM_TIM_2_SHDW | 0x4C000024 | 0x2C437FDA | | EMIF4D_SDRAM_TIM_3 | 0x4C000028 | 0x501F83FF | | EMIF4D_SDRAM_TIM_3_SHDW | 0x4C00002C | 0x501F83FF | | EMIF4D_PWR_MGMT_CTRL | 0x4C000038 | 0x00000000 | | EMIF4D_PWR_MGMT_CTRL_SHDW | 0x4C00003C | 0x00000000 | | EMIF4D_INT_CONFIG | 0x4C000054 | 0x00FFFFFF | | EMIF4D_INT_CFG_VAL_1 | 0x4C000058 | 0x8000140A | | EMIF4D_INT_CFG_VAL_2 | 0x4C00005C | 0x00021616 | | EMIF4D_PERF_CNT_1 | 0x4C000080 | 0x68F5FEB4 | | EMIF4D_PERF_CNT_2 | 0x4C000084 | 0x16739ACB | | EMIF4D_PERF_CNT_CFG | 0x4C000088 | 0x00010000 | | EMIF4D_PERF_CNT_SEL | 0x4C00008C | 0x00000000 | | EMIF4D_PERF_CNT_TIM | 0x4C000090 | 0x0D28C06D | | EMIF4D_READ_IDLE_CTRL | 0x4C000098 | 0x00050000 | | EMIF4D_READ_IDLE_CTRL_SHDW | 0x4C00009C | 0x00050000 | | EMIF4D_IRQSTATUS_RAW_SYS | 0x4C0000A4 | 0x00000000 | | EMIF4D_IRQSTATUS_SYS | 0x4C0000AC | 0x00000000 | | EMIF4D_IRQENABLE_SET_SYS | 0x4C0000B4 | 0x00000000 | | EMIF4D_IRQENABLE_CLR_SYS | 0x4C0000BC | 0x00000000 | | EMIF4D_ZQ_CONFIG | 0x4C0000C8 | 0x50074BE4 | | EMIF4D_RDWR_LVL_RMP_WIN | 0x4C0000D4 | 0x00000000 | | EMIF4D_RDWR_LVL_RMP_CTRL | 0x4C0000D8 | 0x00000000 | | EMIF4D_RDWR_LVL_CTRL | 0x4C0000DC | 0x00000000 | | EMIF4D_DDR_PHY_CTRL_1 | 0x4C0000E4 | 0x00000006 | | EMIF4D_DDR_PHY_CTRL_1_SHDW | 0x4C0000E8 | 0x00000006 | | EMIF4D_PRI_COS_MAP | 0x4C000100 | 0x00000000 | | EMIF4D_CONNID_COS_1_MAP | 0x4C000104 | 0x00000000 | | EMIF4D_CONNID_COS_2_MAP | 0x4C000108 | 0x00000000 | | EMIF4D_RD_WR_EXEC_THRSH | 0x4C000120 | 0x00000305 | |--------------------------------------------------------|
For REV2
# ./omapconf dump emif OMAPCONF (rev v1.73-19-gbe8626b built Wed Sep 13 10:18:44 EDT 2017) HW Platform: Generic AM33XX (Flattened Device Tree) AM3352 ES2.1 GP Device (UNKNOWN performance ZCE package (600MHz)) UNKNOWN POWER IC UNKNOWN AUDIO IC SW Build Details: Build: release_details_get(): could not open /etc/issue.net file?! Version: UNKNOWN Kernel: Version: 4.4.32-001-ts-armv7l Author: ankur@ankur-HP-Z440-Workstation Toolchain: gcc version 4.9.4 (Timesys 20170906) Type: #6 Date: Wed Apr 4 15:50:52 EDT 2018 |--------------------------------------------------------| | EMIF4D Reg. Name | Reg. Address | Reg. Value | |--------------------------------------------------------| | EMIF4D_EMIF_MOD_ID_REV | 0x4C000000 | 0x40443403 | | EMIF4D_STATUS | 0x4C000004 | 0x40000000 | | EMIF4D_SDRAM_CONFIG | 0x4C000008 | 0x61C05332 | | EMIF4D_SDRAM_CONFIG_2 | 0x4C00000C | 0x00000000 | | EMIF4D_SDRAM_REF_CTRL | 0x4C000010 | 0x00000C30 | | EMIF4D_SDRAM_REF_CTRL_SHDW | 0x4C000014 | 0x00000C30 | | EMIF4D_SDRAM_TIM_1 | 0x4C000018 | 0x0AAAD4DB | | EMIF4D_SDRAM_TIM_1_SHDW | 0x4C00001C | 0x0AAAD4DB | | EMIF4D_SDRAM_TIM_2 | 0x4C000020 | 0x3E6B7FDB | | EMIF4D_SDRAM_TIM_2_SHDW | 0x4C000024 | 0x3E6B7FDB | | EMIF4D_SDRAM_TIM_3 | 0x4C000028 | 0x501F867F | | EMIF4D_SDRAM_TIM_3_SHDW | 0x4C00002C | 0x501F867F | | EMIF4D_PWR_MGMT_CTRL | 0x4C000038 | 0x00000000 | | EMIF4D_PWR_MGMT_CTRL_SHDW | 0x4C00003C | 0x00000000 | | EMIF4D_INT_CONFIG | 0x4C000054 | 0x00FFFFFF | | EMIF4D_INT_CFG_VAL_1 | 0x4C000058 | 0x8000140A | | EMIF4D_INT_CFG_VAL_2 | 0x4C00005C | 0x00021616 | | EMIF4D_PERF_CNT_1 | 0x4C000080 | 0x67C8F334 | | EMIF4D_PERF_CNT_2 | 0x4C000084 | 0x15F66294 | | EMIF4D_PERF_CNT_CFG | 0x4C000088 | 0x00010000 | | EMIF4D_PERF_CNT_SEL | 0x4C00008C | 0x00000000 | | EMIF4D_PERF_CNT_TIM | 0x4C000090 | 0x717F2D5D | | EMIF4D_READ_IDLE_CTRL | 0x4C000098 | 0x00050000 | | EMIF4D_READ_IDLE_CTRL_SHDW | 0x4C00009C | 0x00050000 | | EMIF4D_IRQSTATUS_RAW_SYS | 0x4C0000A4 | 0x00000000 | | EMIF4D_IRQSTATUS_SYS | 0x4C0000AC | 0x00000000 | | EMIF4D_IRQENABLE_SET_SYS | 0x4C0000B4 | 0x00000000 | | EMIF4D_IRQENABLE_CLR_SYS | 0x4C0000BC | 0x00000000 | | EMIF4D_ZQ_CONFIG | 0x4C0000C8 | 0x50074BE4 | | EMIF4D_RDWR_LVL_RMP_WIN | 0x4C0000D4 | 0x00000000 | | EMIF4D_RDWR_LVL_RMP_CTRL | 0x4C0000D8 | 0x00000000 | | EMIF4D_RDWR_LVL_CTRL | 0x4C0000DC | 0x00000000 | | EMIF4D_DDR_PHY_CTRL_1 | 0x4C0000E4 | 0x00100007 | | EMIF4D_DDR_PHY_CTRL_1_SHDW | 0x4C0000E8 | 0x00100007 | | EMIF4D_PRI_COS_MAP | 0x4C000100 | 0x00000000 | | EMIF4D_CONNID_COS_1_MAP | 0x4C000104 | 0x00000000 | | EMIF4D_CONNID_COS_2_MAP | 0x4C000108 | 0x00000000 | | EMIF4D_RD_WR_EXEC_THRSH | 0x4C000120 | 0x00000305 | |--------------------------------------------------------|
Meanwhile let me read TRM to answer your other questions.
Thank you,
Regards,
Ankur
Thank you for reply Biser,
In that case also both the boards should have same speed isn't it ? I consistently get low speed on REV2 board. I am not able to understand that.
Regards,
Ankur
Hi Ankur
We ahve done some memory test using lmbench. I suggest you sue the same test that we use that way we can compare apple to apple. Below is the site that has the ti bench mark for memory read and write and other peripherals.
processors.wiki.ti.com/.../Processor_SDK_Linux_Kernel_Performance_Guide
Below gives you the source code for the bench mark.
Hi Mohsen,
Sorry for late reply,
I have captured lmbench results and updated in the format as mentioned in above link.
I have attached the excel with last two column updated for our REV1 and REV2 board lmbench results.
Let me know if I should start looking into some configuration of DDR.
Thanks,
Regards,
Ankur
Do you have the EMIF spreadsheets that correspond to each of these configurations? They are clearly different, and I expect the difference explains the behavior. It would be much easier to compare the spreadsheets instead of decoding all the registers and comparing.
Oh That one we have it,
I have attached two excel for REV1 and REV2
AM335x_DDR_register_calc_tool_MT41K128M16JT-125K_TR_REV1.xlsAM335x_DDR_register_calc_tool_MT41K256M16TW-107P_TR_REV2.xls
HI Brad,
I compared the EMFI registers so only difference we have is with respect to following registers
EMIF4D_SDRAM_CONFIG
EMIF4D_SDRAM_REF_CTRL
EMIF4D_SDRAM_TIM_2
EMIF4D_SDRAM_TIM_3
EMIF4D_PERF_CNT_1
EMIF4D_PERF_CNT_2
EMIF4D_PERF_CNT_TIM
EMIF4D_DDR_PHY_CTRL_1
I don't see anything fishy. ( or I don't understand )
Do you have any suggestion for me ? What should I check next ?
Thanks,
Regards,
Ankur
Sorry for the delay. There's no "out of office" reply for the forum!
According to your spreadsheets, tRFC went from 63 to 103. I'm not surprised to see some minor performance degradation as a result. I double checked in the Micron data sheet, and this is indeed a difference between the 2Gb and 4Gb devices, i.e. this timing is slower for the 4Gb device. So in short, I think the answer here is that things are behaving as one might expect.
Hi Brad,
Thank you for reply.
Is there something we can do to improve performance ?
Can we change other mode/parameter of EMIF to improve the performance ?
Thanks,
Regards,
Ankur
You would need to choose a different memory device with better timings if you wanted to improve the performance. This is a memory limitation, not a processor limitation.