Skip to content

Instantly share code, notes, and snippets.

@clarkngo
Created September 25, 2018 04:07
Show Gist options
  • Save clarkngo/d225cc93dbb0c26d7512611a4aded08f to your computer and use it in GitHub Desktop.
Save clarkngo/d225cc93dbb0c26d7512611a4aded08f to your computer and use it in GitHub Desktop.
[Web Dev]: /vagrant/src/nomster $ heroku logs
2018-09-25T03:32:03.142040+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/railties-5.0.7/lib/rails/engine.rb:522:in `call'
2018-09-25T03:32:03.142041+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/puma-3.12.0/lib/puma/configuration.rb:225:in `call'
2018-09-25T03:32:03.142043+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/puma-3.12.0/lib/puma/server.rb:658:in `handle_request'
2018-09-25T03:32:03.142044+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/puma-3.12.0/lib/puma/server.rb:472:in `process_client'
2018-09-25T03:32:03.142046+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/puma-3.12.0/lib/puma/server.rb:332:in `block in run'
2018-09-25T03:32:03.142047+00:00 app[web.1]: [5e938907-02a8-44ec-b6b6-6f375afe7648] vendor/bundle/ruby/2.4.0/gems/puma-3.12.0/lib/puma/thread_pool.rb:133:in `block in spawn_thread'
2018-09-25T03:32:03.255374+00:00 heroku[router]: at=info method=GET path="/favicon.ico" host=nomster-clark-ngo.herokuapp.com request_id=e4088a82-200d-4d17-b9ab-f06f850c55a5 fwd="24.22.246.12" dyno=web.1 connect=0ms service=1ms status=200 bytes=143 protocol=https
2018-09-25T03:32:32.773157+00:00 heroku[web.1]: Restarting
2018-09-25T03:32:32.773593+00:00 heroku[web.1]: State changed from up to starting
2018-09-25T03:32:33.439744+00:00 heroku[web.1]: Stopping all processes with SIGTERM
2018-09-25T03:32:33.452324+00:00 app[web.1]: - Gracefully stopping, waiting for requests to finish
2018-09-25T03:32:33.454286+00:00 app[web.1]: === puma shutdown: 2018-09-25 03:32:33 +0000 ===
2018-09-25T03:32:33.454312+00:00 app[web.1]: - Goodbye!
2018-09-25T03:32:33.454562+00:00 app[web.1]: Exiting
2018-09-25T03:32:33.704800+00:00 heroku[web.1]: Process exited with status 143
2018-09-25T03:32:39.168560+00:00 heroku[web.1]: Starting process with command `bin/rails server -p 37071 -e production`
2018-09-25T03:32:45.542203+00:00 app[web.1]: => Booting Puma
2018-09-25T03:32:45.542235+00:00 app[web.1]: => Rails 5.0.7 application starting in production on http://0.0.0.0:37071
2018-09-25T03:32:45.542241+00:00 app[web.1]: => Run `rails server -h` for more startup options
2018-09-25T03:32:45.542247+00:00 app[web.1]: Puma starting in single mode...
2018-09-25T03:32:45.542255+00:00 app[web.1]: * Version 3.12.0 (ruby 2.4.4-p296), codename: Llamas in Pajamas
2018-09-25T03:32:45.542269+00:00 app[web.1]: * Min threads: 5, max threads: 5
2018-09-25T03:32:45.542271+00:00 app[web.1]: * Environment: production
2018-09-25T03:32:45.542341+00:00 app[web.1]: * Listening on tcp://0.0.0.0:37071
2018-09-25T03:32:45.542744+00:00 app[web.1]: Use Ctrl-C to stop
2018-09-25T03:32:46.172554+00:00 heroku[web.1]: State changed from starting to up
2018-09-25T03:50:48.929532+00:00 heroku[web.1]: Restarting
2018-09-25T03:50:48.930488+00:00 heroku[web.1]: State changed from up to starting
2018-09-25T03:50:49.993938+00:00 heroku[web.1]: Stopping all processes with SIGTERM
2018-09-25T03:50:50.015872+00:00 app[web.1]: - Gracefully stopping, waiting for requests to finish
2018-09-25T03:50:50.018564+00:00 app[web.1]: === puma shutdown: 2018-09-25 03:50:50 +0000 ===
2018-09-25T03:50:50.018608+00:00 app[web.1]: - Goodbye!
2018-09-25T03:50:50.018835+00:00 app[web.1]: Exiting
2018-09-25T03:50:50.113064+00:00 heroku[web.1]: Process exited with status 143
2018-09-25T03:50:56.030909+00:00 heroku[web.1]: Starting process with command `bin/rails server -p 16293 -e production`
2018-09-25T03:51:03.945878+00:00 heroku[web.1]: State changed from starting to up
2018-09-25T03:51:03.580470+00:00 app[web.1]: => Booting Puma
2018-09-25T03:51:03.580496+00:00 app[web.1]: => Rails 5.0.7 application starting in production on http://0.0.0.0:16293
2018-09-25T03:51:03.580502+00:00 app[web.1]: => Run `rails server -h` for more startup options
2018-09-25T03:51:03.580508+00:00 app[web.1]: Puma starting in single mode...
2018-09-25T03:51:03.580518+00:00 app[web.1]: * Version 3.12.0 (ruby 2.4.4-p296), codename: Llamas in Pajamas
2018-09-25T03:51:03.588105+00:00 app[web.1]: * Min threads: 5, max threads: 5
2018-09-25T03:51:03.588113+00:00 app[web.1]: * Environment: production
2018-09-25T03:51:03.588249+00:00 app[web.1]: * Listening on tcp://0.0.0.0:16293
2018-09-25T03:51:03.589009+00:00 app[web.1]: Use Ctrl-C to stop
2018-09-25T03:55:15.748081+00:00 heroku[web.1]: Restarting
2018-09-25T03:55:15.748952+00:00 heroku[web.1]: State changed from up to starting
2018-09-25T03:55:16.668804+00:00 heroku[web.1]: Stopping all processes with SIGTERM
2018-09-25T03:55:16.690978+00:00 app[web.1]: - Gracefully stopping, waiting for requests to finish
2018-09-25T03:55:16.693793+00:00 app[web.1]: === puma shutdown: 2018-09-25 03:55:16 +0000 ===
2018-09-25T03:55:16.693839+00:00 app[web.1]: - Goodbye!
2018-09-25T03:55:16.693982+00:00 app[web.1]: Exiting
2018-09-25T03:55:16.829426+00:00 heroku[web.1]: Process exited with status 143
2018-09-25T03:55:22.449823+00:00 heroku[web.1]: Starting process with command `bin/rails server -p 9621 -e production`
2018-09-25T03:55:29.696860+00:00 app[web.1]: => Booting Puma
2018-09-25T03:55:29.696882+00:00 app[web.1]: => Rails 5.0.7 application starting in production on http://0.0.0.0:9621
2018-09-25T03:55:29.696884+00:00 app[web.1]: => Run `rails server -h` for more startup options
2018-09-25T03:55:29.696895+00:00 app[web.1]: Puma starting in single mode...
2018-09-25T03:55:29.696905+00:00 app[web.1]: * Version 3.12.0 (ruby 2.4.4-p296), codename: Llamas in Pajamas
2018-09-25T03:55:29.696906+00:00 app[web.1]: * Min threads: 5, max threads: 5
2018-09-25T03:55:29.696908+00:00 app[web.1]: * Environment: production
2018-09-25T03:55:29.697026+00:00 app[web.1]: * Listening on tcp://0.0.0.0:9621
2018-09-25T03:55:29.697643+00:00 app[web.1]: Use Ctrl-C to stop
2018-09-25T03:55:30.192256+00:00 heroku[web.1]: State changed from starting to up
2018-09-25T04:06:00.116948+00:00 app[web.1]: I, [2018-09-25T04:06:00.116794 #4] INFO -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Started POST "/places/1/photos" for 24.22.246.12 at 2018-09-25 04:06:00 +0000
2018-09-25T04:06:00.135824+00:00 app[web.1]: I, [2018-09-25T04:06:00.135658 #4] INFO -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Processing by PhotosController#create as HTML
2018-09-25T04:06:00.143626+00:00 app[web.1]: I, [2018-09-25T04:06:00.143351 #4] INFO -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Parameters: {"utf8"=>"✓", "authenticity_token"=>"XSN4eCEKA0QO3mHlEOeRQRYObpaZwHR9aOlVCLy0oca6RwQsclQjeFS+o/6YUD6cMrRGDT3mBbL+dmmQc2BqWA==", "photo"=>{"caption"=>"", "picture"=>#<ActionDispatch::Http::UploadedFile:0x000055f6366912e8 @tempfile=#<Tempfile:/tmp/RackMultipart20180925-4-1rd7dzz.jpg>, @original_filename="ramen_yoshi_kaarage.jpg", @content_type="image/jpeg", @headers="Content-Disposition: form-data; name=\"photo[picture]\"; filename=\"ramen_yoshi_kaarage.jpg\"\r\nContent-Type: image/jpeg\r\n">}, "commit"=>"Add a Photo", "place_id"=>"1"}
2018-09-25T04:06:00.326552+00:00 app[web.1]: D, [2018-09-25T04:06:00.326380 #4] DEBUG -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Place Load (1.2ms) SELECT "places".* FROM "places" WHERE "places"."id" = $1 LIMIT $2 [["id", 1], ["LIMIT", 1]]
2018-09-25T04:06:00.358247+00:00 app[web.1]: D, [2018-09-25T04:06:00.358085 #4] DEBUG -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] (0.7ms) BEGIN
2018-09-25T04:06:00.389218+00:00 app[web.1]: D, [2018-09-25T04:06:00.389083 #4] DEBUG -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] SQL (1.6ms) INSERT INTO "photos" ("caption", "place_id", "created_at", "updated_at", "picture") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["caption", ""], ["place_id", 1], ["created_at", "2018-09-25 04:06:00.384915"], ["updated_at", "2018-09-25 04:06:00.384915"], ["picture", "ramen_yoshi_kaarage.jpg"]]
2018-09-25T04:06:00.587942+00:00 app[web.1]: D, [2018-09-25T04:06:00.587775 #4] DEBUG -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] (0.9ms) ROLLBACK
2018-09-25T04:06:00.588559+00:00 app[web.1]: I, [2018-09-25T04:06:00.588482 #4] INFO -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Completed 500 Internal Server Error in 445ms (ActiveRecord: 49.5ms)
2018-09-25T04:06:00.591771+00:00 app[web.1]: F, [2018-09-25T04:06:00.591658 #4] FATAL -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f]
2018-09-25T04:06:00.591938+00:00 app[web.1]: F, [2018-09-25T04:06:00.591824 #4] FATAL -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] Excon::Error::Forbidden (Expected(200) <=> Actual(403 Forbidden)
2018-09-25T04:06:00.591941+00:00 app[web.1]: excon.error.response
2018-09-25T04:06:00.591974+00:00 app[web.1]: :body => "<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<Error><Code>SignatureDoesNotMatch</Code><Message>The request signature we calculated does not match the signature you provided. Check your key and signing method.</Message><AWSAccessKeyId>AKIAJ2RQOQBOJMYPONAA</AWSAccessKeyId><StringToSign>AWS4-HMAC-SHA256\n20180925T040600Z\n20180925/us-east-1/s3/aws4_request\n49d97be165c70c862a215132f4526630e23f4de1119dbd9432e2c122d947e376</StringToSign><SignatureProvided>b00a98ef665b0c9c9d5ca1314ec477c6bbdef4fc55d9d126fe856f26485d172b</SignatureProvided><StringToSignBytes>41 57 53 34 2d 48 4d 41 43 2d 53 48 41 32 35 36 0a 32 30 31 38 30 39 32 35 54 30 34 30 36 30 30 5a 0a 32 30 31 38 30 39 32 35 2f 75 73 2d 65 61 73 74 2d 31 2f 73 33 2f 61 77 73 34 5f 72 65 71 75 65 73 74 0a 34 39 64 39 37 62 65 31 36 35 63 37 30 63 38 36 32 61 32 31 35 31 33 32 66 34 35 32 36 36 33 30 65 32 33 66 34 64 65 31 31 31 39 64 62 64 39 34 33 32 65 32 63 31 32 32 64 39 34 37 65 33 37 36</StringToSignBytes><CanonicalRequest>PUT\n/uploads/photo/picture/13/ramen_yoshi_kaarage.jpg\n\ncontent-type:image/jpeg\nhost:clark-firehose-bucket.s3.amazonaws.com\nx-amz-acl:public-read\nx-amz-content-sha256:STREAMING-AWS4-HMAC-SHA256-PAYLOAD\nx-amz-date:20180925T040600Z\nx-amz-decoded-content-length:15317\n\ncontent-type;host;x-amz-acl;x-amz-content-sha256;x-amz-date;x-amz-decoded-content-length\nSTREAMING-AWS4-HMAC-SHA256-PAYLOAD</CanonicalRequest><CanonicalRequestBytes>50 55 54 0a 2f 75 70 6c 6f 61 64 73 2f 70 68 6f 74 6f 2f 70 69 63 74 75 72 65 2f 31 33 2f 72 61 6d 65 6e 5f 79 6f 73 68 69 5f 6b 61 61 72 61 67 65 2e 6a 70 67 0a 0a 63 6f 6e 74 65 6e 74 2d 74 79 70 65 3a 69 6d 61 67 65 2f 6a 70 65 67 0a 68 6f 73 74 3a 63 6c 61 72 6b 2d 66 69 72 65 68 6f 73 65 2d 62 75 63 6b 65 74 2e 73 33 2e 61 6d 61 7a 6f 6e 61 77 73 2e 63 6f 6d 0a 78 2d 61 6d 7a 2d 61 63 6c 3a 70 75 62 6c 69 63 2d 72 65 61 64 0a 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3a 53 54 52 45 41 4d 49 4e 47 2d 41 57 53 34 2d 48 4d 41 43 2d 53 48 41 32 35 36 2d 50 41 59 4c 4f 41 44 0a 78 2d 61 6d 7a 2d 64 61 74 65 3a 32 30 31 38 30 39 32 35 54 30 34 30 36 30 30 5a 0a 78 2d 61 6d 7a 2d 64 65 63 6f 64 65 64 2d 63 6f 6e 74 65 6e 74 2d 6c 65 6e 67 74 68 3a 31 35 33 31 37 0a 0a 63 6f 6e 74 65 6e 74 2d 74 79 70 65 3b 68 6f 73 74 3b 78 2d 61 6d 7a 2d 61 63 6c 3b 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3b 78 2d 61 6d 7a 2d 64 61 74 65 3b 78 2d 61 6d 7a 2d 64 65 63 6f 64 65 64 2d 63 6f 6e 74 65 6e 74 2d 6c 65 6e 67 74 68 0a 53 54 52 45 41 4d 49 4e 47 2d 41 57 53 34 2d 48 4d 41 43 2d 53 48 41 32 35 36 2d 50 41 59 4c 4f 41 44</CanonicalRequestBytes><RequestId>1767A3C7E4393658</RequestId><HostId>6mXBcBVfOl9+t1zWctsi5NXDsoINu5AX7MAlO09xcMJfDSEUohR2ZGEnw/K46IIyR/QdcXCkMvY=</HostId></Error>"
2018-09-25T04:06:00.591977+00:00 app[web.1]: :cookies => [
2018-09-25T04:06:00.591979+00:00 app[web.1]: ]
2018-09-25T04:06:00.591981+00:00 app[web.1]: :headers => {
2018-09-25T04:06:00.591983+00:00 app[web.1]: "Connection" => "close"
2018-09-25T04:06:00.591984+00:00 app[web.1]: "Content-Type" => "application/xml"
2018-09-25T04:06:00.591986+00:00 app[web.1]: "Date" => "Tue, 25 Sep 2018 04:05:59 GMT"
2018-09-25T04:06:00.591988+00:00 app[web.1]: "Server" => "AmazonS3"
2018-09-25T04:06:00.591990+00:00 app[web.1]: "x-amz-id-2" => "6mXBcBVfOl9+t1zWctsi5NXDsoINu5AX7MAlO09xcMJfDSEUohR2ZGEnw/K46IIyR/QdcXCkMvY="
2018-09-25T04:06:00.591991+00:00 app[web.1]: "x-amz-request-id" => "1767A3C7E4393658"
2018-09-25T04:06:00.591993+00:00 app[web.1]: }
2018-09-25T04:06:00.591995+00:00 app[web.1]: :host => "clark-firehose-bucket.s3.amazonaws.com"
2018-09-25T04:06:00.591997+00:00 app[web.1]: :local_address => "172.18.160.250"
2018-09-25T04:06:00.591998+00:00 app[web.1]: :local_port => 45026
2018-09-25T04:06:00.592000+00:00 app[web.1]: :path => "/uploads/photo/picture/13/ramen_yoshi_kaarage.jpg"
2018-09-25T04:06:00.592002+00:00 app[web.1]: :port => 443
2018-09-25T04:06:00.592003+00:00 app[web.1]: :reason_phrase => "Forbidden"
2018-09-25T04:06:00.592005+00:00 app[web.1]: :remote_ip => "52.216.20.0"
2018-09-25T04:06:00.592006+00:00 app[web.1]: :status => 403
2018-09-25T04:06:00.592008+00:00 app[web.1]: :status_line => "HTTP/1.1 403 Forbidden\r\n"
2018-09-25T04:06:00.592010+00:00 app[web.1]: ):
2018-09-25T04:06:00.592120+00:00 app[web.1]: F, [2018-09-25T04:06:00.592042 #4] FATAL -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f]
2018-09-25T04:06:00.592243+00:00 app[web.1]: F, [2018-09-25T04:06:00.592166 #4] FATAL -- : [fd1decc0-8d8b-4015-9b7e-298a3f15d53f] app/controllers/photos_controller.rb:6:in `create'
2018-09-25T04:06:00.593999+00:00 heroku[router]: at=info method=POST path="/places/1/photos" host=nomster-clark-ngo.herokuapp.com request_id=fd1decc0-8d8b-4015-9b7e-298a3f15d53f fwd="24.22.246.12" dyno=web.1 connect=0ms service=684ms status=500 bytes=1827 protocol=https
2018-09-25T04:06:00.794278+00:00 heroku[router]: at=info method=GET path="/favicon.ico" host=nomster-clark-ngo.herokuapp.com request_id=de1d5017-eba2-40a5-b501-834915c520cf fwd="24.22.246.12" dyno=web.1 connect=0ms service=2ms status=200 bytes=143 protocol=https
[Web Dev]: /vagrant/src/nomster $
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment