google-app-engine - GAE/J 请求日志格式分解

Question

这里是 GAE 控制台日志记录的示例:

http://i.stack.imgur.com/M2iJX.png用于可读的高分辨率版本。

我想提供文件的分割，显示在折叠(摘要) View 和扩展(详细) View 中。我将填写我知道其含义的字段，并感谢您协助解决其余问题。一旦有新信息可用，将更新此帖子。

谢谢你，
格言。

---

未决问题:

• 如何读取时间戳？ [...-prod/0-0-39.346862139187007139]
• 为什么总结起来说请求用了 343 毫秒，但详细说是 344 毫秒？
• 如果请求在 CPU 上花费 123 毫秒，在 API 调用上花费 30 毫秒，那么剩下的时间都去哪儿了？为什么总请求时间是 343/344ms？

Answer 1

总结

• 12-14:请求的日期。 12 是月份(12 月)，14 是月份中的第几天(星期二)。
• 05:21AM :请求的时间，PST 偏移量。 05 是小时。 21 是分钟。
• 57.593 :请求时间，PST 偏移量。 57 是第二个。 593 是毫秒。
• /match/... : HTTP请求路径
• 200 :HTTP 返回码。 (200 = 确定)
• 343ms :计算并将响应返回给用户所花费的总时间(以毫秒为单位)
• 123cpu_ms :请求花费在 CPU 计算上的时间(以毫秒为单位)
• 30api_cpu_ms:请求在 API 调用(Datastore get 和 co...)上花费的时间(以毫秒为单位)
• 1kb :发送给用户的响应的大小(以千字节为单位)
• Mozilla/5.0 (X11; U; Linux x86_64; en-US) AppleWebKit/534.7 (KHTML, like Gecko) Chrome/7.0.517.44 Safari/534.7,gzip(gfe) : User Agent 注意gzip(gfe) 是由 AppEngine 前端添加的。

详情

• IP 黄色屏蔽 : 发起请求的客户端IP地址
• HTTP Referrer :请注意，此请求为空，因为它是直接命中
• [14/Dec/2010:05:21:57 -0800]:日期，包括时间戳偏移规范。
• "GET/match/... HTTP/1.1":HTTP GET URI。
• 200 :HTTP 返回码。 (200 = 确定)
• 1036 :发送给用户的响应的大小(以字节为单位)
• Mozilla/5.0 (X11; U; Linux x86_64; en-US) AppleWebKit/534.7 (KHTML, like Gecko) Chrome/7.0.517.44 Safari/534.7,gzip(gfe) : User Agent 注意gzip(gfe) 是由 AppEngine 前端添加的。
• ms=344 :计算并向用户返回响应所花费的总时间(以毫秒为单位)
• cpu_ms=123 :请求花费在 CPU 计算上的时间(以毫秒为单位)
• api_cpu_ms=30:请求在 API 调用(Datastore get 和 co...)上花费的时间(以毫秒为单位)
• cpm_usd=0.003648:像这样的 1000 个请求将花费的金额(以美元为单位)。 ref

日志记录

• 12-14 :此特定应用程序发出日志条目的日期。 12 是月份(12 月)，14 是月份中的第几天(星期二)。
• 05:21AM:此特定应用程序发出日志条目的时间，PST 偏移量。
• 57.833 :请求时间，PST偏移量。 57 是第二个。 833 是毫秒。
• [...-prod/0-0-39.346862139187007139]:发出此日志消息的应用程序当前版本的标识符。注意:...-prod 是应用程序名称。 0-0-39 是部署的版本名称 (app.yaml)。 .346862139187007139是时间吗？ (以什么格式？)当这个版本被部署到 appengine 云时。
• stdout:应用程序将此日志消息发送到的 channel 。可以是标准输出或标准错误。
• INFO ....Matcher - ... Id 208 matched.:应用程序级输出。可以通过 System.out.print 或(在本例中)使用日志记录框架 logback