Laravelのスケジュールに設定したCommandが実行されない

Question

状況

laravelでアプリケーションを作っており、cronで定期的に行うタスクを追加しています。
タスクは下記のように指定していますが、Laravelで設定しているcommandが実行されません。

cron.dで設定しているタスク

rootのcron.dに下記を設定しています。

* * * * * root /opt/lampp/bin/php /home/project/artisan schedule:run >> /dumplog.txt

Laravel側のスケジュール

Console/Kernel.php

<?php

namespace App\Console;

use Illuminate\Console\Scheduling\Schedule;
use Illuminate\Foundation\Console\Kernel as ConsoleKernel;

class Kernel extends ConsoleKernel
{
    /**
     * The Artisan commands provided by your application.
     *
     * @var array
     */
    protected $commands = [
        \App\Console\Commands\Test::class,
    ];

    /**
     * Define the application's command schedule.
     *
     * @param  \Illuminate\Console\Scheduling\Schedule  $schedule
     * @return void
     */
    protected function schedule(Schedule $schedule)
    {

        $schedule->command('command:test')
            ->cron('* * * * * *')
            ->before(function(){
                $monolog = \Log::getMonolog();
                $monolog->info('start scedule');
            })
            ->timezone('Asia/Tokyo')
            ->after(function(){
                $monolog = \Log::getMonolog();
                $monolog->info('end scedule');
            });
    }

    /**
     * Register the commands for the application.
     *
     * @return void
     */
    protected function commands()
    {
        $this->load(__DIR__.'/Commands');

        require base_path('routes/console.php');
    }
}

command:testは下記を実行します。
Console/Commands/Test.php

<?php

namespace App\Console\Commands;

use Illuminate\Console\Command;

class Test extends Command
{
    /**
     * The name and signature of the console command.
     *
     * @var string
     */
    protected $signature = 'command:test';

    /**
     * The console command description.
     *
     * @var string
     */
    protected $description = 'Command Test';

    /**
     * Create a new command instance.
     *
     * @return void
     */
    public function __construct()
    {
        parent::__construct();
    }

    /**
     * Execute the console command.
     *
     * @return mixed
     */
    public function handle()
    {
        $monolog = \Log::getMonolog();
        $monolog->info('handle start');    
    }
}

想定される結果

下記のように、storage/logs/laravel.logに追加される。

[2018-06-14 14:30:02] staging.INFO: start scedule
[2018-06-14 14:30:02] staging.INFO: handle start 
[2018-06-14 14:30:02] staging.INFO: end scedule
[2018-06-14 14:31:01] staging.INFO: start scedule
[2018-06-14 14:31:01] staging.INFO: handle start 
[2018-06-14 14:31:01] staging.INFO: end scedule

現象

毎分指定しているスケジュールなのですが、指定したcommandが実行できていません。

laravelログ

[2018-06-14 14:30:02] staging.INFO: start scedule
[2018-06-14 14:30:02] staging.INFO: end scedule
[2018-06-14 14:31:01] staging.INFO: start scedule
[2018-06-14 14:31:01] staging.INFO: end scedule

cronログ

取得時間に際がありますが、cronで、スケジュールの実行自体はされています。

Jun 14 12:59:01 ik1-325-22848 CROND[6628]: (root) CMD (/opt/lampp/bin/php /home/project/artisan schedule:run >> /dumplog.txt)
Jun 14 13:00:01 ik1-325-22848 CROND[6649]: (root) CMD (/opt/lampp/bin/php /home/project/artisan schedule:run >> /dumplog.txt)

cronで実行したタスクのログ（dumplog.txt）

毎分出力する設定にしていますので、指定したスケジュールで、実行自体はしているのはわかります。

Running scheduled command: '/opt/lampp/bin/php-7.1.14' 'artisan' command:test > '/dev/null' 2>&1
Running scheduled command: '/opt/lampp/bin/php-7.1.14' 'artisan' command:test > '/dev/null' 2>&1
Running scheduled command: '/opt/lampp/bin/php-7.1.14' 'artisan' command:test > '/dev/null' 2>&1

わかっている事

laravelのバージョン

Laravel Framework 5.5.40

環境

OSは、「CentOS release 6.9」です。
phpはxamppに入っているものを使用しています。

cronの状況

cronのlogを見ると、毎分タスクが実行されているようなので、crontabが止まっている訳ではなさそうです。

Laravelのプロジェクトのディレクトリ

projectユーザーのホームディレクトリ配下に展開しています。
projectユーザーのホームディレクトリは、/home/project/です。

試した事

commandで意図的にエラーを起こす

もしかして、Commandにエラーがあってもエラーをログに吐かないのかと思い、意図的に構文エラーをして見ました。

    public function handle()
    {
        $monolog = \Log::getMonolog()
        $monolog->info('handle start');
    }

その結果、下記のエラーが吐き出されるので、Command自体は実行されています。

staging.ERROR: Parse error: syntax error, unexpected '$monolog' (T_VARIABLE) {"exception":"[object] (Symfony\Component\Debug\Exception\FatalThrowableError(code: 0): Parse error: syntax error, unexpected '$monolog' (T_VARIABLE) at /home/project/app/Console/Commands/Test.php:41)

storageに書き出す部分のみの問題な気がします。
何故、scheduleのbeforeとafterは良くて、Commandが書き出せないのかわかりませんが…

storageディレクトリ以下のパーミッションを777にする。

laravel.logのパーミッションが元々「-rw-r--r--」で、projectユーザー（作業ユーザー）しかさわれない状態だったので、laravel.logのパーミッションを777にしてみましたが、logsに書き込まれませんでした。

Comments

[mix-peach]

うーん。。何か違う部分が原因のような気がしますが。。。　crontabを設定したアカウントと、commandの実行を確認した時のアカウントは、同じですか？　あと、 command:test　の中でログとか既存ファイルへの出力とかをしていますか？

[pip]

ありがとうございます。状況を整理して追記させていただきます。

[mix-peach]

artisanのパスが書き換わっている、と書いてありますが、私の手元のlaravel環境でも同じようになりますし、その上でschedule:runの中のコマンドは正常に動きます。ログ出力、ディレクトリやファイル作成、既存ディレクトリや既存ファイルへの書き込みなどを行っているのであれば、パーミッションエラーで落ちている可能性を疑うべきかと思われます。ちなみに、これで落ちている場合、エラーログもパーミッションエラーで書き込めないので、エラーが発生しているようにも見えないという難点が・・・。

[pip]

なるほどですね。外部ファイルへの書き込みをしていますので、パーミッションの可能性があります。一度、外部出力を削ってlaravelログを出力するだけ確認して見たのですが、出力されないようなのでそもそも、スケジュールの設定方法にも誤りがあるかもしれません。

[pip]

質問の内容を変えて見ます。

[mix-peach]

色々試されてるみたいですね・・。Console/Commands/Test.php は、artisanコマンドから作成されましたか？あと、laravelのバージョンはいくつでしょう？5.？←ここが知りたいです。

[pip]

お忙しいところ度々ありがとうございます。「php artisan command:test」で実行できることは確認しました。rootアカウントでも実行できます。ララベルのバージョンは5.5.40です。

[pip]

元々、他のサーバーで稼働していたシステムのソースを流用して作ったシステムで、そのシステムではscheduleの処理が出来ています。流用して別サーバーで運用したところこの現象が起こり始めたので、サーバーの差異も見ているのですがこれといった違いが見当たらず、引き続き調査している次第です。

[mix-peach]

あ。コマンドを直に実行すれば動くんでしたね！　となると、コマンド実行者の違いが原因かもしれません。schedule:run （before、after含む）は、crontabを設定したアカウント（ログから見るに、root）で動くと思うのですが、そこから呼ばれるcommand:testを動かしているのは、ファイル（プロジェクト？）の所有者かapacheかだと思うので、そこらへんの差が「書き出せるか出せないか」に影響しているのかも？（そうすると、やはりパーミッションの話ですかね？　でもログファイルの権限「777」で確認されてるんですよね・・不思議ですね・・）　ちなみに、apacheのログとかにも、エラーは出てないですか？

[pip]

ご回答ありがとうございます。時間をおいてしまい失礼しました。結局根本的な解決には至らず…サーバーを変えたところ解決できたので案件自体は終わりました。取り急ぎご共有まで

Answer 1

根本的な解決はしませんでしたが、
サーバーを変えて一から作り直したところ解決しました。