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)