按请求登录Node.js

Question

我是一位经验丰富的Java开发人员,负责收集Node.js并转向异步模型.除了伐木以外,大多数事情都很顺利.在使用express的Node.js中进行开发时,我在Java中找不到类似于log4j和NDC的任何内容.

我的目标是让每个日志语句自动添加以下信息:

[2013-11-07 11:17:04.615 serverScript INFO 7036 192.168.7.209]

这包括时间戳,编写此语句的js文件的名称(对于模块化节点应用程序),调试级别,进程ID(运行集群)和客户端的IP地址.

我可以在最初进入我的请求处理程序时编写它们,但是如果没有将一堆参数传播到每个被调用的函数,子例程中的记录器语句就没有信息.我知道我可以在每个初始化其名称的js文件中创建我的记录器实例,但我还没有找到客户端IP地址的解决方案.对于运行时间较长的请求,我在记录器中设置的地址会在下一个请求进入时被覆盖,因此记录的IP会被越过.

我看过温斯顿,但即使用它也无法解决这个问题.有没有人完成这个？当您可以按IP过滤以仅查看一个用户的活动时,跟踪字段问题非常有用.

[编辑:从参数传递解决方案测试,直到我学习syslog方式]

[2013-11-07 14:29:28.641 server INFO  7527 192.168.7.209] Got request from 192.168.7.209 for /ionmed/executeQuery?
[2013-11-07 14:29:28.641 router INFO  7527 192.168.7.209] About to route a request for /ionmed/executeQuery, method=POST
[2013-11-07 14:29:28.642 router INFO  7527 192.168.7.209] getting POSTed data
[2013-11-07 14:29:28.642 router INFO  7527 192.168.7.209] POST params: {"sqlQuery":"select sleep(10)","sessionStart":"1383852558799","rand":"0.5510970998368581","jsessionid":"117DBAA89F599D923AF80D4AB171BDDF"}
[2013-11-07 14:29:28.642 requestHandlers INFO  7527 192.168.7.209] 'query' was called.
[2013-11-07 14:29:28.642 requestHandlers INFO  7527 192.168.7.209] select sleep(10)
[2013-11-07 14:29:30.673 server INFO  7527 192.168.7.217] Got request from 192.168.7.217 for /
[2013-11-07 14:29:30.673 router INFO  7527 192.168.7.217] About to route a request for /, method=GET
[2013-11-07 14:29:30.673 router INFO  7527 192.168.7.217] No request handler found for /; serving as file
[2013-11-07 14:29:30.673 router INFO  7527 192.168.7.217] Request handler 'serveFile' was called to get: /index.html
[192.168.7.217 Thu, 07 Nov 2013 19:29:30 GMT] HTTP/1.1 GET "/node/" 200 "Mozilla/5.0 (iPod; CPU iPhone OS 5_1_1 like Mac OS X) AppleWebKit/534.46 (KHTML, like Gecko) Version/5.1 Mobile/9B206 Safari/7534.48.3"
[2013-11-07 14:29:33.578 server INFO  7527 192.168.7.217] Got request from 192.168.7.217 for /
[2013-11-07 14:29:33.578 router INFO  7527 192.168.7.217] About to route a request for /, method=GET
[2013-11-07 14:29:33.578 router INFO  7527 192.168.7.217] No request handler found for /; serving as file
[2013-11-07 14:29:33.579 router INFO  7527 192.168.7.217] Request handler 'serveFile' was called to get: /index.html
[192.168.7.217 Thu, 07 Nov 2013 19:29:33 GMT] HTTP/1.1 GET "/node/" 200 "Mozilla/5.0 (iPod; CPU iPhone OS 5_1_1 like Mac OS X) AppleWebKit/534.46 (KHTML, like Gecko) Version/5.1 Mobile/9B206 Safari/7534.48.3"
[2013-11-07 14:29:38.644 requestHandlers INFO  7527 192.168.7.209] sending response
[192.168.7.209 Thu, 07 Nov 2013 19:29:38 GMT] HTTP/1.1 POST "/node/ionmed/executeQuery?" 200 "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:25.0) Gecko/20100101 Firefox/25.0"
[2013-11-07 14:29:41.540 server INFO  7527 192.168.7.217] Got request from 192.168.7.217 for /
[2013-11-07 14:29:41.541 router INFO  7527 192.168.7.217] About to route a request for /, method=GET
[2013-11-07 14:29:41.541 router INFO  7527 192.168.7.217] No request handler found for /; serving as file
[2013-11-07 14:29:41.541 router INFO  7527 192.168.7.217] Request handler 'serveFile' was called to get: /index.html
[192.168.7.217 Thu, 07 Nov 2013 19:29:41 GMT] HTTP/1.1 GET "/node/" 200 "Mozilla/5.0 (iPod; CPU iPhone OS 5_1_1 like Mac OS X) AppleWebKit/534.46 (KHTML, like Gecko) Version/5.1 Mobile/9B206 Safari/7534.48.3"
[2013-11-07 14:29:45.146 server INFO  7527 192.168.7.209] RLz6tmJ7KTH2R16VCVTX: bye {"user":"1"}
[2013-11-07 14:29:45.176 server INFO  7527 192.168.7.209] RLz6tmJ7KTH2R16VCVTX: disconnected

现在我只需要弄清楚如何使快速请求记录器与我的内部记录器具有相同的行条目格式,直到它全部移动到rsyslog.

Answer 1

这些说明来自我设置的 Ubuntu 12.04 发行版，但它们应该非常适用于 RHEL、Fedora、CentOS 等。

Rsyslog 是一个系统日志实用程序，可用于记录 Linux 计算机上任何程序的消息。首先您需要找到您的 rsylog 配置信息。您可以使用以下命令来执行此操作：

sudo find / -name rsyslog.conf

如果找不到配置文件，您可以使用以下命令列出正在运行的服务，以查看 rsyslog 是否在您的计算机上：

service --status-all

现在打开它找到的文件并执行以下操作：

• 注释掉 $ActionFileDefaultTemplate RSYSLOG_TraditionalFileFormat 行
• 取消注释 $ModLoad imtcp
• 取消注释 $InputTCPServerRun 并指定端口号 1514，将使用 1514 b/c 如果我使用端口 514，Ubuntu 12.04 rsyslog 会出现删除权限问题，其他发行版没有类似问题，您可以保留默认端口号。我通过使用 iptables 将端口 514 流量重新路由到 1514 来解决这个问题
• 将 $FileCreateMode 0640 更改为 0644

现在我创建了一个名为 /etc/rsyslog.d/10.conf 的文件（这是 rsyslog 的辅助配置文件，我们可以在其中过滤消息、命名日志文件等）并向其中添加以下内容：

$template DailyPerHostLogs,"/var/log/MyLogFile_%$YEAR%_%$MONTH%_%$DAY%.log"

#:msg,contains,"MsgName" -?DailyPerHostLogs
*.* -?DailyPerHostLogs
&~

该文件每天创建一个新文件，并查找文本中使用 MsgName 发送的任何消息，并将其放入每日文件中，然后将其从队列中删除以供任何其他日志请求记录，这样我们就不会重复记录它。

现在您可以重新启动您正在使用的机器，一切都应该可以正常工作。您可以通过在上面 10.conf 中定义的 /var/log 中查找文件来检查这一点。通过发出以下命令从命令行点击记录器：

logger this is from the command line
echo "this is from the tcp port" > /dev/tcp/127.0.0.1/1514

您应该会在日志文件中看到这两行弹出。如果您明白了，那么让我们继续讨论能够命中日志的节点模块。

var net = require('net');

var client = net.connect({port: this.1514}, function(){ console.log("Open"); });

client.write(' ' + "sMsgName: What"+ ' ' + "hath" + ' ' + "God wrought?" + '\n');

//Do everything else your program needs. . . 

写入中的 '\n' 告诉 rsyslog 我们已经完成了这一行。另外，您需要在前面添加一个空格才能使过滤工作： http://www.rsyslog.com/log-normalization-and-the-leading-space/

像这样的设置，魔鬼总是在细节中，但我认为这将帮助你完成大部分的工作，而谷歌搜索将帮助你完成剩下的工作。