~bzr-pqm/bzr/bzr.dev

« back to all changes in this revision

Viewing changes to bzrlib/tests/test_trace.py

  • Committer: Alexander Belchenko
  • Date: 2008-03-11 08:49:42 UTC
  • mto: This revision was merged to the branch mainline in revision 3268.
  • Revision ID: bialix@ukr.net-20080311084942-w1w0w3v0m20p2pbc
use sys.version_info

Show diffs side-by-side

added added

removed removed

Lines of Context:
 
1
# Copyright (C) 2005, 2006, 2007, 2008 Canonical Ltd
 
2
#
 
3
# This program is free software; you can redistribute it and/or modify
 
4
# it under the terms of the GNU General Public License as published by
 
5
# the Free Software Foundation; either version 2 of the License, or
 
6
# (at your option) any later version.
 
7
#
 
8
# This program is distributed in the hope that it will be useful,
 
9
# but WITHOUT ANY WARRANTY; without even the implied warranty of
 
10
# MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
 
11
# GNU General Public License for more details.
 
12
#
 
13
# You should have received a copy of the GNU General Public License
 
14
# along with this program; if not, write to the Free Software
 
15
# Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA  02111-1307  USA
 
16
 
 
17
# "weren't nothing promised to you.  do i look like i got a promise face?"
 
18
 
 
19
"""Tests for trace library"""
 
20
 
 
21
from cStringIO import StringIO
 
22
import errno
 
23
import os
 
24
import re
 
25
import sys
 
26
import tempfile
 
27
 
 
28
from bzrlib import (
 
29
    errors,
 
30
    )
 
31
from bzrlib.tests import TestCaseInTempDir, TestCase
 
32
from bzrlib.trace import (
 
33
    mutter, mutter_callsite, report_exception,
 
34
    set_verbosity_level, get_verbosity_level, is_quiet, is_verbose, be_quiet,
 
35
    pop_log_file,
 
36
    push_log_file,
 
37
    _rollover_trace_maybe,
 
38
    )
 
39
 
 
40
 
 
41
def _format_exception():
 
42
    """Format an exception as it would normally be displayed to the user"""
 
43
    buf = StringIO()
 
44
    report_exception(sys.exc_info(), buf)
 
45
    return buf.getvalue()
 
46
 
 
47
 
 
48
class TestTrace(TestCase):
 
49
 
 
50
    def test_format_sys_exception(self):
 
51
        try:
 
52
            raise NotImplementedError, "time travel"
 
53
        except NotImplementedError:
 
54
            pass
 
55
        err = _format_exception()
 
56
        self.assertEqualDiff(err.splitlines()[0],
 
57
                'bzr: ERROR: exceptions.NotImplementedError: time travel')
 
58
        self.assertContainsRe(err,
 
59
                r'File.*test_trace.py')
 
60
 
 
61
    def test_format_interrupt_exception(self):
 
62
        try:
 
63
            raise KeyboardInterrupt()
 
64
        except KeyboardInterrupt:
 
65
            # XXX: Some risk that a *real* keyboard interrupt won't be seen
 
66
            pass
 
67
        msg = _format_exception()
 
68
        self.assertTrue(len(msg) > 0)
 
69
        self.assertEqualDiff(msg, 'bzr: interrupted\n')
 
70
 
 
71
    def test_format_os_error(self):
 
72
        try:
 
73
            file('nosuchfile22222')
 
74
        except (OSError, IOError):
 
75
            pass
 
76
        msg = _format_exception()
 
77
        self.assertContainsRe(msg, r'^bzr: ERROR: \[Errno .*\] No such file.*nosuchfile')
 
78
 
 
79
    def test_format_unicode_error(self):
 
80
        try:
 
81
            raise errors.BzrCommandError(u'argument foo\xb5 does not exist')
 
82
        except errors.BzrCommandError:
 
83
            pass
 
84
        msg = _format_exception()
 
85
 
 
86
    def test_format_exception(self):
 
87
        """Short formatting of bzr exceptions"""
 
88
        try:
 
89
            raise errors.NotBranchError('wibble')
 
90
        except errors.NotBranchError:
 
91
            pass
 
92
        msg = _format_exception()
 
93
        self.assertTrue(len(msg) > 0)
 
94
        self.assertEqualDiff(msg, 'bzr: ERROR: Not a branch: \"wibble\".\n')
 
95
 
 
96
    def test_trace_unicode(self):
 
97
        """Write Unicode to trace log"""
 
98
        self.log(u'the unicode character for benzene is \N{BENZENE RING}')
 
99
        self.assertContainsRe(self._get_log(keep_log_file=True),
 
100
                              "the unicode character for benzene is")
 
101
    
 
102
    def test_trace_argument_unicode(self):
 
103
        """Write a Unicode argument to the trace log"""
 
104
        mutter(u'the unicode character for benzene is %s', u'\N{BENZENE RING}')
 
105
        self.assertContainsRe(self._get_log(keep_log_file=True),
 
106
                              'the unicode character')
 
107
 
 
108
    def test_trace_argument_utf8(self):
 
109
        """Write a Unicode argument to the trace log"""
 
110
        mutter(u'the unicode character for benzene is %s',
 
111
               u'\N{BENZENE RING}'.encode('utf-8'))
 
112
        self.assertContainsRe(self._get_log(keep_log_file=True),
 
113
                              'the unicode character')
 
114
 
 
115
    def test_report_broken_pipe(self):
 
116
        try:
 
117
            raise IOError(errno.EPIPE, 'broken pipe foofofo')
 
118
        except IOError, e:
 
119
            msg = _format_exception()
 
120
            self.assertEquals(msg, "bzr: broken pipe\n")
 
121
        else:
 
122
            self.fail("expected error not raised")
 
123
 
 
124
    def assertLogStartsWith(self, log, string):
 
125
        """Like assertStartsWith, but skips the log timestamp."""
 
126
        self.assertContainsRe(log,
 
127
            '^\\d+\\.\\d+  ' + re.escape(string))
 
128
 
 
129
    def test_mutter_callsite_1(self):
 
130
        """mutter_callsite can capture 1 level of stack frame."""
 
131
        mutter_callsite(1, "foo %s", "a string")
 
132
        log = self._get_log(keep_log_file=True)
 
133
        # begin with the message
 
134
        self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
 
135
        # should show two frame: this frame and the one above
 
136
        self.assertContainsRe(log,
 
137
            'test_trace\\.py", line \\d+, in test_mutter_callsite_1\n')
 
138
        # this frame should be the final one
 
139
        self.assertEndsWith(log, ' "a string")\n')
 
140
 
 
141
    def test_mutter_callsite_2(self):
 
142
        """mutter_callsite can capture 2 levels of stack frame."""
 
143
        mutter_callsite(2, "foo %s", "a string")
 
144
        log = self._get_log(keep_log_file=True)
 
145
        # begin with the message
 
146
        self.assertLogStartsWith(log, 'foo a string\nCalled from:\n')
 
147
        # should show two frame: this frame and the one above
 
148
        self.assertContainsRe(log,
 
149
            'test_trace.py", line \d+, in test_mutter_callsite_2\n')
 
150
        # this frame should be the final one
 
151
        self.assertEndsWith(log, ' "a string")\n')
 
152
 
 
153
    def test_mutter_never_fails(self):
 
154
        # Even if the decode/encode stage fails, mutter should not
 
155
        # raise an exception
 
156
        mutter(u'Writing a greek mu (\xb5) works in a unicode string')
 
157
        mutter('But fails in an ascii string \xb5')
 
158
        mutter('and in an ascii argument: %s', '\xb5')
 
159
        log = self._get_log(keep_log_file=True)
 
160
        self.assertContainsRe(log, 'Writing a greek mu')
 
161
        self.assertContainsRe(log, "But fails in an ascii string")
 
162
        self.assertContainsRe(log, u"ascii argument: \xb5")
 
163
 
 
164
    def test_push_log_file(self):
 
165
        """Can push and pop log file, and this catches mutter messages.
 
166
 
 
167
        This is primarily for use in the test framework. 
 
168
        """
 
169
        tmp1 = tempfile.NamedTemporaryFile()
 
170
        tmp2 = tempfile.NamedTemporaryFile()
 
171
        try:
 
172
            memento1 = push_log_file(tmp1)
 
173
            mutter("comment to file1")
 
174
            try:
 
175
                memento2 = push_log_file(tmp2)
 
176
                try:
 
177
                    mutter("comment to file2")
 
178
                finally:
 
179
                    pop_log_file(memento2)
 
180
                mutter("again to file1")
 
181
            finally:
 
182
                pop_log_file(memento1)
 
183
            # the files were opened in binary mode, so should have exactly
 
184
            # these bytes.  and removing the file as the log target should
 
185
            # have caused them to be flushed out.  need to match using regexps
 
186
            # as there's a timestamp at the front.
 
187
            tmp1.seek(0)
 
188
            self.assertContainsRe(tmp1.read(),
 
189
                r"\d+\.\d+  comment to file1\n\d+\.\d+  again to file1\n")
 
190
            tmp2.seek(0)
 
191
            self.assertContainsRe(tmp2.read(),
 
192
                r"\d+\.\d+  comment to file2\n")
 
193
        finally:
 
194
            tmp1.close()
 
195
            tmp2.close()
 
196
 
 
197
 
 
198
class TestVerbosityLevel(TestCase):
 
199
 
 
200
    def test_verbosity_level(self):
 
201
        set_verbosity_level(1)
 
202
        self.assertEqual(1, get_verbosity_level())
 
203
        self.assertTrue(is_verbose())
 
204
        self.assertFalse(is_quiet())
 
205
        set_verbosity_level(-1)
 
206
        self.assertEqual(-1, get_verbosity_level())
 
207
        self.assertFalse(is_verbose())
 
208
        self.assertTrue(is_quiet())
 
209
        set_verbosity_level(0)
 
210
        self.assertEqual(0, get_verbosity_level())
 
211
        self.assertFalse(is_verbose())
 
212
        self.assertFalse(is_quiet())
 
213
 
 
214
    def test_be_quiet(self):
 
215
        # Confirm the old API still works
 
216
        be_quiet(True)
 
217
        self.assertEqual(-1, get_verbosity_level())
 
218
        be_quiet(False)
 
219
        self.assertEqual(0, get_verbosity_level())
 
220
 
 
221
 
 
222
class TestBzrLog(TestCaseInTempDir):
 
223
 
 
224
    def test_log_rollover(self):
 
225
        temp_log_name = 'test-log'
 
226
        trace_file = open(temp_log_name, 'at')
 
227
        trace_file.write('test_log_rollover padding\n' * 1000000)
 
228
        trace_file.close()
 
229
        _rollover_trace_maybe(temp_log_name)
 
230
        # should have been rolled over
 
231
        self.assertFalse(os.access(temp_log_name, os.R_OK))