6

我想找到 Ruby 提供的最快的记录器。我的直觉告诉我，syslog 会在这场比赛中获胜。但我的直觉似乎是错误的。Syslog 是我测试过的三个记录器中最慢的。我正在使用我的 MacBook Pro、OSX 10.6 (Snow Leopard)、Intel Core2 Duo、4GB 内存和由 MacPorts 构建的 Ruby 1.8.7。难道我做错了什么？还是 Ruby 的 syslog 实现就这么慢？如果结果与我的不同，请随时发布您的结果。也欢迎您将您最喜欢的 Ruby 记录器添加到基准测试中。我的目标是找到最快的记录器。我只对纯粹的性能（吞吐量）感兴趣。多目标日志记录等功能在这里不是问题。

# loggers_bench.rb

require 'rbench'
require 'activesupport'
require 'syslog'
require 'logger'

buffered = ActiveSupport::BufferedLogger.new('buffered.log')
logger   = Logger.new('logger.log')
syslog   = Syslog.open('rb_syslog')

TIMES = 10_000

RBench.run(TIMES) do
  column :syslog,    :title => 'Syslog'
  column :logger,    :title => 'Logger'
  column :buffered,  :title => 'ActiveSuppoort::BufferedLogger'


  report '#info' do
    syslog {
      300.times do |i|
        syslog.info "hello #{i}"
      end
    }

    logger {
      300.times do |i|
        logger.info "#{Time.now} logging_logger[Process.pid]:  INFO  logging_logger : hello #{i}"
      end
    }
    buffered {
      300.times do |i|
        buffered.info "#{Time.now} logging_logger[Process.pid]:  INFO  logging_logger : hello #{i}"
      end
    }

  end
end

# > RUBYOPT=rubygems ruby loggers_bench.rb 
#                Syslog |  Logger | ActiveSuppoort::BufferedLogger|
# -----------------------------------------------------------------
# #info         158.316 | 117.882 |                        98.707 |

请注意，我只对 Syslog 使用了更简单的形式：“hello #{i}”（理论上这应该更快，但事实并非如此）。此外，我的 Mac 的默认 syslogd 似乎具有有限的消息配额（500/秒）。系统日志中不时生成以下消息：

*** process 1962 exceeded 500 log message per second limit  -  remaining messages this second discarded ***
4

4 回答 4

3

好的，我更新了脚本以包含 log4r，这是我的偏好，因为它支持许多不同的功能。（滚动日志，耶！）

我还在缓冲记录器上包含了一个 .flush ，并减少了测试时间，因此它不会永远花费。log4r 只比缓冲记录器慢一点。那将是我的选择。

# loggers_bench.rb

require 'rbench'

require 'active_support'
require 'stringio'
buffered = ActiveSupport::BufferedLogger.new('buffered.log')
require 'logger'
logger   = Logger.new('logger.log')
require 'syslog'
syslog   = Syslog.open('rb_syslog')
require 'log4r'
log4r = Log4r::Logger.new 'mylog'
log4r.outputters = Log4r::FileOutputter.new('log', :filename => 'log4r.log')

TIMES = 5_000

RBench.run(TIMES) do
  column :syslog,    :title => 'Syslog'
  column :logger,    :title => 'Logger'
  column :buffered,  :title => 'ActiveSuppoort::BufferedLogger'
  column :log4r,     :title => 'log4r'


  report '#info' do
    syslog {
      10.times do |i|
        syslog.info "hello #{i}"
      end
    }

    logger {
      10.times do |i|
        logger.info "#{Time.now} logging_logger[Process.pid]:  INFO  logging_logger : hello #{i}"
      end
    }

    buffered {
      10.times do |i|
        buffered.info "#{Time.now} logging_logger[Process.pid]:  INFO  logging_logger : hello #{i}"
      end
      buffered.flush
    }

    log4r {
      10.times do |i|
        log4r.info "#{Time.now} logging_logger[Process.pid]:  INFO  logging_logger : hello #{i}"
      end
    }
  end
end
#                Syslog |  Logger | ActiveSuppoort::BufferedLogger |   log4r |
# ----------------------------------------------------------------------------
# #info           2.377 |   2.040 |                          1.425 |   1.532 |
于 2011-03-22T18:42:48.247 回答
2

我猜BufferedLogger's name 的第一部分解释了它的速度，与其他两个相比，我希望在收到消息后立即编写消息。

权衡将是提高缓冲对象的吞吐量并减少 IO 负载，以防止因存储中未刷新消息的大规模崩溃而可能丢失日志信息。

我想知道，为了在不损失所有消息安全性并以增加复杂性为代价的情况下增加吞吐量，是否可以将这两种形式结合起来：使用缓冲记录器来获取有用但不需要 100.0000% 完整的信息（即小偶尔的损失不会引起痛苦）和非缓冲的选择之一，用于您必须拥有的消息，例如出于法律或诊断目的。

如果必备消息的数量与必备消息相比相对较低（它应该是这样，或者这种方法可能是不必要的复杂），那么您使用哪个非缓冲记录器并不重要。

于 2009-09-09T10:40:55.470 回答
0

您还应该查看日志记录 (http://github.com/TwP/logging) 框架。有一个基准 (https://github.com/TwP/logging/blob/master/test/benchmark.rb) 文件，您可以查看该文件以与 log4r 和核心 ruby​​ 记录器进行一些比较。

于 2012-07-25T23:08:29.080 回答
0

尝试使用 syslog-ng，结果是：

TIMES: 50 个
用户系统总实际
SYSLOG 0.000000 0.000000 0.000000 (0.006187)
LOGGER 0.000000 0.010000 0.010000 (0.003698)
缓冲 0.000000 0.000000 0.0000000 (0.0000000) (06)

缓冲记录器似乎更好。

于 2010-07-06T08:40:00.403 回答