eturk/fhsclock — Issue #99: Heroku app fails to launch.
Author: eturk
Created: 2011-11-06 00:09:22+00:00
State: open
Labels: Critical, Defect, Production

2011-11-06T00:03:05+00:00 app[web.1]:	from config.ru:4:in `require'
2011-11-06T00:03:05+00:00 app[web.1]:	from config.ru:4:in `block in

'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `instance_eval'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `initialize'
2011-11-06T00:03:05+00:00 app[web.1]:	from config.ru:1:in `new'
2011-11-06T00:03:05+00:00 app[web.1]:	from config.ru:1:in `

'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `eval'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `load'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:181:in `load_rackup_config'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:71:in `start'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:185:in `run_command'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:151:in `run!'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/bin/thin:6:in `'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `load'
2011-11-06T00:03:05+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `

'
2011-11-06T00:03:05+00:00 heroku[web.1]: Process exited
2011-11-06T00:03:06+00:00 heroku[web.1]: State changed from starting to crashed
2011-11-06T00:03:08+00:00 heroku[router]: Error H10 (App crashed) -> GET fhsclock.herokuapp.com/admin dyno= queue= wait= service= status=503 bytes=
2011-11-06T00:04:57+00:00 heroku[api]: Config add MONGO_USERNAME, MONGO_PASSWORD by ethan.turkeltaub@gmail.com
2011-11-06T00:04:57+00:00 heroku[api]: Release v8 created by ethan.turkeltaub@gmail.com
2011-11-06T00:04:57+00:00 heroku[web.1]: State changed from crashed to created
2011-11-06T00:04:57+00:00 heroku[web.1]: State changed from created to starting
2011-11-06T00:05:02+00:00 heroku[web.1]: Starting process with command `bundle exec thin start -R config.ru -e production -p 48467`
2011-11-06T00:05:06+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/mongo-1.4.0/lib/mongo/db.rb:137:in `issue_authentication': Failed to authenticate user 'ENV["MONGO_USERNAME"]' on db 'fhsclock' (Mongo::AuthenticationError)
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo-1.4.0/lib/mongo/db.rb:117:in `authenticate'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo_mapper-0.9.2/lib/mongo_mapper/connection.rb:72:in `connect'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo_mapper-0.9.2/lib/mongo_mapper/connection.rb:78:in `setup'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/config/database.rb:10:in `'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `require'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `block in require'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:225:in `block in load_dependency'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:593:in `new_constants_in'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:225:in `load_dependency'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `require'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/reloader.rb:142:in `safe_load'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:140:in `block in require_dependencies'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:138:in `each'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:138:in `require_dependencies'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:53:in `load!'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/config/boot.rb:10:in `'
2011-11-06T00:05:06+00:00 app[web.1]:	from config.ru:4:in `require'
2011-11-06T00:05:06+00:00 app[web.1]:	from config.ru:4:in `block in

'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `instance_eval'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `initialize'
2011-11-06T00:05:06+00:00 app[web.1]:	from config.ru:1:in `new'
2011-11-06T00:05:06+00:00 app[web.1]:	from config.ru:1:in `

'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `eval'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `load'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:181:in `load_rackup_config'

-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:71:in `start'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:185:in `run_command'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:151:in `run!'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/bin/thin:6:in `'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `load'
2011-11-06T00:05:06+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `

'
2011-11-06T00:05:07+00:00 heroku[web.1]: Process exited
2011-11-06T00:05:08+00:00 heroku[web.1]: State changed from starting to crashed
2011-11-06T00:05:10+00:00 heroku[router]: Error H10 (App crashed) -> GET fhsclock.herokuapp.com/admin dyno= queue= wait= service= status=503 bytes=
2011-11-06T00:05:33+00:00 heroku[run.2]: State changed from created to starting
2011-11-06T00:05:39+00:00 app[run.2]: Awaiting client
2011-11-06T00:05:39+00:00 app[run.2]: Starting process with command `bundle update`
2011-11-06T00:05:40+00:00 heroku[run.2]: State changed from starting to up
2011-11-06T00:05:40+00:00 heroku[run.2]: Process exited
2011-11-06T00:05:41+00:00 heroku[run.2]: State changed from up to complete
2011-11-06T00:06:18+00:00 heroku[web.1]: State changed from crashed to created
2011-11-06T00:06:18+00:00 heroku[web.1]: State changed from created to starting
2011-11-06T00:06:26+00:00 heroku[web.1]: Starting process with command `bundle exec thin start -R config.ru -e production -p 56842`
2011-11-06T00:06:30+00:00 app[web.1]: /app/vendor/bundle/ruby/1.9.1/gems/mongo-1.4.0/lib/mongo/db.rb:137:in `issue_authentication': Failed to authenticate user 'ENV["MONGO_USERNAME"]' on db 'fhsclock' (Mongo::AuthenticationError)
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo-1.4.0/lib/mongo/db.rb:117:in `authenticate'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo_mapper-0.9.2/lib/mongo_mapper/connection.rb:72:in `connect'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/mongo_mapper-0.9.2/lib/mongo_mapper/connection.rb:78:in `setup'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/config/database.rb:10:in `'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `require'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `block in require'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:225:in `block in load_dependency'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:593:in `new_constants_in'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:225:in `load_dependency'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/activesupport-3.0.10/lib/active_support/dependencies.rb:239:in `require'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/reloader.rb:142:in `safe_load'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:140:in `block in require_dependencies'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:138:in `each'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:138:in `require_dependencies'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/padrino-core-0.10.0/lib/padrino-core/loader.rb:53:in `load!'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/config/boot.rb:10:in `'
2011-11-06T00:06:30+00:00 app[web.1]:	from config.ru:4:in `require'
2011-11-06T00:06:30+00:00 app[web.1]:	from config.ru:4:in `block in

'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `instance_eval'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/rack-1.3.5/lib/rack/builder.rb:51:in `initialize'
2011-11-06T00:06:30+00:00 app[web.1]:	from config.ru:1:in `new'
2011-11-06T00:06:30+00:00 app[web.1]:	from config.ru:1:in `

'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `eval'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/rack/adapter/loader.rb:36:in `load'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:181:in `load_rackup_config'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/controllers/controller.rb:71:in `start'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:185:in `run_command'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/lib/thin/runner.rb:151:in `run!'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/gems/thin-1.2.11/bin/thin:6:in `'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `load'
2011-11-06T00:06:30+00:00 app[web.1]:	from /app/vendor/bundle/ruby/1.9.1/bin/thin:19:in `

'
2011-11-06T00:06:32+00:00 heroku[web.1]: State changed from starting to crashed