Railsアプリケーションの冗長構成

Question

前提・実現したいこと

エンジニア1年目で、個人開発にて、Ruby on Railsの勉強中です。
Ruby on Railsで作成したアプリケーションをAWSにてWebサーバ2台の冗長構成にしたいと思っております。

• AWSのVPC内に2台のEC2インスタンスを作成し、デプロイ。
• お名前.comでドメインを取得後、Route53で設定。
• ロードバランサーを作成。

ここまでは実現できており、ブラウザからアクセスをすると正常に表示されます。

しかし、それぞれのインスタンスにsshで接続し、ログ（log配下のnginx.access.log、production.log）を確認したところ2台ともに同時にログが出力されています。
出力されるログは同じものではなく、2台のWebサーバに同時にアクセスがいきブラウザにレスポンスが返されているように見受けられます。
通常であれば、リクエストに対してどちらか一方のWebサーバからレスポンスを返すもので、ログに関してもレスポンスを返すサーバにのみ出力されるものと認識しております。

質問内容としていは
1.そもそも、そのようなことが起こり得るのか（2台のWebサーバからレスポンスを返す）
2.正しい状態、リクエストがあったら2台のWebサーバのうち1台にのみアクセスがいき、そのサーバでのみlogが出力されるようにするにはどのようにすればよいのか。
の２点になります。

■補足情報

• AWSのVPC内にパブリックサブネットを２つ作成し、それぞれに1つずつEC2インスタンスを作成。
• ロードバランサーのターゲットグループには作成したEC２インスタンスを登録。
• Route53のAレコードにはエイリアスでロードバランサーのAレコードを設定
• EC２のインスタンス内にはnginx、unicornをインストールして使用しています。
• etc/nginx/conf.d配下に「アプリケーション名.conf」を作成し、server.nameにはドメイン名を指定しています。

Comments

[moya_dev]

webページにアクセスしたときの、サーバ毎のログを貼ってください

[aokm]

## production.log
- 1号機
I, [2020-04-29T03:38:00.440052 #10254] INFO -- : [30e52243-2a40-4d81-bd16-a73a35421988] Started GET "/rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413/D0002040402_00000_V_000.mp4" for 111.239.176.102 at 2020-04-29 03:38:00 +0000
I, [2020-04-29T03:38:00.440816 #10254] INFO -- : [30e52243-2a40-4d81-bd16-a73a35421988] Processing by ActiveStorage::BlobsController#show as MP4
I, [2020-04-29T03:38:00.440866 #10254] INFO -- : [30e52243-2a40-4d81-bd16-a73a35421988] Parameters: {"signed_id"=>"eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413", "filename"=>"D0002040402_00000_V_000"}
D, [2020-04-29T03:38:00.446168 #10254] DEBUG -- : [30e52243-2a40-4d81-bd16-a73a35421988] (0.5ms) SET NAMES utf8, @@SESSION.sql_mode = CONCAT(CONCAT(@@sql_mode, ',STRICT_ALL_TABLES'), ',NO_AUTO_VALUE_ON_ZERO'), @@SESSION.sql_auto_is_null = 0, @@SESSION.wait_timeout = 2147483
D, [2020-04-29T03:38:00.447319 #10254] DEBUG -- : [30e52243-2a40-4d81-bd16-a73a35421988] ActiveStorage::Blob Load (0.6ms) SELECT `active_storage_blobs`.* FROM `active_storage_blobs` WHERE `active_storage_blobs`.`id` = 2 LIMIT 1
D, [2020-04-29T03:38:00.448715 #10254] DEBUG -- : [30e52243-2a40-4d81-bd16-a73a35421988] S3 Storage (1.0ms) Generated URL for file at key: vg6ssz9aols7dqsvg9mhj8de7fum (https://fpmovie.s3.ap-northeast-1.amazonaws.com/vg6ssz9aols7dqsvg9mhj8de7fum?response-content-disposition=attachment%3B%20filename%3D%22D0002040402_00000_V_000.mp4%22%3B%20filename%2A%3DUTF-8%27%27D0002040402_00000_V_000.mp4&response-content-type=video%2Fmp4&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAVHQOADLFFCXJEUH4%2F20200429%2Fap-northeast-1%2Fs3%2Faws4_request&X-Amz-Date=20200429T033800Z&X-Amz-Expires=300&X-Amz-SignedHeaders=host&X-Amz-Signature=bcfbb2cb5f620472ba87d5d819467e001b40507fce9782f1ee75aba00662667e)
I, [2020-04-29T03:38:00.448866 #10254] INFO -- : [30e52243-2a40-4d81-bd16-a73a35421988] Redirected to https://fpmovie.s3.ap-northeast-1.amazonaws.com/vg6ssz9aols7dqsvg9mhj8de7fum?response-content-disposition=attachment%3B%20filename%3D%22D0002040402_00000_V_000.mp4%22%3B%20filename%2A%3DUTF-8%27%27D000

- 2号機
I, [2020-04-29T03:38:00.429590 #21227] INFO -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] Started GET "/rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCZz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--082040253740dbbc45220b17f5cdb4568d263cff/Beach%20-%2029982.mp4" for 111.239.176.102 at 2020-04-29 03:38:00 +0000
I, [2020-04-29T03:38:00.430342 #21227] INFO -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] Processing by ActiveStorage::BlobsController#show as MP4
I, [2020-04-29T03:38:00.430391 #21227] INFO -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] Parameters: {"signed_id"=>"eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCZz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--082040253740dbbc45220b17f5cdb4568d263cff", "filename"=>"Beach - 29982"}
D, [2020-04-29T03:38:00.436939 #21227] DEBUG -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] ActiveStorage::Blob Load (2.9ms) SELECT `active_storage_blobs`.* FROM `active_storage_blobs` WHERE `active_storage_blobs`.`id` = 1 LIMIT 1
D, [2020-04-29T03:38:00.438295 #21227] DEBUG -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] S3 Storage (1.0ms) Generated URL for file at key: 56p9uxhhljy0ofjux9p23vw0viei (https://fpmovie.s3.ap-northeast-1.amazonaws.com/56p9uxhhljy0ofjux9p23vw0viei?response-content-disposition=attachment%3B%20filename%3D%22Beach%20-%2029982.mp4%22%3B%20filename%2A%3DUTF-8%27%27Beach%2520-%252029982.mp4&response-content-type=video%2Fmp4&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAVHQOADLFFCXJEUH4%2F20200429%2Fap-northeast-1%2Fs3%2Faws4_request&X-Amz-Date=20200429T033800Z&X-Amz-Expires=300&X-Amz-SignedHeaders=host&X-Amz-Signature=b66a70cc4e1fff1932b48d232eaa2a30ea5169f888879d777b827047f8bdb16a)
I, [2020-04-29T03:38:00.438448 #21227] INFO -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] Redirected to https://fpmovie.s3.ap-northeast-1.amazonaws.com/56p9uxhhljy0ofjux9p23vw0viei?response-content-disposition=attachment%3B%20filename%3D%22Beach%20-%2029982.mp4%22%3B%20filename%2A%3DUTF-8%27%27Beach%2520-%252029982.mp4&response-content-type=video%2Fmp4&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAVHQOADLFFCXJEUH4%2F20200429%2Fap-northeast-1%2Fs3%2Faws4_request&X-Amz-Date=20200429T033800Z&X-Amz-Expires=300&X-Amz-SignedHeaders=host&X-Amz-Signature=b66a70cc4e1fff1932b48d232eaa2a30ea5169f888879d777b827047f8bdb16a
I, [2020-04-29T03:38:00.438583 #21227] INFO -- : [d2a8757f-be05-488f-87db-d1eb44046ca4] Completed 302 Found in 8ms (ActiveRecord: 2.9ms | Allocations: 1537)
I, [2020-04-29T03:38:00.519287 #21226] INFO -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] Started GET "/rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413/D0002040402_00000_V_000.mp4" for 111.239.176.102 at 2020-04-29 03:38:00 +0000
I, [2020-04-29T03:38:00.519972 #21226] INFO -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] Processing by ActiveStorage::BlobsController#show as MP4
I, [2020-04-29T03:38:00.520023 #21226] INFO -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] Parameters: {"signed_id"=>"eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413", "filename"=>"D0002040402_00000_V_000"}
D, [2020-04-29T03:38:00.526773 #21226] DEBUG -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] ActiveStorage::Blob Load (2.9ms) SELECT `active_storage_blobs`.* FROM `active_storage_blobs` WHERE `active_storage_blobs`.`id` = 2 LIMIT 1
D, [2020-04-29T03:38:00.528128 #21226] DEBUG -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] S3 Storage (1.0ms) Generated URL for file at key: vg6ssz9aols7dqsvg9mhj8de7fum (https://fpmovie.s3.ap-northeast-1.amazonaws.com/vg6ssz9aols7dqsvg9mhj8de7fum?response-content-disposition=attachment%3B%20filename%3D%22D0002040402_00000_V_000.mp4%22%3B%20filename%2A%3DUTF-8%27%27D0002040402_00000_V_000.mp4&response-content-type=video%2Fmp4&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAVHQOADLFFCXJEUH4%2F20200429%2Fap-northeast-1%2Fs3%2Faws4_request&X-Amz-Date=20200429T033800Z&X-Amz-Expires=300&X-Amz-SignedHeaders=host&X-Amz-Signature=bcfbb2cb5f620472ba87d5d819467e001b40507fce9782f1ee75aba00662667e)
I, [2020-04-29T03:38:00.528286 #21226] INFO -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] Redirected to https://fpmovie.s3.ap-northeast-1.amazonaws.com/vg6ssz9aols7dqsvg9mhj8de7fum?response-content-disposition=attachment%3B%20filename%3D%22D0002040402_00000_V_000.mp4%22%3B%20filename%2A%3DUTF-8%27%27D0002040402_00000_V_000.mp4&response-content-type=video%2Fmp4&X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAVHQOADLFFCXJEUH4%2F20200429%2Fap-northeast-1%2Fs3%2Faws4_request&X-Amz-Date=20200429T033800Z&X-Amz-Expires=300&X-Amz-SignedHeaders=host&X-Amz-Signature=bcfbb2cb5f620472ba87d5d819467e001b40507fce9782f1ee75aba00662667e
I, [2020-04-29T03:38:00.528420 #21226] INFO -- : [a4892bf5-a81e-420a-b9f5-16fba8f08dc7] Completed 302 Found in 8ms (ActiveRecord: 2.9ms | Allocations: 1521)

[aokm]

## nginx.acces.log
- 1号機
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /packs/js/application-026e9a7fb00f9b643a6b.js HTTP/1.1" 200 348121 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /assets/living-sakura4.jpg HTTP/1.1" 200 156878 "http://fp-movie.work/assets/application-8814bec42d082bde1153a60a6fa655bb5e9a53ecb732e758c666d9cf83b11c1c.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413/D0002040402_00000_V_000.mp4 HTTP/1.1" 302 635 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCZz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--082040253740dbbc45220b17f5cdb4568d263cff/Beach%20-%2029982.mp4 HTTP/1.1" 302 627 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"

- 2号機
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /packs/css/application-2868b45f.css HTTP/1.1" 200 144293 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /assets/application-8814bec42d082bde1153a60a6fa655bb5e9a53ecb732e758c666d9cf83b11c1c.css HTTP/1.1" 200 885 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCZz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--082040253740dbbc45220b17f5cdb4568d263cff/Beach%20-%2029982.mp4 HTTP/1.1" 302 627 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"
10.0.11.216 - - [29/Apr/2020:03:38:00 +0000] "GET /rails/active_storage/blobs/eyJfcmFpbHMiOnsibWVzc2FnZSI6IkJBaHBCdz09IiwiZXhwIjpudWxsLCJwdXIiOiJibG9iX2lkIn19--b5f671b710c491bce79e252a05d47012f23b4413/D0002040402_00000_V_000.mp4 HTTP/1.1" 302 635 "http://fp-movie.work/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.122 Safari/537.36"

Answer 1

これは正しい挙動です。

ELBの仕様では標準でラウンドロビンルールでリクエストが送られます。
https://docs.aws.amazon.com/ja_jp/elasticloadbalancing/latest/userguide/how-elastic-load-balancing-works.html
現在の標準であるApplication Load Balancerの場合

ターゲットグループに設定されたルーティングアルゴリズムを使用して、ルールアクションのターゲットグループからターゲットを選択します。デフォルトのルーティングアルゴリズムはラウンドロビンです。

ラウンドロビンは複数台のサーバがLBに設定されている場合、1リクエストごとに処理するサーバを順番に変えていくアルゴリズムです。

通常であれば、リクエストに対してどちらか一方のWebサーバからレスポンスを返すもので、ログに関してもレスポンスを返すサーバにのみ出力されるものと認識しております。

これは認識の通りです。
しかし、この例ではCSSや画像など複数のコンテンツを取得するので、リクエストは複数か発生します。
各リクエストはどちらか一方のサーバが応答していますが、複数のリクエストが発生しているため、結果的に同時にレスポンスが返ってきたように見えています。

2.正しい状態、リクエストがあったら2台のWebサーバのうち1台にのみアクセスがいき、そのサーバでのみlogが出力されるようにするにはどのようにすればよいのか。

「正しい状態」にするのはELB単体では無理です。
2台のウェブサーバをそれぞれ別のLB配下にして、Route53のDNSフェイルオーバーという機能を利用することで達成できます。
ただしこの方法ですと、片方のサーバは通常時に無駄になってしまうためおすすめできません。
https://aws.amazon.com/jp/premiumsupport/knowledge-center/route-53-dns-health-checks/

Comments

[aokm]

丁寧なご回答ありがとうございます。
勉強になりました。