log.txt

scala@scalabench:~$ cat /proc/cmdline
BOOT_IMAGE=/boot/vmlinuz-3.16.0-4-amd64 root=UUID=64ababa6-cf06-401f-a05b-749630ec8fca ro quiet

scala@scalabench:~/compiler-benchmark$ sbt
[info] Loading project definition from /home/scala/compiler-benchmark/project
[info] Set current project to compiler-benchmark (in build file:/home/scala/compiler-benchmark/)
> hot -p source=scalap -wi 20 -i 10 -f 1
[info] Running org.openjdk.jmh.Main HotScalacBenchmark -p source=scalap -wi 20 -i 10 -f 1
[info] # JMH 1.14.1 (released 237 days ago, please consider updating!)
[info] # VM version: JDK 1.8.0_131, VM 25.131-b11
[info] # VM invoker: /usr/lib/jvm/java-8-oracle/jre/bin/java
[info] # VM options: -DscalaVersion=2.11.8 -DscalaRef=v2.11.8
[info] # Warmup: 20 iterations, 10 s each
[info] # Measurement: 10 iterations, 10 s each
[info] # Timeout: 10 min per iteration
[info] # Threads: 1 thread, will synchronize iterations
[info] # Benchmark mode: Sampling time
[info] # Benchmark: scala.tools.nsc.HotScalacBenchmark.compile
[info] # Parameters: (extraArgs = , source = scalap)
[info]
[info] # Run progress: 0.00% complete, ETA 00:05:00
[info] # Fork: 1 of 1
[info] # Warmup Iteration   1: 4129.991 ±(99.9%) 45029.389 ms/op
[info] # Warmup Iteration   2: 2119.382 ±(99.9%) 677.746 ms/op
[info] # Warmup Iteration   3: 1589.641 ±(99.9%) 257.247 ms/op
[info] # Warmup Iteration   4: 1403.257 ±(99.9%) 74.752 ms/op
[info] # Warmup Iteration   5: 1341.129 ±(99.9%) 44.907 ms/op
[info] # Warmup Iteration   6: 1303.904 ±(99.9%) 28.494 ms/op
[info] # Warmup Iteration   7: 1288.700 ±(99.9%) 16.602 ms/op
[info] # Warmup Iteration   8: 1289.486 ±(99.9%) 45.639 ms/op
[info] # Warmup Iteration   9: 1271.923 ±(99.9%) 12.497 ms/op
[info] # Warmup Iteration  10: 1269.826 ±(99.9%) 20.890 ms/op
[info] # Warmup Iteration  11: 1261.961 ±(99.9%) 14.338 ms/op
[info] # Warmup Iteration  12: 1265.107 ±(99.9%) 10.223 ms/op
[info] # Warmup Iteration  13: 1264.845 ±(99.9%) 16.662 ms/op
[info] # Warmup Iteration  14: 1257.767 ±(99.9%) 8.772 ms/op
[info] # Warmup Iteration  15: 1259.078 ±(99.9%) 14.684 ms/op
[info] # Warmup Iteration  16: 1249.116 ±(99.9%) 10.039 ms/op
[info] # Warmup Iteration  17: 1250.951 ±(99.9%) 12.312 ms/op
[info] # Warmup Iteration  18: 1255.145 ±(99.9%) 30.386 ms/op
[info] # Warmup Iteration  19: 1252.786 ±(99.9%) 27.190 ms/op
[info] # Warmup Iteration  20: 1260.388 ±(99.9%) 44.907 ms/op
[info] Iteration   1: 1251.475 ±(99.9%) 24.601 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1247.805 ms/op
[info]                  compile·p0.90:   1279.263 ms/op
[info]                  compile·p0.95:   1279.263 ms/op
[info]                  compile·p0.99:   1279.263 ms/op
[info]                  compile·p0.999:  1279.263 ms/op
[info]                  compile·p0.9999: 1279.263 ms/op
[info]                  compile·p1.00:   1279.263 ms/op
[info]
[info] Iteration   2: 1253.048 ±(99.9%) 37.061 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1245.708 ms/op
[info]                  compile·p0.90:   1296.040 ms/op
[info]                  compile·p0.95:   1296.040 ms/op
[info]                  compile·p0.99:   1296.040 ms/op
[info]                  compile·p0.999:  1296.040 ms/op
[info]                  compile·p0.9999: 1296.040 ms/op
[info]                  compile·p1.00:   1296.040 ms/op
[info]
[info] Iteration   3: 1258.815 ±(99.9%) 43.491 ms/op
[info]                  compile·p0.00:   1247.805 ms/op
[info]                  compile·p0.50:   1250.951 ms/op
[info]                  compile·p0.90:   1314.914 ms/op
[info]                  compile·p0.95:   1314.914 ms/op
[info]                  compile·p0.99:   1314.914 ms/op
[info]                  compile·p0.999:  1314.914 ms/op
[info]                  compile·p0.9999: 1314.914 ms/op
[info]                  compile·p1.00:   1314.914 ms/op
[info]
[info] Iteration   4: 1246.407 ±(99.9%) 19.542 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1241.514 ms/op
[info]                  compile·p0.90:   1268.777 ms/op
[info]                  compile·p0.95:   1268.777 ms/op
[info]                  compile·p0.99:   1268.777 ms/op
[info]                  compile·p0.999:  1268.777 ms/op
[info]                  compile·p0.9999: 1268.777 ms/op
[info]                  compile·p1.00:   1268.777 ms/op
[info]
[info] Iteration   5: 1255.408 ±(99.9%) 53.407 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1242.563 ms/op
[info]                  compile·p0.90:   1317.011 ms/op
[info]                  compile·p0.95:   1317.011 ms/op
[info]                  compile·p0.99:   1317.011 ms/op
[info]                  compile·p0.999:  1317.011 ms/op
[info]                  compile·p0.9999: 1317.011 ms/op
[info]                  compile·p1.00:   1317.011 ms/op
[info]
[info] Iteration   6: 1247.339 ±(99.9%) 25.703 ms/op
[info]                  compile·p0.00:   1237.320 ms/op
[info]                  compile·p0.50:   1241.514 ms/op
[info]                  compile·p0.90:   1275.068 ms/op
[info]                  compile·p0.95:   1275.068 ms/op
[info]                  compile·p0.99:   1275.068 ms/op
[info]                  compile·p0.999:  1275.068 ms/op
[info]                  compile·p0.9999: 1275.068 ms/op
[info]                  compile·p1.00:   1275.068 ms/op
[info]
[info] Iteration   7: 1245.475 ±(99.9%) 16.096 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1239.417 ms/op
[info]                  compile·p0.90:   1266.680 ms/op
[info]                  compile·p0.95:   1266.680 ms/op
[info]                  compile·p0.99:   1266.680 ms/op
[info]                  compile·p0.999:  1266.680 ms/op
[info]                  compile·p0.9999: 1266.680 ms/op
[info]                  compile·p1.00:   1266.680 ms/op
[info]
[info] Iteration   8: 1253.310 ±(99.9%) 27.442 ms/op
[info]                  compile·p0.00:   1241.514 ms/op
[info]                  compile·p0.50:   1249.903 ms/op
[info]                  compile·p0.90:   1287.651 ms/op
[info]                  compile·p0.95:   1287.651 ms/op
[info]                  compile·p0.99:   1287.651 ms/op
[info]                  compile·p0.999:  1287.651 ms/op
[info]                  compile·p0.9999: 1287.651 ms/op
[info]                  compile·p1.00:   1287.651 ms/op
[info]
[info] Iteration   9: 1242.446 ±(99.9%) 6.619 ms/op
[info]                  compile·p0.00:   1239.417 ms/op
[info]                  compile·p0.50:   1241.514 ms/op
[info]                  compile·p0.90:   1249.903 ms/op
[info]                  compile·p0.95:   1249.903 ms/op
[info]                  compile·p0.99:   1249.903 ms/op
[info]                  compile·p0.999:  1249.903 ms/op
[info]                  compile·p0.9999: 1249.903 ms/op
[info]                  compile·p1.00:   1249.903 ms/op
[info]
[info] Iteration  10: 1239.650 ±(99.9%) 5.961 ms/op
[info]                  compile·p0.00:   1237.320 ms/op
[info]                  compile·p0.50:   1237.320 ms/op
[info]                  compile·p0.90:   1247.805 ms/op
[info]                  compile·p0.95:   1247.805 ms/op
[info]                  compile·p0.99:   1247.805 ms/op
[info]                  compile·p0.999:  1247.805 ms/op
[info]                  compile·p0.9999: 1247.805 ms/op
[info]                  compile·p1.00:   1247.805 ms/op
[info]
[info]
[info]
[info] Result "compile":
[info]   N = 85
[info]   mean =   1249.039 ±(99.9%) 5.852 ms/op
[info]
[info]   Histogram, ms/op:
[info]     [1230.000, 1235.000) = 0
[info]     [1235.000, 1240.000) = 29
[info]     [1240.000, 1245.000) = 18
[info]     [1245.000, 1250.000) = 18
[info]     [1250.000, 1255.000) = 7
[info]     [1255.000, 1260.000) = 0
[info]     [1260.000, 1265.000) = 3
[info]     [1265.000, 1270.000) = 2
[info]     [1270.000, 1275.000) = 1
[info]     [1275.000, 1280.000) = 3
[info]     [1280.000, 1285.000) = 0
[info]     [1285.000, 1290.000) = 1
[info]     [1290.000, 1295.000) = 0
[info]     [1295.000, 1300.000) = 1
[info]     [1300.000, 1305.000) = 0
[info]     [1305.000, 1310.000) = 0
[info]     [1310.000, 1315.000) = 1
[info]
[info]   Percentiles, ms/op:
[info]       p(0.0000) =   1237.320 ms/op
[info]      p(50.0000) =   1241.514 ms/op
[info]      p(90.0000) =   1270.455 ms/op
[info]      p(95.0000) =   1285.135 ms/op
[info]      p(99.0000) =   1317.011 ms/op
[info]      p(99.9000) =   1317.011 ms/op
[info]      p(99.9900) =   1317.011 ms/op
[info]      p(99.9990) =   1317.011 ms/op
[info]      p(99.9999) =   1317.011 ms/op
[info]     p(100.0000) =   1317.011 ms/op
[info]
[info]
[info] # Run complete. Total time: 00:05:14
[info]
[info] Benchmark                                   (extraArgs)  (source)    Mode  Cnt     Score   Error  Units
[info] HotScalacBenchmark.compile                                 scalap  sample   85  1249.039 ± 5.852  ms/op
[info] HotScalacBenchmark.compile:compile·p0.00                   scalap  sample       1237.320          ms/op
[info] HotScalacBenchmark.compile:compile·p0.50                   scalap  sample       1241.514          ms/op
[info] HotScalacBenchmark.compile:compile·p0.90                   scalap  sample       1270.455          ms/op
[info] HotScalacBenchmark.compile:compile·p0.95                   scalap  sample       1285.135          ms/op
[info] HotScalacBenchmark.compile:compile·p0.99                   scalap  sample       1317.011          ms/op
[info] HotScalacBenchmark.compile:compile·p0.999                  scalap  sample       1317.011          ms/op
[info] HotScalacBenchmark.compile:compile·p0.9999                 scalap  sample       1317.011          ms/op
[info] HotScalacBenchmark.compile:compile·p1.00                   scalap  sample       1317.011          ms/op
[success] Total time: 316 s, completed May 15, 2017 2:10:10 AM
>