Skip to content

Instantly share code, notes, and snippets.

@kookster
Created July 4, 2013 15:39
Show Gist options
  • Save kookster/5928696 to your computer and use it in GitHub Desktop.
Save kookster/5928696 to your computer and use it in GitHub Desktop.
heroku staging sidekiq error
2013-07-04T15:35:35.244160+00:00 app[web.1]: Started POST "/api/items/1464/audio_files/2887" for 23.20.159.164 at 2013-07-04 15:35:35 +0000
2013-07-04T15:35:35.250328+00:00 app[web.1]: Processing by Api::V1::AudioFilesController#update as JSON
2013-07-04T15:35:35.250424+00:00 app[web.1]: Parameters: {"task"=>{"call_back"=>"http://pop-up-archive-staging.herokuapp.com/api/items/1464/audio_files/2887", "id"=>32189, "label"=>"91", "options"=>{"language"=>"en-US", "chunk_duration"=>5, "overlap"=>1, "max_results"=>1, "profanity_filter"=>true}, "result"=>"s3://staging.popuparchive.prx.org/5-hive-trim-wav.GiXafQ.popuparchive.org/hive_trim.wav_ts_start.json?x-fixer-metadata%5Bx-archive-meta-mediatype%5D=data", "task_type"=>"transcribe", "result_details"=>{"status"=>"complete", "message"=>"transcribe audio complete", "info"=>{"average_confidence"=>0.88154636125, "word_count"=>169, "character_count"=>888}, "logged_at"=>"2013-07-04T15:35:33Z"}}, "item_id"=>"1464", "audio_file_id"=>"2887", "audio_file"=>{}}
2013-07-04T15:35:35.251003+00:00 app[web.1]: WARNING: Can't verify CSRF token authenticity
2013-07-04T15:35:35.257169+00:00 app[web.1]: Item Load (2.2ms) SELECT "items".* FROM "items" WHERE "items"."id" = $1 AND ("items"."deleted_at" IS NULL) LIMIT 1 [["id", "1464"]]
2013-07-04T15:35:35.259835+00:00 app[web.1]: Contribution Load (1.2ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.261989+00:00 app[web.1]: Contribution Load (1.3ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewee' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.264541+00:00 app[web.1]: Contribution Load (1.1ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.266587+00:00 app[web.1]: Contribution Load (1.2ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'host' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.268738+00:00 app[web.1]: Contribution Load (1.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'creator' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.273150+00:00 app[web.1]: Contribution Load (1.3ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'producer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.277933+00:00 app[web.1]: AudioFile Load (1.2ms) SELECT "audio_files".* FROM "audio_files" WHERE "audio_files"."item_id" = 1464 AND "audio_files"."id" = $1 AND ("audio_files"."deleted_at" IS NULL) LIMIT 1 [["id", "2887"]]
2013-07-04T15:35:35.281554+00:00 app[web.1]: Task Load (1.3ms) SELECT "tasks".* FROM "tasks" WHERE "tasks"."owner_id" = 2887 AND "tasks"."owner_type" = 'AudioFile' AND "tasks"."id" = 91 ORDER BY "tasks"."id" DESC LIMIT 1
2013-07-04T15:35:35.283033+00:00 app[web.1]: update_from_fixer: task 91 is complete
2013-07-04T15:35:35.284971+00:00 app[web.1]: (0.9ms) BEGIN
2013-07-04T15:35:35.289570+00:00 app[web.1]: (1.2ms) UPDATE "tasks" SET "status" = 'complete', "updated_at" = '2013-07-04 15:35:35.286156', "extras" = 'original=>s3://staging.popuparchive.prx.org/5-hive-trim-wav.GiXafQ.popuparchive.org/hive_trim.wav,start_only=>true' WHERE "tasks"."type" IN ('Tasks::TranscribeTask') AND "tasks"."id" = 91
2013-07-04T15:35:35.293799+00:00 app[web.1]: AudioFile Load (1.3ms) SELECT "audio_files".* FROM "audio_files" WHERE "audio_files"."id" = 2887 AND ("audio_files"."deleted_at" IS NULL) LIMIT 1
2013-07-04T15:35:35.297158+00:00 app[web.1]: Item Load (1.3ms) SELECT "items".* FROM "items" WHERE "items"."id" = 1464 LIMIT 1
2013-07-04T15:35:35.299323+00:00 app[web.1]: Contribution Load (1.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.301142+00:00 app[web.1]: Contribution Load (1.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewee' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.303035+00:00 app[web.1]: Contribution Load (1.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.304924+00:00 app[web.1]: Contribution Load (1.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'host' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.306812+00:00 app[web.1]: Contribution Load (1.2ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'creator' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.308985+00:00 app[web.1]: Contribution Load (1.1ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'producer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.312786+00:00 app[web.1]: Collection Load (1.3ms) SELECT "collections".* FROM "collections" WHERE "collections"."id" = 22 LIMIT 1
2013-07-04T15:35:35.316397+00:00 app[web.1]: StorageConfiguration Load (1.4ms) SELECT "storage_configurations".* FROM "storage_configurations" WHERE "storage_configurations"."id" = 33 LIMIT 1
2013-07-04T15:35:35.324202+00:00 app[web.1]: "Scaling worker to 1"
2013-07-04T15:35:35.326446+00:00 app[web.1]: (0.9ms) ROLLBACK
2013-07-04T15:35:35.326956+00:00 app[web.1]: undefined method `[]' for nil:NilClass (NoMethodError)
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/excon-0.21.0/lib/excon/response.rb:36:in `parse'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/excon-0.21.0/lib/excon/connection.rb:344:in `response'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/excon-0.21.0/lib/excon/connection.rb:241:in `request'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/heroku-api-0.3.10/lib/heroku/api.rb:76:in `request'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/heroku-api-0.3.10/lib/heroku/api/processes.rb:43:in `post_ps_scale'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/autoscaler-0.2.1/lib/autoscaler/heroku_scaler.rb:38:in `workers='
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/autoscaler-0.2.1/lib/autoscaler/sidekiq.rb:17:in `call'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/middleware/chain.rb:111:in `block in invoke'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/middleware/chain.rb:114:in `call'
2013-07-04T15:35:35.327046+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/middleware/chain.rb:114:in `invoke'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/client.rb:113:in `process_single'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/client.rb:41:in `push'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/worker.rb:68:in `client_push'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/sidekiq-2.11.1/lib/sidekiq/worker.rb:38:in `perform_async'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/app/models/tasks/transcribe_task.rb:8:in `block (2 levels) in <class:TranscribeTask>'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/eval_helpers.rb:79:in `call'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/eval_helpers.rb:79:in `evaluate_method'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/callback.rb:191:in `block in run_methods'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/callback.rb:190:in `each'
2013-07-04T15:35:35.327202+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/callback.rb:190:in `run_methods'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/callback.rb:159:in `call'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:450:in `block (2 levels) in after'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:450:in `each'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:450:in `block in after'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:448:in `catch'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:448:in `after'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:251:in `run_callbacks'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:127:in `run_callbacks'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:212:in `run_callbacks'
2013-07-04T15:35:35.327345+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:63:in `block (2 levels) in perform'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:63:in `catch'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:63:in `block in perform'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:186:in `within_transaction'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:62:in `perform'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/integrations/active_record.rb:502:in `block in around_save'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/integrations/active_record.rb:530:in `block in transaction'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.13/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.13/lib/active_record/transactions.rb:208:in `transaction'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/method_tracer.rb:518:in `block in transaction_with_trace_ActiveRecord_self_name_transaction'
2013-07-04T15:35:35.327778+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/method_tracer.rb:268:in `trace_execution_scoped'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/method_tracer.rb:513:in `transaction_with_trace_ActiveRecord_self_name_transaction'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/integrations/active_record.rb:529:in `transaction'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/integrations/active_record.rb:501:in `around_save'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/integrations/active_record.rb:483:in `save'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:154:in `block (2 levels) in run_actions'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:154:in `each'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:154:in `block in run_actions'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:170:in `catch_exceptions'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:148:in `run_actions'
2013-07-04T15:35:35.327939+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition_collection.rb:60:in `perform'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/transition.rb:219:in `perform'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/event.rb:172:in `fire'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/event.rb:247:in `block in add_actions'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/machine.rb:765:in `call'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/machine.rb:765:in `block (2 levels) in define_helper'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/event.rb:252:in `block in add_actions'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/machine.rb:765:in `call'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/state_machine-1.2.0/lib/state_machine/machine.rb:765:in `block (2 levels) in define_helper'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/app/models/audio_file.rb:93:in `update_from_fixer'
2013-07-04T15:35:35.328279+00:00 app[web.1]: /app/app/controllers/api/v1/audio_files_controller.rb:12:in `update'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/abstract_controller/base.rb:167:in `process_action'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/rendering.rb:10:in `process_action'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:414:in `_run__4284233626746088286__process_action__406726190416415615__callbacks'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:405:in `__run_callback'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:81:in `run_callbacks'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/abstract_controller/callbacks.rb:17:in `process_action'
2013-07-04T15:35:35.328436+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/rescue.rb:29:in `process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/notifications.rb:123:in `block in instrument'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/notifications.rb:123:in `instrument'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.13/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:38:in `block in process_action'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/instrumentation/controller_instrumentation.rb:318:in `perform_action_with_newrelic_trace'
2013-07-04T15:35:35.328816+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/agent/instrumentation/rails3/action_controller.rb:37:in `process_action'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/abstract_controller/base.rb:121:in `process'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/abstract_controller/rendering.rb:45:in `process'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal.rb:203:in `dispatch'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_controller/metal.rb:246:in `block in action'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/routing/route_set.rb:73:in `call'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/routing/route_set.rb:36:in `call'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/routing/mapper.rb:42:in `call'
2013-07-04T15:35:35.329023+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/routing/route_set.rb:612:in `call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:184:in `call!'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:164:in `call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:184:in `call!'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:164:in `call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:184:in `call!'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/omniauth-1.1.4/lib/omniauth/strategy.rb:164:in `call'
2013-07-04T15:35:35.329334+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/rack/error_collector.rb:12:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/rack/agent_hooks.rb:22:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/newrelic_rpm-3.6.5.130/lib/new_relic/rack/browser_monitoring.rb:16:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/warden-1.2.1/lib/warden/manager.rb:35:in `block in call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/warden-1.2.1/lib/warden/manager.rb:34:in `catch'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/warden-1.2.1/lib/warden/manager.rb:34:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/etag.rb:23:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/head.rb:14:in `call'
2013-07-04T15:35:35.329497+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/flash.rb:242:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:in `context'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/cookies.rb:341:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.13/lib/active_record/query_cache.rb:64:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.13/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:405:in `_run__4013984307694242673__call__1557420450992079813__callbacks'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:405:in `__run_callback'
2013-07-04T15:35:35.329823+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/callbacks.rb:81:in `run_callbacks'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/rack/logger.rb:32:in `call_app'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/rack/logger.rb:16:in `block in call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/tagged_logging.rb:22:in `tagged'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/rack/logger.rb:16:in `call'
2013-07-04T15:35:35.329979+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/request_id.rb:22:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/runtime.rb:17:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.13/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/lock.rb:15:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.13/lib/action_dispatch/middleware/static.rb:63:in `call'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
2013-07-04T15:35:35.330562+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/engine.rb:479:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/application.rb:223:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/railtie/configurable.rb:30:in `method_missing'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/static.rb:119:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/content_length.rb:14:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/rack/log_tailer.rb:17:in `call'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/connection.rb:81:in `block in pre_process'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/connection.rb:79:in `catch'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/connection.rb:79:in `pre_process'
2013-07-04T15:35:35.330740+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/connection.rb:54:in `process'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/connection.rb:39:in `receive_data'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/eventmachine-1.0.3/lib/eventmachine.rb:187:in `run_machine'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/eventmachine-1.0.3/lib/eventmachine.rb:187:in `run'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/backends/base.rb:63:in `start'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/thin-1.5.1/lib/thin/server.rb:159:in `start'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/handler/thin.rb:13:in `run'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/server.rb:268:in `start'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/commands/server.rb:70:in `start'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/commands.rb:55:in `block in <top (required)>'
2013-07-04T15:35:35.331091+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/commands.rb:50:in `tap'
2013-07-04T15:35:35.331228+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/railties-3.2.13/lib/rails/commands.rb:50:in `<top (required)>'
2013-07-04T15:35:35.331228+00:00 app[web.1]: script/rails:6:in `require'
2013-07-04T15:35:35.331228+00:00 app[web.1]: script/rails:6:in `<main>'
2013-07-04T15:35:35.331228+00:00 app[web.1]: CACHE (0.0ms) SELECT "items".* FROM "items" WHERE "items"."id" = 1464 LIMIT 1
2013-07-04T15:35:35.331228+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.331228+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewee' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.331228+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'interviewer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.331228+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'host' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.332328+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'creator' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.333003+00:00 app[web.1]: CACHE (0.0ms) SELECT "contributions".* FROM "contributions" WHERE "contributions"."role" = 'producer' AND "contributions"."item_id" IN (1464)
2013-07-04T15:35:35.443712+00:00 app[web.1]: CACHE (0.0ms) SELECT "collections".* FROM "collections" WHERE "collections"."id" = 22 LIMIT 1
2013-07-04T15:35:35.444732+00:00 app[web.1]: CACHE (0.0ms) SELECT "storage_configurations".* FROM "storage_configurations" WHERE "storage_configurations"."id" = 33 LIMIT 1
2013-07-04T15:35:35.452461+00:00 app[web.1]: [WARNING] fog: the specified s3 bucket name(staging.popuparchive.prx.org) might fail with https.
2013-07-04T15:35:35.463331+00:00 app[web.1]: [WARNING] fog: the specified s3 bucket name(staging.popuparchive.prx.org) might fail with https.
2013-07-04T15:35:35.464408+00:00 app[web.1]: Completed 201 Created in 214ms (Views: 129.9ms | ActiveRecord: 0.0ms)
2013-07-04T15:35:35.492184+00:00 heroku[router]: at=info method=POST path=/api/items/1464/audio_files/2887 host=pop-up-archive-staging.herokuapp.com fwd="23.20.159.164" dyno=web.1 connect=7ms service=246ms status=201 bytes=473
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment