Как можно продебажить заваленную очередь в laravel 5?

Question

[Павел Гром]

У меня валится job при --tries=3, но работает при --tries=5 и выше.
При 3-х попытках выдается исключение
App\Jobs\CacheHist has been attempted too many times or run too long. The job may have previously timed out.
Ну ок, понятно что попытки исполнить job превышают 3 штуки.
Но как можно продебажить почему job вообще пытается исполнится еще раз?
P.S. Сделал логирование в методе handle непосредственно перед своим кодом.
Так я выяснил, что при 3-х попытках до этого метода даже не доходит.
Laravel 5.6

Comments

[Tesla]

Что в логе?

[Павел Гром]

Бесполезнейший стэк-трэйс
App\Jobs\CacheHist has been attempted too many times or run too long. The job may have previously timed out.
#0 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Queue/Worker.php(316): Illuminate\Queue\Worker->markJobAsFailedIfAlreadyExceedsMaxAttempts('redis', Object(Illuminate\Queue\Jobs\RedisJob), 3)
#1 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Queue/Worker.php(272): Illuminate\Queue\Worker->process('redis', Object(Illuminate\Queue\Jobs\RedisJob), Object(Illuminate\Queue\WorkerOptions))
#2 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Queue/Worker.php(118): Illuminate\Queue\Worker->runJob(Object(Illuminate\Queue\Jobs\RedisJob), 'redis', Object(Illuminate\Queue\WorkerOptions))
#3 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Queue/Console/WorkCommand.php(101): Illuminate\Queue\Worker->daemon('redis', 'hist', Object(Illuminate\Queue\WorkerOptions))
#4 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Queue/Console/WorkCommand.php(85): Illuminate\Queue\Console\WorkCommand->runWorker('redis', 'hist')
#5 [internal function]: Illuminate\Queue\Console\WorkCommand->handle()
#6 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Container/BoundMethod.php(29): call_user_func_array(Array, Array)
#7 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Container/BoundMethod.php(87): Illuminate\Container\BoundMethod::Illuminate\Container\{closure}()
#8 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Container/BoundMethod.php(31): Illuminate\Container\BoundMethod::callBoundMethod(Object(Illuminate\Foundation\Application), Array, Object(Closure))
#9 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Container/Container.php(564): Illuminate\Container\BoundMethod::call(Object(Illuminate\Foundation\Application), Array, Array, NULL)
#10 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Console/Command.php(183): Illuminate\Container\Container->call(Array)
#11 /var/www/icex-index/vendor/symfony/console/Command/Command.php(252): Illuminate\Console\Command->execute(Object(Symfony\Component\Console\Input\ArgvInput), Object(Illuminate\Console\OutputStyle))
#12 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Console/Command.php(170): Symfony\Component\Console\Command\Command->run(Object(Symfony\Component\Console\Input\ArgvInput), Object(Illuminate\Console\OutputStyle))
#13 /var/www/icex-index/vendor/symfony/console/Application.php(886): Illuminate\Console\Command->run(Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#14 /var/www/icex-index/vendor/symfony/console/Application.php(262): Symfony\Component\Console\Application->doRunCommand(Object(Illuminate\Queue\Console\WorkCommand), Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#15 /var/www/icex-index/vendor/symfony/console/Application.php(145): Symfony\Component\Console\Application->doRun(Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#16 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Console/Application.php(88): Symfony\Component\Console\Application->run(Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#17 /var/www/icex-index/vendor/laravel/framework/src/Illuminate/Foundation/Console/Kernel.php(121): Illuminate\Console\Application->run(Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#18 /var/www/icex-index/artisan(35): Illuminate\Foundation\Console\Kernel->handle(Object(Symfony\Component\Console\Input\ArgvInput), Object(Symfony\Component\Console\Output\ConsoleOutput))
#19 {main}

Answer 1

[Александр Аксентьев]

The job may have previously timed out.

В ошибке же прямо написано почему оно могло не выполниться с первого раза, и пробовало до упора пока лимит попыток не кончился.

Comments

[Павел Гром]

Но таймаут не установлен же. И по дефолту вроде не устанавливается.
И почему происходит то, что я написал в P.S.?

[Павел Гром]

нельзя просто так взять и загуглить ошибку

Я в первый раз что то вообще тут спрашиваю, и только потому, что ничего, что бы мне помогло нагуглить не вышло

[Александр Аксентьев]

Павел Гром, про нагуглить это если что у меня в профиле написано, а не в ответе)

Дефолт таймаут вроде 60 секунд стоит.

В целом мало информации, ничего непонятно.

P.S. Сделал логирование в методе handle непосредственно перед своим кодом.
Так я выяснил, что при 3-х попытках до этого метода даже не доходит.

ну значит что-то другое вообще происходит или неправильно вызываете.