[troubleshoot][daily][redhat] 设备反复重启故障排查

一台服务器设备,反复重启,每天重启数次。

一: 原因分析及初步排异。

1.  硬件,内存主板,一一更换,甚至除了硬盘将整台机器都换掉了,依然重启。

2.  排除电源问题,换了电源线,换了插座,还是重启。

3.  那么接下来,还有三种可能:

  A。内核问题,内核crash。(redhat的稳定性还是十分让人信赖的,这种可能性不高)

  B。硬盘或文件系统故障。本质上,这样会导致内核crash。

  C。程序自主reboot。(我们自己的程序reboot,或进了黑客放了reboot脚本。好无聊的黑客。。。。)

二: 最好排除的,就是先解决内核的问题。

  内核在crash那一刻是会发现,自己即将crash的,于是他会在临死前留下一些信息。告诉用户我发生了什么。 可是问题在于:文件系统的复杂性,会导致内核临死之前文件系统也随之崩溃了。

  通过重启之后查看日志,确实没有留下有用的信息。

  这是时候我们还有另一种手段,netcosole,他的功能是吧内核日志从socket以udp的方式,自组IP包而不走协议栈,讲包推出网卡端口。包的格式为syslog格式。

netcosole使用:

1.  修改配置文件

[root@S205 ~]# cat /etc/sysconfig/netconsole                                                                                                                                                 
# This is the configuration file for the netconsole service.  By starting                                                                                                                    
# this service you allow a remote syslog daemon to record console output                                                                                                                     
# from this system.

# The local port number that the netconsole module will use
LOCALPORT=6666

# The ethernet device to send console messages out of (only set this if it
# can't be automatically determined)
DEV=enp3s0

# The IP address of the remote syslog server to send messages to
SYSLOGADDR=192.168.10.214

# The listening port of the remote syslog daemon
SYSLOGPORT=514

# The MAC address of the remote syslog server (only set this if it can't
# be automatically determined)
SYSLOGMACADDR=40:8d:5c:22:53:18
[root@S205 ~]# 
cat /etc/sysconfig/netconsole

2.  启动服务

[root@S205 ~]# systemctl start netconsole
[root@S205 ~]# systemctl enable netconsole

当前系统及内核版本:

[root@S205 ~]# cat /etc/redhat-release 
CentOS Linux release 7.3.1611 (Core) 
[root@S205 ~]# uname -a
Linux S205 3.10.0-514.el7.x86_64 #1 SMP Tue Nov 22 16:42:41 UTC 2016 x86_64 x86_64 x86_64 GNU/Linux
[root@S205 ~]# 

成功收到内核crash日志:

Jul 25 08:14:54 192.168.10.205 [20239.422386] NMI watchdog: Watchdog detected hard LOCKUP on cpu 7
Jul 25 08:14:54 192.168.10.205  
Jul 25 08:14:54 192.168.10.205 [20239.422529] Kernel panic - not syncing: Hard LOCKUP
Jul 25 08:14:54 192.168.10.205 [20239.422543] CPU: 7 PID: 0 Comm: swapper/7 Not tainted 3.10.0-514.el7.x86_64 #1
Jul 25 08:14:54 192.168.10.205 [20239.422561] Hardware name: LENOVO 10C0A038CD/ , BIOS FCKT73AUS 08/28/2015
Jul 25 08:14:54 192.168.10.205 [20239.422579]  ffffffff818d9784
Jul 25 08:14:54 192.168.10.205  90a5d9572fc8872b
Jul 25 08:14:54 192.168.10.205  ffff88041edc5b18
Jul 25 08:14:54 192.168.10.205  ffffffff81685fac
Jul 25 08:14:54 192.168.10.205  
Jul 25 08:14:54 192.168.10.205 [20239.422603]  ffff88041edc5b98
Jul 25 08:14:54 192.168.10.205  ffffffff8167f3b3
Jul 25 08:14:54 192.168.10.205  0000000000000010
Jul 25 08:14:54 192.168.10.205  ffff88041edc5ba8
Jul 25 08:14:54 192.168.10.205  
Jul 25 08:14:54 192.168.10.205 [20239.422627]  ffff88041edc5b48
Jul 25 08:14:54 192.168.10.205  90a5d9572fc8872b
Jul 25 08:14:54 192.168.10.205  ffff88041edc5ba8
Jul 25 08:14:54 192.168.10.205  ffffffff818d948a
Jul 25 08:14:54 192.168.10.205  
Jul 25 08:14:54 192.168.10.205 [20239.422651] Call Trace:
Jul 25 08:14:54 192.168.10.205 [20239.422658]  <NMI> 
Jul 25 08:14:54 192.168.10.205  [<ffffffff81685fac>] dump_stack+0x19/0x1b
Jul 25 08:14:54 192.168.10.205 [20239.422678]  [<ffffffff8167f3b3>] panic+0xe3/0x1f2
Jul 25 08:14:54 192.168.10.205 [20239.422692]  [<ffffffff8108562f>] nmi_panic+0x3f/0x40
Jul 25 08:14:54 192.168.10.205 [20239.422706]  [<ffffffff8112f0e6>] watchdog_overflow_callback+0xf6/0x100
Jul 25 08:14:54 192.168.10.205 [20239.422725]  [<ffffffff8117465e>] __perf_event_overflow+0x8e/0x1f0
Jul 25 08:14:54 192.168.10.205 [20239.422741]  [<ffffffff811752a4>] perf_event_overflow+0x14/0x20
Jul 25 08:14:54 192.168.10.205 [20239.422759]  [<ffffffff81009d88>] intel_pmu_handle_irq+0x1f8/0x4e0
Jul 25 08:14:54 192.168.10.205 [20239.422776]  [<ffffffff8168dbeb>] perf_event_nmi_handler+0x2b/0x50
Jul 25 08:14:54 192.168.10.205 [20239.422793]  [<ffffffff8168f019>] nmi_handle.isra.0+0x69/0xb0
Jul 25 08:14:54 192.168.10.205 [20239.422808]  [<ffffffff8168f193>] do_nmi+0x133/0x410
Jul 25 08:14:54 192.168.10.205 [20239.422822]  [<ffffffff8168e453>] end_repeat_nmi+0x1e/0x2e
Jul 25 08:14:54 192.168.10.205 [20239.422838]  [<ffffffff8168d9c7>] ? _raw_spin_lock_irqsave+0x47/0x60
Jul 25 08:14:54 192.168.10.205 [20239.422855]  [<ffffffff8168d9c7>] ? _raw_spin_lock_irqsave+0x47/0x60
Jul 25 08:14:54 192.168.10.205 [20239.422871]  [<ffffffff8168d9c7>] ? _raw_spin_lock_irqsave+0x47/0x60
Jul 25 08:14:54 192.168.10.205 [20239.422887]  <<EOE>> 
Jul 25 08:14:54 192.168.10.205  <IRQ> 
Jul 25 08:14:54 192.168.10.205  [<ffffffffa01fae23>] nvkm_fantog_update+0x43/0x110 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.422947]  [<ffffffffa01faf48>] nvkm_fantog_set+0x38/0x40 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.422976]  [<ffffffffa01fa378>] nvkm_fan_update+0xc8/0x210 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423005]  [<ffffffffa01fa519>] nvkm_therm_fan_set+0x19/0x20 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423035]  [<ffffffffa01f9b87>] nvkm_therm_update+0x97/0x310 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423064]  [<ffffffffa01f9e17>] nvkm_therm_alarm+0x17/0x20 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423106]  [<ffffffffa01fd2d3>] nvkm_timer_alarm_trigger+0x103/0x150 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423147]  [<ffffffffa01fd3d0>] nvkm_timer_alarm+0x60/0xb0 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423176]  [<ffffffffa01fb651>] alarm_timer_callback+0xd1/0xe0 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423207]  [<ffffffffa01fd2d3>] nvkm_timer_alarm_trigger+0x103/0x150 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423238]  [<ffffffffa01fd3d0>] nvkm_timer_alarm+0x60/0xb0 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423266]  [<ffffffffa01faeea>] nvkm_fantog_update+0x10a/0x110 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423295]  [<ffffffffa01faf0a>] nvkm_fantog_alarm+0x1a/0x20 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423324]  [<ffffffffa01fd2d3>] nvkm_timer_alarm_trigger+0x103/0x150 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423355]  [<ffffffffa01fd6fb>] nv04_timer_intr+0x6b/0xb0 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423384]  [<ffffffffa01fd174>] nvkm_timer_intr+0x14/0x20 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423419]  [<ffffffffa01ada87>] nvkm_subdev_intr+0x17/0x20 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423458]  [<ffffffffa01ef7f9>] nvkm_mc_intr+0x79/0x110 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423486]  [<ffffffffa01f4155>] nvkm_pci_intr+0x55/0xa0 [nouveau]
Jul 25 08:14:54 192.168.10.205 [20239.423503]  [<ffffffff8113015e>] handle_irq_event_percpu+0x3e/0x1e0
Jul 25 08:14:54 192.168.10.205 [20239.423521]  [<ffffffff8113033d>] handle_irq_event+0x3d/0x60
Jul 25 08:14:54 192.168.10.205 [20239.423536]  [<ffffffff81133007>] handle_edge_irq+0x77/0x130
Jul 25 08:14:54 192.168.10.205 [20239.424012]  [<ffffffff8102d26f>] handle_irq+0xbf/0x150
Jul 25 08:14:54 192.168.10.205 [20239.424491]  [<ffffffff810f3c8a>] ? tick_check_idle+0x8a/0xd0
Jul 25 08:14:54 192.168.10.205 [20239.424967]  [<ffffffff8169201a>] ? atomic_notifier_call_chain+0x1a/0x20
Jul 25 08:14:54 192.168.10.205 [20239.425445]  [<ffffffff81698bef>] do_IRQ+0x4f/0xf0
Jul 25 08:14:54 192.168.10.205 [20239.425921]  [<ffffffff8168dd6d>] common_interrupt+0x6d/0x6d
Jul 25 08:14:54 192.168.10.205 [20239.426389]  <EOI> 
Jul 25 08:14:54 192.168.10.205  [<ffffffff81514052>] ? cpuidle_enter_state+0x52/0xc0
Jul 25 08:14:54 192.168.10.205 [20239.426863]  [<ffffffff81514199>] cpuidle_idle_call+0xd9/0x210
Jul 25 08:14:54 192.168.10.205 [20239.427314]  [<ffffffff8103516e>] arch_cpu_idle+0xe/0x30
Jul 25 08:14:54 192.168.10.205 [20239.427793]  [<ffffffff810e7c95>] cpu_startup_entry+0x245/0x290
Jul 25 08:14:54 192.168.10.205 [20239.428222]  [<ffffffff8104f12a>] start_secondary+0x1ba/0x230
Jul 25 08:18:03 192.168.10.205 [    2.633081] nouveau 0000:01:00.0: priv: HUB0: 085014 ffffffff (1b70820b)
Jul 25 08:20:01 S214 systemd: Started Session 171 of user root.
Jul 25 08:20:01 S214 systemd: Starting Session 171 of user root.
Jul 25 08:30:01 S214 systemd: Started Session 172 of user root.

这是正确的处理方式,不是去深入调查原因,也不是去hacking。

1. 升至最新版稳定内核。

2. 回退至前一版稳定内涵。

[root@S205 ~]# yum upgrade
Installing:
kernel                                                x86_64                              3.10.0-514.26.2.el7                                    updates                               37 M

已升最新,待观察:

[root@S205 ~]# cat /etc/redhat-release 
CentOS Linux release 7.3.1611 (Core) 
[root@S205 ~]# uname -a
Linux S205 3.10.0-514.26.2.el7.x86_64 #1 SMP Tue Jul 4 15:04:05 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux

三:仍然重启,现象与错误信息一致。

  参考:https://stackoverflow.com/questions/44039958/kernel-panic-not-syncing-watchdog-detected-hard-lockup

  好像是 nvidia 显卡的问题。

[root@S205 ~]# cat /etc/default/grub |grep CMDLINE
GRUB_CMDLINE_LINUX="crashkernel=auto rd.lvm.lv=cl/root rd.lvm.lv=cl/swap rd.driver.blacklist=nouveau nomodeset rhgb quiet"
[root@S205 ~]# 

  增加内核参数:rd.driver.blacklist=nouveau nomodeset 

  再观察。

 

四:Fixed

连续24小时未重启。

完。

原文地址:https://www.cnblogs.com/hugetong/p/7232500.html