Skip to content

Instantly share code, notes, and snippets.

@jlstr

jlstr/sidekiq_log

Created January 28, 2014 02:59
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save jlstr/8661595 to your computer and use it in GitHub Desktop.
Save jlstr/8661595 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
sidekiq_log
deploy@ln-tiptotem-dev:~/work/tiptotem/current$ bundle exec sidekiq -e production
2014-01-28T02:54:56Z 18064 TID-1awufc INFO: Booting Sidekiq 2.17.3 with redis options {}
2014-01-28T02:54:57Z 18064 TID-1awufc INFO: Running in ruby 1.9.3p194 (2012-04-20 revision 35410) [i686-linux]
2014-01-28T02:54:57Z 18064 TID-1awufc INFO: See LICENSE and the LGPL-3.0 for licensing details.
2014-01-28T02:54:57Z 18064 TID-1awufc INFO: Starting processing, hit Ctrl-C to stop
2014-01-28T02:54:57Z 18064 TID-1msc8y PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: start
2014-01-28T02:54:57Z 18064 TID-1mzs9k PaymentsWorker JID-550902038b468fe9c10b6ab5 INFO: start
2014-01-28T02:54:57Z 18064 TID-1of33o PaymentsWorker JID-a12bc7a8839d74e620b5341b INFO: start
2014-01-28T02:54:57Z 18064 TID-1mqypw PaymentsWorker JID-93c9a65713a0bea5f9a2871d INFO: start
2014-01-28T02:54:57Z 18064 TID-1mqx58 PaymentsWorker JID-198f40ab881b8306677bd207 INFO: start
2014-01-28T02:54:57Z 18064 TID-1lsboa PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: start
2014-01-28T02:54:57Z 18064 TID-1j1hmu PaymentsWorker JID-f80d847cc62d308bd65d5238 INFO: start
2014-01-28T02:54:57Z 18064 TID-1myuwg PaymentsWorker JID-97e35f5e62dba42bd54d28ea INFO: start
2014-01-28T02:54:57Z 18064 TID-1nvh3u PaymentsWorker JID-b574347e73b88d1cc0b01227 INFO: start
2014-01-28T02:54:57Z 18064 TID-1l8et2 PaymentsWorker JID-f986b23db32218d517c8fed7 INFO: start
2014-01-28T02:54:57Z 18064 TID-1kb4yu PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: start
2014-01-28T02:54:57Z 18064 TID-1k4e6u PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: start
2014-01-28T02:54:57Z 18064 TID-1o1t00 PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: start
2014-01-28T02:54:57Z 18064 TID-1msc8y PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: fail: 0.606 sec
2014-01-28T02:54:58Z 18064 TID-1nczhc PaymentsWorker JID-e7fa954165d764e419d5f486 INFO: start
2014-01-28T02:54:58Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"f8806c41ade5fbdf929b2149", "enqueued_at"=>1390877519.0958161, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>2014-01-28 02:54:57 UTC, "retry_count"=>0}
2014-01-28T02:54:58Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=181
2014-01-28T02:54:58Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:01Z 18064 TID-1mzs9k PaymentsWorker JID-550902038b468fe9c10b6ab5 INFO: done: 3.651 sec
2014-01-28T02:55:01Z 18064 TID-1lsboa PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: fail: 3.647 sec
2014-01-28T02:55:01Z 18064 TID-1lsboa WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"36a15bc946f4e8f28cffad32", "enqueued_at"=>1390877545.1395776, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>2014-01-28 02:55:01 UTC, "retry_count"=>0}
2014-01-28T02:55:01Z 18064 TID-1lsboa WARN: Couldn't find Payment with id=181
2014-01-28T02:55:01Z 18064 TID-1lsboa WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:01Z 18064 TID-1mqypw PaymentsWorker JID-93c9a65713a0bea5f9a2871d INFO: done: 3.922 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:01Z 18064 TID-1of33o PaymentsWorker JID-a12bc7a8839d74e620b5341b INFO: done: 3.973 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:01Z 18064 TID-1mqx58 PaymentsWorker JID-198f40ab881b8306677bd207 INFO: done: 3.978 sec
2014-01-28T02:55:02Z 18064 TID-1k4e6u PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: fail: 5.002 sec
2014-01-28T02:55:02Z 18064 TID-1kb4yu PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: fail: 5.003 sec
2014-01-28T02:55:02Z 18064 TID-1k4e6u WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>5.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 180], "jid"=>"3ba6c9adf0d8c9a0f5413358", "enqueued_at"=>1390877552.531884, "error_message"=>"could not obtain a database connection within 5 seconds (waited 5.000450411 seconds). The max pool size is currently 5; consider increasing it.", "error_class"=>"ActiveRecord::ConnectionTimeoutError", "failed_at"=>2014-01-28 02:55:02 UTC, "retry_count"=>0}
2014-01-28T02:55:02Z 18064 TID-1k4e6u WARN: could not obtain a database connection within 5 seconds (waited 5.000450411 seconds). The max pool size is currently 5; consider increasing it.
2014-01-28T02:55:02Z 18064 TID-1k4e6u WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:258:in `block (2 levels) in checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `loop'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `block in checkout'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:239:in `checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:102:in `block in connection'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:101:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:410:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:171:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:145:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/delegation.rb:7:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:333:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:02Z 18064 TID-1kb4yu WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>4.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 179], "jid"=>"8cd9fe1050d6b22b905e56f0", "enqueued_at"=>1390877547.9218113, "error_message"=>"could not obtain a database connection within 5 seconds (waited 5.000257048 seconds). The max pool size is currently 5; consider increasing it.", "error_class"=>"ActiveRecord::ConnectionTimeoutError", "failed_at"=>2014-01-28 02:55:02 UTC, "retry_count"=>0}
2014-01-28T02:55:02Z 18064 TID-1kb4yu WARN: could not obtain a database connection within 5 seconds (waited 5.000257048 seconds). The max pool size is currently 5; consider increasing it.
2014-01-28T02:55:02Z 18064 TID-1kb4yu WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:258:in `block (2 levels) in checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `loop'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `block in checkout'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:239:in `checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:102:in `block in connection'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:101:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:410:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:171:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:145:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/delegation.rb:7:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:333:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:03Z 18064 TID-1o1t00 PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: fail: 5.514 sec
2014-01-28T02:55:03Z 18064 TID-1o1t00 WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>1.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 176], "jid"=>"6b97a103c48a5b89290b0487", "enqueued_at"=>1390877571.6631706, "error_message"=>"could not obtain a database connection within 5 seconds (waited 5.000294197 seconds). The max pool size is currently 5; consider increasing it.", "error_class"=>"ActiveRecord::ConnectionTimeoutError", "failed_at"=>2014-01-28 02:55:03 UTC, "retry_count"=>0}
2014-01-28T02:55:03Z 18064 TID-1o1t00 WARN: could not obtain a database connection within 5 seconds (waited 5.000294197 seconds). The max pool size is currently 5; consider increasing it.
2014-01-28T02:55:03Z 18064 TID-1o1t00 WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:258:in `block (2 levels) in checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `loop'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `block in checkout'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:239:in `checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:102:in `block in connection'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:101:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:410:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:171:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:145:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/delegation.rb:7:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:333:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:03Z 18064 TID-1nczhc PaymentsWorker JID-e7fa954165d764e419d5f486 INFO: fail: 5.002 sec
2014-01-28T02:55:03Z 18064 TID-1nczhc WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>10.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 182], "jid"=>"e7fa954165d764e419d5f486", "enqueued_at"=>1390877630.1299973, "error_message"=>"could not obtain a database connection within 5 seconds (waited 5.000352514 seconds). The max pool size is currently 5; consider increasing it.", "error_class"=>"ActiveRecord::ConnectionTimeoutError", "failed_at"=>2014-01-28 02:55:03 UTC, "retry_count"=>0}
2014-01-28T02:55:03Z 18064 TID-1nczhc WARN: could not obtain a database connection within 5 seconds (waited 5.000352514 seconds). The max pool size is currently 5; consider increasing it.
2014-01-28T02:55:03Z 18064 TID-1nczhc WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:258:in `block (2 levels) in checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `loop'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:242:in `block in checkout'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:239:in `checkout'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:102:in `block in connection'
/usr/local/rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/monitor.rb:211:in `mon_synchronize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:101:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_pool.rb:410:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:171:in `retrieve_connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/connection_adapters/abstract/connection_specification.rb:145:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/delegation.rb:7:in `connection'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:333:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:03Z 18064 TID-1j1hmu PaymentsWorker JID-f80d847cc62d308bd65d5238 INFO: done: 6.238 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:04Z 18064 TID-1l8et2 PaymentsWorker JID-f986b23db32218d517c8fed7 INFO: done: 6.7 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:04Z 18064 TID-1nvh3u PaymentsWorker JID-b574347e73b88d1cc0b01227 INFO: done: 6.847 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:04Z 18064 TID-1myuwg PaymentsWorker JID-97e35f5e62dba42bd54d28ea INFO: done: 7.163 sec
2014-01-28T02:55:35Z 18064 TID-1msc8y PaymentsWorker JID-e7fa954165d764e419d5f486 INFO: start
2014-01-28T02:55:35Z 18064 TID-1mzs9k PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: start
2014-01-28T02:55:35Z 18064 TID-1mzs9k PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: fail: 0.004 sec
2014-01-28T02:55:35Z 18064 TID-1of33o PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: start
2014-01-28T02:55:35Z 18064 TID-1mzs9k WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>1.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 176], "jid"=>"6b97a103c48a5b89290b0487", "enqueued_at"=>1390877571.6631706, "error_message"=>"Couldn't find Payment with id=176", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:03Z", "retry_count"=>1, "retried_at"=>2014-01-28 02:55:35 UTC}
2014-01-28T02:55:35Z 18064 TID-1mzs9k WARN: Couldn't find Payment with id=176
2014-01-28T02:55:35Z 18064 TID-1mzs9k WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:35Z 18064 TID-1of33o PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: fail: 0.007 sec
2014-01-28T02:55:35Z 18064 TID-1of33o WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"36a15bc946f4e8f28cffad32", "enqueued_at"=>1390877545.1395776, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:01Z", "retry_count"=>1, "retried_at"=>2014-01-28 02:55:35 UTC}
2014-01-28T02:55:35Z 18064 TID-1of33o WARN: Couldn't find Payment with id=181
2014-01-28T02:55:35Z 18064 TID-1of33o WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:55:38Z 18064 TID-1msc8y PaymentsWorker JID-e7fa954165d764e419d5f486 INFO: done: 2.568 sec
2014-01-28T02:55:50Z 18064 TID-1msc8y PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: start
2014-01-28T02:55:50Z 18064 TID-1mzs9k PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: start
2014-01-28T02:55:50Z 18064 TID-1msc8y PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: fail: 0.004 sec
2014-01-28T02:55:50Z 18064 TID-1of33o PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: start
2014-01-28T02:55:50Z 18064 TID-1mzs9k PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: fail: 0.003 sec
2014-01-28T02:55:50Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>4.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 179], "jid"=>"8cd9fe1050d6b22b905e56f0", "enqueued_at"=>1390877547.9218113, "error_message"=>"Couldn't find Payment with id=179", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:02Z", "retry_count"=>1, "retried_at"=>2014-01-28 02:55:50 UTC}
2014-01-28T02:55:50Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=179
2014-01-28T02:55:50Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:50Z 18064 TID-1of33o PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: fail: 0.002 sec
2014-01-28T02:55:50Z 18064 TID-1mzs9k WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"f8806c41ade5fbdf929b2149", "enqueued_at"=>1390877519.0958161, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:54:57Z", "retry_count"=>1, "retried_at"=>2014-01-28 02:55:50 UTC}
2014-01-28T02:55:50Z 18064 TID-1mzs9k WARN: Couldn't find Payment with id=181
2014-01-28T02:55:50Z 18064 TID-1of33o WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>5.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 180], "jid"=>"3ba6c9adf0d8c9a0f5413358", "enqueued_at"=>1390877552.531884, "error_message"=>"Couldn't find Payment with id=180", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:02Z", "retry_count"=>1, "retried_at"=>2014-01-28 02:55:50 UTC}
2014-01-28T02:55:50Z 18064 TID-1mzs9k WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:55:50Z 18064 TID-1of33o WARN: Couldn't find Payment with id=180
2014-01-28T02:55:50Z 18064 TID-1of33o WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:56:35Z 18064 TID-1msc8y PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: start
2014-01-28T02:56:35Z 18064 TID-1mzs9k PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: start
2014-01-28T02:56:35Z 18064 TID-1msc8y PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: fail: 0.003 sec
2014-01-28T02:56:35Z 18064 TID-1mzs9k PaymentsWorker JID-3ba6c9adf0d8c9a0f5413358 INFO: fail: 0.002 sec
2014-01-28T02:56:35Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"36a15bc946f4e8f28cffad32", "enqueued_at"=>1390877545.1395776, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:01Z", "retry_count"=>2, "retried_at"=>2014-01-28 02:56:35 UTC}
2014-01-28T02:56:35Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=181
2014-01-28T02:56:35Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:56:35Z 18064 TID-1mzs9k WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>5.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 180], "jid"=>"3ba6c9adf0d8c9a0f5413358", "enqueued_at"=>1390877552.531884, "error_message"=>"Couldn't find Payment with id=180", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:02Z", "retry_count"=>2, "retried_at"=>2014-01-28 02:56:35 UTC}
2014-01-28T02:56:35Z 18064 TID-1mzs9k WARN: Couldn't find Payment with id=180
2014-01-28T02:56:35Z 18064 TID-1mzs9k WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:56:50Z 18064 TID-1msc8y PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: start
2014-01-28T02:56:50Z 18064 TID-1msc8y PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: fail: 0.004 sec
2014-01-28T02:56:50Z 18064 TID-1mzs9k PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: start
2014-01-28T02:56:50Z 18064 TID-1mzs9k PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: fail: 0.003 sec
2014-01-28T02:56:50Z 18064 TID-1of33o PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: start
2014-01-28T02:56:50Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"f8806c41ade5fbdf929b2149", "enqueued_at"=>1390877519.0958161, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:54:57Z", "retry_count"=>2, "retried_at"=>2014-01-28 02:56:50 UTC}
2014-01-28T02:56:50Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=181
2014-01-28T02:56:50Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:56:50Z 18064 TID-1of33o PaymentsWorker JID-8cd9fe1050d6b22b905e56f0 INFO: fail: 0.002 sec
2014-01-28T02:56:50Z 18064 TID-1mzs9k WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>1.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 176], "jid"=>"6b97a103c48a5b89290b0487", "enqueued_at"=>1390877571.6631706, "error_message"=>"Couldn't find Payment with id=176", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:03Z", "retry_count"=>2, "retried_at"=>2014-01-28 02:56:50 UTC}
2014-01-28T02:56:50Z 18064 TID-1mzs9k WARN: Couldn't find Payment with id=176
2014-01-28T02:56:50Z 18064 TID-1mzs9k WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:56:50Z 18064 TID-1of33o WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>4.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 179], "jid"=>"8cd9fe1050d6b22b905e56f0", "enqueued_at"=>1390877547.9218113, "error_message"=>"Couldn't find Payment with id=179", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:02Z", "retry_count"=>2, "retried_at"=>2014-01-28 02:56:50 UTC}
2014-01-28T02:56:50Z 18064 TID-1of33o WARN: Couldn't find Payment with id=179
2014-01-28T02:56:50Z 18064 TID-1of33o WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:57:22Z 18064 TID-1msc8y PaymentsWorker JID-b001bfb0eb251b2ddea6526b INFO: start
2014-01-28T02:57:23Z 18064 TID-1mzs9k PaymentsWorker JID-a0a423503f71084b432f4d6e INFO: start
2014-01-28T02:57:24Z 18064 TID-1of33o PaymentsWorker JID-e0408a8b2d42920d804d1c2d INFO: start
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:57:25Z 18064 TID-1msc8y PaymentsWorker JID-b001bfb0eb251b2ddea6526b INFO: done: 2.722 sec
2014-01-28T02:57:25Z 18064 TID-1msc8y PaymentsWorker JID-54d9728d3585980666beea15 INFO: start
2014-01-28T02:57:26Z 18064 TID-1mqypw PaymentsWorker JID-e1c374e58fc7d3fdc07eabd5 INFO: start
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:57:27Z 18064 TID-1mzs9k PaymentsWorker JID-a0a423503f71084b432f4d6e INFO: done: 3.315 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:57:28Z 18064 TID-1of33o PaymentsWorker JID-e0408a8b2d42920d804d1c2d INFO: done: 3.088 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:57:28Z 18064 TID-1msc8y PaymentsWorker JID-54d9728d3585980666beea15 INFO: done: 2.676 sec
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:57:29Z 18064 TID-1mqypw PaymentsWorker JID-e1c374e58fc7d3fdc07eabd5 INFO: done: 2.572 sec
2014-01-28T02:57:35Z 18064 TID-1msc8y PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: start
2014-01-28T02:57:35Z 18064 TID-1msc8y PaymentsWorker JID-6b97a103c48a5b89290b0487 INFO: fail: 0.002 sec
2014-01-28T02:57:35Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-6CW405459D3191932", "receiverList"=>{"receiver"=>[{"amount"=>1.0, "email"=>"pqtest001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/pqtest004/queue"}, 176], "jid"=>"6b97a103c48a5b89290b0487", "enqueued_at"=>1390877571.6631706, "error_message"=>"Couldn't find Payment with id=176", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:03Z", "retry_count"=>3, "retried_at"=>2014-01-28 02:57:35 UTC}
2014-01-28T02:57:35Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=176
2014-01-28T02:57:35Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:58:00Z 18064 TID-1msc8y PaymentsWorker JID-b730eb9068ba8a760ddd237c INFO: start
PAYMENT PROCESSED SUCCESSFULLY
2014-01-28T02:58:04Z 18064 TID-1msc8y PaymentsWorker JID-b730eb9068ba8a760ddd237c INFO: done: 3.367 sec
2014-01-28T02:58:05Z 18064 TID-1msc8y PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: start
2014-01-28T02:58:05Z 18064 TID-1mzs9k PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: start
2014-01-28T02:58:05Z 18064 TID-1msc8y PaymentsWorker JID-36a15bc946f4e8f28cffad32 INFO: fail: 0.003 sec
2014-01-28T02:58:05Z 18064 TID-1mzs9k PaymentsWorker JID-f8806c41ade5fbdf929b2149 INFO: fail: 0.002 sec
2014-01-28T02:58:05Z 18064 TID-1mzs9k WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"f8806c41ade5fbdf929b2149", "enqueued_at"=>1390877519.0958161, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:54:57Z", "retry_count"=>3, "retried_at"=>2014-01-28 02:58:05 UTC}
2014-01-28T02:58:05Z 18064 TID-1mzs9k WARN: Couldn't find Payment with id=181
2014-01-28T02:58:05Z 18064 TID-1msc8y WARN: {"retry"=>3, "queue"=>"default", "class"=>"PaymentsWorker", "args"=>[{"actionType"=>"PAY", "cancelUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue", "currencyCode"=>"USD", "feesPayer"=>"SENDER", "ipnNotificationUrl"=>"http://ln-tiptotem-dev.com/ipn_notify", "preapprovalKey"=>"PA-14D72421M4278884T", "receiverList"=>{"receiver"=>[{"amount"=>50.0, "email"=>"tester001@tiptotem.com"}]}, "returnUrl"=>"http://ln-tiptotem-dev.com/users/jose--2/queue"}, 181], "jid"=>"36a15bc946f4e8f28cffad32", "enqueued_at"=>1390877545.1395776, "error_message"=>"Couldn't find Payment with id=181", "error_class"=>"ActiveRecord::RecordNotFound", "failed_at"=>"2014-01-28T02:55:01Z", "retry_count"=>3, "retried_at"=>2014-01-28 02:58:05 UTC}
2014-01-28T02:58:05Z 18064 TID-1mzs9k WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
2014-01-28T02:58:05Z 18064 TID-1msc8y WARN: Couldn't find Payment with id=181
2014-01-28T02:58:05Z 18064 TID-1msc8y WARN: /home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:341:in `find_one'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:312:in `find_with_ids'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/relation/finder_methods.rb:107:in `find'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/activerecord-3.2.12/lib/active_record/querying.rb:5:in `find'
/home/deploy/work/tiptotem/releases/20140128021628/app/workers/payments_worker.rb:7:in `perform'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:49:in `block (3 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:122:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/active_record.rb:6:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:11:in `block in call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/logging.rb:22:in `with_context'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/server/logging.rb:7:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:124:in `block in invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/middleware/chain.rb:127:in `invoke'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:48:in `block (2 levels) in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:105:in `stats'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/sidekiq-2.17.3/lib/sidekiq/processor.rb:47:in `block in process'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `public_send'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/calls.rb:67:in `dispatch'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/future.rb:14:in `block in new'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `block in initialize'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
/home/deploy/work/tiptotem/shared/bundle/ruby/1.9.1/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `block in create'
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment