一场足球盛宴的终结:日帐延迟寻因记

本文涉及的产品
日志服务 SLS,月写入数据量 50GB 1个月
简介:
盛宴开端
 


大年初三,酒足饭饱后,拉着亲朋好友就这么餐桌上一围,小赌正式开始。正要胡牌时,手机响了。一看竟然是客户的电话!难道是给我拜年么?!应该不是,昨天就拜过了。难道出什么问题了?!果然。。这来的也太不是时候了。不过作为一个资深的运维DBA,对于这种7*24*365式实时守候待命的日子早就习以为常。麻将顺势一推,迅速更换为笔记本,开始一段抽丝拨茧之旅!


> 2
问题概述
 


2016年02月10日,某省账务库日账出现严重延迟现象,期间其他应用运行正常。


> 3
数据采集
 


对于这种性能突变的问题,还是采取老套路,采集“凶案现场”情况如下:


注:(性能突变的数据库为11g R2 RAC)


1、两节点的数据库alert日志信息。


2、两节点问题时间段的snapshot(注:收集多份及正常时间段的AWR报告进行对比分析)。


3、两节点问题时间段及前后一小时的OSW日志。


4、两节点问题时间段所有相关trace信息。



5、问题时间段的ASH DUMP数据。



> 4
日志剖析
 


1、通过LGWR的trace信息,我们可以发现问题时段log write写超过500ms的告警,说明当时redo IO性能应该存在一些问题。



2、通过分析ASH DUMP信息我们可发现,44%的会话被log file sync堵塞,同时 log file sync在等待log file parallel write 。



3、通过查看AWR报告我们可以发现,log file sync等待高达431ms,同时出现大量GC等待事件,并且出现log buffer space等待事件。 



那么导致该问题是由于log_buffer配置太小?还是redo log太小导致redo log切换过于频繁?如果是的话,那么为什么2月10号前的日账没有问题,该结论不成立!那么应用端是否存在变化?! 


4、针对TOP中大量的GC等待,我们对比分析了1月10日跟2月10日的Global Cache统计数据,发现事发时段GC接收的块较以往增幅20%,同时私网流量也从21M上升到26M,GC交互的增加是否可以说明应用侧做了调整呢?!



通过咨询应用维护人员,我了解到,日账数据量会伴随时间的推移而增加,同时日账的时间同步也会有所延迟。如下图所示:


 


虽然日账数据量及执行时间在同一月内会伴随时间的推移而增加,但是通过上面的“日结时间对比”图可以看出,2月10日的日账时间明显突增,说明导致该问题的还有其他原因。


5、通过问题前后的AWR报告对比分析,我们发现,在2月10日之后,ROLLBACKS从之前的每秒0.5次增长至每秒3.5次,增长了7倍。



6、通过分析log file sync发现,自10号凌晨开始数据库写入时间较之前出现明显上升。



问题时段log file sync/log file parallel write较之前开始出现明显下降,如下表所示:



7、通过OSW的VMSTAT数据,我们也可以发现,在批量日账未运行之时,B列值相对较低,在问题时间段B列在10以上,这说明所有的CPU都是pending在I/O层面。



8、通过OSW的MPSTAT数据,我们也可以发现,问题时间段部分磁盘始终处于100%busy的状态,而且磁盘平均服务时间在80ms以上。 


 

> 5
剖析总结
 


综上所述,主要有以下几方面的影响因素:


  • log file sync及log file parallel write 性能明显下降, redo IO性能变差导致GC严重及数据库性能严重下降。需要主机及存储厂商配合确认。

  • 应用侧是否存在调整,导致GC交互的增加及其他引发问题。需要应用侧配合确认。

  • 问题前后的每秒rollback次数增长了7倍,建议协调应用一起排查rollbacks异常增长的原因。


> 6
足球大战
 


通过分析,问题可能出现在主机or存储or应用层面上,通过逐个沟通确认,得到回复如下:


主机球员:


通过分析,主机侧未发现异常,还请知悉!


存储球员:


从我这边看是正常的,没有出现瓶颈或者非常慢的情况。


应用球员:


你好,

通过应用侧核实,在日账问题前后属于春节封网时段,无任何应用变更。


足联主席:


To DB运维组

请尽快完成日帐问题定位,为了避免影响月底出账,请务必在月底前完成定位并修复。


> 7
点球终结
 


通过一轮咨询,各组成员都反馈尚未发现异常,怎么办?!都没异常,那日账为何会突然延迟呢?!此时球兜了一圈又回到了咱们dba手上。此刻要冷静,再回首缕一缕。


从剖析日志及各组反馈的结果综合看来,问题肯定来自redo io层面,既然主机及存储侧都反馈无异常,那么问题是否在最底层(存储链路)? 马上联系HDS工程师,令其去查看存储链路是否存在异常。


30min后接收到HDS工程师的反馈,兄弟们,根源找到了!这才合上笔记本仰天长叹!!!


我们来看下底层架构:



该省账务库物理容灾采用Veritas镜像技术实现存储底层同步。当应用发起变更数据请求时,必须等待生产、容灾两份存储全部完成写入后,方可反馈提交信息。事发当天该库对应的2条SAN级联链路中有一条出现了异常,到容灾存储的数据变更速率均减半,这样就导致了批量操作(日账)延迟!


作者介绍:张玉朋


  • 新炬网络高级技术专家。

  • 5年以上电信行业运维经验。

  • 擅长oracle架构规划,故障诊断、性能优化、shell编程等,对大型IT系统的oracle数据库运维有丰富的经验。

  • 曾主导某省移动核心CRM、BOSS升级项目。


本文来自云栖社区合作伙伴"DBAplus",原文发布时间:2016-03-07

相关实践学习
日志服务之使用Nginx模式采集日志
本文介绍如何通过日志服务控制台创建Nginx模式的Logtail配置快速采集Nginx日志并进行多维度分析。
目录
相关文章
|
传感器 自动驾驶 安全
没有频繁“越权”的安全驾驶员,还敢适当“闯”黄灯,这才是无人出租车正确打开方式
没有频繁“越权”的安全驾驶员,还敢适当“闯”黄灯,这才是无人出租车正确打开方式
135 0
|
人工智能 供应链 双11
这届打工人不一样!剁手消费之外,还在天猫双11抢网课投资自己
天猫双11预售首日,买网课的人比去年增加了649%
这届打工人不一样!剁手消费之外,还在天猫双11抢网课投资自己
|
人工智能 自动驾驶 安全
奥运推迟,哪些黑科技将失之交臂
奥运的延期,对于科技企业来说,虽然失去了尽早营销推广、签订大客户以及提振销量的机会,但也意味将获得更充足技术研发和沉淀的机会。
955 0
奥运推迟,哪些黑科技将失之交臂
|
存储 Web App开发 安全
惊呆了!颜值爆表的20+位阿里技术女神同一时间向你发出共事邀请!
女神节快到了,云栖社区为此推出“三七”女神节特别分享,20+位颜值爆表的阿里女神们同一时间向你发出了共事邀请,快来赴约吧!
果冻有家,房联网概念的平台化应用;水滴筹回应称:线下团队暂停服务。
果冻有家,房联网概念的平台化应用;水滴筹回应称:线下团队暂停服务。
270 0
|
新零售
“猫爪杯”走红:饥饿营销的宣传方式,有利有弊
最近,星巴克咖啡在中国门店出售了2019年的樱花主题系列的杯子,当中,最受欢送的当属一款名为“猫爪杯”的杯子。不其中的外形设计不仅有樱花,还有肉肉的猫抓,加上粉红色的配色,俘虏了一大批女生的心。 虽然这个看起来并不怎样实用的猫爪杯,却掀起了一众狂热。
|
人工智能 计算机视觉
担心在机场丢行李?这个日本AI能帮你到处找包
日本的日立集团就做研发了这样的一套计算机视觉系统,可以将公共场所摄像头拍下的内容通过神经网络分析,识别人和物体整体的特征,追踪个体的运动轨迹。也就是说,把你丢包的一路上的视频都自动检查一遍,依次进行判断:
1525 0
|
机器人
今日科技联播:姆巴佩独造三球一战成名,阿里云打破世界杯流量纪录
昨晚的八进一世界杯不知道大家是不是又赔了钱,说好的早睡早起,又偷偷为世界杯熬了夜,全方位的人工智能剪辑技术,观赏体验简直不能更棒了!好了,说完了世界杯,接下来跟着小编看看今天的科技资讯吧!
1268 0