foreman issues

gistfile1.txt

Loading runner configuration...
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-90279 t-ow5l9q1mk DEBUG: New configuration: #<Sneakers::Configuration:0x00007fbdbeeee368 @hash={:error_reporters=>[#<Sneakers::ErrorReporter::DefaultLogger:0x00007fbdc04c8968>], :runner_config_file=>nil, :metrics=>nil, :daemonize=>false, :start_worker_delay=>0.2, :workers=>2, :log=>#<IO:<STDOUT>>, :pid_path=>"sneakers.pid", :amqp_heartbeat=>30, :timeout_job_after=>10 minutes, :prefetch=>10, :threads=>1, :share_threads=>false, :ack=>true, :heartbeat=>30, :hooks=>{:before_fork=>#<Proc:0x00007fbdc6176570@/Users/nena/Documents/projects/mandala/config/initializers/sneakers.rb:33>, :after_fork=>#<Proc:0x00007fbdc6177420@/Users/nena/Documents/projects/mandala/config/initializers/sneakers.rb:26>}, :exchange=>"sneakers", :exchange_options=>{:type=>:direct, :durable=>true, :auto_delete=>false, :arguments=>{}}, :queue_options=>{:durable=>true, :auto_delete=>false, :exclusive=>false, :arguments=>{}}, :amqp=>"amqp://localhost", :vhost=>"/", :handler=>Sneakers::Handlers::Oneshot, :logger=>#<ServerEngine::DaemonLogger:0x00007fbdc61762a0 @rotate_age=5, @rotate_size=1048576, @file_dev=nil, @level=0, @progname=nil, @default_formatter=#<Logger::Formatter:0x00007fbdc6176098 @datetime_format=nil>, @formatter=Sneakers::Support::ProductionFormatter, @logdev=#<IO:<STDOUT>>>, :log_level=>0, :worker_type=>"process", :worker_classes=>[ContentWorker, EventWorker, EventWorkerCleanup, BadURLWorker, RecommendedDataWorker, CommunityAdminWorker, OpportunityWorkerCleanup, SimilarArticlesWorker, CommunityWeeklyReport], :log_stdout=>false, :log_stderr=>false}>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-90279 t-ow5l9q1mk INFO:  ** im before-fork'en **
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk INFO:  !! im after forke'n !!
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: [worker-mandala.content_worker:1:4ond71][#<Thread:0x00007fbdbe87ef78>][mandala.content_worker][#<Sneakers::Configuration:0x00007fbdc049ae00>] New worker: subscribing.
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Sent protocol preamble
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Sent connection.start-ok
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Heartbeat interval negotiation: client = 30, server = 60, result = 30
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk INFO: Heartbeat interval used (in seconds): 30
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Will use socket read timeout of 66.0
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Sent connection.tune-ok with heartbeat interval = 30, frame_max = 131072, channel_max = 65535
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Sent connection.open with vhost = /
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5l9q1mk DEBUG: Initializing heartbeat sender...
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5lbe134 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Channel::OpenOk:0x00007fbdc0469b20 @channel_id="">
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5lbe134 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Basic::QosOk:0x00007fbdc04538c0>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5lbe134 DEBUG: Channel#handle_frame on channel 1: #<AMQ::Protocol::Basic::QosOk:0x00007fbdc04538c0>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5lbe134 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Exchange::DeclareOk:0x00007fbdc04423b8>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91174 t-ow5lbe134 DEBUG: Channel#handle_frame on channel 1: #<AMQ::Protocol::Exchange::DeclareOk:0x00007fbdc04423b8>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-90279 t-ow5l9q1mk INFO:  ** im before-fork'en **
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk INFO:  !! im after forke'n !!
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: [worker-mandala.content_worker:1:3f4jyn][#<Thread:0x00007fbdbe87ef78>][mandala.content_worker][#<Sneakers::Configuration:0x00007fbdbeeafe10>] New worker: subscribing.
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Sent protocol preamble
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Sent connection.start-ok
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Heartbeat interval negotiation: client = 30, server = 60, result = 30
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk INFO: Heartbeat interval used (in seconds): 30
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Will use socket read timeout of 66.0
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Sent connection.tune-ok with heartbeat interval = 30, frame_max = 131072, channel_max = 65535
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Sent connection.open with vhost = /
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5l9q1mk DEBUG: Initializing heartbeat sender...
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5lifua0 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Channel::OpenOk:0x00007fbdc04584b0 @channel_id="">
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5lifua0 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Basic::QosOk:0x00007fbdc044a630>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5lifua0 DEBUG: Channel#handle_frame on channel 1: #<AMQ::Protocol::Basic::QosOk:0x00007fbdc044a630>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5lifua0 DEBUG: Session#handle_frame on 1: #<AMQ::Protocol::Exchange::DeclareOk:0x00007fbdbedbf500>
09:15:57 workers.1 | 2018-05-29T16:15:57Z p-91191 t-ow5lifua0 DEBUG: Channel#handle_frame on channel 1: #<AMQ::Protocol::Exchange::DeclareOk:0x00007fbdbedbf500>
09:16:12 workers.1 | Unexpected error Timeout::Error
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/concurrent/continuation_queue.rb:39:in `block in poll'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/concurrent/continuation_queue.rb:30:in `synchronize'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/concurrent/continuation_queue.rb:30:in `poll'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/channel.rb:1769:in `wait_on_continuations'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/channel.rb:1012:in `queue_declare'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/queue.rb:372:in `declare!'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/queue.rb:51:in `initialize'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/channel.rb:416:in `new'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/bunny-2.9.2/lib/bunny/channel.rb:416:in `queue'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/queue.rb:37:in `subscribe'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/worker.rb:114:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/workergroup.rb:42:in `block in run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/workergroup.rb:41:in `each'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/workergroup.rb:41:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/worker.rb:77:in `main'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_process_server.rb:85:in `block in start_worker'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/process_manager.rb:133:in `block in fork'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/process_manager.rb:125:in `fork'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/process_manager.rb:125:in `fork'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_process_server.rb:75:in `start_worker'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:146:in `delayed_start_worker'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:119:in `block in keepalive_workers'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:101:in `each'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:101:in `each_with_index'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:101:in `keepalive_workers'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_worker_server.rb:58:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/multi_process_server.rb:46:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/server.rb:123:in `main'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/daemon.rb:119:in `main'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/serverengine-2.0.6/lib/serverengine/daemon.rb:68:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/runner.rb:12:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/sneakers-2.7.0/lib/sneakers/tasks.rb:42:in `block (2 levels) in <top (required)>'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:271:in `block in execute'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:271:in `each'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:271:in `execute'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:213:in `block in invoke_with_call_chain'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:193:in `invoke_with_call_chain'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/task.rb:182:in `invoke'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:160:in `invoke_task'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:116:in `block (2 levels) in top_level'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:116:in `each'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:116:in `block in top_level'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:125:in `run_with_threads'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:110:in `top_level'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:83:in `block in run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:186:in `standard_exception_handling'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/lib/rake/application.rb:80:in `run'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/lib/ruby/gems/2.4.0/gems/rake-12.3.1/exe/rake:27:in `<top (required)>'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/bin/rake:23:in `load'
09:16:12 workers.1 |   /Users/nena/.rbenv/versions/2.4.2/bin/rake:23:in `<main>'
09:16:12 workers.1 | 2018-05-29T16:16:12Z p-90279 t-ow5l9q1mk INFO: Worker 0 finished unexpectedly with status 1