| 1 | // Partial Copyright Jerome Benoit. 2021. All Rights Reserved. |
| 2 | |
| 3 | import { PerformanceEntry, PerformanceObserver, performance } from 'perf_hooks'; |
| 4 | import type { URL } from 'url'; |
| 5 | import { parentPort } from 'worker_threads'; |
| 6 | |
| 7 | import { MessageChannelUtils } from '../charging-station/MessageChannelUtils'; |
| 8 | import { MessageType } from '../types/ocpp/MessageType'; |
| 9 | import type { IncomingRequestCommand, RequestCommand } from '../types/ocpp/Requests'; |
| 10 | import type Statistics from '../types/Statistics'; |
| 11 | // eslint-disable-next-line no-duplicate-imports |
| 12 | import type { StatisticsData, TimeSeries } from '../types/Statistics'; |
| 13 | import { CircularArray, DEFAULT_CIRCULAR_ARRAY_SIZE } from '../utils/CircularArray'; |
| 14 | import Configuration from '../utils/Configuration'; |
| 15 | import logger from '../utils/Logger'; |
| 16 | import Utils from '../utils/Utils'; |
| 17 | |
| 18 | export default class PerformanceStatistics { |
| 19 | private static readonly instances: Map<string, PerformanceStatistics> = new Map< |
| 20 | string, |
| 21 | PerformanceStatistics |
| 22 | >(); |
| 23 | |
| 24 | private readonly objId: string; |
| 25 | private readonly objName: string; |
| 26 | private performanceObserver: PerformanceObserver; |
| 27 | private readonly statistics: Statistics; |
| 28 | private displayInterval: NodeJS.Timeout; |
| 29 | |
| 30 | private constructor(objId: string, objName: string, uri: URL) { |
| 31 | this.objId = objId; |
| 32 | this.objName = objName; |
| 33 | this.initializePerformanceObserver(); |
| 34 | this.statistics = { |
| 35 | id: this.objId ?? 'Object id not specified', |
| 36 | name: this.objName ?? 'Object name not specified', |
| 37 | uri: uri.toString(), |
| 38 | createdAt: new Date(), |
| 39 | statisticsData: new Map<string, Partial<StatisticsData>>(), |
| 40 | }; |
| 41 | } |
| 42 | |
| 43 | public static getInstance( |
| 44 | objId: string, |
| 45 | objName: string, |
| 46 | uri: URL |
| 47 | ): PerformanceStatistics | undefined { |
| 48 | if (!PerformanceStatistics.instances.has(objId)) { |
| 49 | PerformanceStatistics.instances.set(objId, new PerformanceStatistics(objId, objName, uri)); |
| 50 | } |
| 51 | return PerformanceStatistics.instances.get(objId); |
| 52 | } |
| 53 | |
| 54 | public static beginMeasure(id: string): string { |
| 55 | const markId = `${id.charAt(0).toUpperCase() + id.slice(1)}~${Utils.generateUUID()}`; |
| 56 | performance.mark(markId); |
| 57 | return markId; |
| 58 | } |
| 59 | |
| 60 | public static endMeasure(name: string, markId: string): void { |
| 61 | performance.measure(name, markId); |
| 62 | performance.clearMarks(markId); |
| 63 | } |
| 64 | |
| 65 | public addRequestStatistic( |
| 66 | command: RequestCommand | IncomingRequestCommand, |
| 67 | messageType: MessageType |
| 68 | ): void { |
| 69 | switch (messageType) { |
| 70 | case MessageType.CALL_MESSAGE: |
| 71 | if ( |
| 72 | this.statistics.statisticsData.has(command) && |
| 73 | this.statistics.statisticsData.get(command)?.countRequest |
| 74 | ) { |
| 75 | this.statistics.statisticsData.get(command).countRequest++; |
| 76 | } else { |
| 77 | this.statistics.statisticsData.set( |
| 78 | command, |
| 79 | Object.assign({ countRequest: 1 }, this.statistics.statisticsData.get(command)) |
| 80 | ); |
| 81 | } |
| 82 | break; |
| 83 | case MessageType.CALL_RESULT_MESSAGE: |
| 84 | if ( |
| 85 | this.statistics.statisticsData.has(command) && |
| 86 | this.statistics.statisticsData.get(command)?.countResponse |
| 87 | ) { |
| 88 | this.statistics.statisticsData.get(command).countResponse++; |
| 89 | } else { |
| 90 | this.statistics.statisticsData.set( |
| 91 | command, |
| 92 | Object.assign({ countResponse: 1 }, this.statistics.statisticsData.get(command)) |
| 93 | ); |
| 94 | } |
| 95 | break; |
| 96 | case MessageType.CALL_ERROR_MESSAGE: |
| 97 | if ( |
| 98 | this.statistics.statisticsData.has(command) && |
| 99 | this.statistics.statisticsData.get(command)?.countError |
| 100 | ) { |
| 101 | this.statistics.statisticsData.get(command).countError++; |
| 102 | } else { |
| 103 | this.statistics.statisticsData.set( |
| 104 | command, |
| 105 | Object.assign({ countError: 1 }, this.statistics.statisticsData.get(command)) |
| 106 | ); |
| 107 | } |
| 108 | break; |
| 109 | default: |
| 110 | // eslint-disable-next-line @typescript-eslint/restrict-template-expressions |
| 111 | logger.error(`${this.logPrefix()} wrong message type ${messageType}`); |
| 112 | break; |
| 113 | } |
| 114 | } |
| 115 | |
| 116 | public start(): void { |
| 117 | this.startLogStatisticsInterval(); |
| 118 | if (Configuration.getPerformanceStorage().enabled) { |
| 119 | logger.info( |
| 120 | `${this.logPrefix()} storage enabled: type ${ |
| 121 | Configuration.getPerformanceStorage().type |
| 122 | }, uri: ${Configuration.getPerformanceStorage().uri}` |
| 123 | ); |
| 124 | } |
| 125 | } |
| 126 | |
| 127 | public stop(): void { |
| 128 | if (this.displayInterval) { |
| 129 | clearInterval(this.displayInterval); |
| 130 | } |
| 131 | performance.clearMarks(); |
| 132 | this.performanceObserver?.disconnect(); |
| 133 | } |
| 134 | |
| 135 | public restart(): void { |
| 136 | this.stop(); |
| 137 | this.start(); |
| 138 | } |
| 139 | |
| 140 | private initializePerformanceObserver(): void { |
| 141 | this.performanceObserver = new PerformanceObserver((list) => { |
| 142 | const lastPerformanceEntry = list.getEntries()[0]; |
| 143 | this.addPerformanceEntryToStatistics(lastPerformanceEntry); |
| 144 | logger.debug( |
| 145 | `${this.logPrefix()} '${lastPerformanceEntry.name}' performance entry: %j`, |
| 146 | lastPerformanceEntry |
| 147 | ); |
| 148 | }); |
| 149 | this.performanceObserver.observe({ entryTypes: ['measure'] }); |
| 150 | } |
| 151 | |
| 152 | private logStatistics(): void { |
| 153 | logger.info(this.logPrefix() + ' %j', this.statistics); |
| 154 | } |
| 155 | |
| 156 | private startLogStatisticsInterval(): void { |
| 157 | if (Configuration.getLogStatisticsInterval() > 0) { |
| 158 | this.displayInterval = setInterval(() => { |
| 159 | this.logStatistics(); |
| 160 | }, Configuration.getLogStatisticsInterval() * 1000); |
| 161 | logger.info( |
| 162 | this.logPrefix() + |
| 163 | ' logged every ' + |
| 164 | Utils.formatDurationSeconds(Configuration.getLogStatisticsInterval()) |
| 165 | ); |
| 166 | } else { |
| 167 | logger.info( |
| 168 | this.logPrefix() + |
| 169 | ' log interval is set to ' + |
| 170 | Configuration.getLogStatisticsInterval().toString() + |
| 171 | '. Not logging statistics' |
| 172 | ); |
| 173 | } |
| 174 | } |
| 175 | |
| 176 | private median(dataSet: number[]): number { |
| 177 | if (Array.isArray(dataSet) && dataSet.length === 1) { |
| 178 | return dataSet[0]; |
| 179 | } |
| 180 | const sortedDataSet = dataSet.slice().sort((a, b) => a - b); |
| 181 | const middleIndex = Math.floor(sortedDataSet.length / 2); |
| 182 | if (sortedDataSet.length % 2) { |
| 183 | return sortedDataSet[middleIndex / 2]; |
| 184 | } |
| 185 | return (sortedDataSet[middleIndex - 1] + sortedDataSet[middleIndex]) / 2; |
| 186 | } |
| 187 | |
| 188 | // TODO: use order statistics tree https://en.wikipedia.org/wiki/Order_statistic_tree |
| 189 | private percentile(dataSet: number[], percentile: number): number { |
| 190 | if (percentile < 0 && percentile > 100) { |
| 191 | throw new RangeError('Percentile is not between 0 and 100'); |
| 192 | } |
| 193 | if (Utils.isEmptyArray(dataSet)) { |
| 194 | return 0; |
| 195 | } |
| 196 | const sortedDataSet = dataSet.slice().sort((a, b) => a - b); |
| 197 | if (percentile === 0) { |
| 198 | return sortedDataSet[0]; |
| 199 | } |
| 200 | if (percentile === 100) { |
| 201 | return sortedDataSet[sortedDataSet.length - 1]; |
| 202 | } |
| 203 | const percentileIndex = (percentile / 100) * sortedDataSet.length - 1; |
| 204 | if (Number.isInteger(percentileIndex)) { |
| 205 | return (sortedDataSet[percentileIndex] + sortedDataSet[percentileIndex + 1]) / 2; |
| 206 | } |
| 207 | return sortedDataSet[Math.round(percentileIndex)]; |
| 208 | } |
| 209 | |
| 210 | private stdDeviation(dataSet: number[]): number { |
| 211 | let totalDataSet = 0; |
| 212 | for (const data of dataSet) { |
| 213 | totalDataSet += data; |
| 214 | } |
| 215 | const dataSetMean = totalDataSet / dataSet.length; |
| 216 | let totalGeometricDeviation = 0; |
| 217 | for (const data of dataSet) { |
| 218 | const deviation = data - dataSetMean; |
| 219 | totalGeometricDeviation += deviation * deviation; |
| 220 | } |
| 221 | return Math.sqrt(totalGeometricDeviation / dataSet.length); |
| 222 | } |
| 223 | |
| 224 | private addPerformanceEntryToStatistics(entry: PerformanceEntry): void { |
| 225 | let entryName = entry.name; |
| 226 | // Rename entry name |
| 227 | const MAP_NAME: Record<string, string> = {}; |
| 228 | if (MAP_NAME[entryName]) { |
| 229 | entryName = MAP_NAME[entryName]; |
| 230 | } |
| 231 | // Initialize command statistics |
| 232 | if (!this.statistics.statisticsData.has(entryName)) { |
| 233 | this.statistics.statisticsData.set(entryName, {}); |
| 234 | } |
| 235 | // Update current statistics |
| 236 | this.statistics.updatedAt = new Date(); |
| 237 | this.statistics.statisticsData.get(entryName).countTimeMeasurement = |
| 238 | this.statistics.statisticsData.get(entryName)?.countTimeMeasurement |
| 239 | ? this.statistics.statisticsData.get(entryName).countTimeMeasurement + 1 |
| 240 | : 1; |
| 241 | this.statistics.statisticsData.get(entryName).currentTimeMeasurement = entry.duration; |
| 242 | this.statistics.statisticsData.get(entryName).minTimeMeasurement = |
| 243 | this.statistics.statisticsData.get(entryName)?.minTimeMeasurement |
| 244 | ? this.statistics.statisticsData.get(entryName).minTimeMeasurement > entry.duration |
| 245 | ? entry.duration |
| 246 | : this.statistics.statisticsData.get(entryName).minTimeMeasurement |
| 247 | : entry.duration; |
| 248 | this.statistics.statisticsData.get(entryName).maxTimeMeasurement = |
| 249 | this.statistics.statisticsData.get(entryName)?.maxTimeMeasurement |
| 250 | ? this.statistics.statisticsData.get(entryName).maxTimeMeasurement < entry.duration |
| 251 | ? entry.duration |
| 252 | : this.statistics.statisticsData.get(entryName).maxTimeMeasurement |
| 253 | : entry.duration; |
| 254 | this.statistics.statisticsData.get(entryName).totalTimeMeasurement = |
| 255 | this.statistics.statisticsData.get(entryName)?.totalTimeMeasurement |
| 256 | ? this.statistics.statisticsData.get(entryName).totalTimeMeasurement + entry.duration |
| 257 | : entry.duration; |
| 258 | this.statistics.statisticsData.get(entryName).avgTimeMeasurement = |
| 259 | this.statistics.statisticsData.get(entryName).totalTimeMeasurement / |
| 260 | this.statistics.statisticsData.get(entryName).countTimeMeasurement; |
| 261 | Array.isArray(this.statistics.statisticsData.get(entryName).timeMeasurementSeries) |
| 262 | ? this.statistics.statisticsData |
| 263 | .get(entryName) |
| 264 | .timeMeasurementSeries.push({ timestamp: entry.startTime, value: entry.duration }) |
| 265 | : (this.statistics.statisticsData.get(entryName).timeMeasurementSeries = |
| 266 | new CircularArray<TimeSeries>(DEFAULT_CIRCULAR_ARRAY_SIZE, { |
| 267 | timestamp: entry.startTime, |
| 268 | value: entry.duration, |
| 269 | })); |
| 270 | this.statistics.statisticsData.get(entryName).medTimeMeasurement = this.median( |
| 271 | this.extractTimeSeriesValues( |
| 272 | this.statistics.statisticsData.get(entryName).timeMeasurementSeries |
| 273 | ) |
| 274 | ); |
| 275 | this.statistics.statisticsData.get(entryName).ninetyFiveThPercentileTimeMeasurement = |
| 276 | this.percentile( |
| 277 | this.extractTimeSeriesValues( |
| 278 | this.statistics.statisticsData.get(entryName).timeMeasurementSeries |
| 279 | ), |
| 280 | 95 |
| 281 | ); |
| 282 | this.statistics.statisticsData.get(entryName).stdDevTimeMeasurement = this.stdDeviation( |
| 283 | this.extractTimeSeriesValues( |
| 284 | this.statistics.statisticsData.get(entryName).timeMeasurementSeries |
| 285 | ) |
| 286 | ); |
| 287 | if (Configuration.getPerformanceStorage().enabled) { |
| 288 | parentPort.postMessage( |
| 289 | MessageChannelUtils.buildPerformanceStatisticsMessage(this.statistics) |
| 290 | ); |
| 291 | } |
| 292 | } |
| 293 | |
| 294 | private extractTimeSeriesValues(timeSeries: CircularArray<TimeSeries>): number[] { |
| 295 | return timeSeries.map((timeSeriesItem) => timeSeriesItem.value); |
| 296 | } |
| 297 | |
| 298 | private logPrefix(): string { |
| 299 | return Utils.logPrefix(` ${this.objName} | Performance statistics`); |
| 300 | } |
| 301 | } |