]> git.mxchange.org Git - friendica.git/blobdiff - src/Core/Worker.php
Let the worker run for an hour in daemon mode
[friendica.git] / src / Core / Worker.php
index 4f455277641f9b6ca43f1547b02685d814b20e1d..bb17e430c14ab736dee2934b9bc010ad22050e95 100644 (file)
@@ -21,6 +21,7 @@
 
 namespace Friendica\Core;
 
+use Friendica\App\Mode;
 use Friendica\Core;
 use Friendica\Database\DBA;
 use Friendica\DI;
@@ -49,6 +50,7 @@ class Worker
        private static $lock_duration = 0;
        private static $last_update;
        private static $state;
+       private static $daemon_mode = null;
 
        /**
         * Processes the tasks that are in the workerqueue table
@@ -92,66 +94,104 @@ class Worker
 
                $last_check = $starttime = time();
                self::$state = self::STATE_STARTUP;
+               $wait_interval = self::isDaemonMode() ? 360 : 10;
+               $start = time();
+
+               do {
+                       // We fetch the next queue entry that is about to be executed
+                       while ($r = self::workerProcess()) {
+                               // Don't refetch when a worker fetches tasks for multiple workers
+                               $refetched = DI::config()->get('system', 'worker_multiple_fetch');
+                               foreach ($r as $entry) {
+                                       // Assure that the priority is an integer value
+                                       $entry['priority'] = (int)$entry['priority'];
+
+                                       // The work will be done
+                                       if (!self::execute($entry)) {
+                                               Logger::notice('Process execution failed, quitting.');
+                                               return;
+                                       }
 
-               // We fetch the next queue entry that is about to be executed
-               while ($r = self::workerProcess()) {
-                       // Don't refetch when a worker fetches tasks for multiple workers
-                       $refetched = DI::config()->get('system', 'worker_multiple_fetch');
-                       foreach ($r as $entry) {
-                               // Assure that the priority is an integer value
-                               $entry['priority'] = (int)$entry['priority'];
-
-                               // The work will be done
-                               if (!self::execute($entry)) {
-                                       Logger::info('Process execution failed, quitting.');
-                                       return;
-                               }
-
-                               // Trying to fetch new processes - but only once when successful
-                               if (!$refetched && DI::lock()->acquire(self::LOCK_PROCESS, 0)) {
-                                       self::findWorkerProcesses();
-                                       DI::lock()->release(self::LOCK_PROCESS);
-                                       self::$state = self::STATE_REFETCH;
-                                       $refetched = true;
-                               } else {
-                                       self::$state = self::STATE_SHORT_LOOP;
+                                       // Trying to fetch new processes - but only once when successful
+                                       if (!$refetched && DI::lock()->acquire(self::LOCK_PROCESS, 0)) {
+                                               self::findWorkerProcesses();
+                                               DI::lock()->release(self::LOCK_PROCESS);
+                                               self::$state = self::STATE_REFETCH;
+                                               $refetched = true;
+                                       } else {
+                                               self::$state = self::STATE_SHORT_LOOP;
+                                       }
                                }
-                       }
-
-                       // To avoid the quitting of multiple workers only one worker at a time will execute the check
-                       if ((time() > $last_check + 5) && !self::getWaitingJobForPID()) {
-                               self::$state = self::STATE_LONG_LOOP;
 
-                               if (DI::lock()->acquire(self::LOCK_WORKER, 0)) {
-                               // Count active workers and compare them with a maximum value that depends on the load
-                                       if (self::tooMuchWorkers()) {
-                                               Logger::info('Active worker limit reached, quitting.');
+                               // To avoid the quitting of multiple workers only one worker at a time will execute the check
+                               if ((time() > $last_check + 5) && !self::getWaitingJobForPID()) {
+                                       self::$state = self::STATE_LONG_LOOP;
+
+                                       if (DI::lock()->acquire(self::LOCK_WORKER, 0)) {
+                                       // Count active workers and compare them with a maximum value that depends on the load
+                                               if (self::tooMuchWorkers()) {
+                                                       Logger::notice('Active worker limit reached, quitting.');
+                                                       DI::lock()->release(self::LOCK_WORKER);
+                                                       return;
+                                               }
+
+                                               // Check free memory
+                                               if (DI::process()->isMinMemoryReached()) {
+                                                       Logger::warning('Memory limit reached, quitting.');
+                                                       DI::lock()->release(self::LOCK_WORKER);
+                                                       return;
+                                               }
                                                DI::lock()->release(self::LOCK_WORKER);
-                                               return;
                                        }
+                                       $last_check = time();
+                               }
 
-                                       // Check free memory
-                                       if (DI::process()->isMinMemoryReached()) {
-                                               Logger::notice('Memory limit reached, quitting.');
-                                               DI::lock()->release(self::LOCK_WORKER);
-                                               return;
+                               // Quit the worker once every cron interval
+                               if (time() > ($starttime + (DI::config()->get('system', 'cron_interval') * 60))) {
+                                       Logger::info('Process lifetime reached, respawning.');
+                                       self::unclaimProcess();
+                                       if (self::isDaemonMode()) {
+                                               self::IPCSetJobState(true);
+                                       } else {
+                                               self::spawnWorker();
                                        }
-                                       DI::lock()->release(self::LOCK_WORKER);
+                                       return;
                                }
-                               $last_check = time();
+                               $start = time();
                        }
 
-                       // Quit the worker once every cron interval
-                       if (time() > ($starttime + (DI::config()->get('system', 'cron_interval') * 60))) {
-                               Logger::info('Process lifetime reached, respawning.');
-                               self::unclaimProcess();
-                               self::spawnWorker();
-                               return;
+                       $seconds = (time() - $start);
+
+                       // logarithmic wait time calculation.
+                       $arg = (($seconds + 1) / ($wait_interval / 9)) + 1;
+                       $sleep = min(1000000, round(log10($arg) * 1000000, 0));
+                       usleep($sleep);
+
+                       $timeout = ($seconds >= $wait_interval);
+                       Logger::info('Timeout', ['timeout' => $timeout, 'seconds' => $seconds, 'sleep' => $sleep]);
+
+                       if (!$timeout) {
+                               if (DI::process()->isMaxLoadReached()) {
+                                       Logger::notice('maximum load reached, quitting.');
+                                       return;
+                               }
+
+                               // Kill stale processes every 5 minutes
+                               $last_cleanup = DI::config()->get('system', 'worker_last_cleaned', 0);
+                               if (time() > ($last_cleanup + 300)) {
+                                       DI::config()->set('system', 'worker_last_cleaned', time());
+                                       self::killStaleWorkers();
+                               }
+
+                               // Check if the system is ready
+                               if (!self::isReady()) {
+                                       return;
+                               }               
                        }
-               }
+               } while (!$timeout);
 
                // Cleaning up. Possibly not needed, but it doesn't harm anything.
-               if (DI::config()->get('system', 'worker_daemon_mode', false)) {
+               if (self::isDaemonMode()) {
                        self::IPCSetJobState(false);
                }
                Logger::info("Couldn't select a workerqueue entry, quitting process", ['pid' => getmypid()]);
@@ -168,28 +208,28 @@ class Worker
        {
                // Count active workers and compare them with a maximum value that depends on the load
                if (self::tooMuchWorkers()) {
-                       Logger::info('Active worker limit reached, quitting.');
+                       Logger::notice('Active worker limit reached, quitting.');
                        return false;
                }
 
                // Do we have too few memory?
                if (DI::process()->isMinMemoryReached()) {
-                       Logger::notice('Memory limit reached, quitting.');
+                       Logger::warning('Memory limit reached, quitting.');
                        return false;
                }
 
                // Possibly there are too much database connections
                if (self::maxConnectionsReached()) {
-                       Logger::notice('Maximum connections reached, quitting.');
+                       Logger::warning('Maximum connections reached, quitting.');
                        return false;
                }
 
                // Possibly there are too much database processes that block the system
                if (DI::process()->isMaxProcessesReached()) {
-                       Logger::notice('Maximum processes reached, quitting.');
+                       Logger::warning('Maximum processes reached, quitting.');
                        return false;
                }
-               
+
                return true;
        }
 
@@ -290,13 +330,13 @@ class Worker
 
                // Constantly check the number of parallel database processes
                if (DI::process()->isMaxProcessesReached()) {
-                       Logger::notice("Max processes reached for process", ['pid' => $mypid]);
+                       Logger::warning("Max processes reached for process", ['pid' => $mypid]);
                        return false;
                }
 
                // Constantly check the number of available database connections to let the frontend be accessible at any time
                if (self::maxConnectionsReached()) {
-                       Logger::notice("Max connection reached for process", ['pid' => $mypid]);
+                       Logger::warning("Max connection reached for process", ['pid' => $mypid]);
                        return false;
                }
 
@@ -411,6 +451,12 @@ class Worker
        {
                $a = DI::app();
 
+               $cooldown = DI::config()->get("system", "worker_cooldown", 0);
+               if ($cooldown > 0) {
+                       Logger::info('Pre execution cooldown.', ['priority' => $queue["priority"], 'id' => $queue["id"], 'cooldown' => $cooldown]);
+                       sleep($cooldown);
+               }
+
                Logger::enableWorker($funcname);
 
                Logger::info("Process start.", ['priority' => $queue["priority"], 'id' => $queue["id"]]);
@@ -421,6 +467,11 @@ class Worker
                // For this reason the variables have to be initialized.
                DI::profiler()->reset();
 
+               if (!in_array($queue['priority'], PRIORITIES)) {
+                       Logger::warning('Invalid priority', ['queue' => $queue, 'callstack' => System::callstack(20)]);
+                       $queue['priority'] = PRIORITY_MEDIUM;
+               }
+
                $a->queue = $queue;
 
                $up_duration = microtime(true) - self::$up_start;
@@ -478,10 +529,8 @@ class Worker
 
                DI::profiler()->saveLog(DI::logger(), "ID " . $queue["id"] . ": " . $funcname);
 
-               $cooldown = DI::config()->get("system", "worker_cooldown", 0);
-
                if ($cooldown > 0) {
-                       Logger::info('Cooldown.', ['priority' => $queue["priority"], 'id' => $queue["id"], 'cooldown' => $cooldown]);
+                       Logger::info('Post execution cooldown.', ['priority' => $queue["priority"], 'id' => $queue["id"], 'cooldown' => $cooldown]);
                        sleep($cooldown);
                }
        }
@@ -535,7 +584,7 @@ class Worker
                        $level = ($used / $max) * 100;
 
                        if ($level >= $maxlevel) {
-                               Logger::notice("Maximum level (".$maxlevel."%) of user connections reached: ".$used."/".$max);
+                               Logger::warning("Maximum level (".$maxlevel."%) of user connections reached: ".$used."/".$max);
                                return true;
                        }
                }
@@ -565,7 +614,7 @@ class Worker
                if ($level < $maxlevel) {
                        return false;
                }
-               Logger::notice("Maximum level (".$level."%) of system connections reached: ".$used."/".$max);
+               Logger::warning("Maximum level (".$level."%) of system connections reached: ".$used."/".$max);
                return true;
        }
 
@@ -765,7 +814,7 @@ class Worker
                        // Are there fewer workers running as possible? Then fork a new one.
                        if (!DI::config()->get("system", "worker_dont_fork", false) && ($queues > ($active + 1)) && self::entriesExists()) {
                                Logger::info("There are fewer workers as possible, fork a new worker.", ['active' => $active, 'queues' => $queues]);
-                               if (DI::config()->get('system', 'worker_daemon_mode', false)) {
+                               if (self::isDaemonMode()) {
                                        self::IPCSetJobState(true);
                                } else {
                                        self::spawnWorker();
@@ -774,7 +823,7 @@ class Worker
                }
 
                // if there are too much worker, we don't spawn a new one.
-               if (DI::config()->get('system', 'worker_daemon_mode', false) && ($active > $queues)) {
+               if (self::isDaemonMode() && ($active > $queues)) {
                        self::IPCSetJobState(false);
                }
 
@@ -1175,6 +1224,60 @@ class Worker
                self::killStaleWorkers();
        }
 
+       /**
+        * Fork a child process
+        *
+        * @param boolean $do_cron
+        * @return void
+        */
+       private static function forkProcess(bool $do_cron)
+       {
+               if (DI::process()->isMinMemoryReached()) {
+                       Logger::warning('Memory limit reached - quitting');
+                       return;
+               }
+
+               // Children inherit their parent's database connection.
+               // To avoid problems we disconnect and connect both parent and child
+               DBA::disconnect();
+               $pid = pcntl_fork();
+               if ($pid == -1) {
+                       DBA::connect();
+                       Logger::warning('Could not spawn worker');
+                       return;
+               } elseif ($pid) {
+                       // The parent process continues here
+                       DBA::connect();
+                       Logger::info('Spawned new worker', ['cron' => $do_cron, 'pid' => $pid]);
+                       return;
+               }
+
+               // We now are in the new worker
+               DBA::connect();
+               Logger::info('Worker spawned', ['cron' => $do_cron, 'pid' => getmypid()]);
+
+               DI::process()->start();
+
+               self::processQueue($do_cron);
+
+               self::unclaimProcess();
+
+               DI::process()->end();
+               Logger::info('Worker ended', ['cron' => $do_cron, 'pid' => getmypid()]);
+
+               DBA::disconnect();
+/*
+               $php = '/usr/bin/php';
+               $param = ['bin/worker.php'];
+               if ($do_cron) {
+                       $param[] = 'no_cron';
+               }
+               pcntl_exec($php, $param);
+               Logger::warning('Error calling worker', ['cron' => $do_cron, 'pid' => getmypid()]);
+*/
+               exit();
+       }
+
        /**
         * Spawns a new worker
         *
@@ -1184,17 +1287,13 @@ class Worker
         */
        public static function spawnWorker($do_cron = false)
        {
-               $command = 'bin/worker.php';
-
-               $args = ['no_cron' => !$do_cron];
-
-               $a = DI::app();
-               $process = new Core\Process(DI::logger(), DI::mode(), DI::config(), DI::modelProcess(), $a->getBasePath(), getmypid());
-               $process->run($command, $args);
-
-               // after spawning we have to remove the flag.
-               if (DI::config()->get('system', 'worker_daemon_mode', false)) {
+               if (self::isDaemonMode()) {
+                       self::forkProcess($do_cron);
                        self::IPCSetJobState(false);
+               } else {
+                       $process = new Core\Process(DI::logger(), DI::mode(), DI::config(),
+                               DI::modelProcess(), DI::app()->getBasePath(), getmypid());
+                       $process->run('bin/worker.php', ['no_cron' => !$do_cron]);
                }
        }
 
@@ -1264,6 +1363,11 @@ class Worker
                $found = DBA::exists('workerqueue', ['command' => $command, 'parameter' => $parameters, 'done' => false]);
                $added = false;
 
+               if (!in_array($priority, PRIORITIES)) {
+                       Logger::warning('Invalid priority', ['priority' => $priority, 'command' => $command, 'callstack' => System::callstack(20)]);
+                       $priority = PRIORITY_MEDIUM;
+               }
+
                // Quit if there was a database error - a precaution for the update process to 3.5.3
                if (DBA::errorNo() != 0) {
                        return false;
@@ -1280,7 +1384,7 @@ class Worker
                }
 
                // Set the IPC flag to ensure an immediate process execution via daemon
-               if (DI::config()->get('system', 'worker_daemon_mode', false)) {
+               if (self::isDaemonMode()) {
                        self::IPCSetJobState(true);
                }
 
@@ -1305,7 +1409,7 @@ class Worker
                }
 
                // Quit on daemon mode
-               if (DI::config()->get('system', 'worker_daemon_mode', false)) {
+               if (self::isDaemonMode()) {
                        return $added;
                }
 
@@ -1342,7 +1446,7 @@ class Worker
                                $new_retrial = $retrial;
                        }
                }
-               Logger::info('New retrial for task', ['id' => $queue['id'], 'created' => $queue['created'], 'old' => $queue['retrial'], 'new' => $new_retrial]);
+               Logger::notice('New retrial for task', ['id' => $queue['id'], 'created' => $queue['created'], 'old' => $queue['retrial'], 'new' => $new_retrial]);
                return $new_retrial;
        }
 
@@ -1368,7 +1472,7 @@ class Worker
                $new_retrial = self::getNextRetrial($queue, $max_level);
 
                if ($new_retrial > $max_level) {
-                       Logger::info('The task exceeded the maximum retry count', ['id' => $id, 'created' => $queue['created'], 'old_prio' => $queue['priority'], 'old_retrial' => $queue['retrial'], 'max_level' => $max_level, 'retrial' => $new_retrial]);
+                       Logger::notice('The task exceeded the maximum retry count', ['id' => $id, 'created' => $queue['created'], 'old_prio' => $queue['priority'], 'old_retrial' => $queue['retrial'], 'max_level' => $max_level, 'retrial' => $new_retrial]);
                        return false;
                }
 
@@ -1429,6 +1533,51 @@ class Worker
                return (bool)$row['jobs'];
        }
 
+       /**
+        * Checks if the worker is running in the daemon mode.
+        *
+        * @return boolean
+        */
+       public static function isDaemonMode()
+       {
+               if (!is_null(self::$daemon_mode)) {
+                       return self::$daemon_mode;
+               }
+
+               if (DI::mode()->getExecutor() == Mode::DAEMON) {
+                       return true;
+               }
+
+               $daemon_mode = DI::config()->get('system', 'worker_daemon_mode', false, true);
+               if ($daemon_mode) {
+                       return $daemon_mode;
+               }
+
+               if (!function_exists('pcntl_fork')) {
+                       self::$daemon_mode = false;
+                       return false;
+               }
+
+               $pidfile = DI::config()->get('system', 'pidfile');
+               if (empty($pidfile)) {
+                       // No pid file, no daemon
+                       self::$daemon_mode = false;
+                       return false;
+               }
+
+               if (!is_readable($pidfile)) {
+                       // No pid file. We assume that the daemon had been intentionally stopped.
+                       self::$daemon_mode = false;
+                       return false;
+               }
+
+               $pid = intval(file_get_contents($pidfile));
+               $running = posix_kill($pid, 0);
+
+               self::$daemon_mode = $running;
+               return $running;
+       }
+
        /**
         * Test if the daemon is running. If not, it will be started
         *
@@ -1480,12 +1629,12 @@ class Worker
         */
        private static function spawnDaemon()
        {
-               Logger::info('Starting new daemon process');
+               Logger::notice('Starting new daemon process');
                $command = 'bin/daemon.php';
                $a = DI::app();
                $process = new Core\Process(DI::logger(), DI::mode(), DI::config(), DI::modelProcess(), $a->getBasePath(), getmypid());
                $process->run($command, ['start']);
-               Logger::info('New daemon process started');
+               Logger::notice('New daemon process started');
        }
 
        /**