~bzr-pqm/bzr/bzr.dev

« back to all changes in this revision

Viewing changes to bzrlib/tests/test_trace.py

  • Committer: Alexander Belchenko
  • Date: 2006-07-30 16:43:12 UTC
  • mto: (1711.2.111 jam-integration)
  • mto: This revision was merged to the branch mainline in revision 1906.
  • Revision ID: bialix@ukr.net-20060730164312-b025fd3ff0cee59e
rename  gpl.txt => COPYING.txt

Show diffs side-by-side

added added

removed removed

Lines of Context:
1
 
# Copyright (C) 2005, 2006, 2007, 2008, 2009 Canonical Ltd
 
1
# Copyright (C) 2005, 2006 by Canonical Ltd
2
2
#
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
12
12
#
13
13
# You should have received a copy of the GNU General Public License
14
14
# along with this program; if not, write to the Free Software
15
 
# Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA
 
15
# Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA  02111-1307  USA
16
16
 
17
17
# "weren't nothing promised to you.  do i look like i got a promise face?"
18
18
 
19
19
"""Tests for trace library"""
20
20
 
21
 
from cStringIO import StringIO
22
21
import errno
23
22
import os
24
 
import re
25
23
import sys
26
 
import tempfile
 
24
from StringIO import StringIO
27
25
 
28
 
from bzrlib import (
29
 
    errors,
30
 
    trace,
31
 
    )
32
26
from bzrlib.tests import TestCaseInTempDir, TestCase
33
 
from bzrlib.trace import (
34
 
    mutter, mutter_callsite, report_exception,
35
 
    set_verbosity_level, get_verbosity_level, is_quiet, is_verbose, be_quiet,
36
 
    pop_log_file,
37
 
    push_log_file,
38
 
    _rollover_trace_maybe,
39
 
    )
 
27
from bzrlib.trace import mutter, report_exception
 
28
from bzrlib.errors import NotBranchError
40
29
 
41
30
 
42
31
def _format_exception():
49
38
class TestTrace(TestCase):
50
39
 
51
40
    def test_format_sys_exception(self):
52
 
        # Test handling of an internal/unexpected error that probably
53
 
        # indicates a bug in bzr.  The details of the message may vary
54
 
        # depending on whether apport is available or not.  See test_crash for
55
 
        # more.
56
41
        try:
57
42
            raise NotImplementedError, "time travel"
58
43
        except NotImplementedError:
61
46
        self.assertEqualDiff(err.splitlines()[0],
62
47
                'bzr: ERROR: exceptions.NotImplementedError: time travel')
63
48
        self.assertContainsRe(err,
64
 
            'Bazaar has encountered an internal error.')
 
49
                r'File.*test_trace.py')
65
50
 
66
51
    def test_format_interrupt_exception(self):
67
52
        try:
73
58
        self.assertTrue(len(msg) > 0)
74
59
        self.assertEqualDiff(msg, 'bzr: interrupted\n')
75
60
 
76
 
    def test_format_memory_error(self):
77
 
        try:
78
 
            raise MemoryError()
79
 
        except MemoryError:
80
 
            pass
81
 
        msg = _format_exception()
82
 
        self.assertEquals(msg,
83
 
            "bzr: out of memory\n")
84
 
 
85
61
    def test_format_os_error(self):
86
62
        try:
87
 
            os.rmdir('nosuchfile22222')
88
 
        except OSError, e:
89
 
            e_str = str(e)
90
 
        msg = _format_exception()
91
 
        # Linux seems to give "No such file" but Windows gives "The system
92
 
        # cannot find the file specified".
93
 
        self.assertEqual('bzr: ERROR: %s\n' % (e_str,), msg)
94
 
 
95
 
    def test_format_io_error(self):
96
 
        try:
97
63
            file('nosuchfile22222')
98
 
        except IOError:
 
64
        except (OSError, IOError):
99
65
            pass
100
66
        msg = _format_exception()
101
 
        # Even though Windows and Linux differ for 'os.rmdir', they both give
102
 
        # 'No such file' for open()
103
 
        self.assertContainsRe(msg,
104
 
            r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile')
 
67
        self.assertContainsRe(msg, r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile')
105
68
 
106
 
    def test_format_unicode_error(self):
107
 
        try:
108
 
            raise errors.BzrCommandError(u'argument foo\xb5 does not exist')
109
 
        except errors.BzrCommandError:
110
 
            pass
111
 
        msg = _format_exception()
112
69
 
113
70
    def test_format_exception(self):
114
71
        """Short formatting of bzr exceptions"""
115
72
        try:
116
 
            raise errors.NotBranchError('wibble')
117
 
        except errors.NotBranchError:
 
73
            raise NotBranchError, 'wibble'
 
74
        except NotBranchError:
118
75
            pass
119
76
        msg = _format_exception()
120
77
        self.assertTrue(len(msg) > 0)
121
 
        self.assertEqualDiff(msg, 'bzr: ERROR: Not a branch: \"wibble\".\n')
122
 
 
123
 
    def test_report_external_import_error(self):
124
 
        """Short friendly message for missing system modules."""
125
 
        try:
126
 
            import ImaginaryModule
127
 
        except ImportError, e:
128
 
            pass
129
 
        else:
130
 
            self.fail("somehow succeeded in importing %r" % ImaginaryModule)
131
 
        msg = _format_exception()
132
 
        self.assertEqual(msg,
133
 
            'bzr: ERROR: No module named ImaginaryModule\n'
134
 
            'You may need to install this Python library separately.\n')
135
 
 
136
 
    def test_report_import_syntax_error(self):
137
 
        try:
138
 
            raise ImportError("syntax error")
139
 
        except ImportError, e:
140
 
            pass
141
 
        msg = _format_exception()
142
 
        self.assertContainsRe(msg,
143
 
            r'Bazaar has encountered an internal error')
 
78
        self.assertEqualDiff(msg, 'bzr: ERROR: Not a branch: wibble\n')
144
79
 
145
80
    def test_trace_unicode(self):
146
81
        """Write Unicode to trace log"""
147
82
        self.log(u'the unicode character for benzene is \N{BENZENE RING}')
148
 
        log = self.get_log()
149
 
        self.assertContainsRe(log, "the unicode character for benzene is")
150
 
 
151
 
    def test_trace_argument_unicode(self):
152
 
        """Write a Unicode argument to the trace log"""
153
 
        mutter(u'the unicode character for benzene is %s', u'\N{BENZENE RING}')
154
 
        log = self.get_log()
155
 
        self.assertContainsRe(log, 'the unicode character')
156
 
 
157
 
    def test_trace_argument_utf8(self):
158
 
        """Write a Unicode argument to the trace log"""
159
 
        mutter(u'the unicode character for benzene is %s',
160
 
               u'\N{BENZENE RING}'.encode('utf-8'))
161
 
        log = self.get_log()
162
 
        self.assertContainsRe(log, 'the unicode character')
 
83
        self.assertContainsRe('the unicode character',
 
84
                self._get_log())
163
85
 
164
86
    def test_report_broken_pipe(self):
165
87
        try:
170
92
        else:
171
93
            self.fail("expected error not raised")
172
94
 
173
 
    def assertLogStartsWith(self, log, string):
174
 
        """Like assertStartsWith, but skips the log timestamp."""
175
 
        self.assertContainsRe(log,
176
 
            '^\\d+\\.\\d+  ' + re.escape(string))
177
 
 
178
 
    def test_mutter_callsite_1(self):
179
 
        """mutter_callsite can capture 1 level of stack frame."""
180
 
        mutter_callsite(1, "foo %s", "a string")
181
 
        log = self.get_log()
182
 
        # begin with the message
183
 
        self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
184
 
        # should show two frame: this frame and the one above
185
 
        self.assertContainsRe(log,
186
 
            'test_trace\\.py", line \\d+, in test_mutter_callsite_1\n')
187
 
        # this frame should be the final one
188
 
        self.assertEndsWith(log, ' "a string")\n')
189
 
 
190
 
    def test_mutter_callsite_2(self):
191
 
        """mutter_callsite can capture 2 levels of stack frame."""
192
 
        mutter_callsite(2, "foo %s", "a string")
193
 
        log = self.get_log()
194
 
        # begin with the message
195
 
        self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
196
 
        # should show two frame: this frame and the one above
197
 
        self.assertContainsRe(log,
198
 
            'test_trace.py", line \d+, in test_mutter_callsite_2\n')
199
 
        # this frame should be the final one
200
 
        self.assertEndsWith(log, ' "a string")\n')
201
 
 
202
95
    def test_mutter_never_fails(self):
203
96
        # Even if the decode/encode stage fails, mutter should not
204
97
        # raise an exception
205
 
        # This test checks that mutter doesn't fail; the current behaviour
206
 
        # is that it doesn't fail *and writes non-utf8*.
207
98
        mutter(u'Writing a greek mu (\xb5) works in a unicode string')
208
99
        mutter('But fails in an ascii string \xb5')
209
 
        mutter('and in an ascii argument: %s', '\xb5')
210
 
        log = self.get_log()
 
100
        # TODO: jam 20051227 mutter() doesn't flush the log file, and
 
101
        #       self._get_log() opens the file directly and reads it.
 
102
        #       So we need to manually flush the log file
 
103
        import bzrlib.trace
 
104
        bzrlib.trace._trace_file.flush()
 
105
        log = self._get_log()
211
106
        self.assertContainsRe(log, 'Writing a greek mu')
212
 
        self.assertContainsRe(log, "But fails in an ascii string")
213
 
        # However, the log content object does unicode replacement on reading
214
 
        # to let it get unicode back where good data has been written. So we
215
 
        # have to do a replaceent here as well.
216
 
        self.assertContainsRe(log, "ascii argument: \xb5".decode('utf8',
217
 
            'replace'))
218
 
 
219
 
    def test_push_log_file(self):
220
 
        """Can push and pop log file, and this catches mutter messages.
221
 
 
222
 
        This is primarily for use in the test framework.
223
 
        """
224
 
        tmp1 = tempfile.NamedTemporaryFile()
225
 
        tmp2 = tempfile.NamedTemporaryFile()
226
 
        try:
227
 
            memento1 = push_log_file(tmp1)
228
 
            mutter("comment to file1")
229
 
            try:
230
 
                memento2 = push_log_file(tmp2)
231
 
                try:
232
 
                    mutter("comment to file2")
233
 
                finally:
234
 
                    pop_log_file(memento2)
235
 
                mutter("again to file1")
236
 
            finally:
237
 
                pop_log_file(memento1)
238
 
            # the files were opened in binary mode, so should have exactly
239
 
            # these bytes.  and removing the file as the log target should
240
 
            # have caused them to be flushed out.  need to match using regexps
241
 
            # as there's a timestamp at the front.
242
 
            tmp1.seek(0)
243
 
            self.assertContainsRe(tmp1.read(),
244
 
                r"\d+\.\d+  comment to file1\n\d+\.\d+  again to file1\n")
245
 
            tmp2.seek(0)
246
 
            self.assertContainsRe(tmp2.read(),
247
 
                r"\d+\.\d+  comment to file2\n")
248
 
        finally:
249
 
            tmp1.close()
250
 
            tmp2.close()
251
 
 
252
 
    def test__open_bzr_log_uses_stderr_for_failures(self):
253
 
        # If _open_bzr_log cannot open the file, then we should write the
254
 
        # warning to stderr. Since this is normally happening before logging is
255
 
        # set up.
256
 
        self.overrideAttr(sys, 'stderr', StringIO())
257
 
        # Set the log file to something that cannot exist
258
 
        # FIXME: A bit dangerous: we are not in an isolated dir here -- vilajam
259
 
        # 20100125
260
 
        os.environ['BZR_LOG'] = os.getcwd() + '/no-dir/bzr.log'
261
 
        self.overrideAttr(trace, '_bzr_log_filename')
262
 
        logf = trace._open_bzr_log()
263
 
        self.assertIs(None, logf)
264
 
        self.assertContainsRe(sys.stderr.getvalue(),
265
 
                              'failed to open trace file: .*/no-dir/bzr.log')
266
 
 
267
 
 
268
 
class TestVerbosityLevel(TestCase):
269
 
 
270
 
    def test_verbosity_level(self):
271
 
        set_verbosity_level(1)
272
 
        self.assertEqual(1, get_verbosity_level())
273
 
        self.assertTrue(is_verbose())
274
 
        self.assertFalse(is_quiet())
275
 
        set_verbosity_level(-1)
276
 
        self.assertEqual(-1, get_verbosity_level())
277
 
        self.assertFalse(is_verbose())
278
 
        self.assertTrue(is_quiet())
279
 
        set_verbosity_level(0)
280
 
        self.assertEqual(0, get_verbosity_level())
281
 
        self.assertFalse(is_verbose())
282
 
        self.assertFalse(is_quiet())
283
 
 
284
 
    def test_be_quiet(self):
285
 
        # Confirm the old API still works
286
 
        be_quiet(True)
287
 
        self.assertEqual(-1, get_verbosity_level())
288
 
        be_quiet(False)
289
 
        self.assertEqual(0, get_verbosity_level())
290
 
 
291
 
 
292
 
class TestBzrLog(TestCaseInTempDir):
293
 
 
294
 
    def test_log_rollover(self):
295
 
        temp_log_name = 'test-log'
296
 
        trace_file = open(temp_log_name, 'at')
297
 
        trace_file.writelines(['test_log_rollover padding\n'] * 200000)
298
 
        trace_file.close()
299
 
        _rollover_trace_maybe(temp_log_name)
300
 
        # should have been rolled over
301
 
        self.assertFalse(os.access(temp_log_name, os.R_OK))
 
107
        self.assertContainsRe(log, 'UnicodeError')
 
108
        self.assertContainsRe(log, "'But fails in an ascii string")