SEO外包平台,我们为您提供专业的企业网站SEO整站优化外包服务 SEO设置

SEO外包平台

专注于企业网站SEO整站优化外包服务

ZGC源分析(6)-测井分析

作者:八月      发布时间:2021-05-15      浏览量:1
根据ZGC的运行方式拦截一些关键日志信息

根据ZGC的运行方式拦截一些关键日志信息。分析结果如下:

1。ZGC初始化信息

的部分主要是ZGC初始化的状态。从日志中我们可以看到JVM的版本,它是否支持NUMA、主内存等等。另外,CPU数为8,GC工作线程数为5,并发工作线程数为1。整个堆空间为15279 MB(接近15 GB,实际设置的堆空间为15 GB,有效期为14.9GB),预分配内存为256 M。[

[2018-12-17T14:15:10.878+0800][59454][gc,init]初始化Z垃圾收集器[2018-12-17T14:15:10.878+0800][59454][GC,init]版本:11.0.1+13-LTS(发布)[2018-12-17T14:15:10.878+0800][59454][GC,init]NUMA支持:禁用[2018-12-17T14:15:10.878+0800][59454][gc,init]CPU:8,总计,8可用[2018-12-17T14:15:10.878+0800][59454][GC,INIT]内存:15279 M[2018-12-17T14:15:10]。878+0800][59454][GC,init]大页支持:禁用[2018-12-17T14:15:10.878+0800][59454][GC,init]工人:5并行,1并行[2018-12-17T14:15:10.879+0800][59454][GC,init]堆支持文件:/memfd:JAVA_堆[2018-12-17T14:15:10.879+0800][59454][gc,init]备份文件系统上的可用空间:n/A[2018-12-17T14:15:10.928+0800][59454][2018-12-17T14:15:10.928+0800][59454][gc,init]预映射:256 m[2018-12-17T14:15:10.935+0800][59454][gc,init]运行时工作人员:5并行[2018-12-17T14:15:10.935+0800][59454][GC]使用Z垃圾收集器。

2.GC第一次触发

GC以满足预热规则。我在日志里看不到热身这个词。

[2018-12-17T14:15:11.166+0800][59461][gc,Start]GC(0)垃圾收集(热身)[2018-12-17T14:15:11.169+0800][59468][GC,阶段]GC(0)暂停标记开始0.332ms[2018-12-17T14:15:11.208+0800][59461][GC,阶段]GC(0)并发标记38。999ms[2018-12-17T14:15:11.213+0800][59468][GC,相]GC(0)停顿标记结束0.111ms[2018-12-17T14:15:11.213+0800][59461][GC,相]GC(0)并发进程不强引用0.191ms[2018-12-17T14:15:11.213+0800][59461][GC,相]GC(0)同时重置集合0.001ms[2018-12-17T14:15:11.215+0800][59461][[2018-12-17T14:15:11.217+0800][59461][GC,阶段]GC(0)并行选择重新定位集1.929ms[2018-12-17T14:15:11.217+0800][59461][GC,阶段]GC(0)同时准备重新安置集0.329ms[2018-12-17T14:15:11.218+0800][59468]GC(0)暂停重新定位开始启动0.499ms[2018-12-17T14:15:11.245:11.245+0800][59461][[2018-12-17T14:15:11.245+0800][59461][GC,LOAD]GC(0)负载:0.75/0.80/0.90[2018-12-17T14:15:11.245+0800][59461][GC,MMU]GC(0)MMU:2ms/75.0%,5ms/90.0%,10ms/93.9%,20ms/96.9%,50 ms/98.1%,100 ms/99.1%[2018-12-17T14:15:11][59461][GC,标记]GC(0)标记:1条(S),1条主动冲洗(ES),1条终止(ES),0完成(S),0继续[2018-12-17T14:15:11.245+0800][59461][GC,reloc]GC(0)重新安置:成功,24M重新安置[2018-12-17T14:15:11.245+0800][59461][GC,nMethod]GC(0)Nods:38已登记,0未注册[2018-12-17T14:15:11.245+0800][59461][GC,metaspace]GC(0)Metaspace:4m使用,4m容量,5m已提交,8m保留[2018-12-17T14:15:11.245+0800][59461][gc,ref]gc(0)软:106遇到,0发现,0已排队[2018-12-17T14:15:11.245+0800][59461][gc,ref]gc(0)弱:遇到215,发现184,46排队[2018-12-17T14:15:11.245+0800][59461][GC,Ref]GC(0)最后:0遇到,0被发现,0被排队[2018-12-17T14:15:11.245+0800][59461][GC,Ref]GC(0)幻影:2遇到,2发现,1排队[2018-12-17T14:15:11。246个+0800][59461][gc,堆]gc(0)标记标记端重新定位开始重新定位高低端[2018-12-17T14:15:11.246+0800][59461][gc,堆]GC(0)容量:2.98亿(29%)3.86亿(38%)3.96亿(39%)4.5亿(44%)4.5亿(44%)2.98亿(29%)[2018-12-17T14:15:11.246+0800][GC,(4%)42m(4%)42m(4%)42m(4%)42m(4%)42m(4%)42m(4%)42m(4%)42m(4%)42m(4%)[2018-12-17T14:15:11.246+0800][gc,GC(0)自由:728m(71%)638 M(62%)788 M(77%)644 M(63%)788 M(77%)616 M(60%)[2018-12-17T14:15:11.246+0800][59461][GC堆]GC(0)使用:254 M(25%)344 m(34%)194 M(19%)338 M(33%)366m(36%)194 M(19%)[2018-12-17T14:15:11.246+0800][59461][GC堆]GC(0)Live:-4300万(4%)4300万(4%)4300万(4%)[2018-12-17T14≤15≤11]。246个+0800][59461][gc,堆]gc(0)分配:-90m(9%)100m(10%)3.14亿(31%)-[2018-12-17T14:15:11.246+0800][59461][GC,[堆]gc(0)垃圾:-210 M(21%)5000万(5%)1400万(1%)-[2018-12-17T14:15:11.246+0800][59461][gc,堆]GC(0)回收:-160 m(16%)196 m(19%)-[2018-12-17T14:15:11.246+0800][59461][GC]GC(0)254 m(25%)->338 M(33%)。

GC触发器满足分配率规则,我们可以看到分配率。

[2018-12-17T14:15:11.495+0800][59461][gc]GC(3)垃圾收集(分配率)130 m(13%)->62m(6%)[2018-12-17T14:15:11.566+0800][59461][GC,Start]GC(4)垃圾收集(分配率)。

GC触发器满足循环收集规则,我们可以看到主动

[2018-12-17T10≤32≤30.556+0800][45833][GC,Start]GC(7)垃圾收集(主动)。

iii.Zstat出口统计数据

[2018-12-17T14:15:20.966+0800][59462][gc,stats]=垃圾收集统计=统计数据]最后10s最后10h总计[2018-12-17T14:15:20.966+0800][59462][gc,[2018-12-17T14:15:20,966+0800][59462][GC,STATS]收集器:垃圾收集周期154.948/345.168 154.948/345.168 154.948/345.168 154.948/345.168 ms[2018-12-17T14:15:20.966+0800][59462][GC,争夺:马克段重置争用2/9 2/9 2/9 2/9操作系统/s[2018-12-17T14:15:20。966+0800][59462][gc,stats]争用:Mark SeqNum重置争用0/2 0/2 0/2 Ops/s[2018-12-17T14:15:20.966+0800][59462][GC,[STATS]争用:重新定位争用59462/5 0/5 0/5 OPS/s[2018-12-17T14:15:20.966+0800][59462][gc,关键:分配档128.845/308.257 128.845/308.257 128.845/308.257 128.845/308.257 ms[2018-12-17T14:15:20.966+0800][59462][gc,关键:分配档3/6 3/6 3/6 3/6 OP/s[2018-12-17T14:15:20.966+0800][59462][GC,关键:GC锁货摊0.000/0.000 0.000/0.000 0.000/0.000 0.000/0.000毫秒[2018-12-17T14:15:20。966+0800][59462][gc,stats]临界:GC锁货摊0/0 0/0/0/0 Ops/s[2018-12-17T14:15:20.966+0800][59462][GC,内存:分配率1927/3098 1927/3098 1927/3098 1927/3098 MB/s[2018-12-17T14:15:20.966+0800][59462][GC,内存:Mark 832/982 832/982 832/982 832/982 MB之后使用的堆[2018-12-17T14:15:20.966+0800][59462][gc,内存:重定位540/982 540/982 540/982 540/982 540/982 540/982 MB后使用的堆[2018-12-17T14:15:20.966+0800][59462][GC,内存:Mark 690/982 690/982 690/982 690/982 690/982 MB之前使用的堆[2018-12-17T14:15:20]。966+0800][59462][gc,stats]内存:在重新定位505/982 505/982 505/982 505/982 505/982 MB之前使用的堆[2018-12-17T14:15:20.966+0800][59462][gc,内存:内存中0/0/0/0/0/0 Ops/s[2018-12-17T14:15:20.966+0800][59462][GC,内存:页缓存刷新287/516 287/516 287/516 287/516 MB/s[2018-12-17T14:15:20.966+0800][59462][GC,内存:页面缓存命中L1 172/303 172/303 172/303操作系统/s[2018-12-17T14:15:20]。966+0800][59462][GC,STATs]内存:页缓存命中L2 0/0 0/0/0/0 Ops/s[2018-12-17T14:15:20.966+0800][59462][GC,内存:页Cache小姐34/102 34/102 34/102 OPS/s[2018-12-17T14:15:20.966+0800][59462][gc,内存:撤消对象分配失败0/0/0/0/0/0操作系统/s[2018-12-17T14:15:20.966+0800][59462][gc,内存:撤消对象分配成功0/5 0/5 0/5 OPS/s[2018-12-17T14:15:20。966+0800][59462][GC,stats]内存:撤消页分配2/6 2/6 2/6 2/6 OPS/s[2018-12-17T14:15:20.966+0800][59462][GC,统计]阶段:并发销毁分离页0.073/1.222 0.073/1.222 0.073/1.222 0.073/1.222 ms[2018-12-17T14:15:20.966+0800][59462][gc,统计]阶段:并发Mark 142.312/310.943 142.312/310.943 142.312/310.943 142.312/310.943 ms[2018-12-17T14:15:20.966+0800][59462][gc,统计]阶段:并发标记继续0.000/0.000 0.000/0.000 0.000/0.000/0.000 ms[2018-12-17T14:15:20.966+0800][59462][gc,统计]阶段:同步准备搬迁集0.172/1.081 0.172/1.081 0.172/1.081 0.172/1.081毫秒[2018-12-17T14:15:20。966+0800][59462][gc,stats]阶段:并发处理非强引用0.135/0.264 0.135/0.264 0.135/0.264 0.135/0.264 ms[2018-12-17T14:15:20.966+0800][59462][gc,[2018-12-17T14:15:20.966+0800][59462][gc,统计]阶段:同步重置重新定位集0.020/0.221 0.020/0.221 0.020/0.221 0.020/0.221 ms[2018-12-17T14:15:20.966+0800][59462][gc,统计]阶段:并行选择搬迁集1.728/5.198 1.728/5.198 1.728/5.198 1.728/5.198 ms[2018-12-17T14:15:20.966+0800][59462][GC,统计]阶段:暂停标记结束0.082/0.127 0.082/0.127 0.082/0.127 0.082/0.127 MS[2018-12-17T14:15:20.966+0800][59462][GC,Stats]阶段:暂停标记启动0.107/0.332 0.107/0。332 0.107/0.332 0.107/0.332 MS[2018-12-17T14:15:20.966+0800][59462][GC,STATs]阶段:暂停搬迁启动0.164/1.865/1.865/1.865 0.164/1.865 ms[2018-12-17T14:15:20.966+0800][59462][GC,分阶段:并发Mark 142.913/310.887 142.913/310.887 142.913/310.887/310.887 ms[2018-12-17T14:15:20.967+0800][59462][GC,分阶段:并发MarkIdle 1.070/3.907 1.070/3.907 1.070/3.907 1.070/3.907 ms[2018-12-17T14:15:20.967+0800][59462][gc,子阶段:并发标记尝试刷新0.230/4.609 0.230/4.609 0.230/4.609 0.230/4.609 ms[2018-12-17T14:15:20.967+0800][59462][gc,统计数据]子阶段:并发标记尝试终止1.061/4.611,1.061/4.611,1.061/4。611 1.061/4.611 ms[2018-12-17T14:15:20.967+0800][59462][GC,stats]子阶段:并发引用EnQueue 0.001/0.008 0.001/0.008/0.008/0.008/0.008 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:并发引用处理0.044/0.084 0.044/0.084 0.044/0.084 0.044/0.084 ms[2018-12-17T14:15:20.967+0800][59462][GC,分阶段:并发弱根0.033/0.148 0.033/0.148 0.033/0.148 0.033/0.148 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:并发弱根JNIWeakHandles 0.000/0.000 0.000/0.000 0.000/0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59468][GC,阶段]GC(52)暂停标记启动0.080ms[2018-12-17T14:15:20]。967+0800][59462][gc,stats]子阶段:并发弱根字符串表0.032/0.131/0.131 0.032/0.131 0.032/0.131 ms[2018-12-17T14:15:20.967+0800][59462][gc,统计]子阶段:并发弱根VMWeakHandles 0.005/0.016 0.005/0.016 0.005/0.016/0.016 ms[2018-12-17T14:15:20.967+0800][59462][GC,子阶段:暂停标记尝试完成0.001/0.001 0.001/0.001 0.001/0.001 0.001/0.001 ms[2018-12-17T14:15:20.967+0800][59462][gc,分阶段:暂停重新映射TLabs 0.001/0.009 0.001/0.009 0.001/0.009 0.001/0.009 ms[2018-12-17T14:15:20.967+0800][59462][GC,分阶段:暂停退休TLabs 0.007/0.025 0.007/0.025 0.007/0.025 0.007/0.025 ms[2018-12-17T14:15:20]。967+0800][59462][GC,STATS]分相:暂停根0.034/1.742 0.034/1.742 0.034/1.742 0.034/1.742 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根类LoaderDataGraph0.022/1.570 0.022/1.570 0.022/1.570/1.570 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根代码缓存0.022/1.742 0.022/1.742 0.022/1.742 0.022/1.742 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根JNIHandles 0.008/0.952 0.008/0.952 0.008/0.952 0.008/0.952 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根JNIWeakHandles 0.000/0.000 0.000/0.000 0.000/0.000/0.000 ms[2018-12-17T14:15:20。967+0800][59462][GC,STATS]分阶段:暂停根JRFWeak 0.000/0.000/0.000 0.000/0.000 0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根JVMTIexport 0.001/0.003 0.001/0.003 0.001/0.003 0.001/0.003 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根JVMTIWeakexport 0.001/0.001 0.001/0.001 0.001/0.001/0.001 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根管理0.002/0.023 0.002/0.023 0.002/0.023 0.002/0.023 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根对象同步器0.000/0.001 0.000/0.001 0.000/0.001/0.001 ms[2018-12-17T14:15:20。967+0800][59462][gc,stats]子阶段:暂停根设置0.002/0.012 0.002/0.012 0.002/0.012 0.002/0.012 ms[2018-12-17T14:15:20.967+0800][59462][GC,子阶段:暂停根字符串表0.000/0.000 0.000/0.000 0.000/0.000 0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根系统字典0.011/0.664 0.011/0.664 0.011/0.664 0.011/0.664 ms[2018-12-17T14:15:20.967+0800][59462][GC,[2018-12-17T14:15:20.967+0800][gc,统计]子阶段:暂停根线0.010/0.168 0.010/0.168 0.010/0.168 0.010/0.168毫秒[2018-12-17T14:15:20。967+0800][59462][gc,stats]次相:暂停根宇宙0.006/0.348 0.006/0.348 0.006/0.348 0.006/0.348 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停根VMWeakHandles 0.000/0.000 0.000/0.000 0.000/0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59462][GC,亚相:暂停弱根0.001/0.005 0.001/0.005 0.001/0.005 0.001/0.005 ms[2018-12-17T14:15:20.967+0800][59462][GC,亚相:暂停弱根JFRWeak 0.001/0.002 0.001/0.002 0.001/0.002/0.002 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:暂停弱根JNIWeakHandles 0.000/0.000 0.000/0.000 0.000/0.000 0.000/0.000 ms[2018-12-17T14:15:20。967+0800][59462][GC,STATS]分阶段:暂停弱根JVMTIWeakexport 0.001/0.003 0.001/0.003/0.003 0.001/0.003 ms[2018-12-17T14:15:20.967+0800][59462][GC,子阶段:暂停弱根设置0.000/0.001 0.000/0.001 0.000/0.001 0.000/0.001 ms[2018-12-17T14:15:20.967+0800][59462][GC,子阶段:暂停弱根字符串表0.000/0.000 0.000/0.000 0.000/0.000 0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59462][GC,统计]子阶段:停顿弱根符号表0.000/0.000 0.000/0.000 0.000/0.000 0.000/0.000 ms[2018-12-17T14:15:20.967+0800][59462][GC,亚相:暂停弱根拉伸0.001/0.003 0.001/0.003 0.001/0.003 0.001/0.003毫秒[2018-12-17T14:15:20]。967+0800][59462][GC,STATS]子阶段:暂停弱根VMWeakHandles 0.000/0.000/0.000 0.000/0.000 0.000/0.000ms[2018-12-17T14:15:20.967+0800][59462][GC,STATS]系统:JAVA线程9≤109≤109个≤10线程[2018-12-17T14:15:20.967+0800][gc,stats]=。