同じ処理で必ずテスト環境でタイムアウトする。

Question

実現したいこと

通知機能を作成しているのですが、他の通知作成処理ではタイムアウトが起こらないのですが特定の通知作成処理の所でタイムアウトが起きるので修正したいです。

前提

タイムアウトが送る箇所はチャットに入る際にパスワードを入力した際に中間テーブルを作成すると共にチャットを建てた人に入室のお知らせをする通知を作成をする内容です。

発生している問題・エラーメッセージ

504 Gateway Time-out
 WARN  PHP Fatal error: Maximum execution time of 60 seconds exceeded in vendor/symfony/polyfill-mbstring/Mbstring.php on line 515.  

該当のソースコード

public function store(Request $request)
    {
        $user = Auth::user();
        $rooms = Room::all();
        $room = Room::find($request->room_id);
        $messages = Message::where('room_id', '=', $room->id)
                           ->get();
        if($room->room_password == $request->room_password)
        {
            $member = new Member;
            $member->room_id = $request->room_id;
            $member->user_id = $request->user_id;
            $member->host_id = $request->host_id;
            $member->save();
            
            $notification = new Notification;
            $notification->user_id = $request->user_id;
            $notification->member_id = $member->id;
            $notification->notification_type = 1;
            $notification->title = "{$user->name}さんが{$member->room->name}に入室しました。";
            $notification->save();
            
            return view('rooms/show', ['user'=>$user, 'rooms'=>$rooms, 'room'=>$room, 'member'=>$member, 'messages'=>$messages]);
        }
        return view('rooms/index', ['user'=>$user, 'rooms'=>$rooms, 'room'=>$room]);
        
    }

試したこと

タイムアウトの原因が処理が多いから重いのか、ifの処理が原因なのかを調べています。
WARN PHP Fatal error: Maximum execution time of 60 seconds exceeded in vendor/symfony/polyfill-mbstring/Mbstring.php on line 515. のエラーを発見して探したところ、タイムアウトの時間を変更する方法を見たのですが、そちらはどうでしょうか？

補足情報（FW/ツールのバージョンなど）

PHP8
Laravel10

Comments

[student]

EC2からボリュームを増やしたりしたほうが良いのでしょうか？

[student]

文字数制限に引っ掛かったのですがリダイレクト先のrooms/showはif文やループ文を多く記載しているのですが、そちらも関係してますでしょうか？

Answer 1

まず、タイムアウトが発生するのは本当にstoreメソッドですか？そこから確認しましょう。

デバッガなどを導入するのは難しそうに見受けられるので、ログ出力を各所にいれて何行目がいつ実行されているか、などを見て、時間がかかっているメソッドを調べていくと良いでしょう。

Log::debug(__LINE__ . '行目が実行されました');

また、タイムアウトと直接関係ないかもですが、storeメソッドでviewを表示するのは避けたほうがいいです。

storeメソッドは恐らくPOSTリクエストを受けるものでしょうけど、POSTリクエストでデータ更新後、そのまま同一のリクエストでViewを表示するのは10年以上前のWebアプリケーションの作り方です。

return view('rooms/show', ['user'=>$user, 'rooms'=>$rooms, 'room'=>$room, 'member'=>$member, 'messages'=>$messages]);

// ↓ たとえば、下記のような形に変更してshowメソッドにリダイレクト (route nameやパラメータ等はroute定義により異なりますので合わせてください)
return redirect()->route('rooms/show', ['id' => $room->id]);

Answer 2

timeoutエラーがphpで出ているわけですからサーバー側の問題ですね
環境にもよりますがタスクマネージャーやtopコマンドで負荷状況は確認できますし
SQLの課題であればスロークエリーのログなど確認できます
push処理ということであればwebsocketかSSEでやる流れだと思いますが、
入室案内くらいならよほど変な作りをしていない限りtimeoutするようなものにはならないはずです
逆にどうしても待ち時間が必要という仕組みであれば
max_execution_time を変更する方法もありますが、暴走が止まらなくなるリスクもあるので
あまりおすすめできません

Comments

[student]

>入室案内くらいならよほど変な作りをしていない限りtimeoutするようなものにはならないはずです

現状、質問に記載している中間テーブルの作成するコード箇所stroreメソッド内の記載でタイムアウトが起きる原因になりそうな箇所はif処理くらいしか原因になりそうな箇所が分かりません。
if文はタイムアウトになる原因はありえますでしょうか？

Answer 3

実際に何の処理で時間がかかってるかを見つけ出す必要があります。データベースに接続する処理があるならデータベースのログを見る、大量のデータを扱ってるのならメモリ周りで不具合が起きてると想定してApacheやNginx、php-fpmなどのエラーログを見ることで何かしら分かることがあるかもしれません。

断片的な情報しかないので、これ以上の具体的なアドバイスは出にくいのでは？と思います。

追記）
マッチングみたいなことを行っているのであれば、もしかするとどこかで無限ループが発生してるのかもしれません。forやforeach、whileなどを使ってるのであれば、その辺をチェックしてみると良いかもしれません。ループする回数を制限したりすれば直る可能性もあるかと思います。

Comments

[student]

探してみます。

[student]

探しているのですが、通知を作成するコード前はタイムアウトが起きていなかったので一度通知作成コードを消した状態で再度試してもタイムアウトが起きました。
これは他の処理の所が原因の可能性がありますか？

[AbeTakashi]

うーん、どうでしょうね。追記された内容も含めて質問文だけの情報だけだとヒントがなさ過ぎてなんとも言えないですね。xdebugとか使ったことありますか？ ないならそういうので怪しいところを探してみるのはありかもしれません。

[student]

まだうまくデバッグを表示出来ていないのですが、下記のやり方でもタイムアウトに関するものが出ることありますでしょうか？
https://laraweb.net/practice/2561/

またデバッグに関しての知識がまだ薄いのですがデバッグによって表示される内容が大きく違う事はあるのでしょうか？
その場合はxdebugを入れようと思うのですがcloud9だからなのか入れられなかったので、他のデバッグ方法を聞いてみました。
すみません。

[AbeTakashi]

LaravelのログはあくまでもLaravelフレームワーク上でのエラーしか補足できません。このタイムアウトはLaravelフレームワーク外で起きてるように見受けられるので、そこのログには残らないような気がします。

Cloud9は使ったことがないので具体的なアドバイスはできませんが、xdebugの導入に関しての記事は数多く散見されますので、なるべく最新の情報を参考にして再チャレンジしてみたらどうでしょう。上手く入れられたら、どこで止まってるかに関しては一発で分かると思います。

[student]

xdebugは入れることが出来たのですがphp.iniの編集また特定が分からなくて調べていたのですが、
Configuration File (php.ini) Path => /etc
Loaded Configuration File => /etc/php.ini
上記の書き方はphp.iniファイルが存在していますか？

次にls -al php.iniで編集を試みたのですが、ls: cannot access php.ini: No such file or directoryと書かれており、恐らくファイルが存在していない事だと思います。

php.iniファイルは作成する必要があるのでしょうか？