Apache 接続タイムアウト。何が問題なのでしょうか?

Apache 接続タイムアウト。何が問題なのでしょうか?

私は非常に不安定な Apache サーバーを所有しており、1 日に何度も Apache に接続するのに苦労しています。私の場合は何も問題ないように見えるので、その理由がわかりません。サーバーには常に「十分な」空きリソースがあります。サーバーは 4GB の RAM、48GB の HD、2 つの CPU を搭載した Linode 4098 です。

私は使用していますmod_statusApache のステータスを確認しようとしましたが、/server-status ページにアクセスしようとすると、接続タイムアウト エラーが発生し続けます。

現在のApacheの設定

<IfModule mpm_prefork_module>
        StartServers            3
        ServerLimit             200
        MinSpareServers         3
        MaxSpareServers         5
        MaxRequestWorkers       175
        MaxConnectionsPerChild  100
        MaxRequestsPerChild     20
</IfModule>

/server-status への Apache リクエストはほぼ 1 回に 1 回、ERR_CONNECTION_TIMED_OUT で失敗します。

サーバーを確認すると、利用可能なリソースとアイドル接続が十分にあるのに、なぜこれが起こるのか理解できません。

たとえば、/server-status は利用可能な接続を多数報告します。

Parent Server Config. Generation: 1
Parent Server MPM Generation: 0
Server uptime: 1 hour 6 minutes 6 seconds
Server load: 0.09 0.15 0.14
Total accesses: 27206 - Total Traffic: 79.8 MB
CPU Usage: u3.92 s.77 cu0 cs0 - .118% CPU load
6.86 requests/sec - 20.6 kB/second - 3077 B/request
52 requests currently being processed, 1 idle workers

RRRCRRRRRRRRRRRRRRRRRRCRRC.RR.R.RRRRRRRRRCRRRRR_WCCRR.R..RR.....
................................................................
...............................................

そしてアップタイムではCPUも利用可能であることがわかります

load average: 0.05, 0.12, 0.14

使用可能なメモリがあります:

        total   used   free  shared  buff/cache   available  
> Mem:   3.9G    1.3G   413M     81M        2.1G        2.3G

何が問題なのか、何か分かる人はいますか?

更新: ご要望に応じて、アクセス ログの一部を以下に示します。(99% は、/api2/counting/count スクリプト ハンドラーを呼び出して統計を追跡するリクエストです)

4.139.128.194 - - [23/Jan/2017:11:38:36 +0000] "POST /api2/counter/count?r=1485171514298 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
176.38.149.76 - - [23/Jan/2017:11:38:36 +0000] "POST /api2/counter/count?r=1485171517077 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
67.84.227.131 - - [23/Jan/2017:11:38:36 +0000] "GET /keepalive?471:1485171524866 HTTP/1.1" 200 469 "https://www.writediary.com/notes" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"
122.178.156.252 - - [23/Jan/2017:11:38:36 +0000] "POST /api2/counter/count?r=1485171515804 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
82.137.13.18 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171525558 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
103.199.35.221 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515470 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
95.218.142.245 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171516264 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
47.31.143.106 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515787 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
94.139.128.194 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515619 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
120.188.33.198 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515867 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
47.29.174.163 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515122 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
85.115.224.151 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515453 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
47.11.219.147 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171516596 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
61.5.53.52 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171520665 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
116.102.205.20 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171516590 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
101.60.31.133 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171516802 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
47.31.147.213 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171515431 HTTP/1.1" 200 5131 "-" "WriteDiary4.72"
186.78.73.222 - - [23/Jan/2017:11:38:37 +0000] "POST /api2/counter/count?r=1485171669305 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
112.79.186.36 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485171736857 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
94.139.128.194 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485171516605 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
24.236.88.161 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485171516752 HTTP/1.1" 200 441 "-" "WriteDiary4.72"
150.31.100.192 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485171517315 HTTP/1.1" 200 454 "-" "WriteDiary4.72"
12.181.196.67 - - [23/Jan/2017:11:38:38 +0000] "GET /keepalive?50412:1485171517975 HTTP/1.1" 200 501 "https://www.writediary.com/notes" "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.99 Safari/537.36"
112.198.75.139 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485171408169 HTTP/1.1" 200 4794 "-" "WriteDiary4.72"
114.125.201.114 - - [23/Jan/2017:11:38:38 +0000] "POST /api2/counter/count?r=1485172944904 HTTP/1.1" 200 441 "-" "WriteDiary4.72"

解決策: より多くのワーカーを許可するには、サーバーをアップグレードする必要がありました。Apache 接続ごとに約 30 MB の RAM が必要なため、サーバーは 4 GB RAM の制限 (4000 MB / 30 MB = 最大 133 接続) に達していました。また、一部のメモリが mysql やその他のシステム機能に割り当てられると、実際には 3.5 GB しか使用できず、最大接続数は 100 に近くなります。

8GB RAM にアップグレードすると、最大制限は 250 接続に近くなり、すべてがスムーズに実行されます。実際、RAM を再スケールした後、ピーク時に何時間も Apache によって 200 のリクエストが同時に処理されたことがサーバー ステータスで報告されました。これが、制限が 100 に近い状態でピーク時にサーバーが苦戦した理由を説明しています。

答え1

Apacheのアクセスログを投稿していただけますか

Ubuntuを使用している場合は、このコマンドを使用してログを見つけることができます。

cat /var/log/apache2/access.log

ただし、プラットフォームによって異なります

関連情報