## 1 BUG現象 系統併發請求,系統停滯無法使用,所有介面都是無法與後端進行交互的狀態,系統並沒有宕機 ## 2 BUG的業務流程 1. 插入分數方法 涉及插入表ABCD 加了聲明式事務 2. 查詢分數方法 涉及表ABCD ``` controller() { @Transactional in ...
1 BUG現象
系統併發請求,系統停滯無法使用,所有介面都是無法與後端進行交互的狀態,系統並沒有宕機
2 BUG的業務流程
- 插入分數方法 涉及插入表ABCD 加了聲明式事務
- 查詢分數方法 涉及表ABCD
controller() {
@Transactional
insertVo();
selectById();
}
3 排查原因
因為代碼不是我寫的,一開始我就是懷疑是死鎖導致的BUG,然後我用Jconsole,去檢測一下死鎖,並沒有發現死鎖,接下來我去Mysql看有沒有死鎖,結果也沒有發現,然後我就懵了,jvm沒有鎖,mysql也沒有鎖且沒有SQL在執行,為什麼請求就會全註阻塞?
然後我去開始去看這個代碼了,我發現他在控制層調用了兩個業務層,通常我們只在控制層去做校驗去調用一個service啊,然後我就繼續看,insertVo插入了很多查詢了很多,耗時3秒鐘左右,selectById查詢了一條SQL,這兩個明面上的代碼並沒有什麼加鎖或什麼飛天操作,想了半天搞不懂為什麼。
然後我開始用排除法,把這些代碼一一註釋調試一下。我把insertVo註釋掉,這個毋庸置疑,那隻有一個簡單的操作了,就查一表返回,這個絕對是沒問題的,然後我把selectById註釋掉,居然就好了?,selectById只有一條查詢SQL啊也沒有加鎖,這個能解決但是肯定也不是這個原因。
然後我用druid監控到可使用連接數一直在占用,沒有釋放,我就去查druid配置,發現配置了
initial-size: 20 #初始大小
min-idle: 20 #最小空閑
max-active: 40 #最大鏈接
max-wait: 10000 #配置獲取連接等待超時的時間
這個配置也沒有毛病啊,沒辦法了我只能去看線程的具體信息了,查出來所有的線程池連接線程都是這樣的
"pool-6-thread-10" #244 prio=5 os_prio=31 tid=0x00007fe94235f000 nid=0x22803 waiting on condition [0x00000003150ce000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000006c109e090> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at com.alibaba.druid.pool.DruidDataSource.takeLast(DruidDataSource.java:2315)
at com.alibaba.druid.pool.DruidDataSource.getConnectionInternal(DruidDataSource.java:1781)
at com.alibaba.druid.pool.DruidDataSource.getConnectionDirect(DruidDataSource.java:1494)
at com.alibaba.druid.filter.FilterChainImpl.dataSource_connect(FilterChainImpl.java:5058)
at com.alibaba.druid.filter.stat.StatFilter.dataSource_getConnection(StatFilter.java:704)
at com.alibaba.druid.filter.FilterChainImpl.dataSource_connect(FilterChainImpl.java:5054)
at com.alibaba.druid.filter.FilterAdapter.dataSource_getConnection(FilterAdapter.java:2759)
at com.alibaba.druid.filter.FilterChainImpl.dataSource_connect(FilterChainImpl.java:5054)
at com.alibaba.druid.pool.DruidDataSource.getConnection(DruidDataSource.java:1469)
at com.alibaba.druid.pool.DruidDataSource.getConnection(DruidDataSource.java:1459)
at com.alibaba.druid.pool.DruidDataSource.getConnection(DruidDataSource.java:83)
at org.hibernate.engine.jdbc.connections.internal.DatasourceConnectionProviderImpl.getConnection(DatasourceConnectionProviderImpl.java:122)
at org.hibernate.internal.NonContextualJdbcConnectionAccess.obtainConnection(NonContextualJdbcConnectionAccess.java:38)
at org.hibernate.resource.jdbc.internal.LogicalConnectionManagedImpl.acquireConnectionIfNeeded(LogicalConnectionManagedImpl.java:104)
at org.hibernate.resource.jdbc.internal.LogicalConnectionManagedImpl.getPhysicalConnection(LogicalConnectionManagedImpl.java:134)
at org.hibernate.resource.jdbc.internal.LogicalConnectionManagedImpl.getConnectionForTransactionManagement(LogicalConnectionManagedImpl.java:250)
at org.hibernate.resource.jdbc.internal.LogicalConnectionManagedImpl.begin(LogicalConnectionManagedImpl.java:258)
at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.begin(JdbcResourceLocalTransactionCoordinatorImpl.java:246)
at org.hibernate.engine.transaction.internal.TransactionImpl.begin(TransactionImpl.java:83)
at org.springframework.orm.jpa.vendor.HibernateJpaDialect.beginTransaction(HibernateJpaDialect.java:184)
at org.springframework.orm.jpa.JpaTransactionManager.doBegin(JpaTransactionManager.java:402)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.getTransaction(AbstractPlatformTransactionManager.java:376)
at org.springframework.transaction.interceptor.TransactionAspectSupport.createTransactionIfNecessary(TransactionAspectSupport.java:572)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:360)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:99)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:747)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:95)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:747)
at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:689)
at com.treach.platform.modules.service.impl.SysLogService$$EnhancerBySpringCGLIB$$36a85251.insert(<generated>)
at com.treach.platform.log.factory.LogTaskFactory$2.run(LogTaskFactory.java:56)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:750)
Locked ownable synchronizers:
- <0x00000007741f0d68> (a java.util.concurrent.ThreadPoolExecutor$Worker)
是的,連接池連接一直被占用鎖住了,為什麼會被鎖住呢?,也設置了連接等待超時時間啊,然後我懷疑是配置沒有生效,寫了個代碼看看
public static void main(String[] args) throws SQLException {
ConfigurableApplicationContext run = SpringApplication.run(NdCyApplication.class, args);
DruidDataSource bean = run.getBean(DruidDataSource.class);
int maxActive = bean.getMaxActive();
long maxWait = bean.getMaxWait();
log.info("資料庫線程池與資料庫最大鏈接數" + String.valueOf(maxActive));
log.info("資料庫線程池等待鏈接數超時時間"+String.valueOf(maxWait));
}
結果:
資料庫線程池與資料庫最大鏈接數8
資料庫線程池等待鏈接數超時時間-1
這個和配置的不一樣啊,真的沒有生效,然後我又去查為什麼沒有生效,原來配置類裡面有個DataSoure
@Bean //聲明其為Bean實例
@Primary //在同樣的DataSource中,首先使用被標註的DataSource
@ConfigurationProperties(prefix = "spring.datasource")
public DruidDataSource dataSource(){
DruidDataSource datasource = new DruidDataSource();
List<Filter> filters = new ArrayList<>();
filters.add(wallFilter);
filters.add(new StatFilter());
datasource.setProxyFilters(filters);
return datasource;
}
我們applcation.yaml的配置被覆蓋了,druid預設等待鏈接數超時時間-1,難怪長時間占用連接沒有超時。
4 解決
把等待連接超時時間等設置上
@Bean //聲明其為Bean實例
@Primary //在同樣的DataSource中,首先使用被標註的DataSource
@ConfigurationProperties(prefix = "spring.datasource")
public DruidDataSource dataSource(){
DruidDataSource datasource = new DruidDataSource();
datasource.setInitialSize(20);
datasource.setMaxActive(80);
datasource.setMaxWait(5000);
List<Filter> filters = new ArrayList<>();
filters.add(wallFilter);
filters.add(new StatFilter());
datasource.setProxyFilters(filters);
return datasource;
}
成功
5 不懂的點
為什麼會出現不回收線程的情況 按理來說現在沒有SQL在執行,連接數不是會被回收嗎 回到線程池 等待的線程就有連接了 就能不卡死了 為什麼呢?