播放框架60秒延遲 - 在日誌中擴展信息

在我們的scala/play應用程序中，我們有一個通常需要大約一秒的響應時間的請求。大約2-4％的時間請求需要大約秒 - 並且webproxy超時請求（504）。 61秒看起來像超時可疑。播放框架60秒延遲 - 在日誌中擴展信息

我想從日誌中獲取更多信息，但他們在這61秒內保持沉默。我曾嘗試設置玩和io.netty級別DEBUG，但還沒有找到任何東西。成功的響應，接着的下一個請求（1.4秒間隙）看起來像

2017-01-31 13:13:09,931 - [DEBUG] - from com.xxx.api.controllers.Api in monolith-akka.actor.default-dispatcher-34 
new Result created - .. .. 
2017-01-31 13:13:11,318 - [DEBUG] - from com.xxx.api.controllers.Api in monolith- 
akka.actor.default-dispatcher-34 
Upload media - .. ..

的響應，隨後下一個請求以秒間隙看起來像

2017-01-31 13:12:08,624 - [DEBUG] - from com.xxx.api.controllers.Api in monolith-akka.actor.default-dispatcher-31 
new Result created - .. .. 
2017-01-31 13:13:09,892 - [DEBUG] - from com.xxx.controllers.Api in monolith-akka.actor.default-dispatcher-34 
Upload media - .. ..

有沒有人有任何建議在配置logback.xml來告訴我13:12:08和13:13:09之間發生了什麼。

來源

2017-01-31 Martin Flower

您在哪裏登錄代碼？ – nmat

'uploadMedia（）'是控制器的實現方法，參考'routes'配置。 _上傳媒體.._是方法中的第一個語句，_new Result created .._是方法中的最後一個語句。 –

在我能找到的所有日誌上設置了TRACE的級別後，我將延遲縮小到以下兩行，表示Play框架內出現延遲。 'XX：X5：45640 \t play.core.server.netty.PlayRequestHandler在網狀事件 - 環 - 1由netty' 'XX接收\t HTTP請求：5233：45630 \t play.api.mvc.Action在整料-akka.actor.default-dispatcher-23 \t用請求調用動作 –

我設置日誌級別在CONF/logback.xml追查，<logger name="play" level="TRACE" />

的日誌顯示在60秒延遲播放框架內的某處發生。我現在已將此問題報告爲問題6943

來源

2017-02-06 07:21:58

播放框架60秒延遲 - 在日誌中擴展信息

回答

相關問題