2011-10-16 22:49:14 +04:00
|
|
|
<?php
|
2018-02-27 00:34:13 +03:00
|
|
|
declare(strict_types=1);
|
2011-10-16 22:49:14 +04:00
|
|
|
/**
|
2016-07-21 18:07:57 +03:00
|
|
|
* @copyright Copyright (c) 2016, ownCloud, Inc.
|
|
|
|
*
|
2017-11-06 17:56:42 +03:00
|
|
|
* @author Arthur Schiwon <blizzz@arthur-schiwon.de>
|
2015-03-26 13:44:34 +03:00
|
|
|
* @author Bart Visscher <bartv@thisnet.nl>
|
|
|
|
* @author Bernhard Posselt <dev@bernhard-posselt.com>
|
2016-07-21 18:07:57 +03:00
|
|
|
* @author Joas Schilling <coding@schilljs.com>
|
2017-11-06 17:56:42 +03:00
|
|
|
* @author Johannes Schlichenmaier <johannes@schlichenmaier.info>
|
|
|
|
* @author Juan Pablo Villafáñez <jvillafanez@solidgear.es>
|
2016-05-26 20:56:05 +03:00
|
|
|
* @author Lukas Reschke <lukas@statuscode.ch>
|
2015-03-26 13:44:34 +03:00
|
|
|
* @author Morris Jobke <hey@morrisjobke.de>
|
2015-10-05 21:54:56 +03:00
|
|
|
* @author Olivier Paroz <github@oparoz.com>
|
2016-07-21 19:13:36 +03:00
|
|
|
* @author Robin Appelman <robin@icewind.nl>
|
2015-03-26 13:44:34 +03:00
|
|
|
* @author Thomas Müller <thomas.mueller@tmit.eu>
|
2017-11-06 17:56:42 +03:00
|
|
|
* @author Thomas Pulzer <t.pulzer@kniel.de>
|
2015-03-26 13:44:34 +03:00
|
|
|
* @author Victor Dubiniuk <dubiniuk@owncloud.com>
|
|
|
|
*
|
|
|
|
* @license AGPL-3.0
|
|
|
|
*
|
|
|
|
* This code is free software: you can redistribute it and/or modify
|
|
|
|
* it under the terms of the GNU Affero General Public License, version 3,
|
|
|
|
* as published by the Free Software Foundation.
|
|
|
|
*
|
|
|
|
* This program is distributed in the hope that it will be useful,
|
|
|
|
* but WITHOUT ANY WARRANTY; without even the implied warranty of
|
|
|
|
* MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
|
|
|
|
* GNU Affero General Public License for more details.
|
|
|
|
*
|
|
|
|
* You should have received a copy of the GNU Affero General Public License, version 3,
|
|
|
|
* along with this program. If not, see <http://www.gnu.org/licenses/>
|
|
|
|
*
|
2011-10-16 22:49:14 +04:00
|
|
|
*/
|
2015-02-26 13:37:37 +03:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
namespace OC;
|
|
|
|
|
2019-05-09 15:06:44 +03:00
|
|
|
use function array_merge;
|
2015-04-27 21:06:24 +03:00
|
|
|
use InterfaSys\LogNormalizer\Normalizer;
|
|
|
|
|
2018-04-13 17:39:27 +03:00
|
|
|
use OC\Log\ExceptionSerializer;
|
2018-04-25 13:26:03 +03:00
|
|
|
use OCP\Log\IFileBased;
|
2018-04-25 03:27:43 +03:00
|
|
|
use OCP\Log\IWriter;
|
2017-11-12 17:28:04 +03:00
|
|
|
use OCP\ILogger;
|
|
|
|
use OCP\Support\CrashReport\IRegistry;
|
2014-05-07 03:55:06 +04:00
|
|
|
|
2011-10-16 22:49:14 +04:00
|
|
|
/**
|
2012-03-31 01:15:48 +04:00
|
|
|
* logging utilities
|
2011-10-16 22:49:14 +04:00
|
|
|
*
|
2013-03-10 00:00:48 +04:00
|
|
|
* This is a stand in, this should be replaced by a Psr\Log\LoggerInterface
|
|
|
|
* compatible logger. See https://github.com/php-fig/fig-standards/blob/master/accepted/PSR-3-logger-interface.md
|
|
|
|
* for the full interface specification.
|
|
|
|
*
|
|
|
|
* MonoLog is an example implementing this interface.
|
2011-10-16 22:49:14 +04:00
|
|
|
*/
|
2014-05-07 03:55:06 +04:00
|
|
|
class Log implements ILogger {
|
2014-05-12 15:32:03 +04:00
|
|
|
|
2018-04-25 03:27:43 +03:00
|
|
|
/** @var IWriter */
|
2014-05-07 03:55:06 +04:00
|
|
|
private $logger;
|
2016-01-11 14:25:28 +03:00
|
|
|
|
2018-04-25 15:57:08 +03:00
|
|
|
/** @var SystemConfig */
|
2015-04-30 13:43:58 +03:00
|
|
|
private $config;
|
|
|
|
|
|
|
|
/** @var boolean|null cache the result of the log condition check for the request */
|
|
|
|
private $logConditionSatisfied = null;
|
2016-01-11 14:25:28 +03:00
|
|
|
|
2015-04-27 21:06:24 +03:00
|
|
|
/** @var Normalizer */
|
|
|
|
private $normalizer;
|
2014-05-07 03:55:06 +04:00
|
|
|
|
2017-11-12 17:28:04 +03:00
|
|
|
/** @var IRegistry */
|
|
|
|
private $crashReporters;
|
|
|
|
|
2014-05-07 03:55:06 +04:00
|
|
|
/**
|
2018-04-25 03:27:43 +03:00
|
|
|
* @param IWriter $logger The logger that should be used
|
2018-04-25 15:57:08 +03:00
|
|
|
* @param SystemConfig $config the system config object
|
2017-11-12 17:28:04 +03:00
|
|
|
* @param Normalizer|null $normalizer
|
|
|
|
* @param IRegistry|null $registry
|
2014-05-07 03:55:06 +04:00
|
|
|
*/
|
2018-04-25 15:57:08 +03:00
|
|
|
public function __construct(IWriter $logger, SystemConfig $config = null, $normalizer = null, IRegistry $registry = null) {
|
2015-04-30 13:43:58 +03:00
|
|
|
// FIXME: Add this for backwards compatibility, should be fixed at some point probably
|
2018-03-22 17:52:46 +03:00
|
|
|
if ($config === null) {
|
2018-04-25 15:57:08 +03:00
|
|
|
$config = \OC::$server->getSystemConfig();
|
2015-04-30 13:43:58 +03:00
|
|
|
}
|
|
|
|
|
|
|
|
$this->config = $config;
|
2018-04-24 23:14:00 +03:00
|
|
|
$this->logger = $logger;
|
2015-04-27 21:06:24 +03:00
|
|
|
if ($normalizer === null) {
|
|
|
|
$this->normalizer = new Normalizer();
|
|
|
|
} else {
|
|
|
|
$this->normalizer = $normalizer;
|
|
|
|
}
|
2017-11-12 17:28:04 +03:00
|
|
|
$this->crashReporters = $registry;
|
2014-05-07 03:55:06 +04:00
|
|
|
}
|
|
|
|
|
2011-10-16 22:49:14 +04:00
|
|
|
/**
|
2013-03-10 00:00:48 +04:00
|
|
|
* System is unusable.
|
|
|
|
*
|
2011-10-16 22:49:14 +04:00
|
|
|
* @param string $message
|
2013-03-10 00:00:48 +04:00
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2011-10-16 22:49:14 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function emergency(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::FATAL, $message, $context);
|
2011-10-16 22:49:14 +04:00
|
|
|
}
|
2013-01-14 23:30:39 +04:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
/**
|
|
|
|
* Action must be taken immediately.
|
|
|
|
*
|
|
|
|
* Example: Entire website down, database unavailable, etc. This should
|
|
|
|
* trigger the SMS alerts and wake you up.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function alert(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::ERROR, $message, $context);
|
2012-09-12 23:30:04 +04:00
|
|
|
}
|
2013-01-14 23:30:39 +04:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
/**
|
|
|
|
* Critical conditions.
|
|
|
|
*
|
|
|
|
* Example: Application component unavailable, unexpected exception.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function critical(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::ERROR, $message, $context);
|
2012-09-26 15:38:06 +04:00
|
|
|
}
|
2012-09-12 23:30:04 +04:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
/**
|
|
|
|
* Runtime errors that do not require immediate action but should typically
|
|
|
|
* be logged and monitored.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function error(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::ERROR, $message, $context);
|
2013-03-10 00:00:48 +04:00
|
|
|
}
|
|
|
|
|
|
|
|
/**
|
|
|
|
* Exceptional occurrences that are not errors.
|
|
|
|
*
|
|
|
|
* Example: Use of deprecated APIs, poor use of an API, undesirable things
|
|
|
|
* that are not necessarily wrong.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function warning(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::WARN, $message, $context);
|
2013-03-10 00:00:48 +04:00
|
|
|
}
|
2012-09-12 23:30:04 +04:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
/**
|
|
|
|
* Normal but significant events.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function notice(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::INFO, $message, $context);
|
2013-03-10 00:00:48 +04:00
|
|
|
}
|
|
|
|
|
|
|
|
/**
|
|
|
|
* Interesting events.
|
|
|
|
*
|
|
|
|
* Example: User logs in, SQL logs.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function info(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::INFO, $message, $context);
|
2013-03-10 00:00:48 +04:00
|
|
|
}
|
|
|
|
|
|
|
|
/**
|
|
|
|
* Detailed debug information.
|
|
|
|
*
|
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 00:34:13 +03:00
|
|
|
public function debug(string $message, array $context = []) {
|
2018-04-25 16:22:28 +03:00
|
|
|
$this->log(ILogger::DEBUG, $message, $context);
|
2013-03-10 00:00:48 +04:00
|
|
|
}
|
|
|
|
|
2013-06-27 22:10:45 +04:00
|
|
|
|
2013-03-10 00:00:48 +04:00
|
|
|
/**
|
|
|
|
* Logs with an arbitrary level.
|
|
|
|
*
|
2018-02-27 12:45:35 +03:00
|
|
|
* @param int $level
|
2013-03-10 00:00:48 +04:00
|
|
|
* @param string $message
|
|
|
|
* @param array $context
|
2016-01-11 14:25:28 +03:00
|
|
|
* @return void
|
2013-03-10 00:00:48 +04:00
|
|
|
*/
|
2018-02-27 12:45:35 +03:00
|
|
|
public function log(int $level, string $message, array $context = []) {
|
2018-03-22 17:52:46 +03:00
|
|
|
$minLevel = $this->getLogLevel($context);
|
2015-04-30 13:43:58 +03:00
|
|
|
|
2015-04-27 21:06:24 +03:00
|
|
|
array_walk($context, [$this->normalizer, 'format']);
|
|
|
|
|
2018-03-22 17:52:46 +03:00
|
|
|
$app = $context['app'] ?? 'no app in context';
|
2015-04-30 13:43:58 +03:00
|
|
|
|
2014-05-12 12:54:09 +04:00
|
|
|
// interpolate $message as defined in PSR-3
|
2018-02-27 00:34:13 +03:00
|
|
|
$replace = [];
|
2014-05-12 12:54:09 +04:00
|
|
|
foreach ($context as $key => $val) {
|
|
|
|
$replace['{' . $key . '}'] = $val;
|
|
|
|
}
|
|
|
|
$message = strtr($message, $replace);
|
|
|
|
|
2019-09-19 13:20:34 +03:00
|
|
|
try {
|
|
|
|
if ($level >= $minLevel) {
|
|
|
|
$this->writeLog($app, $message, $level);
|
|
|
|
|
|
|
|
if ($this->crashReporters !== null) {
|
|
|
|
$messageContext = array_merge(
|
|
|
|
$context,
|
|
|
|
[
|
|
|
|
'level' => $level
|
|
|
|
]
|
|
|
|
);
|
|
|
|
$this->crashReporters->delegateMessage($message, $messageContext);
|
|
|
|
}
|
|
|
|
} else {
|
|
|
|
if ($this->crashReporters !== null) {
|
|
|
|
$this->crashReporters->delegateBreadcrumb($message, 'log', $context);
|
|
|
|
}
|
2019-05-09 15:06:44 +03:00
|
|
|
}
|
2019-09-19 13:20:34 +03:00
|
|
|
} catch (\Throwable $e) {
|
|
|
|
// make sure we dont hard crash if logging fails
|
2018-09-03 09:37:50 +03:00
|
|
|
}
|
2018-03-22 17:52:46 +03:00
|
|
|
}
|
|
|
|
|
|
|
|
private function getLogLevel($context) {
|
2018-07-30 15:57:19 +03:00
|
|
|
$logCondition = $this->config->getValue('log.condition', []);
|
|
|
|
|
2015-04-30 13:43:58 +03:00
|
|
|
/**
|
|
|
|
* check for a special log condition - this enables an increased log on
|
|
|
|
* a per request/user base
|
|
|
|
*/
|
2018-03-22 17:52:46 +03:00
|
|
|
if ($this->logConditionSatisfied === null) {
|
2015-04-30 13:43:58 +03:00
|
|
|
// default to false to just process this once per request
|
|
|
|
$this->logConditionSatisfied = false;
|
2018-03-22 17:52:46 +03:00
|
|
|
if (!empty($logCondition)) {
|
2015-04-30 13:43:58 +03:00
|
|
|
|
|
|
|
// check for secret token in the request
|
2018-03-22 17:52:46 +03:00
|
|
|
if (isset($logCondition['shared_secret'])) {
|
2015-04-30 13:43:58 +03:00
|
|
|
$request = \OC::$server->getRequest();
|
|
|
|
|
2018-05-30 21:03:41 +03:00
|
|
|
if ($request->getMethod() === 'PUT' &&
|
|
|
|
strpos($request->getHeader('Content-Type'), 'application/x-www-form-urlencoded') === false &&
|
|
|
|
strpos($request->getHeader('Content-Type'), 'application/json') === false) {
|
|
|
|
$logSecretRequest = '';
|
|
|
|
} else {
|
|
|
|
$logSecretRequest = $request->getParam('log_secret', '');
|
|
|
|
}
|
|
|
|
|
2015-04-30 13:43:58 +03:00
|
|
|
// if token is found in the request change set the log condition to satisfied
|
2018-05-30 21:03:41 +03:00
|
|
|
if ($request && hash_equals($logCondition['shared_secret'], $logSecretRequest)) {
|
2015-04-30 13:43:58 +03:00
|
|
|
$this->logConditionSatisfied = true;
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
|
|
|
// check for user
|
2018-03-22 17:52:46 +03:00
|
|
|
if (isset($logCondition['users'])) {
|
2015-04-30 13:43:58 +03:00
|
|
|
$user = \OC::$server->getUserSession()->getUser();
|
|
|
|
|
|
|
|
// if the user matches set the log condition to satisfied
|
2018-03-22 17:52:46 +03:00
|
|
|
if ($user !== null && in_array($user->getUID(), $logCondition['users'], true)) {
|
2015-04-30 13:43:58 +03:00
|
|
|
$this->logConditionSatisfied = true;
|
|
|
|
}
|
|
|
|
}
|
|
|
|
}
|
|
|
|
}
|
2015-04-30 13:06:52 +03:00
|
|
|
|
2015-04-30 13:43:58 +03:00
|
|
|
// if log condition is satisfied change the required log level to DEBUG
|
2018-03-22 17:52:46 +03:00
|
|
|
if ($this->logConditionSatisfied) {
|
2018-04-25 16:22:28 +03:00
|
|
|
return ILogger::DEBUG;
|
2015-04-30 13:43:58 +03:00
|
|
|
}
|
2015-04-30 13:06:52 +03:00
|
|
|
|
2018-03-22 17:52:46 +03:00
|
|
|
if (isset($context['app'])) {
|
|
|
|
$app = $context['app'];
|
|
|
|
|
|
|
|
/**
|
|
|
|
* check log condition based on the context of each log message
|
|
|
|
* once this is met -> change the required log level to debug
|
|
|
|
*/
|
|
|
|
if (!empty($logCondition)
|
|
|
|
&& isset($logCondition['apps'])
|
|
|
|
&& in_array($app, $logCondition['apps'], true)) {
|
2018-04-25 16:22:28 +03:00
|
|
|
return ILogger::DEBUG;
|
2018-03-22 17:52:46 +03:00
|
|
|
}
|
|
|
|
}
|
|
|
|
|
2018-04-25 15:57:08 +03:00
|
|
|
return min($this->config->getValue('loglevel', ILogger::WARN), ILogger::FATAL);
|
2018-03-22 17:52:46 +03:00
|
|
|
}
|
|
|
|
|
2015-09-24 18:01:31 +03:00
|
|
|
/**
|
|
|
|
* Logs an exception very detailed
|
|
|
|
*
|
2017-06-29 12:43:32 +03:00
|
|
|
* @param \Exception|\Throwable $exception
|
2015-09-24 18:01:31 +03:00
|
|
|
* @param array $context
|
|
|
|
* @return void
|
|
|
|
* @since 8.2.0
|
|
|
|
*/
|
2018-02-27 12:43:34 +03:00
|
|
|
public function logException(\Throwable $exception, array $context = []) {
|
2018-03-22 17:52:46 +03:00
|
|
|
$app = $context['app'] ?? 'no app in context';
|
2018-04-25 16:22:28 +03:00
|
|
|
$level = $context['level'] ?? ILogger::ERROR;
|
2018-03-22 17:52:46 +03:00
|
|
|
|
2018-04-13 17:39:27 +03:00
|
|
|
$serializer = new ExceptionSerializer();
|
|
|
|
$data = $serializer->serializeException($exception);
|
2018-04-10 11:30:48 +03:00
|
|
|
$data['CustomMessage'] = $context['message'] ?? '--';
|
2018-03-22 17:52:46 +03:00
|
|
|
|
|
|
|
$minLevel = $this->getLogLevel($context);
|
|
|
|
|
|
|
|
array_walk($context, [$this->normalizer, 'format']);
|
|
|
|
|
2019-09-19 13:20:34 +03:00
|
|
|
try {
|
|
|
|
if ($level >= $minLevel) {
|
|
|
|
if (!$this->logger instanceof IFileBased) {
|
2019-10-15 17:09:23 +03:00
|
|
|
$data = json_encode($data, JSON_PARTIAL_OUTPUT_ON_ERROR | JSON_UNESCAPED_SLASHES);
|
2019-09-19 13:20:34 +03:00
|
|
|
}
|
|
|
|
$this->writeLog($app, $data, $level);
|
2018-03-22 17:52:46 +03:00
|
|
|
}
|
|
|
|
|
2019-09-19 13:20:34 +03:00
|
|
|
$context['level'] = $level;
|
|
|
|
if (!is_null($this->crashReporters)) {
|
|
|
|
$this->crashReporters->delegateReport($exception, $context);
|
|
|
|
}
|
|
|
|
} catch (\Throwable $e) {
|
|
|
|
// make sure we dont hard crash if logging fails
|
2017-11-12 17:28:04 +03:00
|
|
|
}
|
2015-09-24 18:01:31 +03:00
|
|
|
}
|
2017-01-05 14:47:27 +03:00
|
|
|
|
2018-04-11 12:46:36 +03:00
|
|
|
/**
|
|
|
|
* @param string $app
|
|
|
|
* @param string|array $entry
|
|
|
|
* @param int $level
|
|
|
|
*/
|
|
|
|
protected function writeLog(string $app, $entry, int $level) {
|
2018-04-24 23:14:00 +03:00
|
|
|
$this->logger->write($app, $entry, $level);
|
2018-04-11 12:46:36 +03:00
|
|
|
}
|
|
|
|
|
2018-04-24 23:14:00 +03:00
|
|
|
public function getLogPath():string {
|
|
|
|
if($this->logger instanceof IFileBased) {
|
|
|
|
return $this->logger->getLogFilePath();
|
2017-01-05 14:47:27 +03:00
|
|
|
}
|
2018-04-24 23:14:00 +03:00
|
|
|
throw new \RuntimeException('Log implementation has no path');
|
2017-01-05 14:47:27 +03:00
|
|
|
}
|
2011-10-16 22:49:14 +04:00
|
|
|
}
|