import cStringIO import json import logging import sys from nova import context from nova import flags from nova import log from nova.notifier import api as notifier from nova import test FLAGS = flags.FLAGS flags.DECLARE('list_notifier_drivers', 'nova.notifier.list_notifier') def _fake_context(): return context.RequestContext(1, 1) class LoggerTestCase(test.TestCase): def setUp(self): super(LoggerTestCase, self).setUp() self.log = log.getLogger() def test_handlers_have_nova_formatter(self): formatters = [] for h in self.log.logger.handlers: f = h.formatter if isinstance(f, log.LegacyNovaFormatter): formatters.append(f) self.assert_(formatters) self.assertEqual(len(formatters), len(self.log.logger.handlers)) def test_handles_context_kwarg(self): self.log.info("foo", context=_fake_context()) self.assert_(True) # didn't raise exception def test_audit_handles_context_arg(self): self.log.audit("foo", context=_fake_context()) self.assert_(True) # didn't raise exception def test_will_be_verbose_if_verbose_flag_set(self): self.flags(verbose=True) log.setup() self.assertEqual(logging.DEBUG, self.log.logger.getEffectiveLevel()) def test_will_not_be_verbose_if_verbose_flag_not_set(self): self.flags(verbose=False) log.setup() self.assertEqual(logging.INFO, self.log.logger.getEffectiveLevel()) def test_no_logging_via_module(self): for func in ('critical', 'error', 'exception', 'warning', 'warn', 'info', 'debug', 'log', 'audit'): self.assertRaises(AttributeError, getattr, log, func) class LogHandlerTestCase(test.TestCase): def test_log_path_logdir(self): self.flags(logdir='/some/path', logfile=None) self.assertEquals(log._get_log_file_path(binary='foo-bar'), '/some/path/foo-bar.log') def test_log_path_logfile(self): self.flags(logfile='/some/path/foo-bar.log') self.assertEquals(log._get_log_file_path(binary='foo-bar'), '/some/path/foo-bar.log') def test_log_path_none(self): self.flags(logdir=None, logfile=None) self.assertTrue(log._get_log_file_path(binary='foo-bar') is None) def test_log_path_logfile_overrides_logdir(self): self.flags(logdir='/some/other/path', logfile='/some/path/foo-bar.log') self.assertEquals(log._get_log_file_path(binary='foo-bar'), '/some/path/foo-bar.log') class PublishErrorsHandlerTestCase(test.TestCase): """Tests for nova.log.PublishErrorsHandler""" def setUp(self): super(PublishErrorsHandlerTestCase, self).setUp() self.handler = logging.Handler() self.publiserrorshandler = log.PublishErrorsHandler(logging.ERROR) def test_emit_cfg_list_notifier_drivers_in_flags(self): self.stub_flg = False def fake_notifier(*args, **kwargs): self.stub_flg = True self.stubs.Set(notifier, 'notify', fake_notifier) logrecord = logging.LogRecord('name', 'WARN', '/tmp', 1, 'Message', None, None) self.publiserrorshandler.emit(logrecord) self.assertTrue(self.stub_flg) def test_emit_cfg_log_notifier_in_list_notifier_drivers(self): self.flags(list_notifier_drivers=['nova.notifier.rabbit_notifier', 'nova.notifier.log_notifier']) self.stub_flg = True def fake_notifier(*args, **kwargs): self.stub_flg = False self.stubs.Set(notifier, 'notify', fake_notifier) logrecord = logging.LogRecord('name', 'WARN', '/tmp', 1, 'Message', None, None) self.publiserrorshandler.emit(logrecord) self.assertTrue(self.stub_flg) class NovaFormatterTestCase(test.TestCase): def setUp(self): super(NovaFormatterTestCase, self).setUp() self.flags(logging_context_format_string="HAS CONTEXT " "[%(request_id)s]: " "%(message)s", logging_default_format_string="NOCTXT: %(message)s", logging_debug_format_suffix="--DBG") self.log = log.getLogger() self.stream = cStringIO.StringIO() self.handler = logging.StreamHandler(self.stream) self.handler.setFormatter(log.LegacyNovaFormatter()) self.log.logger.addHandler(self.handler) self.level = self.log.logger.getEffectiveLevel() self.log.logger.setLevel(logging.DEBUG) def tearDown(self): self.log.logger.setLevel(self.level) self.log.logger.removeHandler(self.handler) super(NovaFormatterTestCase, self).tearDown() def test_uncontextualized_log(self): self.log.info("foo") self.assertEqual("NOCTXT: foo\n", self.stream.getvalue()) def test_contextualized_log(self): ctxt = _fake_context() self.log.info("bar", context=ctxt) expected = "HAS CONTEXT [%s]: bar\n" % ctxt.request_id self.assertEqual(expected, self.stream.getvalue()) def test_debugging_log(self): self.log.debug("baz") self.assertEqual("NOCTXT: baz --DBG\n", self.stream.getvalue()) class NovaLoggerTestCase(test.TestCase): def setUp(self): super(NovaLoggerTestCase, self).setUp() levels = FLAGS.default_log_levels levels.append("nova-test=AUDIT") self.flags(default_log_levels=levels, verbose=True) log.setup() self.log = log.getLogger('nova-test') def test_has_level_from_flags(self): self.assertEqual(logging.AUDIT, self.log.logger.getEffectiveLevel()) def test_child_log_has_level_of_parent_flag(self): l = log.getLogger('nova-test.foo') self.assertEqual(logging.AUDIT, l.logger.getEffectiveLevel()) class JSONFormatterTestCase(test.TestCase): def setUp(self): super(JSONFormatterTestCase, self).setUp() self.log = log.getLogger('test-json') self.stream = cStringIO.StringIO() handler = logging.StreamHandler(self.stream) handler.setFormatter(log.JSONFormatter()) self.log.logger.addHandler(handler) self.log.logger.setLevel(logging.DEBUG) def test_json(self): test_msg = 'This is a %(test)s line' test_data = {'test': 'log'} self.log.debug(test_msg, test_data) data = json.loads(self.stream.getvalue()) self.assertTrue(data) self.assertTrue('extra' in data) self.assertEqual('test-json', data['name']) self.assertEqual(test_msg % test_data, data['message']) self.assertEqual(test_msg, data['msg']) self.assertEqual(test_data, data['args']) self.assertEqual('test_log.py', data['filename']) self.assertEqual('test_json', data['funcname']) self.assertEqual('DEBUG', data['levelname']) self.assertEqual(logging.DEBUG, data['levelno']) self.assertFalse(data['traceback']) def test_json_exception(self): test_msg = 'This is %s' test_data = 'exceptional' try: raise Exception('This is exceptional') except Exception: self.log.exception(test_msg, test_data) data = json.loads(self.stream.getvalue()) self.assertTrue(data) self.assertTrue('extra' in data) self.assertEqual('test-json', data['name']) self.assertEqual(test_msg % test_data, data['message']) self.assertEqual(test_msg, data['msg']) self.assertEqual([test_data], data['args']) self.assertEqual('ERROR', data['levelname']) self.assertEqual(logging.ERROR, data['levelno']) self.assertTrue(data['traceback'])