從鎖提及吧,內置鎖synchroinzed和jucl(java.util.concurrent.locks)lock仍是有很大區別的,一個很重要的區別就是使用jstack導出線程dump,使用synchronized命令能夠很容易看到鎖被哪一個線程持有,可是jucl lock缺不行。上文中使用httpclient寫了一個鏈接池,使用了一個socket設置,setSoLinger(60),而後在一次測試中重啓了遠程的服務,結果致使了系統的卡死,查看本機的鏈接,發現不少鏈接都處於close_wait狀態。考慮到遠程服務重啓,因此已有的鏈接確定須要所有斷開,斷開就須要四次揮手,那麼close_wait是什麼狀態呢?java
看上圖,很明顯是遠程server要重啓,主動關閉鏈接,發送了FIN,本機收到應該會馬上回應ACK,而後,本機應馬上發送FIN,可是卻沒有發,停留在CLOSE_WAIT狀態。聯想到使用的SocketConfig setSoLinger(60),多是這個設置致使這個問題。在hang住的時候,使用jstack查看線程dump,以下:正則表達式
"Thread-2158" #2171 prio=5 os_prio=0 tid=0x00000000193fe800 nid=0xa3c80 waiting on condition [0x000000003e70e000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000081eab2a8> (a java.util.concurrent.locks.ReentrantLock$NonfairSync) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:836) at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:870) at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1199) at java.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:209) at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:285) at org.apache.http.pool.AbstractConnPool.getTotalStats(AbstractConnPool.java:509) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.formatStats(PoolingHttpClientConnectionManager.java:227) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.requestConnection(PoolingHttpClientConnectionManager.java:265) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:176) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at http.connectionPool.TestConnectionPool.lambda$main$1(TestConnectionPool.java:85) at http.connectionPool.TestConnectionPool$$Lambda$3/670035812.run(Unknown Source) at java.lang.Thread.run(Thread.java:748)
不少線程在等待進入鎖,那麼查詢一下誰持有0x0000000081eab2a8不就好了,哈哈。但是查了整個線程dump文件,卻找不到究竟是哪一個線程持有了這把鎖,由於用的是ReentrantLock,和synchronized不同。 jstack命令能夠加參數,-l打印lock詳情 -e打印線程詳情,試試 jstack -l pid 多了這麼一句apache
Locked ownable synchronizers:
再次搜索,可使用正則表達式排除大部分記錄多線程
^.*(?<!wait for )<0x0000000081eb5388>.*$ 查詢不是wait for的行
結果:socket
"Thread-2623" #2636 prio=5 os_prio=0 tid=0x000000001aa7f000 nid=0xba4c8 runnable [0x000000005daed000] java.lang.Thread.State: RUNNABLE at java.net.DualStackPlainSocketImpl.close0(Native Method) at java.net.DualStackPlainSocketImpl.socketClose0(DualStackPlainSocketImpl.java:167) at java.net.AbstractPlainSocketImpl.socketPreClose(AbstractPlainSocketImpl.java:693) at java.net.AbstractPlainSocketImpl.close(AbstractPlainSocketImpl.java:530) - locked <0x00000000839b0280> (a java.lang.Object) at java.net.PlainSocketImpl.close(PlainSocketImpl.java:237) at java.net.SocksSocketImpl.close(SocksSocketImpl.java:1075) at java.net.Socket.close(Socket.java:1495) - locked <0x00000000839b00e0> (a java.lang.Object) - locked <0x00000000839b00c0> (a java.net.Socket) at sun.security.ssl.BaseSSLSocketImpl.close(BaseSSLSocketImpl.java:624) - locked <0x00000000839aff68> (a sun.security.ssl.SSLSocketImpl) at sun.security.ssl.SSLSocketImpl.closeSocket(SSLSocketImpl.java:1585) at sun.security.ssl.SSLSocketImpl.closeInternal(SSLSocketImpl.java:1723) at sun.security.ssl.SSLSocketImpl.close(SSLSocketImpl.java:1612) at org.apache.http.impl.BHttpConnectionBase.close(BHttpConnectionBase.java:334) at org.apache.http.impl.conn.LoggingManagedHttpClientConnection.close(LoggingManagedHttpClientConnection.java:81) at org.apache.http.impl.conn.CPoolEntry.closeConnection(CPoolEntry.java:70) at org.apache.http.impl.conn.CPoolEntry.close(CPoolEntry.java:96) at org.apache.http.pool.AbstractConnPool.getPoolEntryBlocking(AbstractConnPool.java:318) at org.apache.http.pool.AbstractConnPool.access$200(AbstractConnPool.java:69) at org.apache.http.pool.AbstractConnPool$2.get(AbstractConnPool.java:246) - locked <0x00000000d814f998> (a org.apache.http.pool.AbstractConnPool$2) at org.apache.http.pool.AbstractConnPool$2.get(AbstractConnPool.java:193) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.leaseConnection(PoolingHttpClientConnectionManager.java:303) at org.apache.http.impl.conn.PoolingHttpClientConnectionManager$1.get(PoolingHttpClientConnectionManager.java:279) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:191) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at http.connectionPool.TestConnectionPool.lambda$main$1(TestConnectionPool.java:90) at http.connectionPool.TestConnectionPool$$Lambda$3/670035812.run(Unknown Source) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - <0x0000000081eb5388> (a java.util.concurrent.locks.ReentrantLock$NonfairSync)
找到了一個線程!!,能夠看到正在close,而且持有了鏈接池的鎖,這樣的話,因爲設置了setSoLinger,它會一直等待60s,因而請求鏈接的線程就堆積起來了。測試