Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 094A2200CB3 for ; Mon, 12 Jun 2017 06:34:46 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 07E4D160BD8; Mon, 12 Jun 2017 04:34:46 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 4E657160BD7 for ; Mon, 12 Jun 2017 06:34:45 +0200 (CEST) Received: (qmail 70864 invoked by uid 500); 12 Jun 2017 04:34:44 -0000 Mailing-List: contact commits-help@kafka.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@kafka.apache.org Delivered-To: mailing list commits@kafka.apache.org Received: (qmail 70855 invoked by uid 99); 12 Jun 2017 04:34:44 -0000 Received: from git1-us-west.apache.org (HELO git1-us-west.apache.org) (140.211.11.23) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 12 Jun 2017 04:34:44 +0000 Received: by git1-us-west.apache.org (ASF Mail Server at git1-us-west.apache.org, from userid 33) id 5C45EDFF15; Mon, 12 Jun 2017 04:34:44 +0000 (UTC) Content-Type: text/plain; charset="us-ascii" MIME-Version: 1.0 Content-Transfer-Encoding: 7bit From: junrao@apache.org To: commits@kafka.apache.org Message-Id: X-Mailer: ASF-Git Admin Mailer Subject: kafka git commit: KAFKA-5405; Request log should log throttle time Date: Mon, 12 Jun 2017 04:34:44 +0000 (UTC) archived-at: Mon, 12 Jun 2017 04:34:46 -0000 Repository: kafka Updated Branches: refs/heads/0.11.0 50b20d2d3 -> 4508fb2e4 KAFKA-5405; Request log should log throttle time Record `apiThrottleTime` in RequestChannel. junrao A trivial change. Please review. Thanks. Author: huxihx Reviewers: Jun Rao Closes #3265 from huxihx/KAFKA-5405 (cherry picked from commit a623d8fe391f4c4439707b4522c66a3764f620d8) Signed-off-by: Jun Rao Project: http://git-wip-us.apache.org/repos/asf/kafka/repo Commit: http://git-wip-us.apache.org/repos/asf/kafka/commit/4508fb2e Tree: http://git-wip-us.apache.org/repos/asf/kafka/tree/4508fb2e Diff: http://git-wip-us.apache.org/repos/asf/kafka/diff/4508fb2e Branch: refs/heads/0.11.0 Commit: 4508fb2e488cb44647cb8da03b17e2e8e9a07e62 Parents: 50b20d2 Author: huxihx Authored: Sun Jun 11 21:34:29 2017 -0700 Committer: Jun Rao Committed: Sun Jun 11 21:34:40 2017 -0700 ---------------------------------------------------------------------- core/src/main/scala/kafka/network/RequestChannel.scala | 4 ++-- 1 file changed, 2 insertions(+), 2 deletions(-) ---------------------------------------------------------------------- http://git-wip-us.apache.org/repos/asf/kafka/blob/4508fb2e/core/src/main/scala/kafka/network/RequestChannel.scala ---------------------------------------------------------------------- diff --git a/core/src/main/scala/kafka/network/RequestChannel.scala b/core/src/main/scala/kafka/network/RequestChannel.scala index bb19346..aff1526 100644 --- a/core/src/main/scala/kafka/network/RequestChannel.scala +++ b/core/src/main/scala/kafka/network/RequestChannel.scala @@ -189,8 +189,8 @@ object RequestChannel extends Logging { val apiRemoteTimeMs = nanosToMs(apiRemoteCompleteTimeNanos - apiLocalCompleteTimeNanos) val responseQueueTimeMs = nanosToMs(responseDequeueTimeNanos - responseCompleteTimeNanos) val responseSendTimeMs = nanosToMs(endTimeNanos - responseDequeueTimeNanos) - requestLogger.trace("Completed request:%s from connection %s;totalTime:%f,requestQueueTime:%f,localTime:%f,remoteTime:%f,responseQueueTime:%f,sendTime:%f,securityProtocol:%s,principal:%s,listener:%s" - .format(requestDesc(detailsEnabled), connectionId, totalTimeMs, requestQueueTimeMs, apiLocalTimeMs, apiRemoteTimeMs, responseQueueTimeMs, responseSendTimeMs, securityProtocol, session.principal, listenerName.value)) + requestLogger.trace("Completed request:%s from connection %s;totalTime:%f,requestQueueTime:%f,localTime:%f,remoteTime:%f,throttleTime:%f,responseQueueTime:%f,sendTime:%f,securityProtocol:%s,principal:%s,listener:%s" + .format(requestDesc(detailsEnabled), connectionId, totalTimeMs, requestQueueTimeMs, apiLocalTimeMs, apiRemoteTimeMs, apiThrottleTime, responseQueueTimeMs, responseSendTimeMs, securityProtocol, session.principal, listenerName.value)) } } }