secs, 0.0225 secs DNS-lookup: 0.0000 secs, 0.0000 secs, 0.0008 secs req write: 0.0000 secs, secs, 0.0042 secs DNS-lookup: 0.0000 secs, 0.0000 secs, 0.0009 secs req write: 0.0000 secs, secs, 0.0027 secs DNS-lookup: 0.0000 secs, 0.0000 secs, 0.0008 secs req write: 0.0000 secs, secs, 0.0002 secs resp wait: 0.0011 secs, 0.0007 secs, 0.0013 secs resp read: 0.0002 secs, secs, 0.0002 secs resp wait: 0.9101 secs, 0.0008 secs, 1.0015 secs resp read: 0.0002 secs,
25% in 0.3721 secs 50% in 0.7243 secs 75% in 1.4874 secs 90% in 3.3814 secs 95% in 6.0845 secs, 19.9194 secs DNS-lookup: 0.0000 secs, 0.0000 secs, 0.0000 secs req write: 0.0001 secs, 0.0000 secs, 0.0309 secs resp wait: 1.4584 secs, 0.3515 secs, 19.9191 secs resp read: 0.0002 , 19.4560 secs DNS-lookup: 0.0000 secs, 0.0000 secs, 0.0000 secs req write: 0.0001 secs, 0.0000 secs, 0.0117 secs resp wait: 0.9539 secs, 0.3519 secs, 19.4558 secs resp read: 0.0002 secs
] 5504K->1556K(19840K), 0.0137738 secs] [Times: user=0.06 sys=0.01, real=0.01 secs] [GC (Allocation Failure) [ParNew: 6144K->544K(6144K), 0.0012950 secs] 7060K->2102K(19840K), 0.0013310 secs] [Times: user =0.01 sys=0.00, real=0.00 secs] [GC (Allocation Failure) [ParNew: 6048K->640K(6144K), 0.0175172 secs ] 7606K->4311K(19840K), 0.0176172 secs] [Times: user=0.07 sys=0.01, real=0.01 secs] [GC (Allocation , 0.0000870 secs][class unloading, 0.0017562 secs][scrub symbol table, 0.0012416 secs][scrub string table
200 0.28 secs: 126124 bytes ==> GET /HTTP/1.1 301 0.06 secs: 0 bytes ==> GET /HTTP/ /0f482622.pngHTTP/1.1 200 0.52 secs: 126124 bytes ==> GET /HTTP/1.1 200 0.15 secs: 6752 301 0.05 secs: 0 bytes ==> GET /HTTP/1.1 301 0.07 secs: 0 bytes ==> GET /HTTP/ 301 0.07 secs: 0 bytes ==> GET /HTTP/1.1 301 0.07 secs: 0 bytes ==> GET /HTTP/ from=noscriptHTTP/1.1 200 0.28 secs: 102168 bytes ==> GET /HTTP/1.1 200 0.06 secs: 2947
4: TEST OK [0.011 secs, 6912 KB] Test 5: TEST OK [0.043 secs, 6912 KB] Test 6: TEST OK [0.151 secs 4: TEST OK [0.011 secs, 4448 KB] Test 5: TEST OK [0.032 secs, 4844 KB] Test 6: TEST OK [0.151 secs secs, 4184 KB] Test 12: TEST OK [0.216 secs, 7220 KB] */ 方法三:超时一个点。 [0.000 secs, 5740 KB] Test 5: TEST OK [0.000 secs, 5740 KB] Test 6: TEST OK [0.022 secs, 5740 KB] Test 7: TEST OK [0.000 secs, 5740 KB] Test 8: TEST OK [0.000 secs, 5740 KB] Test 9: TEST OK [0.000 secs,
] 9702786K->6354533K(24746432K), 0.0974940 secs] [Times: user=0.95 sys=0.00, real=0.09 secs] 依次分析一下上面日志信息的含义 ), 0.1365130 secs] [Times: user=1.24 sys=0.00, real=0.14 secs] 2018-04-12T13:48:30.128+0800: 15582.043 ] 4836483K->1489601K(24746432K), 0.0877490 secs] [Times: user=0.84 sys=0.00, real=0.09 secs] 2018-04- ; 0.056/0.057 secs:这个阶段的持续时间与时钟时间; [Times: user=0.20 sys=0.12, real=0.06 secs]:同并发标记阶段中的含义。 secs:这个阶段的持续时间与时钟时间; [Times: user=0.15 sys=0.10, real=0.04 secs]:同前面。
Slowest: 0.0770 secs Fastest: 0.0067 secs // 平均响应时间 Average: 0.0097 secs // 每秒响应数(QBS) 25% in 0.0077 secs 50% in 0.0081 secs 75% in 0.0088 secs 90% in 0.0093 secs 95% in 0.0106 +dialup: 0.0010 secs, 0.0067 secs, 0.0770 secs DNS-lookup: 0.0001 secs, 0.0000 secs, 0.0076 secs req write: 0.0000 secs, 0.0000 secs, 0.0008 secs resp wait: 0.0083 secs, 0.0066 secs, 0.0769 secs resp read: 0.0001 secs, 0.0000 secs, 0.0029 secs // http状态码分布 Status code distribution:
Summary: Total: 9.9769 secs Slowest: 0.3740 secs Fastest: 0.0350 secs Average 25% in 0.0802 secs 50% in 0.0917 secs 75% in 0.1056 secs 90% in 0.1266 secs 95% in 0.1510 secs 99% in 0.2334 secs Details (average, fastest, slowest): DNS+dialup: 0.0016 secs, 0.0350 secs , 0.3740 secs DNS-lookup: 0.0008 secs, 0.0000 secs, 0.1045 secs req write: 0.0001 secs, 0.0000 secs, 0.0716 secs resp wait: 0.0896 secs, 0.0320 secs, 0.2326 secs resp read: 0.0054 secs,
)] 2025K->1948K(263168K), 0.0012290 secs] [Times: user=0.01 sys=0.01, real=0.01 secs] [GC (Allocation ), 0.0023170 secs] [Times: user=0.02 sys=0.00, real=0.00 secs] [GC (Allocation Failure) [PSYoungGen: 4593K->2560K(5120K)] 8195K->6681K(265728K), 0.0249700 secs] [Times: user=0.31 sys=0.00, real=0.03 secs ->30103K(270848K), 0.0285370 secs] [Times: user=0.36 sys=0.01, real=0.03 secs] [GC (Allocation Failure ), 0.0065540 secs] [Times: user=0.08 sys=0.00, real=0.00 secs] [GC (Allocation Failure) [PSYoungGen:
Slowest: 7.2684 secs Fastest: 0.0005 secs Average: 0.0518 secs Requests/sec: 19079.9386 25% in 0.0055 secs 50% in 0.0086 secs 75% in 0.0133 secs 90% in 0.0212 secs 95% in 0.0350 secs 99% in 1.0409 secs ========== Rust (actix-web) oha ========== Success rate: 1.0000 Total : 78.6992 secs Slowest: 15.3911 secs Fastest: 0.0003 secs Average: 0.0767 secs Requests/sec 25% in 0.0052 secs 50% in 0.0145 secs 75% in 0.0484 secs 90% in 0.1082 secs 95% in 0.3382
] 2986990K->2923498K(4185792K), 0.0807806 secs] [Times: user=0.06 sys=0.02, real=0.08 secs] 2021-08- 10T14:51:48.296+0800: [CMS-concurrent-mark: 0.248/0.333 secs] [Times: user=1.49 sys=0.06, real=0.33 secs ] 2991658K->2937785K(4185792K), 0.1290695 secs] [Times: user=0.11 sys=0.02, real=0.13 secs] 2021-08- table, 0.0009098 secs][1 CMS-remark: 2929273K(4109120K)] 2980795K(4185792K), 0.1049206 secs] [Times: : 0.026/0.026 secs] [Times: user=0.10 sys=0.01, real=0.02 secs] 从日志就可以看出使用了CMS垃圾回收器。
, 11992 KB] Test 2: TEST OK [0.011 secs, 11992 KB] Test 3: TEST OK [0.011 secs, 11992 KB] Test 4: TEST OK [0.011 secs, 11992 KB] Test 5: TEST OK [0.011 secs, 11992 KB] Test 6: TEST OK [0.011 secs, 11992 KB] Test 7: TEST OK [0.000 secs, 11992 KB] Test 8: TEST OK [0.011 secs, 11992 KB] Test 9: TEST OK [0.011 secs, 11992 KB] Test 10: TEST OK [0.076 secs, 11992 KB] Test 11: TEST OK [0.216 secs, 11992 KB] Test 12: TEST OK [0.076 secs, 11992 KB] Test 13: TEST OK [0.119 secs
: [GC 16616K->152K(62848K), 0.0007727 secs] 25.157: [GC 16600K->152K(62848K), 0.0007124 secs] 33.881: [GC 16600K->136K(62848K), 0.0003721 secs] 43.032: [GC 16584K->152K(59968K), 0.0004123 secs] 50.795: [GC 16216K->152K(59584K), 0.0009683 secs] 58.584: [GC 15832K->148K(59136K), 0.0008669 secs] 66.430: [ secs]24.890: [WeakReference, 1 refs, 0.0000053 secs]24.890: [FinalReference, 4 refs, 0.0000056 secs] secs]32.830: [FinalReference, 4 refs, 0.0000056 secs]32.830: [PhantomReference, 0 refs, 0.0000045 secs
] 272640K->69574K(2063104K), 0.0845560 secs] [Times: user=0.23 sys=0.03, real=0.09 secs] 4.092: [GC ), 0.1014307 secs] [Times: user=0.25 sys=0.05, real=0.10 secs] ... cut for brevity ... 11.292: [GC ( ), 0.0857875 secs] [Times: user=0.26 sys=0.04, real=0.09 secs] 12.140: [GC (Allocation Failure) 12.140 ] 1204052K->1010638K(2063104K), 0.0961542 secs] [Times: user=0.29 sys=0.04, real=0.09 secs] 14.366: ]14.395: [weak refs processing, 0.0000232 secs]14.395: [class unloading, 0.0117661 secs]14.407: [scrub
: [ParNew: 583983K->27488K(623550K), 0.0868170 secs] 614171K->104810K(3102652K), 0.0875620 secs] [Times 3、总分配堆信息 584661K->87777K(3102652K), 0.0426850 secs] 614171K->104810K(3102652K), 0.0892180 secs] secs]40.883: [weak refs processing, 0.0100966 secs] [1 CMS-remark: 26386K(786432K)] 52644K(1048384K) : [ParNew: 1152K>128K(1152K), 0.0006430 secs] 16780K->15913K(26936K), 0.0006640 secs] [Times: user= 0.00 sys=0.00, real=0.00 secs] 1.819: [GC1.819: [ParNew: 1152K>128K(1152K), 0.0005370 secs] 16937K-
] 272640K->69574K(2063104K), 0.0845560 secs] [Times: user=0.23 sys=0.03, real=0.09 secs] 4.092: [GC ), 0.1014307 secs] [Times: user=0.25 sys=0.05, real=0.10 secs] ... cut for brevity ... 11.292: [GC ), 0.0857875 secs] [Times: user=0.26 sys=0.04, real=0.09 secs] 12.140: [GC (Allocation Failure) 12.140 ] 1204052K->1010638K(2063104K), 0.0961542 secs] [Times: user=0.29 sys=0.04, real=0.09 secs] 14.366: ]14.395: [weak refs processing, 0.0000232 secs]14.395: [class unloading, 0.0117661 secs]14.407: [scrub
] 272640K->69574K(2063104K), 0.0845560 secs] [Times: user=0.23 sys=0.03, real=0.09 secs] 4.092: [GC ), 0.1014307 secs] [Times: user=0.25 sys=0.05, real=0.10 secs] ... cut for brevity ... 11.292: [GC ( ), 0.0857875 secs] [Times: user=0.26 sys=0.04, real=0.09 secs] 12.140: [GC (Allocation Failure) 12.140 ] 1204052K->1010638K(2063104K), 0.0961542 secs] [Times: user=0.29 sys=0.04, real=0.09 secs] 14.366: ]14.395: [weak refs processing, 0.0000232 secs]14.395: [class unloading, 0.0117661 secs]14.407: [scrub
: [ParNew: 583983K->27488K(623550K), 0.0868170 secs] 614171K->104810K(3102652K), 0.0875620 secs] [Times 3、总分配堆信息 584661K->87777K(3102652K), 0.0426850 secs] 614171K->104810K(3102652K), 0.0892180 secs] secs]40.883: [weak refs processing, 0.0100966 secs] [1 CMS-remark: 26386K(786432K)] 52644K(1048384K) : [ParNew: 1152K>128K(1152K), 0.0006430 secs] 16780K->15913K(26936K), 0.0006640 secs] [Times: user= 0.00 sys=0.00, real=0.00 secs] 1.819: [GC1.819: [ParNew: 1152K>128K(1152K), 0.0005370 secs] 16937K-
] 272640K->69574K(2063104K), 0.0845560 secs] [Times: user=0.23 sys=0.03, real=0.09 secs] 4.092: [GC ( secs] [Times: user=0.26 sys=0.04, real=0.09 secs] 12.140: [GC (Allocation Failure) 12.140: [ParNew: , 0.1086667 secs] 1128760K->931412K(2063104K), 0.1087416 secs] [Times: user=0.24 sys=0.04, real=0.11 ] 1204052K->1010638K(2063104K), 0.0961542 secs] [Times: user=0.29 sys=0.04, real=0.09 secs] 14.366: [ ]14.633: [CMS-concurrent-sweep: 0.221/0.221 secs] [Times: user=0.37 sys=0.00, real=0.22 secs] 14.633:
) 2136K->1992K(3584K), 0.0040658 secs] [Full GC (Ergonomics) 2807K->2791K(3584K), 0.0036280 secs] [ (3584K), 0.0034554 secs] [Full GC (Allocation Failure) 2773K->373K(3584K), 0.0032667 secs] [Full GC ) 2125K->1994K(3584K), 0.0054759 secs] [Full GC (Ergonomics) 2822K->2794K(3584K), 0.0023167 secs] [ (3584K), 0.0042365 secs] [Full GC (Allocation Failure) 2776K->376K(3584K), 0.0035122 secs] [Full GC ) 2778K->2758K(3584K), 0.0021269 secs] [Full GC (Ergonomics) 2779K->2770K(3584K), 0.0016329 secs] [