1
# Copyright (C) 2005, 2006, 2007, 2008 Canonical Ltd
1
# Copyright (C) 2005-2010 Canonical Ltd
3
3
# This program is free software; you can redistribute it and/or modify
4
4
# it under the terms of the GNU General Public License as published by
28
28
from bzrlib import (
31
from bzrlib.tests import TestCaseInTempDir, TestCase
33
from bzrlib.tests import features, TestCaseInTempDir, TestCase
32
34
from bzrlib.trace import (
33
35
mutter, mutter_callsite, report_exception,
34
36
set_verbosity_level, get_verbosity_level, is_quiet, is_verbose, be_quiet,
37
39
_rollover_trace_maybe,
48
51
class TestTrace(TestCase):
50
53
def test_format_sys_exception(self):
54
# Test handling of an internal/unexpected error that probably
55
# indicates a bug in bzr. The details of the message may vary
56
# depending on whether apport is available or not. See test_crash for
52
59
raise NotImplementedError, "time travel"
53
60
except NotImplementedError:
56
63
self.assertEqualDiff(err.splitlines()[0],
57
64
'bzr: ERROR: exceptions.NotImplementedError: time travel')
58
65
self.assertContainsRe(err,
59
r'File.*test_trace.py')
66
'Bazaar has encountered an internal error.')
61
68
def test_format_interrupt_exception(self):
68
75
self.assertTrue(len(msg) > 0)
69
76
self.assertEqualDiff(msg, 'bzr: interrupted\n')
78
def test_format_memory_error(self):
83
msg = _format_exception()
84
self.assertEquals(msg,
85
"bzr: out of memory\nUse -Dmem_dump to dump memory to a file.\n")
87
def test_format_mem_dump(self):
88
self.requireFeature(features.meliae)
89
debug.debug_flags.add('mem_dump')
94
msg = _format_exception()
95
self.assertStartsWith(msg,
96
"bzr: out of memory\nMemory dumped to ")
71
98
def test_format_os_error(self):
73
100
os.rmdir('nosuchfile22222')
76
103
msg = _format_exception()
77
self.assertContainsRe(msg,
78
r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile22222')
104
# Linux seems to give "No such file" but Windows gives "The system
105
# cannot find the file specified".
106
self.assertEqual('bzr: ERROR: %s\n' % (e_str,), msg)
80
108
def test_format_io_error(self):
85
113
msg = _format_exception()
86
self.assertContainsRe(msg, r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile')
114
# Even though Windows and Linux differ for 'os.rmdir', they both give
115
# 'No such file' for open()
116
self.assertContainsRe(msg,
117
r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile')
119
def test_format_pywintypes_error(self):
120
self.requireFeature(features.pywintypes)
121
import pywintypes, win32file
123
win32file.RemoveDirectory('nosuchfile22222')
124
except pywintypes.error:
126
msg = _format_exception()
127
# GZ 2010-05-03: Formatting for pywintypes.error is basic, a 3-tuple
128
# with errno, function name, and locale error message
129
self.assertContainsRe(msg,
130
r"^bzr: ERROR: \(2, 'RemoveDirectory[AW]?', .*\)")
88
132
def test_format_unicode_error(self):
123
167
msg = _format_exception()
124
168
self.assertContainsRe(msg,
125
r"Traceback \(most recent call last\)")
169
r'Bazaar has encountered an internal error')
127
171
def test_trace_unicode(self):
128
172
"""Write Unicode to trace log"""
129
173
self.log(u'the unicode character for benzene is \N{BENZENE RING}')
130
self.assertContainsRe(self._get_log(keep_log_file=True),
131
"the unicode character for benzene is")
175
self.assertContainsRe(log, "the unicode character for benzene is")
133
177
def test_trace_argument_unicode(self):
134
178
"""Write a Unicode argument to the trace log"""
135
179
mutter(u'the unicode character for benzene is %s', u'\N{BENZENE RING}')
136
self.assertContainsRe(self._get_log(keep_log_file=True),
137
'the unicode character')
181
self.assertContainsRe(log, 'the unicode character')
139
183
def test_trace_argument_utf8(self):
140
184
"""Write a Unicode argument to the trace log"""
141
185
mutter(u'the unicode character for benzene is %s',
142
186
u'\N{BENZENE RING}'.encode('utf-8'))
143
self.assertContainsRe(self._get_log(keep_log_file=True),
144
'the unicode character')
188
self.assertContainsRe(log, 'the unicode character')
146
190
def test_report_broken_pipe(self):
160
204
def test_mutter_callsite_1(self):
161
205
"""mutter_callsite can capture 1 level of stack frame."""
162
206
mutter_callsite(1, "foo %s", "a string")
163
log = self._get_log(keep_log_file=True)
164
208
# begin with the message
165
209
self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
166
210
# should show two frame: this frame and the one above
172
216
def test_mutter_callsite_2(self):
173
217
"""mutter_callsite can capture 2 levels of stack frame."""
174
218
mutter_callsite(2, "foo %s", "a string")
175
log = self._get_log(keep_log_file=True)
176
220
# begin with the message
177
221
self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
178
222
# should show two frame: this frame and the one above
184
228
def test_mutter_never_fails(self):
185
229
# Even if the decode/encode stage fails, mutter should not
186
230
# raise an exception
231
# This test checks that mutter doesn't fail; the current behaviour
232
# is that it doesn't fail *and writes non-utf8*.
187
233
mutter(u'Writing a greek mu (\xb5) works in a unicode string')
188
234
mutter('But fails in an ascii string \xb5')
189
235
mutter('and in an ascii argument: %s', '\xb5')
190
log = self._get_log(keep_log_file=True)
191
237
self.assertContainsRe(log, 'Writing a greek mu')
192
238
self.assertContainsRe(log, "But fails in an ascii string")
193
self.assertContainsRe(log, u"ascii argument: \xb5")
239
# However, the log content object does unicode replacement on reading
240
# to let it get unicode back where good data has been written. So we
241
# have to do a replaceent here as well.
242
self.assertContainsRe(log, "ascii argument: \xb5".decode('utf8',
245
def test_show_error(self):
247
show_error(u'error2 \xb5 blah')
248
show_error('arg: %s', 'blah')
249
show_error('arg2: %(key)s', {'key':'stuff'})
251
raise Exception("oops")
253
show_error('kwarg', exc_info=True)
255
self.assertContainsRe(log, 'error1')
256
self.assertContainsRe(log, u'error2 \xb5 blah')
257
self.assertContainsRe(log, 'arg: blah')
258
self.assertContainsRe(log, 'arg2: stuff')
259
self.assertContainsRe(log, 'kwarg')
260
self.assertContainsRe(log, 'Traceback \\(most recent call last\\):')
261
self.assertContainsRe(log, 'File ".*test_trace.py", line .*, in test_show_error')
262
self.assertContainsRe(log, 'raise Exception\\("oops"\\)')
263
self.assertContainsRe(log, 'Exception: oops')
195
265
def test_push_log_file(self):
196
266
"""Can push and pop log file, and this catches mutter messages.
298
def test__open_bzr_log_uses_stderr_for_failures(self):
299
# If _open_bzr_log cannot open the file, then we should write the
300
# warning to stderr. Since this is normally happening before logging is
302
self.overrideAttr(sys, 'stderr', StringIO())
303
# Set the log file to something that cannot exist
304
# FIXME: A bit dangerous: we are not in an isolated dir here -- vilajam
306
os.environ['BZR_LOG'] = os.getcwd() + '/no-dir/bzr.log'
307
self.overrideAttr(trace, '_bzr_log_filename')
308
logf = trace._open_bzr_log()
309
self.assertIs(None, logf)
310
self.assertContainsRe(sys.stderr.getvalue(),
311
'failed to open trace file: .*/no-dir/bzr.log')
229
314
class TestVerbosityLevel(TestCase):
255
340
def test_log_rollover(self):
256
341
temp_log_name = 'test-log'
257
342
trace_file = open(temp_log_name, 'at')
258
trace_file.write('test_log_rollover padding\n' * 1000000)
343
trace_file.writelines(['test_log_rollover padding\n'] * 200000)
259
344
trace_file.close()
260
345
_rollover_trace_maybe(temp_log_name)
261
346
# should have been rolled over
262
347
self.assertFalse(os.access(temp_log_name, os.R_OK))
350
class TestTraceConfiguration(TestCaseInTempDir):
352
def test_default_config(self):
353
config = trace.DefaultConfig()
354
self.overrideAttr(trace, "_bzr_log_filename", None)
355
trace._bzr_log_filename = None
356
expected_filename = trace._get_bzr_log_filename()
357
self.assertEqual(None, trace._bzr_log_filename)
360
# Should have entered and setup a default filename.
361
self.assertEqual(expected_filename, trace._bzr_log_filename)
363
config.__exit__(None, None, None)
364
# Should have exited and cleaned up.
365
self.assertEqual(None, trace._bzr_log_filename)