记录一次tomcat自动关闭的解决过程

一、背景
问题:有一个springboot项目部署在Tomcat/8.5.38里面,发现每隔15s左右tomcat重启一次
服务器信息:
  • CentOS release 6.5
  • java version "1.8.0_60"
  • Apache Tomcat/8.5.38
二、发现问题

发现这个问题是很意外的,一开始只是看到服务器内存快要用完了,当时并没有考虑到可能出了什么问题,只是想着如果实在不行就升级服务器内存。下图是当时服务器内存的情况。

[root@iZbp19zv1tu402bo15tpq9Z log]# free -lm
             total       used       free     shared    buffers     cached
Mem:          7872       7115        757          0        261       2918
Low:          7872       7115        757
High:            0          0          0
-/+ buffers/cache:       3935       3937
Swap:            0          0          0

问题是这样被发现的,有一天我想看一下日志,打开日志的时候却发现有很多条启动日志,大概每隔15s左右就重启一次,正常情况下并不会重启才对。

11:03:54 [localhost-startStop-1] INFO  org.apache.jasper.servlet.TldScanner - At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
11:03:54 [localhost-startStop-1] INFO  o.s.b.c.e.AnnotationConfigEmbeddedWebApplicationContext - Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@336d2b9b: startup date [Sat Oct 12 11:03:46 CST 2019]; root of context hierarchy
11:03:54 [localhost-startStop-1] INFO  o.s.c.s.DefaultLifecycleProcessor - Stopping beans in phase 2147483647
11:03:55 [localhost-startStop-1] INFO  o.s.c.s.DefaultLifecycleProcessor - Stopping beans in phase -2147482648
11:03:55 [localhost-startStop-1] INFO  o.s.j.e.a.AnnotationMBeanExporter - Unregistering JMX-exposed beans on shutdown
11:03:55 [localhost-startStop-1] INFO  o.s.j.e.a.AnnotationMBeanExporter - Unregistering JMX-exposed beans
11:03:55 [localhost-startStop-1] INFO  c.alibaba.druid.pool.DruidDataSource - {dataSource-1} closed

通过观察日志,发现tomcat重启是因为我用了supervisor去监控tomcat,如果tomcat异常退出就自动重启。

11:04:19 [localhost-startStop-1] INFO  org.xx.boot.XxBootAppliaction - Starting XxBootAppliaction on iZbp19zv1tu402bo15tpq9Z with PID 813 (/data/tomcat/ihomepay/webapps/ihomepayapi/WEB-INF/classes started by root in /etc/supervisor/conf.d)

其实很早之前我查看supervisor的监控状态,就发现tomcat-ihomepay经常是start状态,但项目运行正常,以为是supervisor的问题,就忽略了,现在再仔细看,的确运行15s左右又重新启动了。下图是查看通过supervisor监控的项目的启动状态,服务器上部署了两个tomcat,出问题的是tomcat-ihomepay这个,可以看到pid经常变化,uptime都是几秒,证实了服务在不断重启。

[root@iZbp19zv1tu402bo15tpq9Z log]# supervisorctl status
tomcat-ihomepay                  RUNNING   pid 8444, uptime 0:00:07
tomcat-nwcl                      RUNNING   pid 22640, uptime 19:32:06
三、解决问题

意外发现了这个问题,这下有意思了。

思路1:supervisor配置不对?

一开始首先考虑的是会不会是supervisor关于tomcat的配置不对,但看了没发现问题,这个配置和另外一个正常运行的tomcat配置没有不同。

[program:tomcat-ihomepay]
command=/data/tomcat/ihomepay/bin/catalina.sh run
stdout_logfile=/data/tomcat/ihomepay/logs/catalina.out
autostart=true
autorestart=true
startsecs=5
priority=1
stopasgroup=true
killasgroup=true
思路2:tomcat版本和springboot项目冲突?

然后根据关键词Unregistering JMX-exposed beans on shutdownUnregistering JMX-exposed beans百度了一下,发现搜出来的答案都是说pom.xml打包的问题,但对我这个情况没有帮助,我的项目已经是下面的配置了。

<dependency>
   <groupId>org.springframework.boot</groupId>
   <artifactId>spring-boot-starter</artifactId>
</dependency>

上面这段pom改成下面试下,(https://stackoverflow.com/questions/22380119/why-my-spring-boot-app-always-shutdown

<dependency>
    <groupId>org.springframework.boot</groupId>
    <artifactId>spring-boot-starter-web</artifactId>
</dependency>
思路3:服务器内存不足,自动关闭了进程?

接着开始考虑是不是java内存溢出自动关闭了tomcat,想找相关的日志,但不知道怎么找。
百度找到这篇文章,发现和我的问题有相关性https://blog.csdn.net/qq_35981283/article/details/62233725
但我执行sudo dmesg -T | grep "(java)"这个命令会报错。

[root@iZbp19zv1tu402bo15tpq9Z ~]# dmesg -T | grep "(java)"
dmesg: invalid option -- 'T'
Usage: dmesg [-c] [-n level] [-r] [-s bufsize]

虽然命令没有执行成功,但提供了一个思路给我,通过top命令去查看java相关的进程,看占用的内存大小是否出现异常。
top命令参考https://blog.csdn.net/qq_31860135/article/details/83620093
通过top命令查看并按使用内存就行排序,发现有好几个java进程

image.png

查看某个进程的详细信息,竟然发现有进程重复启动了。
两个tomcat都分别有两个进程在启动

ps -aux | grep -v grep |grep 13154

网上查找了以下,发现有相关的文章也提到tomcat重复启动这个问题。
https://blog.csdn.net/qq_26684469/article/details/78758465

ps aux|head -1;ps aux|grep -v PID|sort -rn -k +4|head

看了一下tomcat进程的启动时间,有两个是在很久之前启动的,另两个是最近启动的,因为最近有重启过。这时恍然大悟,之前安装supervisor管理tomcat的时候,没有把之前的tomcat进程清理干净,导致之前和进程和supervisor启动的进程重复了,这也导致服务器使用内存变大了。
解决方法:直接把不是由supervisor启动的两个tomcat进程杀掉就行了。

kill -9 13154

现在查看tomcat的运行状态正常了,内存也正常了。


[root@iZbp19zv1tu402bo15tpq9Z log]# supervisorctl status
tomcat-ihomepay                  RUNNING   pid 19581, uptime 0:39:45
tomcat-nwcl                      RUNNING   pid 22640, uptime 21:14:36
[root@iZbp19zv1tu402bo15tpq9Z bin]# free -lm
             total       used       free     shared    buffers     cached
Mem:          7872       6309       1563          0        256       3553
Low:          7872       6309       1563
High:            0          0          0
-/+ buffers/cache:       2498       5373
Swap:            0          0          0
四、总结

学海无涯,从入门到放弃

©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 202,406评论 5 475
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 84,976评论 2 379
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 149,302评论 0 335
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 54,366评论 1 273
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 63,372评论 5 363
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 48,457评论 1 281
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 37,872评论 3 395
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 36,521评论 0 256
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 40,717评论 1 295
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,523评论 2 319
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 37,590评论 1 329
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,299评论 4 318
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 38,859评论 3 306
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 29,883评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,127评论 1 259
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 42,760评论 2 349
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,290评论 2 342

推荐阅读更多精彩内容