2015-02-24 19:03:39 +01:00
|
|
|
# -*- coding: utf-8 -*-
|
2016-01-07 05:26:29 +01:00
|
|
|
# Copyright 2015, 2016 OpenMarket Ltd
|
2015-02-24 19:03:39 +01:00
|
|
|
#
|
|
|
|
# Licensed under the Apache License, Version 2.0 (the "License");
|
|
|
|
# you may not use this file except in compliance with the License.
|
|
|
|
# You may obtain a copy of the License at
|
|
|
|
#
|
|
|
|
# http://www.apache.org/licenses/LICENSE-2.0
|
|
|
|
#
|
|
|
|
# Unless required by applicable law or agreed to in writing, software
|
|
|
|
# distributed under the License is distributed on an "AS IS" BASIS,
|
|
|
|
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
|
|
|
|
# See the License for the specific language governing permissions and
|
|
|
|
# limitations under the License.
|
|
|
|
|
2015-03-05 17:15:21 +01:00
|
|
|
# Because otherwise 'resource' collides with synapse.metrics.resource
|
|
|
|
from __future__ import absolute_import
|
|
|
|
|
2015-03-04 18:13:09 +01:00
|
|
|
import logging
|
2015-09-07 17:45:48 +02:00
|
|
|
from resource import getrusage, RUSAGE_SELF
|
2015-08-13 12:38:59 +02:00
|
|
|
import functools
|
2015-04-01 20:04:55 +02:00
|
|
|
import os
|
|
|
|
import stat
|
2015-08-13 12:38:59 +02:00
|
|
|
import time
|
|
|
|
|
|
|
|
from twisted.internet import reactor
|
2015-03-04 18:13:09 +01:00
|
|
|
|
2015-03-10 16:21:03 +01:00
|
|
|
from .metric import (
|
|
|
|
CounterMetric, CallbackMetric, DistributionMetric, CacheMetric
|
|
|
|
)
|
2015-02-24 19:03:39 +01:00
|
|
|
|
|
|
|
|
2015-03-04 18:13:09 +01:00
|
|
|
logger = logging.getLogger(__name__)
|
|
|
|
|
|
|
|
|
2016-06-02 12:29:44 +02:00
|
|
|
all_metrics = []
|
2015-02-24 19:03:39 +01:00
|
|
|
|
|
|
|
|
|
|
|
class Metrics(object):
|
|
|
|
""" A single Metrics object gives a (mutable) slice view of the all_metrics
|
|
|
|
dict, allowing callers to easily register new metrics that are namespaced
|
|
|
|
nicely."""
|
|
|
|
|
|
|
|
def __init__(self, name):
|
|
|
|
self.name_prefix = name
|
|
|
|
|
2015-03-04 20:43:46 +01:00
|
|
|
def _register(self, metric_class, name, *args, **kwargs):
|
2015-03-06 17:18:21 +01:00
|
|
|
full_name = "%s_%s" % (self.name_prefix, name)
|
2015-03-04 17:46:44 +01:00
|
|
|
|
2015-03-04 20:43:46 +01:00
|
|
|
metric = metric_class(full_name, *args, **kwargs)
|
2015-03-04 17:46:44 +01:00
|
|
|
|
2016-06-02 12:29:44 +02:00
|
|
|
all_metrics.append(metric)
|
2015-03-04 17:46:44 +01:00
|
|
|
return metric
|
|
|
|
|
2015-03-04 20:43:46 +01:00
|
|
|
def register_counter(self, *args, **kwargs):
|
|
|
|
return self._register(CounterMetric, *args, **kwargs)
|
2015-03-04 16:47:23 +01:00
|
|
|
|
2015-03-04 20:43:46 +01:00
|
|
|
def register_callback(self, *args, **kwargs):
|
|
|
|
return self._register(CallbackMetric, *args, **kwargs)
|
2015-03-04 16:47:23 +01:00
|
|
|
|
2015-03-10 16:21:03 +01:00
|
|
|
def register_distribution(self, *args, **kwargs):
|
|
|
|
return self._register(DistributionMetric, *args, **kwargs)
|
2015-03-04 16:47:23 +01:00
|
|
|
|
2015-03-04 20:43:46 +01:00
|
|
|
def register_cache(self, *args, **kwargs):
|
|
|
|
return self._register(CacheMetric, *args, **kwargs)
|
2015-03-04 16:47:23 +01:00
|
|
|
|
2015-02-24 19:03:39 +01:00
|
|
|
|
2015-03-06 17:18:21 +01:00
|
|
|
def get_metrics_for(pkg_name):
|
2015-02-24 19:03:39 +01:00
|
|
|
""" Returns a Metrics instance for conveniently creating metrics
|
|
|
|
namespaced with the given name prefix. """
|
2015-03-06 17:18:21 +01:00
|
|
|
|
|
|
|
# Convert a "package.name" to "package_name" because Prometheus doesn't
|
|
|
|
# let us use . in metric names
|
|
|
|
return Metrics(pkg_name.replace(".", "_"))
|
2015-02-24 19:03:39 +01:00
|
|
|
|
|
|
|
|
|
|
|
def render_all():
|
|
|
|
strs = []
|
|
|
|
|
2015-03-05 17:15:21 +01:00
|
|
|
# TODO(paul): Internal hack
|
|
|
|
update_resource_metrics()
|
|
|
|
|
2016-06-02 12:29:44 +02:00
|
|
|
for metric in all_metrics:
|
2015-03-04 18:13:09 +01:00
|
|
|
try:
|
2016-06-02 12:29:44 +02:00
|
|
|
strs += metric.render()
|
2015-03-12 17:45:05 +01:00
|
|
|
except Exception:
|
2016-06-02 12:29:44 +02:00
|
|
|
strs += ["# FAILED to render"]
|
|
|
|
logger.exception("Failed to render metric")
|
2015-02-24 19:03:39 +01:00
|
|
|
|
2015-03-12 17:24:38 +01:00
|
|
|
strs.append("") # to generate a final CRLF
|
2015-03-06 20:08:47 +01:00
|
|
|
|
2015-02-24 19:03:39 +01:00
|
|
|
return "\n".join(strs)
|
2015-03-05 17:15:21 +01:00
|
|
|
|
|
|
|
|
|
|
|
# Now register some standard process-wide state metrics, to give indications of
|
|
|
|
# process resource usage
|
|
|
|
|
|
|
|
rusage = None
|
|
|
|
|
2015-03-12 17:24:38 +01:00
|
|
|
|
2015-03-05 17:15:21 +01:00
|
|
|
def update_resource_metrics():
|
|
|
|
global rusage
|
|
|
|
rusage = getrusage(RUSAGE_SELF)
|
|
|
|
|
|
|
|
resource_metrics = get_metrics_for("process.resource")
|
|
|
|
|
|
|
|
# msecs
|
|
|
|
resource_metrics.register_callback("utime", lambda: rusage.ru_utime * 1000)
|
|
|
|
resource_metrics.register_callback("stime", lambda: rusage.ru_stime * 1000)
|
|
|
|
|
2015-09-07 17:45:48 +02:00
|
|
|
# kilobytes
|
|
|
|
resource_metrics.register_callback("maxrss", lambda: rusage.ru_maxrss * 1024)
|
2015-04-01 20:04:55 +02:00
|
|
|
|
|
|
|
TYPES = {
|
|
|
|
stat.S_IFSOCK: "SOCK",
|
|
|
|
stat.S_IFLNK: "LNK",
|
|
|
|
stat.S_IFREG: "REG",
|
|
|
|
stat.S_IFBLK: "BLK",
|
|
|
|
stat.S_IFDIR: "DIR",
|
|
|
|
stat.S_IFCHR: "CHR",
|
|
|
|
stat.S_IFIFO: "FIFO",
|
|
|
|
}
|
|
|
|
|
2015-04-01 20:17:38 +02:00
|
|
|
|
2015-04-01 20:04:55 +02:00
|
|
|
def _process_fds():
|
|
|
|
counts = {(k,): 0 for k in TYPES.values()}
|
|
|
|
counts[("other",)] = 0
|
|
|
|
|
2015-09-07 17:45:55 +02:00
|
|
|
# Not every OS will have a /proc/self/fd directory
|
|
|
|
if not os.path.exists("/proc/self/fd"):
|
|
|
|
return counts
|
|
|
|
|
2015-04-01 20:04:55 +02:00
|
|
|
for fd in os.listdir("/proc/self/fd"):
|
|
|
|
try:
|
|
|
|
s = os.stat("/proc/self/fd/%s" % (fd))
|
|
|
|
fmt = stat.S_IFMT(s.st_mode)
|
|
|
|
if fmt in TYPES:
|
|
|
|
t = TYPES[fmt]
|
|
|
|
else:
|
|
|
|
t = "other"
|
|
|
|
|
|
|
|
counts[(t,)] += 1
|
|
|
|
except OSError:
|
|
|
|
# the dirh itself used by listdir() is usually missing by now
|
|
|
|
pass
|
|
|
|
|
|
|
|
return counts
|
|
|
|
|
|
|
|
get_metrics_for("process").register_callback("fds", _process_fds, labels=["type"])
|
2015-08-13 12:38:59 +02:00
|
|
|
|
|
|
|
reactor_metrics = get_metrics_for("reactor")
|
|
|
|
tick_time = reactor_metrics.register_distribution("tick_time")
|
|
|
|
pending_calls_metric = reactor_metrics.register_distribution("pending_calls")
|
|
|
|
|
|
|
|
|
|
|
|
def runUntilCurrentTimer(func):
|
|
|
|
|
|
|
|
@functools.wraps(func)
|
|
|
|
def f(*args, **kwargs):
|
2015-08-14 16:42:52 +02:00
|
|
|
now = reactor.seconds()
|
|
|
|
num_pending = 0
|
|
|
|
|
|
|
|
# _newTimedCalls is one long list of *all* pending calls. Below loop
|
|
|
|
# is based off of impl of reactor.runUntilCurrent
|
2015-08-18 12:47:00 +02:00
|
|
|
for delayed_call in reactor._newTimedCalls:
|
|
|
|
if delayed_call.time > now:
|
2015-08-14 16:42:52 +02:00
|
|
|
break
|
|
|
|
|
2015-08-18 12:47:00 +02:00
|
|
|
if delayed_call.delayed_time > 0:
|
2015-08-14 16:42:52 +02:00
|
|
|
continue
|
|
|
|
|
|
|
|
num_pending += 1
|
|
|
|
|
|
|
|
num_pending += len(reactor.threadCallQueue)
|
|
|
|
|
2015-08-13 12:41:57 +02:00
|
|
|
start = time.time() * 1000
|
2015-08-13 12:38:59 +02:00
|
|
|
ret = func(*args, **kwargs)
|
|
|
|
end = time.time() * 1000
|
|
|
|
tick_time.inc_by(end - start)
|
2015-08-14 16:42:52 +02:00
|
|
|
pending_calls_metric.inc_by(num_pending)
|
2015-08-13 12:38:59 +02:00
|
|
|
return ret
|
|
|
|
|
|
|
|
return f
|
|
|
|
|
|
|
|
|
2015-08-18 12:51:08 +02:00
|
|
|
try:
|
|
|
|
# Ensure the reactor has all the attributes we expect
|
|
|
|
reactor.runUntilCurrent
|
|
|
|
reactor._newTimedCalls
|
|
|
|
reactor.threadCallQueue
|
|
|
|
|
2015-08-13 12:38:59 +02:00
|
|
|
# runUntilCurrent is called when we have pending calls. It is called once
|
|
|
|
# per iteratation after fd polling.
|
|
|
|
reactor.runUntilCurrent = runUntilCurrentTimer(reactor.runUntilCurrent)
|
2015-08-18 12:51:08 +02:00
|
|
|
except AttributeError:
|
|
|
|
pass
|