質問編集履歴

3

調査追記

2021/04/09 10:28

投稿

neverever823
neverever823

スコア2

test CHANGED
File without changes
test CHANGED
@@ -205,3 +205,25 @@
205
205
  - MaxSpareServers 10(デフォルト)
206
206
 
207
207
  - MinSpareServers 5(デフォルト)
208
+
209
+
210
+
211
+
212
+
213
+ # 2021/04/09追記
214
+
215
+ 皆様ご意見くださりありがとうございます。
216
+
217
+ 現在の進捗ですが、依然究明には至っていない次第でございます。
218
+
219
+ 引き続きご意見いただけますと幸いでございます。
220
+
221
+
222
+
223
+ また、ここに来てAuroraServerlessの線も再浮上しました。
224
+
225
+ 理由としては最初にログを仕込んだミドルウェアの前に処理されるミドルウェアにて
226
+
227
+ データベースへの接続をしている箇所がありました。
228
+
229
+ Amazonへ直接問い合わせてみるなど原因調査の方法を検討中です。

2

「Webサーバーか、プログラムかの切り分けのため、Laravelのミドルウェアにログを入れて確認したところ、 Laravelのミドルウェアまでの到達の時間に30秒かかっていることがわかりました。」の補

2021/04/09 10:28

投稿

neverever823
neverever823

スコア2

test CHANGED
File without changes
test CHANGED
@@ -40,7 +40,7 @@
40
40
 
41
41
 
42
42
 
43
- ### 追記情報
43
+ ### 追記
44
44
 
45
45
  下記について調査内容、結果を追記しました。
46
46
 
@@ -68,6 +68,70 @@
68
68
 
69
69
 
70
70
 
71
+ > Webサーバーか、プログラムかの切り分けのため、Laravelのミドルウェアにログを入れて確認したところ、
72
+
73
+ > Laravelのミドルウェアまでの到達の時間に30秒かかっていることがわかりました。
74
+
75
+
76
+
77
+ こちらの情報を補足すると
78
+
79
+ CloudWatchのログには、Apacheのアクセスログが記録されています。
80
+
81
+ Laravelのミドルウェアには下記のように処理前後にログを仕込みました。
82
+
83
+
84
+
85
+ ```
86
+
87
+ $path = $request->path();
88
+
89
+ $ip = $request->ip();
90
+
91
+ $start = Carbon::now();
92
+
93
+ $requestId = uniqid("UNIQ_");
94
+
95
+
96
+
97
+ \Log::info('start::uniq::'.$requestId.'::'.$ip.'::'.$path.'::'.$start);
98
+
99
+
100
+
101
+
102
+
103
+ $request = $next($request);
104
+
105
+
106
+
107
+
108
+
109
+ $end = Carbon::now();
110
+
111
+ $diff = $start->diffInSeconds($end);
112
+
113
+ \Log::info('end::uniq::'.$requestId.'::'.$ip.'::'.$path.'::'.$end.'-diff::'.$diff);
114
+
115
+ ```
116
+
117
+
118
+
119
+ するとApacheのアクセスログのインからLaravelの処理前に到達する時点で30秒かかっていることがわかり、
120
+
121
+ 処理前から処理後の差分を出していますが、
122
+
123
+ ```
124
+
125
+ \Log::info('end::uniq::'.$requestId.'::'.$ip.'::'.$path.'::'.$end.'-diff::'.$diff);
126
+
127
+ ```
128
+
129
+ この差分には1秒もかからずに処理が返ってきていることから
130
+
131
+ DBやプログラムが重いことはないと判断しています。
132
+
133
+
134
+
71
135
  ### わからないこと
72
136
 
73
137
 

1

「Apacheのリクエストが返ってくるまでに30秒かかってしまいます。サーバーサイドのプログラム内の問題ではないことがわかっています。」について調査内容と結果を追記しました。

2021/04/08 09:56

投稿

neverever823
neverever823

スコア2

test CHANGED
File without changes
test CHANGED
@@ -11,6 +11,8 @@
11
11
  一旦事象は解消していますが、根本的な部分がわかりません。
12
12
 
13
13
  再発することを懸念し現在も調査しています。
14
+
15
+
14
16
 
15
17
 
16
18
 
@@ -35,6 +37,32 @@
35
37
  ApacheからLaravelのミドルウェアに到達するまでに30秒近くかかっていることがわかりました。
36
38
 
37
39
  サーバーサイドのプログラム内の問題ではないことがわかっています。
40
+
41
+
42
+
43
+ ### 追記情報
44
+
45
+ 下記について調査内容、結果を追記しました。
46
+
47
+
48
+
49
+ > Apacheのリクエストが返ってくるまでに30秒かかってしまいます。
50
+
51
+ > サーバーサイドのプログラム内の問題ではないことがわかっています。
52
+
53
+
54
+
55
+ はじめにCloudWatchのログを確認し、WebサーバーからRDSへの到達に30秒かかっていることがわかりました。
56
+
57
+ この結果からALB、WAFには問題がないとわかりました。
58
+
59
+ 次にDBのクエリのレスポンスを調査しましたが、
60
+
61
+ RDSのスロークエリには時間がかかっているログは確認できませんでした。
62
+
63
+ Webサーバーか、プログラムかの切り分けのため、Laravelのミドルウェアにログを入れて確認したところ、
64
+
65
+ Laravelのミドルウェアまでの到達の時間に30秒かかっていることがわかりました。
38
66
 
39
67
 
40
68