1
|
|
|
<?php |
2
|
|
|
|
3
|
|
|
namespace Abacaphiliac\Doctrine; |
4
|
|
|
|
5
|
|
|
use Doctrine\DBAL\Logging\SQLLogger; |
6
|
|
|
use Psr\Log\LoggerInterface; |
7
|
|
|
use Psr\Log\LogLevel; |
8
|
|
|
|
9
|
|
|
class PsrSqlLogger implements SQLLogger |
10
|
|
|
{ |
11
|
|
|
/** @var LoggerInterface */ |
12
|
|
|
private $logger; |
13
|
|
|
|
14
|
|
|
/** @var float */ |
15
|
|
|
private $start; |
16
|
|
|
|
17
|
|
|
/** @var callable */ |
18
|
|
|
private $startQueryCallable; |
19
|
|
|
|
20
|
|
|
/** @var string */ |
21
|
|
|
private $queryId; |
22
|
|
|
|
23
|
|
|
/** @var string */ |
24
|
|
|
private $defaultLogLevel; |
25
|
|
|
|
26
|
|
|
/** @var array|null */ |
27
|
|
|
private $logLevelMapping; |
28
|
|
|
|
29
|
7 |
|
public function __construct(LoggerInterface $logger, string $defaultLogLevel = LogLevel::INFO, array $logLevelMapping = null) |
30
|
|
|
{ |
31
|
7 |
|
$this->logger = $logger; |
32
|
7 |
|
$this->defaultLogLevel = $defaultLogLevel; |
33
|
7 |
|
$this->logLevelMapping = $logLevelMapping; |
34
|
7 |
|
$this->startQueryCallable = $this->getStartQueryCallable($defaultLogLevel); |
35
|
7 |
|
} |
36
|
|
|
|
37
|
7 |
|
private function getStartQueryCallable(string $level) : callable |
38
|
|
|
{ |
39
|
7 |
|
$callable = $this->getLoggerCallable($level); |
40
|
|
|
|
41
|
7 |
|
if (!\is_callable($callable)) { |
42
|
1 |
|
throw new \InvalidArgumentException(sprintf( |
43
|
1 |
|
'%s::%s" is not callable', |
44
|
1 |
|
LoggerInterface::class, |
45
|
1 |
|
$this->defaultLogLevel |
46
|
|
|
)); |
47
|
|
|
} |
48
|
|
|
|
49
|
7 |
|
return $callable; |
50
|
|
|
} |
51
|
|
|
|
52
|
7 |
|
private function getLoggerCallable(string $level) : array |
53
|
|
|
{ |
54
|
7 |
|
return [$this->logger, $level]; |
55
|
|
|
} |
56
|
|
|
|
57
|
6 |
|
public function startQuery($sql, array $params = null, array $types = null) |
58
|
|
|
{ |
59
|
6 |
|
$this->queryId = \uniqid('', true); |
60
|
|
|
|
61
|
6 |
|
$this->start = \microtime(true); |
62
|
|
|
|
63
|
6 |
|
call_user_func($this->startQueryCallable, 'Query started', \array_merge( |
64
|
6 |
|
$this->getStartQueryContext($sql, $params, $types), |
65
|
|
|
[ |
66
|
6 |
|
'query_id' => $this->queryId, |
67
|
|
|
] |
68
|
|
|
)); |
69
|
6 |
|
} |
70
|
|
|
|
71
|
6 |
|
protected function getStartQueryContext($sql, array $params = null, array $types = null) |
72
|
|
|
{ |
73
|
|
|
return [ |
74
|
6 |
|
'sql' => $sql, |
75
|
6 |
|
'types' => $types, |
76
|
|
|
]; |
77
|
|
|
} |
78
|
|
|
|
79
|
4 |
|
public function stopQuery() |
80
|
|
|
{ |
81
|
4 |
|
$stop = \microtime(true); |
82
|
4 |
|
$durationInSeconds = $stop - $this->start; |
83
|
|
|
|
84
|
4 |
|
\call_user_func($this->getStopQueryCallable($durationInSeconds), 'Query finished', [ |
85
|
4 |
|
'query_id' => $this->queryId, |
86
|
4 |
|
'start' => $this->start, |
87
|
4 |
|
'stop' => $stop, |
88
|
4 |
|
'duration_μs' => $durationInSeconds, |
89
|
|
|
]); |
90
|
4 |
|
} |
91
|
|
|
|
92
|
4 |
|
private function getStopQueryCallable(float $durationInSeconds): callable |
93
|
|
|
{ |
94
|
4 |
|
return $this->getLoggerCallable($this->getApplicableLogLevel($durationInSeconds)); |
95
|
|
|
} |
96
|
|
|
|
97
|
4 |
|
private function getApplicableLogLevel(float $durationInSeconds): string |
98
|
|
|
{ |
99
|
4 |
|
return is_array($this->logLevelMapping) ? $this->determineApplicableLogLevel($durationInSeconds) : $this->defaultLogLevel; |
100
|
|
|
} |
101
|
|
|
|
102
|
1 |
|
private function determineApplicableLogLevel(float $durationInSeconds) : string |
103
|
|
|
{ |
104
|
1 |
|
$durationInMilliseconds = $durationInSeconds * 1000; |
105
|
1 |
|
$durations = array_values($this->logLevelMapping); //Acquire a common / non-associative array |
106
|
1 |
|
$durations[] = $durationInMilliseconds; //Append the incoming query duration in milliseconds to the array of duration thresholds |
107
|
|
|
|
108
|
1 |
|
asort($durations, SORT_NUMERIC); //Sort the array from low to high: the provided duration will end up somewhere between the thresholds |
109
|
1 |
|
$durations = array_values($durations); //A re-index is required after sorting |
110
|
|
|
|
111
|
1 |
|
$key = array_search($durationInMilliseconds, $durations, true); //Determine at which position the duration ended up after sorting |
112
|
|
|
|
113
|
1 |
|
$logLevels = array_keys($this->logLevelMapping); |
114
|
|
|
|
115
|
1 |
|
return $logLevels[$key - 1]; //Now take the "previous" key |
116
|
|
|
} |
117
|
|
|
} |
118
|
|
|
|