def test_logging_responses(self):
request_id = 'test-%s' % uuid4()
trans_logger.logger.debug('START')
def start_response(status, headers):
start_response.called = True
self.assertEquals(status, '200 OK')
self.assertEquals(headers, [('Content-Length', '13'),
('X-Request-Id', request_id)])
local_conf = {
'echo': 'True',
'level': 'WARNING',
}
_filter = trans_logger.filter_factory({}, **local_conf)
filtered_app = _filter(application)
req = Request.blank('/', headers={'x-request-id': request_id})
body_iter = filtered_app(req.environ, start_response)
self.assertEquals(start_response.called, True)
self.assertEquals(body_iter, ['hello, world\n'])
trans_logger.logger.info('FINISH')
expected = """
lunr:DEBUG:START
lunr:WARNING:REQUEST:
GET / HTTP/1.0\r
Host: localhost:80\r
X-Request-Id: %(request_id)s
lunr:INFO:- "GET /" 200 13
lunr:WARNING:RESPONSE:
200 OK
Content-Length: 13
X-Request-Id: %(request_id)s
hello, world
lunr:INFO:FINISH
""".lstrip() % {
'request_id': request_id
}
self.assertEquals(trans_logger.logger.pop_log_messages(), expected)
def test_not_logging_responses(self):
request_id = 'test-%s' % uuid4()
trans_logger.logger.debug('START')
def start_response(status, headers):
start_response.called = True
self.assertEquals(status, '200 OK')
self.assertEquals(headers, [('Content-Length', '13'),
('X-Request-Id', request_id)])
filtered_app = trans_logger.filter_factory({})(application)
req = Request.blank('/', headers={'x-request-id': request_id})
body_iter = filtered_app(req.environ, start_response)
self.assertEquals(start_response.called, True)
self.assertEquals(body_iter, ['hello, world\n'])
trans_logger.logger.info('FINISH')
expected = """
lunr:DEBUG:START
lunr:INFO:- "GET /" 200 13
lunr:INFO:FINISH
""".lstrip()
self.assertEquals(trans_logger.logger.pop_log_messages(), expected)
def test_not_logging_responses(self):
request_id = 'test-%s' % uuid4()
trans_logger.logger.debug('START')
def start_response(status, headers):
start_response.called = True
self.assertEquals(status, '200 OK')
self.assertEquals(headers, [('Content-Length', '13'),
('X-Request-Id', request_id)])
filtered_app = trans_logger.filter_factory({})(application)
req = Request.blank('/', headers={'x-request-id': request_id})
body_iter = filtered_app(req.environ, start_response)
self.assertEquals(start_response.called, True)
self.assertEquals(body_iter, ['hello, world\n'])
trans_logger.logger.info('FINISH')
expected = """
lunr:DEBUG:START
lunr:INFO:- "GET /" 200 13
lunr:INFO:FINISH
""".lstrip()
self.assertEquals(trans_logger.logger.pop_log_messages(), expected)
def test_logging_responses(self):
request_id = 'test-%s' % uuid4()
trans_logger.logger.debug('START')
def start_response(status, headers):
start_response.called = True
self.assertEquals(status, '200 OK')
self.assertEquals(headers, [('Content-Length', '13'),
('X-Request-Id', request_id)])
local_conf = {
'echo': 'True',
'level': 'WARNING',
}
_filter = trans_logger.filter_factory({}, **local_conf)
filtered_app = _filter(application)
req = Request.blank('/', headers={'x-request-id': request_id})
body_iter = filtered_app(req.environ, start_response)
self.assertEquals(start_response.called, True)
self.assertEquals(body_iter, ['hello, world\n'])
trans_logger.logger.info('FINISH')
expected = """
lunr:DEBUG:START
lunr:WARNING:REQUEST:
GET / HTTP/1.0\r
Host: localhost:80\r
X-Request-Id: %(request_id)s
lunr:INFO:- "GET /" 200 13
lunr:WARNING:RESPONSE:
200 OK
Content-Length: 13
X-Request-Id: %(request_id)s
hello, world
lunr:INFO:FINISH
""".lstrip() % {'request_id': request_id}
self.assertEquals(trans_logger.logger.pop_log_messages(), expected)
