~bzr-pqm/bzr/bzr.dev

« back to all changes in this revision

Viewing changes to bzrlib/trace.py

  • Committer: Martin Pool
  • Date: 2005-05-02 04:24:33 UTC
  • Revision ID: mbp@sourcefrog.net-20050502042433-c825a7f7235f6b15
doc: notes on merge

Show diffs side-by-side

added added

removed removed

Lines of Context:
 
1
#! /usr/bin/env python
 
2
# -*- coding: UTF-8 -*-
 
3
 
1
4
# This program is free software; you can redistribute it and/or modify
2
5
# it under the terms of the GNU General Public License as published by
3
6
# the Free Software Foundation; either version 2 of the License, or
13
16
# Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA  02111-1307  USA
14
17
 
15
18
 
16
 
"""Messages and logging for bazaar-ng
17
 
 
18
 
Messages are sent out through the Python logging library.
19
 
 
20
 
They can be sent to two places: to stderr, and to ~/.bzr.log.
21
 
 
22
 
~/.bzr.log gets all messages, and tracebacks of all uncaught
23
 
exceptions.
24
 
 
25
 
Normally stderr only gets messages of level INFO and higher, and gets
26
 
only a summary of exceptions, not the traceback.
27
 
"""
28
 
 
29
 
 
30
 
# TODO: in debug mode, stderr should get full tracebacks and also
31
 
# debug messages.  (Is this really needed?)
32
 
 
33
 
# TODO: When running the test suites, we should add an additional
34
 
# logger that sends messages into the test log file.
35
 
 
36
 
 
37
 
import sys
38
 
import os
39
 
import logging
40
 
import traceback
41
 
 
42
 
 
43
 
_file_handler = None
44
 
_stderr_handler = None
45
 
 
46
 
 
47
 
class QuietFormatter(logging.Formatter):
48
 
    """Formatter that supresses the details of errors.
49
 
 
50
 
    This is used by default on stderr so as not to scare the user.
51
 
    """
52
 
    # At first I tried overriding formatException to suppress the
53
 
    # exception details, but that has global effect: no loggers
54
 
    # can get the exception details is we suppress them here.
55
 
 
56
 
    def format(self, record):
57
 
        if record.levelno >= logging.WARNING:
58
 
            s = 'bzr: ' + record.levelname + ': '
59
 
        else:
60
 
            s = ''
61
 
            
62
 
        s += record.getMessage()
63
 
 
64
 
        ##import textwrap
65
 
        ##s = textwrap.fill(s)
66
 
            
67
 
        if record.exc_info:
68
 
            # give just a summary of the exception, not the whole thing
69
 
            exinfo = traceback.extract_tb(record.exc_info[2])
70
 
            # the format of this string matches one of the REs
71
 
            s += '\n'
72
 
            s += ('  at %s line %d, in %s()\n' % exinfo[-1][:3])
73
 
            s += '  see ~/.bzr.log for debug information'
74
 
 
75
 
        return s
76
 
        
77
 
 
78
 
 
79
 
 
80
 
################
81
 
# configure convenient aliases for output routines
82
 
 
83
 
_bzr_logger = logging.getLogger('bzr')
84
 
_bzr_logger.setLevel(logging.DEBUG) 
85
 
 
86
 
info = note = _bzr_logger.info
87
 
warning =   _bzr_logger.warning
88
 
log_error = _bzr_logger.error
89
 
error =     _bzr_logger.error
90
 
mutter =    _bzr_logger.debug
91
 
debug =     _bzr_logger.debug
92
 
 
93
 
 
94
 
 
95
 
 
96
 
# we do the rollover using this code, rather than the default from python
97
 
# logging, because we only want to rollover at program startup, not on each
98
 
# message.  maybe that's not a good enough reason.
 
19
__copyright__ = "Copyright (C) 2005 Canonical Ltd."
 
20
__author__ = "Martin Pool <mbp@canonical.com>"
 
21
 
 
22
 
 
23
import sys, os, time, socket, stat, codecs
 
24
import bzrlib
 
25
 
 
26
######################################################################
 
27
# messages and logging
 
28
 
 
29
## TODO: If --verbose is given then write to both stderr and
 
30
## _tracefile; perhaps replace _tracefile with a tee thing.
 
31
 
 
32
global _tracefile, _starttime
 
33
_tracefile = None
 
34
 
 
35
# used to have % (os.environ['USER'], time.time(), os.getpid()), 'w')
 
36
_starttime = None
 
37
 
 
38
# If false, notes also go to stdout; should replace this with --silent
 
39
# at some point.
 
40
silent = False
 
41
 
 
42
 
 
43
# TODO: Somehow tie this to the --verbose option?
 
44
verbose = False
 
45
 
 
46
 
 
47
# fix this if we ever fork within python
 
48
_mypid = os.getpid()
 
49
_logprefix = '[%d] ' % _mypid
 
50
 
 
51
 
 
52
def _write_trace(msg):
 
53
    _tracefile.write(_logprefix + msg + '\n')
 
54
 
 
55
 
 
56
def warning(msg):
 
57
    sys.stderr.write('bzr: warning: ' + msg + '\n')
 
58
    _write_trace('warning: ' + msg)
 
59
 
 
60
 
 
61
mutter = _write_trace
 
62
 
 
63
 
 
64
def note(msg):
 
65
    b = '* ' + str(msg) + '\n'
 
66
    if not silent:
 
67
        sys.stderr.write(b)
 
68
    _write_trace('note: ' + msg)
 
69
 
 
70
 
 
71
def log_error(msg):
 
72
    sys.stderr.write(msg + '\n')
 
73
    _write_trace(msg)
 
74
 
 
75
 
 
76
# TODO: Something to log exceptions in here.
 
77
 
 
78
 
99
79
 
100
80
def _rollover_trace_maybe(trace_fname):
101
 
    import stat
102
81
    try:
103
82
        size = os.stat(trace_fname)[stat.ST_SIZE]
104
 
        if size <= 4 << 20:
 
83
        if size <= 1 << 20:
105
84
            return
106
85
        old_fname = trace_fname + '.old'
107
86
 
108
 
        from osutils import rename
109
 
        rename(trace_fname, old_fname)
 
87
        try:
 
88
            # must remove before rename on windows
 
89
            os.remove(old_fname)
 
90
        except OSError:
 
91
            pass
110
92
 
 
93
        try:
 
94
            # might fail if in use on windows
 
95
            os.rename(trace_fname, old_fname)
 
96
        except OSError:
 
97
            pass
111
98
    except OSError:
112
99
        return
113
100
 
114
101
 
115
102
 
116
 
def open_tracefile(tracefilename='~/.bzr.log'):
 
103
def create_tracefile(argv):
 
104
    # TODO: Also show contents of /etc/lsb-release, if it can be parsed.
 
105
    #       Perhaps that should eventually go into the platform library?
 
106
    # TODO: If the file doesn't exist, add a note describing it.
 
107
 
117
108
    # Messages are always written to here, so that we have some
118
109
    # information if something goes wrong.  In a future version this
119
110
    # file will be removed on successful completion.
120
 
    global _file_handler
121
 
    import stat, codecs
122
 
 
123
 
    trace_fname = os.path.join(os.path.expanduser(tracefilename))
 
111
    global _starttime, _tracefile
 
112
 
 
113
    _starttime = os.times()[4]
 
114
 
 
115
    trace_fname = os.path.join(os.path.expanduser('~/.bzr.log'))
124
116
    _rollover_trace_maybe(trace_fname)
125
117
 
126
118
    # buffering=1 means line buffered
127
 
    try:
128
 
        tf = codecs.open(trace_fname, 'at', 'utf8', buffering=1)
129
 
 
130
 
        if os.fstat(tf.fileno())[stat.ST_SIZE] == 0:
131
 
            tf.write("\nthis is a debug log for diagnosing/reporting problems in bzr\n")
132
 
            tf.write("you can delete or truncate this file, or include sections in\n")
133
 
            tf.write("bug reports to bazaar-ng@lists.canonical.com\n\n")
 
119
    _tracefile = codecs.open(trace_fname, 'at', 'utf8', buffering=1)
 
120
    t = _tracefile
 
121
 
 
122
    if os.fstat(t.fileno())[stat.ST_SIZE] == 0:
 
123
        t.write("\nthis is a debug log for diagnosing/reporting problems in bzr\n")
 
124
        t.write("you can delete or truncate this file, or include sections in\n")
 
125
        t.write("bug reports to bazaar-ng@lists.canonical.com\n\n")
 
126
 
 
127
    # TODO: If we failed to create the file, perhaps give a warning
 
128
    # but don't abort; send things to /dev/null instead?
 
129
 
 
130
    _write_trace('bzr %s invoked on python %s (%s)'
 
131
                 % (bzrlib.__version__,
 
132
                    '.'.join(map(str, sys.version_info)),
 
133
                    sys.platform))
 
134
 
 
135
    _write_trace('  arguments: %r' % argv)
 
136
    _write_trace('  working dir: ' + os.getcwdu())
 
137
 
 
138
 
 
139
def close_trace():
 
140
    times = os.times()
 
141
    mutter("finished, %.3fu/%.3fs cpu, %.3fu/%.3fs cum, %.3f elapsed"
 
142
           % (times[:4] + ((times[4] - _starttime),)))
 
143
 
 
144
 
 
145
 
 
146
def log_exception(e):
 
147
    import traceback, cStringIO
 
148
    s = cStringIO.StringIO()
 
149
    traceback.print_exc(None, s)
 
150
    for l in s.getvalue().split('\n'):
 
151
        _write_trace(l)
134
152
        
135
 
        _file_handler = logging.StreamHandler(tf)
136
 
        fmt = r'[%(process)5d] %(asctime)s.%(msecs)03d %(levelname)s: %(message)s'
137
 
        datefmt = r'%a %H:%M:%S'
138
 
        _file_handler.setFormatter(logging.Formatter(fmt, datefmt))
139
 
        _file_handler.setLevel(logging.DEBUG)
140
 
        logging.getLogger('').addHandler(_file_handler)
141
 
 
142
 
    except IOError, e:
143
 
        warning("failed to open trace file: %s" % (e))
144
 
 
145
 
 
146
 
def log_startup(argv):
147
 
    import bzrlib
148
 
 
149
 
    debug('bzr %s invoked on python %s (%s)',
150
 
          bzrlib.__version__,
151
 
          '.'.join(map(str, sys.version_info)),
152
 
          sys.platform)
153
 
 
154
 
    debug('  arguments: %r', argv)
155
 
    debug('  working dir: %s', os.getcwdu())
156
 
 
157
 
 
158
 
def log_exception(msg=None):
159
 
    """Log the last exception into the trace file.
160
 
 
161
 
    The exception string representation is used as the error
162
 
    summary, unless msg is given.
163
 
    """
164
 
    command = ' '.join(repr(arg) for arg in sys.argv)
165
 
    prefix = "command: %s\npwd: %s\n" % (command, os.getcwd())
166
 
    if msg == None:
167
 
        ei = sys.exc_info()
168
 
        msg = str(ei[1])
169
 
    if msg and (msg[-1] == '\n'):
170
 
        msg = msg[:-1]
171
 
    ## msg = "(%s) %s" % (str(type(ei[1])), msg)
172
 
    _bzr_logger.exception(prefix + msg)
173
 
 
174
 
 
175
 
 
176
 
def enable_default_logging():
177
 
    """Configure default logging to stderr and .bzr.log"""
178
 
    global _stderr_handler, _file_handler
179
 
 
180
 
    _stderr_handler = logging.StreamHandler()
181
 
    _stderr_handler.setFormatter(QuietFormatter())
182
 
 
183
 
    if not _file_handler:
184
 
        open_tracefile()
185
 
 
186
 
    if os.environ.get('BZR_DEBUG'):
187
 
        level = logging.DEBUG
188
 
    else:
189
 
        level = logging.INFO
190
 
 
191
 
    _stderr_handler.setLevel(logging.INFO)
192
 
    _file_handler.setLevel(level)
193
 
 
194
 
    logging.getLogger('').addHandler(_stderr_handler)
195
 
 
196
 
 
197
 
def disable_default_logging():
198
 
    """Turn off default log handlers.
199
 
 
200
 
    This is intended to be used by the test framework, which doesn't
201
 
    want leakage from the code-under-test into the main logs.
202
 
    """
203
 
 
204
 
    l = logging.getLogger('')
205
 
    l.removeHandler(_stderr_handler)
206
 
    if _file_handler:
207
 
        l.removeHandler(_file_handler)
 
153