Projet

Général

Profil

0001-add-integrated-log-system-14191.patch

Josué Kouka, 19 janvier 2017 16:37

Télécharger (14,6 ko)

Voir les différences:

Subject: [PATCH] add integrated log system (#14191)

 passerelle/base/migrations/0003_resourcelog.py     | 31 +++++++++
 passerelle/base/models.py                          | 81 ++++++++++++++++++++--
 passerelle/base/templatetags/passerelle.py         | 25 ++++++-
 .../passerelle/includes/resource-logs-table.html   | 50 +++++++++++++
 .../templates/passerelle/manage/service_view.html  | 11 +++
 passerelle/views.py                                |  1 +
 tests/test_generic_endpoint.py                     | 72 ++++++++++++++++++-
 7 files changed, 264 insertions(+), 7 deletions(-)
 create mode 100644 passerelle/base/migrations/0003_resourcelog.py
 create mode 100644 passerelle/templates/passerelle/includes/resource-logs-table.html
passerelle/base/migrations/0003_resourcelog.py
1
# -*- coding: utf-8 -*-
2
from __future__ import unicode_literals
3

  
4
from django.db import migrations, models
5
import jsonfield.fields
6

  
7

  
8
class Migration(migrations.Migration):
9

  
10
    dependencies = [
11
        ('base', '0002_auto_20151009_0326'),
12
    ]
13

  
14
    operations = [
15
        migrations.CreateModel(
16
            name='ResourceLog',
17
            fields=[
18
                ('id', models.AutoField(verbose_name='ID', serialize=False, auto_created=True, primary_key=True)),
19
                ('timestamp', models.DateTimeField(auto_now_add=True)),
20
                ('appname', models.CharField(max_length=128, null=True, verbose_name=b'appname')),
21
                ('slug', models.CharField(max_length=128, null=True, verbose_name=b'slug')),
22
                ('loglevel', models.CharField(max_length=16, verbose_name=b'log level')),
23
                ('ipsource', models.GenericIPAddressField(null=True, verbose_name='IP Address', blank=True)),
24
                ('message', models.TextField(max_length=2048, verbose_name=b'message')),
25
                ('extra', jsonfield.fields.JSONField(default={}, verbose_name=b'extras')),
26
            ],
27
            options={
28
                'permissions': (('view_resourcelog', 'Can view resource logs'),),
29
            },
30
        ),
31
    ]
passerelle/base/models.py
6 6
from django.db import models
7 7
from django.db.models import Q
8 8
from django.utils.translation import ugettext_lazy as _
9
from django.utils.text import slugify
10 9

  
11 10
from django.contrib.contenttypes.models import ContentType
12 11
from django.contrib.contenttypes import fields
13 12

  
14 13
from model_utils.managers import InheritanceManager as ModelUtilsInheritanceManager
15 14

  
15
import jsonfield
16

  
16 17
import passerelle
17 18

  
18 19
KEYTYPE_CHOICES = (
......
97 98

  
98 99
    def __init__(self, *args, **kwargs):
99 100
        super(BaseResource, self).__init__(*args, **kwargs)
100
        self.logger = logging.getLogger('passerelle.resource.%s.%s' % (
101
            slugify(unicode(self.__class__.__name__)), self.slug)
102
        )
103
        self.logger.setLevel(getattr(logging, self.log_level))
101
        self.logger = ProxyLogger(self.log_level, self.get_connector_slug(), self.slug)
104 102

  
105 103
    def __unicode__(self):
106 104
        return self.title
......
166 164

  
167 165
    def __unicode__(self):
168 166
        return '%s (on %s <%s>) (for %s)' % (self.codename, self.resource_type, self.resource_pk, self.apiuser)
167

  
168

  
169
class ResourceLog(models.Model):
170
    timestamp = models.DateTimeField(auto_now_add=True)
171
    appname = models.CharField(max_length=128, verbose_name='appname', null=True)
172
    slug = models.CharField(max_length=128, verbose_name='slug', null=True)
173
    loglevel = models.CharField(max_length=16, verbose_name='log level')
174
    ipsource = models.GenericIPAddressField(blank=True, null=True, verbose_name=_('IP Address'))
175
    message = models.TextField(max_length=2048, verbose_name='message')
176
    extra = jsonfield.JSONField(verbose_name='extras', default={})
177

  
178
    class Meta:
179
        permissions = (
180
            ('view_resourcelog', 'Can view resource logs'),
181
        )
182

  
183
    def __unicode__(self):
184
        return '%s %s %s' % (self.timestamp, self.loglevel, self.connector)
185

  
186

  
187
class ProxyLogger(object):
188

  
189
    def __init__(self, level, appname=None, slug=None):
190
        self.appname = appname
191
        self.slug = slug
192
        if appname:
193
            logger_name = 'passerelle.resource.%s.%s' % (self.appname, self.slug)
194
        else:
195
            logger_name = 'passerelle.resource'
196

  
197
        self._logger = logging.getLogger(logger_name)
198
        self._logger.setLevel(level)
199

  
200
    def _log(self, levelname, message, *args, **kwargs):
201
        attr = {}
202
        attr['loglevel'] = levelname
203
        attr['message'] = message
204
        attr['appname'] = self.appname
205
        attr['slug'] = self.slug
206
        attr['extra'] = kwargs.get('extra', {})
207
        request = kwargs.pop('request', None)
208

  
209
        if getattr(request, 'META', None):
210
            if 'HTTP_X_FORWARDED_FOR' in request.META:
211
                ipsource = request.META.get('HTTP_X_FORWARDED_FOR', '').split(",")[0].strip()
212
            else:
213
                ipsource = request.META.get('REMOTE_ADDR')
214
        else:
215
            ipsource = None
216
        attr['ipsource'] = ipsource
217

  
218
        if self._logger.level <= getattr(logging, levelname):
219
            ResourceLog.objects.create(**attr)
220

  
221
        getattr(self._logger, levelname.lower())(message, *args, **kwargs)
222

  
223
    def debug(self, message, *args, **kwargs):
224
        self._log('DEBUG', message, *args, **kwargs)
225

  
226
    def info(self, message, *args, **kwargs):
227
        self._log('INFO', message, *args, **kwargs)
228

  
229
    def warning(self, message, *args, **kwargs):
230
        self._log('WARNING', message, *args, **kwargs)
231

  
232
    def critical(self, message, *args, **kwargs):
233
        self._log('CRITICAL', message, *args, **kwargs)
234

  
235
    def error(self, message, *args, **kwargs):
236
        self._log('ERROR', message, *args, **kwargs)
237

  
238
    def fatal(self, message, *args, **kwargs):
239
        self._log('FATAL', message, *args, **kwargs)
passerelle/base/templatetags/passerelle.py
3 3
from django import template
4 4
from django.contrib.contenttypes.models import ContentType
5 5
from django.contrib.auth import get_permission_codename
6
from django.core.paginator import Paginator, EmptyPage, PageNotAnInteger
6 7

  
7 8
from passerelle.utils import get_trusted_services
8
from ..models import AccessRight
9
from ..models import AccessRight, ResourceLog
9 10

  
10 11
register = template.Library()
11 12

  
......
22 23
    return context
23 24

  
24 25

  
26
@register.inclusion_tag('passerelle/includes/resource-logs-table.html', takes_context=True)
27
def resource_logs_table(context, resource):
28
    request = context.get('request')
29
    page = request.GET.get('page', 1)
30

  
31
    connector = resource.get_connector_slug()
32
    context['connector'] = connector
33
    context['slug'] = resource.slug
34
    qs = ResourceLog.objects.filter(appname=connector, slug=resource.slug).order_by('-timestamp')
35

  
36
    paginator = Paginator(qs, 10)
37
    try:
38
        logrecords = paginator.page(page)
39
    except PageNotAnInteger:
40
        logrecords = paginator.page(1)
41
    except (EmptyPage,):
42
        logrecords = paginator.page(paginator.num_pages)
43

  
44
    context['logrecords'] = logrecords
45
    return context
46

  
47

  
25 48
@register.filter
26 49
def can_edit(obj, user):
27 50
    return user.has_perm(get_permission_codename('change', obj._meta), obj=obj)
passerelle/templates/passerelle/includes/resource-logs-table.html
1
{% load i18n passerelle %}
2
{% load tz %}
3

  
4
{% block content %}
5
{% if logrecords %}
6
<table class="main">
7
    <thead>
8
        <th>Id</th>
9
        <th>{% trans 'Timestamp' %}</th>
10
        <th>{% trans 'Level' %}</th>
11
        <th>{% trans 'Ip Source' %}</th>
12
        <th>{% trans 'Message' %}</th>
13
    </thead>
14
    <tbody>
15
    {% for record in logrecords %}
16
    <tr>
17
        <td>{{ record.id }}</td>
18
        <td>{{ record.timestamp|localtime }}</td>
19
        <td>{{ record.loglevel }}</td>
20
        <td>{{ record.ipsource }}</td>
21
        <td>{{ record.message}}</td>
22
    </tr>
23
    {% endfor %}
24
    </tbody>
25
</table>
26

  
27
{% if logrecords.has_other_pages %}
28
<p class="paginator">
29
  {% if logrecords.has_previous %}
30
      <a href="?page={{ logrecords.previous_page_number }}#logs">&lt;&lt;</a>
31
  {% else %}
32
  <span>&lt;&lt;</span>
33
  {% endif %}
34
    &nbsp;
35
    <span class="current">
36
        {{ logrecords.number }} / {{ logrecords.paginator.num_pages }}
37
    </span>
38
     &nbsp;
39
    {% if logrecords.has_next %}
40
        <a href="?page={{ logrecords.next_page_number }}#logs">&gt;&gt;</a>
41
    {% else %}
42
        <span>&gt;&gt;</span>
43
    {% endif %}
44
        </div>
45
    {% endif %}
46

  
47
{% else %}
48
<p>{% trans 'No records found' %}</p>
49
{% endif %}
50
{% endblock %}
passerelle/templates/passerelle/manage/service_view.html
48 48
{% endif %}
49 49
</div>
50 50

  
51
{% if perms.base.view_resourcelog %}
52
<div id="logs">
53
    <h3>{% trans "Logs" %}</h3>
54
    {% block logs %}
55
        {% resource_logs_table resource=object %}
56
    {% endblock %}
57
</div>
58
{% endif %}
59

  
51 60
{% endblock %}
61

  
62

  
passerelle/views.py
228 228
        payload = request.body[:5000]
229 229
        connector.logger.debug('endpoint %s %s (%r) ' %
230 230
                               (request.method, url, payload),
231
                               request=request,
231 232
                               extra={
232 233
                                   'connector': connector_name,
233 234
                                   'connector_endpoint': endpoint_name,
tests/test_generic_endpoint.py
25 25

  
26 26
import utils
27 27

  
28
from passerelle.base.models import ResourceLog, ProxyLogger
28 29
from passerelle.contrib.mdel.models import MDEL
30
from passerelle.contrib.arcgis.models import Arcgis
29 31

  
30 32

  
31 33
@pytest.fixture
......
33 35
    return utils.setup_access_rights(MDEL.objects.create(slug='test'))
34 36

  
35 37

  
38
@pytest.fixture
39
def arcgis(db):
40
    return utils.setup_access_rights(Arcgis.objects.create(slug='test', log_level='DEBUG'))
41

  
42

  
36 43
DEMAND_STATUS = {
37 44
    'closed': True,
38 45
    'status': 'accepted',
......
57 64

  
58 65
    records = [record for record in caplog.records() if record.name == 'passerelle.resource.mdel.test']
59 66
    for record in records:
60
        assert record.module == 'views'
61 67
        assert record.levelname == 'DEBUG'
62 68
        assert record.connector == 'mdel'
63 69
        if record.connector_endpoint_method == 'POST':
......
67 73
            assert 'endpoint GET /mdel/test/status?demand_id=1-14-ILE-LA' in record.message
68 74
            assert record.connector_endpoint == 'status'
69 75
            assert record.connector_endpoint_url == '/mdel/test/status?demand_id=1-14-ILE-LA'
76

  
77

  
78
@mock.patch('passerelle.utils.LoggedRequest.get')
79
def test_proxy_logger(mocked_get, caplog, app, arcgis):
80
    payload = file(os.path.join(os.path.dirname(__file__), 'data', 'nancy_arcgis', 'sigresponse.json')).read()
81
    mocked_get.return_value = utils.FakedResponse(content=payload, status_code=200)
82

  
83
    # simple logger
84
    logger = ProxyLogger('DEBUG')
85
    logger.debug('this is a debug test')
86
    logger.info('this is an info test')
87

  
88
    assert ResourceLog.objects.count() == 2
89
    for log in ResourceLog.objects.all():
90
        if log.loglevel == 'DEBUG':
91
            assert log.message == 'this is a debug test'
92
        else:
93
            assert log.message == 'this is an info test'
94

  
95
    resp = app.get('/arcgis/test/district', {'lon': 6.172122, 'lat': 48.673836}, status=200)
96

  
97
    logger.debug('new token: %s (timeout %ss)', 'hfgjsfg=', 45)
98

  
99
    # Resource Custom DB Logger
100
    log = ResourceLog.objects.filter(appname='arcgis', slug='test').first()
101
    assert log.appname == 'arcgis'
102
    assert log.slug == 'test'
103
    assert log.loglevel == 'DEBUG'
104
    assert log.ipsource == '127.0.0.1'
105
    assert log.extra['connector'] == 'arcgis'
106
    assert log.extra['connector_endpoint'] == 'district'
107
    assert log.extra['connector_endpoint_method'] == 'GET'
108
    assert log.extra['connector_endpoint_url'] == '/arcgis/test/district?lat=48.673836&lon=6.172122'
109

  
110
    # Resource Generic Logger
111
    for record in caplog.records():
112
        if record.name != 'passerelle.resource.arcgis.test':
113
            continue
114
        assert record.levelno == 10
115
        assert record.levelname == 'DEBUG'
116
        assert record.name == 'passerelle.resource.arcgis.test'
117
        assert record.message == u"endpoint GET /arcgis/test/district?lat=48.673836&lon=6.172122 ('') "
118

  
119
    data = resp.json['data']
120
    assert data['id'] == 4
121
    assert data['text'] == 'HAUSSONVILLE / BLANDAN / MON DESERT / SAURUPT'
122

  
123
    # when changing log level
124
    ResourceLog.objects.all().delete()
125
    arcgis.log_level = 'INFO'
126
    arcgis.save()
127
    app.get('/arcgis/test/district', {'lon': 6.172122, 'lat': 48.673836}, status=200)
128
    assert ResourceLog.objects.count() == 0
129

  
130
    arcgis.logger.info('testing info log message')
131
    assert ResourceLog.objects.count() == 1
132
    log = ResourceLog.objects.first()
133
    assert log.loglevel == 'INFO'
134
    assert log.message == 'testing info log message'
135

  
136
    arcgis.logger.warning('first warning')
137
    assert ResourceLog.objects.count() == 2
138
    assert ResourceLog.objects.last().message == 'first warning'
139
    assert ResourceLog.objects.last().loglevel == 'WARNING'
70
-