Получение ошибки с использованием log4r с отложенным заданием

У меня есть работающее приложение Rails 3.2 (Ruby 1.9.2p290 на Windows Server 2008 R2), которое использует отложенное задание для нескольких фоновых заданий. Недавно я заменил ведение журнала Rails по умолчанию на log4r, и оно отлично работает для приложения Rails. Однако, когда я запускаю задачу rake jobs:work, я получаю сообщение об ошибке, жалующееся на нулевой вывод:

Starting job worker
rake aborted!
TypeError: Expected kind of Outputter, got NilClass
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/log4r-1.1.10/lib/log4r/logger.rb:120:in `each'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/log4r-1.1.10/lib/log4r/logger.rb:120:in `add'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/delayed_job-4.0.0/lib/delayed/worker.rb:248:in `say'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/delayed_job-4.0.0/lib/delayed/worker.rb:147:in `start'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/delayed_job-4.0.0/lib/delayed/tasks.rb:9:in `block (2 levels) in '
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:240:in `call'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:240:in `block in execute'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:235:in `each'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:235:in `execute'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:179:in `block in invoke_with_call_chain'
F:/web-shared/Ruby192/lib/ruby/1.9.1/monitor.rb:201:in `mon_synchronize'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:172:in `invoke_with_call_chain'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/task.rb:165:in `invoke'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:150:in `invoke_task'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:106:in `block (2 levels) in top_level
'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:106:in `each'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:106:in `block in top_level'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:115:in `run_with_threads'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:100:in `top_level'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:78:in `block in run'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:176:in `standard_exception_handling'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/lib/rake/application.rb:75:in `run'
F:/web-shared/Ruby192/lib/ruby/gems/1.9.1/gems/rake-10.2.2/bin/rake:33:in `'
F:/web-shared/Ruby192/bin/rake:19:in `load'
F:/web-shared/Ruby192/bin/rake:19:in `'
Tasks: TOP => jobs:work

Кто-нибудь еще успешно использует log4r с отложенной работой? Я мог бы использовать некоторые указатели; не смог ничего найти через поиск Google или DuckDuckGo.

Вот фрагмент config/application.rb:

require File.expand_path('../boot', __FILE__)

require 'erb'
require 'rails/all'

# log4r
require 'log4r'
require 'log4r/yamlconfigurator'
require 'log4r/outputter/datefileoutputter'
require 'log4r/outputter/consoleoutputters'
include Log4r

...

class Application < Rails::Application

...

  # assign log4r's logger as rails' logger.
  log4r_config = YAML.load(ERB.new(File.read(File.join(File.dirname(__FILE__), 'log4r.yml'))).result)
  log4r_config['ENV'] = Rails.env
  log4r_config['APPNAME'] = Rails.application.class.parent_name
  YamlConfigurator.decode_yaml(log4r_config['log4r_config'])
  config.logger = Log4r::Logger[Rails.env]
  ActiveRecord::Base.logger = Log4r::Logger[Rails.env]
end

Вот файл config/log4r.yml:

log4r_config:
  # define all loggers ...
  loggers:
  - name: production
    level: WARN
    trace: 'false'
    outputters:
      - datefile_production
      - console_production
  - name: development
    level: DEBUG
    trace: 'true'
    outputters:
      - datefile_development
      - console_development
  - name: test
    level: DEBUG
    trace: 'true'
    outputters:
      - datefile_test
      - console_test

  # define all outputters (incl. formatters)
  outputters:
  - type: DateFileOutputter
    name: datefile_production
    dirname: "<%= File.join(Rails.root, 'log') %>"
    filename: "production.log"
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%X{:remote_ip}\t%X{:user}\t%X{:controller}\t%X{:action}\t%l\t%m'
      type: PatternFormatter
  - type: DateFileOutputter
    name: datefile_development
    dirname: "<%= File.join(Rails.root, 'log') %>"
    filename: "development.log"
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%X{:remote_ip}\t%X{:user}\t%X{:controller}\t%X{:action}\t%l\t%m'
      type: PatternFormatter
  - type: DateFileOutputter
    name: datefile_test
    dirname: "<%= File.join(Rails.root, 'log') %>"
    filename: "test.log"
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%X{:remote_ip}\t%X{:user}\t%X{:controller}\t%X{:action}\t%l\t%m'
      type: PatternFormatter
  - type: StdoutOutputter
    name: console_production
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%l\t%m'
      type: PatternFormatter
  - type: StdoutOutputter
    name: console_development
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%X{:remote_ip}\t%X{:user}\t%X{:controller}\t%X{:action}\t%l\t%m'
      type: PatternFormatter
  - type: StdoutOutputter
    name: console_test
    formatter:
      date_pattern: '%H:%M:%S.%L'
      pattern: '%p\t%d\t%X{:remote_ip}\t%X{:user}\t%X{:controller}\t%X{:action}\t%l\t%m'
      type: PatternFormatter

ОБНОВЛЕНИЕ

Что ж, пропустив через отладчик задачу rake, я разобрался, что происходит.

Delayed::Worker (delayed_job/lib/delayed/worker.rb, строка 248) регистрирует сообщение с помощью метода add регистратора следующим образом:

logger.add level, "#{Time.now.strftime('%FT%T%z')}: #{text}" if logger

Это действительно и правильно для класса Logger Ruby, см. Logger.html#method-i-add. Однако при использовании log4r он разрешается в Log4r::Logger.add (log4r/lib /log4r/logger.rb строка 119), который пытается добавить средство вывода.

Я не уверен, почему это происходит, или какое решение было бы.


person Edward J. Stembler    schedule 29.03.2014    source источник
comment
Похоже, вы используете Windows. И он не может создать выходной интерфейс. Проверьте это и опубликуйте как ответ, если это сработает. Если нет, опубликуйте свои настройки конфигурации для Log4r, которые могут быть полезны.   -  person Kashyap    schedule 30.03.2014


Ответы (2)


(см. blog.mmlac.com, чтобы получить исходный комментарий по этому вопросу. )

Проблема в том, что DelayedJob ожидает, что регистратор будет Rails::Logger, и делает к нему вызовы, которые log4r не поддерживает.

В этой статье переменная регистратора перезаписывается для использования Log4r::Logger, который не поддерживает вызов .add, как предполагалось. В отличие от Java, здесь нет пакетов, определяющих, какой класс логгера использовать, или slf4j, который объединяет разные логгеры со стандартным интерфейсом.

Простого решения этой проблемы не существует. С одной стороны, вы можете перезаписать затронутые части delayedJob. С другой стороны, вы можете предотвратить загрузку log4r, когда delayedJob работает на рельсах, то есть с помощью пользовательской среды:

  • разработка
  • производство
  • задержанная работа

Также не гарантируется, что это будет работать везде (все, что проверяет rails == «production», будет иметь проблемы).

Другой способ — использовать переменные среды и проверить их в application.rb:

if (ENV["log4rlogger"] == "true") config.logger = Log4r::Logger["rails"]
person mmlac    schedule 31.03.2014

Теперь вы можете обновить гем delayed_jobs 4.0.1, и это решит эту проблему!

person Mathieu J.    schedule 29.04.2014