vendredi 22 mai 2020

Project deploying in Tomcat running on Linux had a very long TTFB waitting time while visiting

Here is the catalina.out:

[2020-05-23 13:59:41] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(2075行) -Checking for expired resources - Sat May 23 13:59:41 CST 2020 [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:41] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1538行) -BEGIN check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:41] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1559行) -FINISHED check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:46] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:run(730行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@4797eb0c -- Running DeadlockDetector[Exiting. No pending tasks.]
[2020-05-23 13:59:56] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:run(730行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@4797eb0c -- Running DeadlockDetector[Exiting. No pending tasks.]
[2020-05-23 13:59:56] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(2075行) -Checking for expired resources - Sat May 23 13:59:56 CST 2020 [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:56] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1538行) -BEGIN check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:56] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1559行) -FINISHED check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler:debug(76行) -Entering nullPropertyValue [target=[com.opensymphony.xwork2.DefaultTextProvider@72d7788f], property=struts]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler:debug(76行) -Entering nullPropertyValue [target=[com.opensymphony.xwork2.DefaultTextProvider@72d7788f], property=com]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler:debug(76行) -Entering nullPropertyValue [target=[com.opensymphony.xwork2.DefaultTextProvider@72d7788f], property=org]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.conversion.impl.InstantiatingNullHandler:debug(76行) -Entering nullPropertyValue [target=[com.opensymphony.xwork2.DefaultTextProvider@72d7788f], property=struts]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.DefaultActionProxy:debug(76行) -Creating an DefaultActionProxy for namespace [/sys/login] and action name [login]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.I18nInterceptor:debug(76行) -intercept '/sys/login/login' {
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.I18nInterceptor:debug(80行) -applied invocation context locale=zh_CN
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.I18nInterceptor:debug(80行) -before Locale=zh_CN
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.StaticParametersInterceptor:debug(76行) -Setting static parameters {}
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.ParametersInterceptor:debug(76行) -Setting params NONE
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.ParametersInterceptor:debug(76行) -Setting params 
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.validator.ValidationInterceptor:debug(76行) -Invoking validate() on action com.gsafety.cloudframework.login.action.LoginAction@36216ab2
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.PrefixMethodInvocationUtil:debug(76行) -cannot find method [validateLogin_noDecorator] in action [com.gsafety.cloudframework.login.action.LoginAction@36216ab2]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.interceptor.PrefixMethodInvocationUtil:debug(76行) -cannot find method [validateDoLogin_noDecorator] in action [com.gsafety.cloudframework.login.action.LoginAction@36216ab2]
[2020-05-23 13:59:59] DEBUG com.opensymphony.xwork2.DefaultActionInvocation:debug(76行) -Executing action method = login_noDecorator
[2020-05-23 13:59:59] DEBUG freemarker.cache:debug(45行) -Couldn't find template in cache for "extends/cloud-login/resources/page/sys/login/login/login.ftl"("zh_CN", utf-8, parsed); will try to load it.
[2020-05-23 14:00:00] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:trace(1735行) -trace com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd [managed: 5, unused: 2, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@7c4583b4)
[2020-05-23 14:00:00] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:postRunnable(236行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner@70a1bac4: Adding task to queue -- com.mchange.v2.resourcepool.BasicResourcePool$1RefurbishCheckinResourceTask@3297748c
[2020-05-23 14:00:00] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:trace(1735行) -trace com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd [managed: 5, unused: 2, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@7c4583b4)
[2020-05-23 14:00:06] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:run(730行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@4797eb0c -- Running DeadlockDetector[Exiting. No pending tasks.]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(2075行) -Checking for expired resources - Sat May 23 14:00:11 CST 2020 [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1538行) -BEGIN check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:shouldExpire(1620行) -EXPIRED idle resource: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c ---> idle_time: 71382; max_idle_time: 60000 [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1549行) -Removing expired resource: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:postRunnable(236行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner@70a1bac4: Adding task to queue -- com.mchange.v2.resourcepool.BasicResourcePool$1DestroyResourceTask@588b77cf
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:trace(1735行) -trace com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd [managed: 4, unused: 2, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@7c4583b4)
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:trace(1735行) -trace com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd [managed: 4, unused: 2, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@7c4583b4)
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:cullExpired(1559行) -FINISHED check for expired resources.  [com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd]
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:incrementPendingAcquires(438行) -incremented pending_acquires: 1
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(1010行) -Preparing to destroy resource: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c
[2020-05-23 14:00:11] DEBUG com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool:destroyResource(604行) -Preparing to destroy PooledConnection: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:<init>(1776行) -Starting acquisition series. Incremented pending_acquires [1],  attempts_remaining: 30
[2020-05-23 14:00:11] DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner:postRunnable(236行) -com.mchange.v2.async.ThreadPoolAsynchronousRunner@70a1bac4: Adding task to queue -- com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask@78e4af1f
[2020-05-23 14:00:11] DEBUG com.mchange.v2.c3p0.impl.NewPooledConnection:close(634行) -com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c closed by a client.
java.lang.Exception: DEBUG -- CLOSE BY CLIENT STACK TRACE
    at com.mchange.v2.c3p0.impl.NewPooledConnection.close(NewPooledConnection.java:634)
    at com.mchange.v2.c3p0.impl.NewPooledConnection.closeMaybeCheckedOut(NewPooledConnection.java:247)
    at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.destroyResource(C3P0PooledConnectionPool.java:607)
    at com.mchange.v2.resourcepool.BasicResourcePool$1DestroyResourceTask.run(BasicResourcePool.java:1012)
    at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:648)
[2020-05-23 14:00:11] DEBUG com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool:destroyResource(615行) -Successfully destroyed PooledConnection: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(1015行) -Successfully destroyed resource: com.mchange.v2.c3p0.impl.NewPooledConnection@2f089f1c
[2020-05-23 14:00:11] DEBUG com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool:acquireResource(271行) -com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager@5b544a9.acquireResource() returning. 
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:trace(1735行) -trace com.mchange.v2.resourcepool.BasicResourcePool@1471fbbd [managed: 5, unused: 3, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@7c4583b4)
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:_decrementPendingAcquires(459行) -decremented pending_acquires: 0
[2020-05-23 14:00:11] DEBUG com.mchange.v2.resourcepool.BasicResourcePool:run(1813行) -Acquisition series terminated successfully. Decremented pending_acquires [0],  attempts_remaining: 30
[2020-05-23 14:00:13] DEBUG freemarker.cache:debug(45行) -TemplateLoader.findTemplateSource("extends/cloud-login/resources/page/sys/login/login/login.ftl"): Found

which I post the request at 14:00:00 but recieve response at 14:00:13 (probably,cause it's a login html needing to read login.ftl) I wannna know if there is any problem with the database connection or something else. Plz anyone gimme a hand!~ Love u guys.

enter image description here enter image description here




Aucun commentaire:

Enregistrer un commentaire