socify icon indicating copy to clipboard operation
socify copied to clipboard
verified publisher icon scaffeinate

Error at signup

Open Delano83 opened this issue 9 years ago • 11 comments

Hello,

I followed the instructions, generated my AWS Keys and configured SendGrid with my SMTP username and password. Now the app is running fine on Heroku until I try to sign up, it gives me the following error:

image

Here's my log file: image

Any sugggestions? Thanks

Delano83 avatar Jan 12 '17 11:01 Delano83

@Delano83 Have you run your migrations on heroku? And also can you post in the complete stack trace for this?

scaffeinate avatar Jan 12 '17 19:01 scaffeinate

@sudharti For db migrations, I ran:

heroku run rake db:migrate

Here is the complete stack trace of the error:

2017-01-13T02:02:32.525572+00:00 app[api]: Starting process with command bundle exec rake db:migrate by user ### 2017-01-13T02:02:37.346604+00:00 heroku[run.2184]: Starting process with command bundle exec rake db:migrate 2017-01-13T02:02:37.595872+00:00 heroku[run.2184]: State changed from starting to up 2017-01-13T02:02:37.314586+00:00 heroku[run.2184]: Awaiting client 2017-01-13T02:02:45.655472+00:00 heroku[run.2184]: Process exited with status 0 2017-01-13T02:02:45.682708+00:00 heroku[run.2184]: State changed from up to complete 2017-01-13T02:22:31.185396+00:00 app[api]: Release v12 created by user ### 2017-01-13T02:22:31.185396+00:00 app[api]: Set LOG_LEVEL config vars by user ### 2017-01-13T02:22:31.434796+00:00 heroku[web.1]: Restarting 2017-01-13T02:22:31.435470+00:00 heroku[web.1]: State changed from up to starting 2017-01-13T02:22:32.098521+00:00 app[web.1]: [2017-01-13 02:22:32] FATAL SignalException: SIGTERM 2017-01-13T02:22:32.098533+00:00 app[web.1]: /app/vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/server.rb:177:in select' 2017-01-13T02:22:32.098535+00:00 app[web.1]: /app/vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/server.rb:177:in block in start' 2017-01-13T02:22:32.098536+00:00 app[web.1]: /app/vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/server.rb:33:in start' 2017-01-13T02:22:32.098537+00:00 app[web.1]: /app/vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/server.rb:164:in start' 2017-01-13T02:22:32.098537+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/handler/webrick.rb:34:in run' 2017-01-13T02:22:32.098538+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/server.rb:296:in start' 2017-01-13T02:22:32.098539+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands/server.rb:79:in start' 2017-01-13T02:22:32.098540+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands/commands_tasks.rb:90:in block in server' 2017-01-13T02:22:32.098541+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands/commands_tasks.rb:85:in tap' 2017-01-13T02:22:32.098542+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands/commands_tasks.rb:85:in server' 2017-01-13T02:22:32.098542+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands/commands_tasks.rb:49:in run_command!' 2017-01-13T02:22:32.098543+00:00 app[web.1]: /app/vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/commands.rb:18:in <top (required)>' 2017-01-13T02:22:32.098544+00:00 app[web.1]: bin/rails:8:in require' 2017-01-13T02:22:32.098545+00:00 app[web.1]: bin/rails:8:in

' 2017-01-13T02:22:32.098674+00:00 app[web.1]: [2017-01-13 02:22:32] INFO going to shutdown ... 2017-01-13T02:22:32.098731+00:00 app[web.1]: [2017-01-13 02:22:32] INFO WEBrick::HTTPServer#start done. 2017-01-13T02:22:32.099574+00:00 app[web.1]: Exiting 2017-01-13T02:22:32.075461+00:00 heroku[web.1]: Stopping all processes with SIGTERM 2017-01-13T02:22:32.266516+00:00 heroku[web.1]: Process exited with status 143 2017-01-13T02:22:37.011572+00:00 heroku[web.1]: Starting process with command bin/rails server -p 21614 -e production 2017-01-13T02:22:44.558073+00:00 app[web.1]: [2017-01-13 02:22:44] INFO WEBrick 1.3.1 2017-01-13T02:22:44.558122+00:00 app[web.1]: [2017-01-13 02:22:44] INFO ruby 2.3.1 (2016-04-26) [x86_64-linux] 2017-01-13T02:22:44.558717+00:00 app[web.1]: [2017-01-13 02:22:44] INFO WEBrick::HTTPServer#start: pid=4 port=21614 2017-01-13T02:22:45.000592+00:00 heroku[web.1]: State changed from starting to up 2017-01-13T02:34:27.036945+00:00 app[web.1]: => Booting WEBrick 2017-01-13T02:34:27.036960+00:00 app[web.1]: => Rails 5.0.0 application starting in production on http://0.0.0.0:21614 2017-01-13T02:34:27.036962+00:00 app[web.1]: => Run rails server -h for more startup options 2017-01-13T02:34:27.036963+00:00 app[web.1]: Started GET "/" for 71.244.130.75 at 2017-01-13 02:34:27 +0000 2017-01-13T02:34:27.064630+00:00 app[web.1]: Processing by HomeController#front as HTML 2017-01-13T02:34:27.084819+00:00 app[web.1]: Rendering home/front.html.erb within layouts/application 2017-01-13T02:34:27.100511+00:00 app[web.1]: (4.4ms) SELECT COUNT() FROM "activities" 2017-01-13T02:34:27.102163+00:00 app[web.1]: Rendered shared/_no_resource.html.erb (0.6ms) 2017-01-13T02:34:27.103628+00:00 app[web.1]: CACHE (0.0ms) SELECT COUNT() FROM "activities" 2017-01-13T02:34:27.103710+00:00 app[web.1]: Rendered shared/_paginate.html.erb (1.1ms) 2017-01-13T02:34:27.103780+00:00 app[web.1]: Rendered home/front.html.erb within layouts/application (18.9ms) 2017-01-13T02:34:27.109720+00:00 app[web.1]: Rendered shared/_navbar.html.erb (1.6ms) 2017-01-13T02:34:27.110093+00:00 app[web.1]: Completed 200 OK in 45ms (Views: 23.0ms | ActiveRecord: 13.8ms) 2017-01-13T02:34:27.111441+00:00 heroku[router]: at=info method=GET path="/" host=blooming-stream-15146.herokuapp.com request_id=8342725d-acc8-4e54-9284-710f1075338d fwd="71.244.130.75" dyno=web.1 connect=0ms service=127ms status=200 bytes=2981 2017-01-13T02:37:11.196157+00:00 app[web.1]: Started GET "/" for 71.244.130.75 at 2017-01-13 02:37:11 +0000 2017-01-13T02:37:11.199642+00:00 app[web.1]: Processing by HomeController#front as HTML 2017-01-13T02:37:11.202480+00:00 app[web.1]: Rendering home/front.html.erb within layouts/application 2017-01-13T02:37:11.204979+00:00 app[web.1]: (1.4ms) SELECT COUNT() FROM "activities" 2017-01-13T02:37:11.205271+00:00 app[web.1]: Rendered shared/_no_resource.html.erb (0.0ms) 2017-01-13T02:37:11.206078+00:00 app[web.1]: CACHE (0.0ms) SELECT COUNT() FROM "activities" 2017-01-13T02:37:11.206158+00:00 app[web.1]: Rendered shared/_paginate.html.erb (0.7ms) 2017-01-13T02:37:11.206296+00:00 app[web.1]: Rendered home/front.html.erb within layouts/application (3.7ms) 2017-01-13T02:37:11.207589+00:00 app[web.1]: Rendered shared/_navbar.html.erb (0.5ms) 2017-01-13T02:37:11.207969+00:00 app[web.1]: Completed 200 OK in 8ms (Views: 5.0ms | ActiveRecord: 1.4ms) 2017-01-13T02:37:11.213639+00:00 heroku[router]: at=info method=GET path="/" host=blooming-stream-15146.herokuapp.com request_id=02d70c8f-ae51-4e60-aa62-e0dcccdc095d fwd="71.244.130.75" dyno=web.1 connect=1ms service=24ms status=200 bytes=2981 2017-01-13T02:37:11.504746+00:00 heroku[router]: at=info method=GET path="/assets/application-ca02b73d394ececce0f57c7ee6277be612faea2f4aa809406f517a33727cb5d0.css" host=blooming-stream-15146.herokuapp.com request_id=02299a30-3c63-43c8-93d4-2363341fdf05 fwd="71.244.130.75" dyno=web.1 connect=1ms service=7ms status=304 bytes=133 2017-01-13T02:37:11.551757+00:00 heroku[router]: at=info method=GET path="/assets/application-a70d07178327a29bab055588f3f709f15cc791bd72e456f720705a0469a5c7a7.js" host=blooming-stream-15146.herokuapp.com request_id=ca46ddc6-4392-4f43-96b6-af26dd235a40 fwd="71.244.130.75" dyno=web.1 connect=2ms service=11ms status=304 bytes=133 2017-01-13T02:37:11.728283+00:00 heroku[router]: at=info method=GET path="/assets/ajax-loader-20b3c2cf0c50583716b68afb1d964b4b07bb999faead1260bca34c43bf7ee4ea.gif" host=blooming-stream-15146.herokuapp.com request_id=5f4ba606-796c-4533-8f0c-cbe8b1b96ca2 fwd="71.244.130.75" dyno=web.1 connect=1ms service=5ms status=304 bytes=133 2017-01-13T02:37:11.781038+00:00 heroku[router]: at=info method=GET path="/assets/fontawesome-webfont-7dacf83f51179de8d7980a513e67ab3a08f2c6272bb5946df8fd77c0d1763b73.woff2" host=blooming-stream-15146.herokuapp.com request_id=f77093a4-e8c4-4c7c-ba9d-0dcb3db4bb84 fwd="71.244.130.75" dyno=web.1 connect=1ms service=5ms status=304 bytes=133 2017-01-13T02:37:44.041486+00:00 app[web.1]: Started GET "/users/sign_up" for 71.244.130.75 at 2017-01-13 02:37:44 +0000 2017-01-13T02:37:44.043502+00:00 app[web.1]: Processing by Devise::RegistrationsController#new as HTML 2017-01-13T02:37:44.121037+00:00 app[web.1]: Rendering devise/registrations/new.html.erb within layouts/application 2017-01-13T02:37:44.944774+00:00 heroku[router]: at=info method=GET path="/users/sign_up" host=blooming-stream-15146.herokuapp.com request_id=671a2879-e6f8-4e5c-9416-4446e5891c14 fwd="71.244.130.75" dyno=web.1 connect=3ms service=891ms status=200 bytes=4045 2017-01-13T02:37:44.922036+00:00 app[web.1]: Rendered devise/shared/_links.html.erb (2.3ms) 2017-01-13T02:37:44.922097+00:00 app[web.1]: Rendered devise/registrations/new.html.erb within layouts/application (801.0ms) 2017-01-13T02:37:44.923399+00:00 app[web.1]: Rendered shared/_navbar.html.erb (0.6ms) 2017-01-13T02:37:44.923800+00:00 app[web.1]: Completed 200 OK in 880ms (Views: 805.2ms | ActiveRecord: 21.0ms) 2017-01-13T02:38:10.514727+00:00 app[web.1]: Started POST "/users" for 71.244.130.75 at 2017-01-13 02:38:10 +0000 2017-01-13T02:38:10.519577+00:00 app[web.1]: Processing by Devise::RegistrationsController#create as HTML 2017-01-13T02:38:10.519650+00:00 app[web.1]: Parameters: {"utf8"=>"✓", "authenticity_token"=>"YldlKKnEdjfRrZyc9PqPu7dFMCjmX02R5UBpz3mnhryL/7mqaj/mbWhlNi7wAx01Tf75MtlzhfHaExyM3FZn0Q==", "user"=>{"name"=>"John Travolta", "email"=>"[email protected]", "password"=>"[FILTERED]", "password_confirmation"=>"[FILTERED]"}, "commit"=>"Sign up"} 2017-01-13T02:38:10.626175+00:00 app[web.1]: (1.3ms) BEGIN 2017-01-13T02:38:10.766054+00:00 app[web.1]: User Exists (1.9ms) SELECT 1 AS one FROM "users" WHERE ("users"."id" IS NOT NULL) AND "users"."slug" = $1 LIMIT $2 [["slug", "john-travolta"], ["LIMIT", 1]] 2017-01-13T02:38:10.769857+00:00 app[web.1]: User Exists (1.4ms) SELECT 1 AS one FROM "users" WHERE "users"."email" = $1 LIMIT $2 [["email", "[email protected]"], ["LIMIT", 1]] 2017-01-13T02:38:10.774118+00:00 app[web.1]: SQL (1.6ms) INSERT INTO "users" ("name", "email", "encrypted_password", "confirmation_token", "confirmation_sent_at", "created_at", "updated_at", "slug") VALUES ($1, $2, $3, $4, $5, $6, $7, $8) RETURNING "id" [["name", "John Travolta"], ["email", "[email protected]"], ["encrypted_password", "$2a$10$XkgK8j1Q7Fxp5Xs2sduZGe276tKtBNvD.dJl24YZTp4OaaTWHMFcm"], ["confirmation_token", "qZBXwyPnbsgXdQzF1-ii"], ["confirmation_sent_at", 2017-01-13 02:38:10 UTC], ["created_at", 2017-01-13 02:38:10 UTC], ["updated_at", 2017-01-13 02:38:10 UTC], ["slug", "john-travolta"]] 2017-01-13T02:38:10.779551+00:00 app[web.1]: (2.3ms) COMMIT 2017-01-13T02:38:10.796452+00:00 app[web.1]: Rendering devise/mailer/confirmation_instructions.html.erb 2017-01-13T02:38:10.799095+00:00 app[web.1]: Rendered devise/mailer/confirmation_instructions.html.erb (2.5ms) 2017-01-13T02:38:11.190091+00:00 app[web.1]: Devise::Mailer#confirmation_instructions: processed outbound mail in 403.3ms 2017-01-13T02:38:11.249111+00:00 app[web.1]: Sent mail to [email protected] (58.6ms) 2017-01-13T02:38:11.249113+00:00 app[web.1]: Date: Fri, 13 Jan 2017 02:38:11 +0000 2017-01-13T02:38:11.249114+00:00 app[web.1]: From: [email protected] 2017-01-13T02:38:11.249114+00:00 app[web.1]: Reply-To: [email protected] 2017-01-13T02:38:11.249115+00:00 app[web.1]: To: [email protected] 2017-01-13T02:38:11.249116+00:00 app[web.1]: Message-ID: 58783d932ff20_43ffd8fc468748532d@0fd0b50e-d1e0-4a48-a585-1fba6bdcf587.mail 2017-01-13T02:38:11.249117+00:00 app[web.1]: Subject: Confirmation instructions 2017-01-13T02:38:11.249118+00:00 app[web.1]: Mime-Version: 1.0 2017-01-13T02:38:11.249118+00:00 app[web.1]: Content-Type: text/html; 2017-01-13T02:38:11.249120+00:00 app[web.1]: charset=UTF-8 2017-01-13T02:38:11.249120+00:00 app[web.1]: Content-Transfer-Encoding: 7bit 2017-01-13T02:38:11.249121+00:00 app[web.1]: 2017-01-13T02:38:11.249121+00:00 app[web.1]:

Welcome [email protected]!

2017-01-13T02:38:11.249122+00:00 app[web.1]: 2017-01-13T02:38:11.249123+00:00 app[web.1]:

You can confirm your account email through the link below:

2017-01-13T02:38:11.249123+00:00 app[web.1]: 2017-01-13T02:38:11.249124+00:00 app[web.1]:

Confirm my account

2017-01-13T02:38:11.249125+00:00 app[web.1]: 2017-01-13T02:38:11.250008+00:00 app[web.1]: Completed 500 Internal Server Error in 730ms (ActiveRecord: 8.5ms) 2017-01-13T02:38:11.253890+00:00 app[web.1]:
2017-01-13T02:38:11.253915+00:00 app[web.1]: Net::SMTPAuthenticationError (535 Authentication failed: The provided authorization grant is invalid, expired, or revoked 2017-01-13T02:38:11.253916+00:00 app[web.1]: ): 2017-01-13T02:38:11.253931+00:00 app[web.1]:
2017-01-13T02:38:11.253950+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/net/smtp.rb:977:in check_auth_response' 2017-01-13T02:38:11.253966+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/net/smtp.rb:741:in auth_plain' 2017-01-13T02:38:11.253983+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/net/smtp.rb:733:in authenticate' 2017-01-13T02:38:11.253998+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/net/smtp.rb:568:in do_start' 2017-01-13T02:38:11.254014+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/net/smtp.rb:521:in start' 2017-01-13T02:38:11.254030+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/mail-2.6.4/lib/mail/network/delivery_methods/smtp.rb:113:in deliver!' 2017-01-13T02:38:11.254045+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/mail-2.6.4/lib/mail/message.rb:2149:in do_delivery' 2017-01-13T02:38:11.254063+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/mail-2.6.4/lib/mail/message.rb:237:in block in deliver' 2017-01-13T02:38:11.254066+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionmailer-5.0.0/lib/action_mailer/base.rb:543:in block in deliver_mail' 2017-01-13T02:38:11.254087+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications.rb:164:in block in instrument' 2017-01-13T02:38:11.254102+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications/instrumenter.rb:21:in instrument' 2017-01-13T02:38:11.254118+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications.rb:164:in instrument' 2017-01-13T02:38:11.254134+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionmailer-5.0.0/lib/action_mailer/base.rb:541:in deliver_mail' 2017-01-13T02:38:11.254150+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/mail-2.6.4/lib/mail/message.rb:237:in deliver' 2017-01-13T02:38:11.254166+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionmailer-5.0.0/lib/action_mailer/message_delivery.rb:96:in block in deliver_now' 2017-01-13T02:38:11.254181+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionmailer-5.0.0/lib/action_mailer/rescuable.rb:15:in handle_exceptions' 2017-01-13T02:38:11.254198+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionmailer-5.0.0/lib/action_mailer/message_delivery.rb:95:in deliver_now' 2017-01-13T02:38:11.254225+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/devise-4.2.0/lib/devise/models/authenticatable.rb:191:in send_devise_notification' 2017-01-13T02:38:11.254229+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/devise-4.2.0/lib/devise/models/confirmable.rb:117:in send_confirmation_instructions' 2017-01-13T02:38:11.254230+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/devise-4.2.0/lib/devise/models/confirmable.rb:179:in send_on_create_confirmation_instructions' 2017-01-13T02:38:11.254248+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:382:in block in make_lambda' 2017-01-13T02:38:11.254268+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:232:in block in conditional' 2017-01-13T02:38:11.254285+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:456:in block in call' 2017-01-13T02:38:11.254300+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:456:in each' 2017-01-13T02:38:11.254315+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:456:in call' 2017-01-13T02:38:11.254331+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:101:in run_callbacks' 2017-01-13T02:38:11.254346+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:750:in _run_commit_callbacks' 2017-01-13T02:38:11.254362+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:354:in committed!' 2017-01-13T02:38:11.254378+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/connection_adapters/abstract/transaction.rb:87:in commit_records' 2017-01-13T02:38:11.254394+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/connection_adapters/abstract/transaction.rb:178:in commit_transaction' 2017-01-13T02:38:11.254409+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/connection_adapters/abstract/transaction.rb:202:in within_new_transaction' 2017-01-13T02:38:11.254425+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/connection_adapters/abstract/database_statements.rb:232:in transaction' 2017-01-13T02:38:11.254441+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:211:in transaction' 2017-01-13T02:38:11.254456+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:392:in with_transaction_returning_status' 2017-01-13T02:38:11.254472+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:319:in block in save' 2017-01-13T02:38:11.254515+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:334:in rollback_active_record_state!' 2017-01-13T02:38:11.254518+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/transactions.rb:318:in save' 2017-01-13T02:38:11.254544+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/suppressor.rb:41:in save' 2017-01-13T02:38:11.254547+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/devise-4.2.0/app/controllers/devise/registrations_controller.rb:17:in create' 2017-01-13T02:38:11.254569+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/basic_implicit_render.rb:4:in send_action' 2017-01-13T02:38:11.254572+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/abstract_controller/base.rb:188:in process_action' 2017-01-13T02:38:11.254602+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/rendering.rb:30:in process_action' 2017-01-13T02:38:11.254632+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/abstract_controller/callbacks.rb:20:in block in process_action' 2017-01-13T02:38:11.254662+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:126:in call' 2017-01-13T02:38:11.254694+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:506:in block (2 levels) in compile' 2017-01-13T02:38:11.254723+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:455:in call' 2017-01-13T02:38:11.254754+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:448:in block (2 levels) in around' 2017-01-13T02:38:11.254785+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:286:in block (2 levels) in halting' 2017-01-13T02:38:11.254816+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/public_activity-1.5.0/lib/public_activity/utility/store_controller.rb:25:in store_controller_for_public_activity' 2017-01-13T02:38:11.254846+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:382:in block in make_lambda' 2017-01-13T02:38:11.254876+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:285:in block in halting' 2017-01-13T02:38:11.254905+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:447:in block in around' 2017-01-13T02:38:11.254936+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:455:in call' 2017-01-13T02:38:11.254966+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:101:in run_callbacks' 2017-01-13T02:38:11.254996+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:750:in _run_process_action_callbacks' 2017-01-13T02:38:11.255028+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:90:in run_callbacks' 2017-01-13T02:38:11.255056+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/abstract_controller/callbacks.rb:19:in process_action' 2017-01-13T02:38:11.255088+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/rescue.rb:20:in process_action' 2017-01-13T02:38:11.255118+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/instrumentation.rb:32:in block in process_action' 2017-01-13T02:38:11.255281+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications.rb:164:in block in instrument' 2017-01-13T02:38:11.255296+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications/instrumenter.rb:21:in instrument' 2017-01-13T02:38:11.255355+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/notifications.rb:164:in instrument' 2017-01-13T02:38:11.255360+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/instrumentation.rb:30:in process_action' 2017-01-13T02:38:11.255363+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal/params_wrapper.rb:248:in process_action' 2017-01-13T02:38:11.255405+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activerecord-5.0.0/lib/active_record/railties/controller_runtime.rb:18:in process_action' 2017-01-13T02:38:11.255451+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/abstract_controller/base.rb:126:in process' 2017-01-13T02:38:11.255693+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionview-5.0.0/lib/action_view/rendering.rb:30:in process' 2017-01-13T02:38:11.255731+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal.rb:190:in dispatch' 2017-01-13T02:38:11.255975+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_controller/metal.rb:262:in dispatch' 2017-01-13T02:38:11.257891+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/routing/route_set.rb:50:in dispatch' 2017-01-13T02:38:11.257896+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/routing/route_set.rb:32:in serve' 2017-01-13T02:38:11.257934+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/routing/mapper.rb:16:in block in class:Constraints' 2017-01-13T02:38:11.257967+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/routing/mapper.rb:46:in serve' 2017-01-13T02:38:11.257997+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/journey/router.rb:39:in block in serve' 2017-01-13T02:38:11.258027+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/journey/router.rb:26:in each' 2017-01-13T02:38:11.258062+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/journey/router.rb:26:in serve' 2017-01-13T02:38:11.258084+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/routing/route_set.rb:725:in call' 2017-01-13T02:38:11.258115+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/warden-1.2.6/lib/warden/manager.rb:35:in block in call' 2017-01-13T02:38:11.258146+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/warden-1.2.6/lib/warden/manager.rb:34:in catch' 2017-01-13T02:38:11.258176+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/warden-1.2.6/lib/warden/manager.rb:34:in call' 2017-01-13T02:38:11.258216+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/etag.rb:25:in call' 2017-01-13T02:38:11.258219+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/conditional_get.rb:38:in call' 2017-01-13T02:38:11.258253+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/head.rb:12:in call' 2017-01-13T02:38:11.258285+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/session/abstract/id.rb:222:in context' 2017-01-13T02:38:11.258315+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/session/abstract/id.rb:216:in call' 2017-01-13T02:38:11.258349+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/cookies.rb:613:in call' 2017-01-13T02:38:11.258405+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/callbacks.rb:38:in block in call' 2017-01-13T02:38:11.258406+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:97:in run_callbacks' 2017-01-13T02:38:11.258440+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:750:in _run_call_callbacks' 2017-01-13T02:38:11.258475+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/callbacks.rb:90:in run_callbacks' 2017-01-13T02:38:11.258510+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/callbacks.rb:36:in call' 2017-01-13T02:38:11.258544+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/remote_ip.rb:79:in call' 2017-01-13T02:38:11.258570+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/debug_exceptions.rb:49:in call' 2017-01-13T02:38:11.258602+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in call' 2017-01-13T02:38:11.258638+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/rack/logger.rb:36:in call_app' 2017-01-13T02:38:11.258662+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/rack/logger.rb:24:in block in call' 2017-01-13T02:38:11.258665+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/tagged_logging.rb:70:in block in tagged' 2017-01-13T02:38:11.258688+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/tagged_logging.rb:26:in tagged' 2017-01-13T02:38:11.258691+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/tagged_logging.rb:70:in tagged' 2017-01-13T02:38:11.258724+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/rack/logger.rb:24:in call' 2017-01-13T02:38:11.258727+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/request_id.rb:24:in call' 2017-01-13T02:38:11.258728+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/method_override.rb:22:in call' 2017-01-13T02:38:11.258742+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/runtime.rb:22:in call' 2017-01-13T02:38:11.258789+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/activesupport-5.0.0/lib/active_support/cache/strategy/local_cache_middleware.rb:28:in call' 2017-01-13T02:38:11.258815+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/executor.rb:12:in call' 2017-01-13T02:38:11.258834+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/actionpack-5.0.0/lib/action_dispatch/middleware/static.rb:136:in call' 2017-01-13T02:38:11.258842+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/sendfile.rb:111:in call' 2017-01-13T02:38:11.258863+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/railties-5.0.0/lib/rails/engine.rb:522:in call' 2017-01-13T02:38:11.258865+00:00 app[web.1]: vendor/bundle/ruby/2.3.0/gems/rack-2.0.1/lib/rack/handler/webrick.rb:86:in service' 2017-01-13T02:38:11.258898+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/httpserver.rb:140:in service' 2017-01-13T02:38:11.258899+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/httpserver.rb:96:in run' 2017-01-13T02:38:11.258902+00:00 app[web.1]: vendor/ruby-2.3.1/lib/ruby/2.3.0/webrick/server.rb:296:in block in start_thread'

Delano83 avatar Jan 13 '17 02:01 Delano83

@Delano83 From your log I can see that the SMTP authorization fails. This part below:

2017-01-13T02:38:11.249125+00:00 app[web.1]:
2017-01-13T02:38:11.250008+00:00 app[web.1]: Completed 500 Internal Server Error in 730ms (ActiveRecord: 8.5ms)
2017-01-13T02:38:11.253890+00:00 app[web.1]:
2017-01-13T02:38:11.253915+00:00 app[web.1]: Net::SMTPAuthenticationError (535 Authentication failed: The provided authorization grant is invalid, expired, or revoked

scaffeinate avatar Jan 13 '17 05:01 scaffeinate

Thanks @sudharti! This is what I have for smtp configuration: config.active_record.dump_schema_after_migration = false

config.action_mailer.default_url_options = { host: 'http://myapp.herokuapp.com' }

ActionMailer::Base.delivery_method = :smtp ActionMailer::Base.perform_deliveries = true

ActionMailer::Base.smtp_settings = { :address => 'smtp.sendgrid.net', :port => '587', :authentication => :plain, :user_name => ENV['SENDGRID_USERNAME'], :password => ENV['SENDGRID_PASSWORD'], :domain => 'heroku.com', :enable_starttls_auto => true }

For my Config Vars in Heroku, I have 'apikey' for username and my key for the password. Does it sound right to you?

Delano83 avatar Jan 14 '17 03:01 Delano83

@Delano83 No I think for sendgrid it's just the username and password of your account. That's what I have set. Just checked my heroku config.

scaffeinate avatar Jan 14 '17 04:01 scaffeinate

@Delano83 Were you able to resolve it?

scaffeinate avatar Jan 16 '17 16:01 scaffeinate

@sudharti Not quite yet...I put my Sendgrid username and password but still get SMTP error. Did you have to do any extra configuration on Sendgrid apart from setting up an SMTP Api Key?

Delano83 avatar Jan 16 '17 16:01 Delano83

@Delano83 Try printing the ENV variable in Log to test if you are able to get the correct values. If that's not the issue then your account might not have been provisioned yet. Check this https://twitter.com/ryandeussing/status/260363641533108225?lang=en

scaffeinate avatar Jan 16 '17 21:01 scaffeinate

@sudharti The authentification is set to 'plain' and the const variables are correctly printed in the console... How long does it take for your account to be provisioned since I just created my account? I now get a different error:

ArgumentError (SMTP-AUTH requested but missing user name)

Something to do with a missing username?

Delano83 avatar Jan 17 '17 01:01 Delano83

@Delano83 Make sure that the username is not your email id. Because I have set the same and it seems to work. Either that or problem with heroku provisioning 🤔

scaffeinate avatar Jan 17 '17 02:01 scaffeinate

@scaffeinate Still getting the error even afer checking with SendGrid for provisioning... Hopefully it's an isolated issue and we can close this thread.

Delano83 avatar Feb 12 '17 14:02 Delano83