2

我们在 Tomcat 上部署了一个 JRuby on Rails 2 应用程序，使用默认的 Rails 记录器。在我们越过 DST 边界一段时间后，我们发现应用程序已死，日志中显示以下错误消息：

org.jruby.rack.RackInitializationException: Shifting failed. '/tc_instance/applogs/search.log.20111106' already exists.

通常，日志在午夜轮换。前一天的日志的最终时间戳始终为 23:59，但11 月 6 日的日志除外，其时间戳为 22:59。

-rw-rw-rw-  300683179 Nov  3 23:59 search.log.20111103
-rw-rw-rw-  226082012 Nov  4 23:59 search.log.20111104
-rw-rw-rw-  79789353 Nov  5 23:59 search.log.20111105
-rw-rw-rw-  109080879 Nov  6 22:59 search.log.20111106

所以发生的事情是日志试图在晚上 11 点而不是午夜翻滚。然后，当它尝试创建新一天的日志时，它使用的是同一日期。

看起来我们的配置有问题，或者日志轮换时序逻辑中存在错误。

这是 environment.rb 的相关部分：

  # Set Time.zone default to the specified zone and make Active Record auto-convert to this zone.
  # Run "rake -D time" for a list of tasks for finding time zone names.
  config.time_zone = 'UTC'

  if defined?($servlet_context)
    include_class java.lang.System
    app_logs_path = System.getProperty("appLogsPath")
    if app_logs_path.nil?
      Rails.logger.error("***System Property 'appLogsPath' was not set.  Please contact the system administrator immediately!")
    else
      config.logger = Logger.new("#{app_logs_path}/search.log", "daily")
      config.logger.formatter = Logger::Formatter.new
      config.logger.datetime_format = "%Y-%m-%d %H:%M:%S"
      config.logger.level = Logger::Severity::WARN
      def config.format_message(severity, timestamp, progname, msg)
        "[#{timestamp.to_formatted_s(:db)} #{severity}] #{msg}\n"
      end

    end
  end

我假设 'UTC' 的 config.time_zone 设置用于 ActiveRecord，但我想知道这是否是原因。

我的问题是：可能是什么问题？另外，日志轮换时序逻辑在哪里？是在 Rails、Jruby-Rack 还是底层的日志机制中？

4

2 回答 2

2

这似乎是影响 1.8 模式的 JRuby (http://jira.codehaus.org/browse/JRUBY-6191) 中的一个错误。解决方法是使用 1.9 模式或使用“记录器”gem。

更新：JRuby 团队指出，实际上是 MRI Ruby 1.8.7 中的记录器出现了这个错误。为了兼容性，他们不会改变这种行为。上述解决方法适用（并且似乎工作正常）。

于 2011-11-15T18:33:11.200 回答
0

Tomcat 通常使用 log4j，这通常需要最后润色才能顺利进行。

查看一些最佳实践：

* 显然有点不同，未经测试但看起来很有用。这可能会杀死您的狗并吃掉您的孩子，因此请谨慎部署任何更改。

于 2011-11-14T19:25:03.930 回答