Logging
Every Java programmer is familiar with the process of inserting calls to System.out.println into troublesome code to gain insight into program behavior. Of course, once you have figured out the cause of trouble, you remove the print statements, only to put them back in when the next problem surfaces. The logging API of SDK 1.4 is designed to overcome this problems. Here are the principal advantages of the API.
- It is easy to suppress all log records, or just those below a certain level, and just as easy to turn them back on.
- Suppressed logs are very cheap, so that there is only a minimal penalty for leaving the logging code in your app.
- Log records can be directed towards different handlers, for display in the console, storage in a file, and so on.
- Both loggers and handlers can filter records. Filters discard boring log entries, using any criteria supplied by the filter implementor.
- Log records can be formatted in different ways, for example in plain text or XML.
- apps can use multiple loggers, with hierarchical names such as com.mycompany.myapp, similar to package names.
- By default, the logging configuration is controlled by a configuration file. apps can replace this mechanism if desired.
Basic Logging
Let's get started with the simplest possible case. The logging system manages a default logger with name "global". Get a reference to it, and then issue calls to the info method:
Logger logger = Logger.getLogger("global"); logger.info("File->Open menu item selected");
By default, the record is printed like this:
Feb 2, 2002 10:12:15 PM LoggingImageViewer fileOpen INFO: File->Open menu item selected
(Note that the time and the names of the calling class and method are automatically included.) But if you call
logger.setLevel(Level.OFF);
at an appropriate place (such as the beginning of main), then the record is suppressed. In other words, you can simply print debugging messages with Logger.getLogger("global").info instead of System.out.println.
Advanced Logging
Now that you have seen "logging for dummies," let's go on to industrial-strength logging. In a professional app, one wouldn't want to log all records to a single global logger. Instead, you can define your own loggers. When you request a logger with a given name for the first time, it is created.
Logger myLogger = Logger.getLogger("com.mycompany.myapp");
Subsequent calls to the same name yield the same logger object. Similar to package names, logger names are hierarchical. In fact, they are more hierarchical than packages. There is no semantic relationship between a package and its parent, but logger parents and children share certain properties. For example, if you set the log level on the logger "com.mycompany", then the child loggers inherit that level. There are seven logging levels:
- SEVERE
- WARNING
- INFO
- CONFIG
- FINE
- FINER
- FINEST
By default, the top three levels are actually logged. You can set a different level, for example
logger.setLevel(Level.FINE);
Now all levels of FINE and higher are logged. You can also use Level.ALL to turn on logging for all levels or Level.OFF to turn all logging off. There are logging methods for all levels, such as
logger.warning(message); logger.fine(message);
and so on. Alternatively, you can use the log method and supply the level, such as
logger.log(Level.FINE, message);
The default logging configuration logs all records with level of INFO or higher. Therefore, you should use the levels CONFIG, FINE, FINER, and FINEST for debugging messages that are useful for diagnostics but meaningless to the program user.
If you set the logging level to a value finer than INFO, then you also need to change the log handler configuration. The default log handler suppresses messages below INFO. See the next section for details.The default log record shows the name of the class and method that contains the logging call, as inferred from the call stack. However, if the virtual machine optimizes execution, accurate call information may not be available. You can use the logp method to give the precise location of the calling class and method. The method signature is
void logp(Level l, String className, String methodName, String message)
There are convenience methods for tracing execution flow:
void entering(String className, String methodName) void entering(String className, String methodName, Object param) void entering(String className, String methodName, Object[] params) void exiting(String className, String methodName) void exiting(String className, String methodName, Object result)
For example,
int read(String file, String pattern) { entering("com.mycompany.mylib.Reader", "read", new Object[] { file, pattern }); . . . exiting("com.mycompany.mylib.Reader", "read", count); return count; }
These calls generate log records of level FINER that start with the strings ENTRY and RETURN. A common use for logging is to log unexpected exceptions. There are two convenience methods that include a description of the exception in the log record.
void throwing(String className, String methodName, Throwable t) void log(Level l, String message, Throwable t)
Typical uses are
if (. . .) { IOException exception = new IOException(". . ."); throwing("com.mycompany.mylib.Reader", "read", exception); throw exception; }
and
try { . . . } catch (IOException e) { Logger.getLogger("com.mycompany.myapp").log(Level.WARNING, "Reading image", e); }
The throwing call logs a record with level FINER and a message that starts with THROW.
Changing the Log Manager Configuration
You can change various properties of the logging system by editing a logging properties file. The default configuration file is located at
jre_directory/lib/logging.properties
If you want to use another file, you need to set the java.util.logging.config.file property to the file location by starting your app with
java -Djava.util.logging.config.file=file MainClass
Calling System.setProperty(java.util.logging.config.file, file) in main has no effect since the log manager is initialized during VM startup, before main executes.To change the default logging level, edit the configuration file and modify the line
.level=INFO
You can specify the logging levels for your own loggers by adding lines such as
com.mycompany.myapp.level=FINE
That is, append the .level suffix to the logger name. As you will see later in this section, the loggers don't actually send the messages to the console-that is the job of the handlers. Handlers also have levels. To see fine messages on the console, you also need to set
java.util.logging.ConsoleHandler.level=FINE
The settings in the log manager configuration are not system properties. Starting a program with -Dcom.mycompany.myapp.level=FINE does not have any influence on the logger.
The logging properties file is processed by the java.util.logging.LogManager class. It is possible to specify a different log manager by setting the java.util.logging.manager system property to the name of a subclass. Alternatively, you can keep the standard log manager and still bypass the initialization from the logging properties file. Set the java.util.logging.config.class system property to the name of a class that sets log manager properties in some other way. See the API documentation for the LogManager class for more information.
Localization
You may want to localize logging messages so that they are readable for international users. Internationalization of apps is the topic of of Volume 2. Briefly, here are the points to keep in mind when localizing logging messages. Localized apps contain locale-specific information in resource bundles. A resource bundle consists of a set of tables for various locales (such as United States or Germany). Each table maps resource names into objects. For example, a resource bundle may map the string "reading_file" into strings "Reading file" or "Achtung! Datei wird eingelesen." A program may contain multiple resource bundles, perhaps one for menus and another for log messages. Each resource bundle has a name (such as "log_messages"). of Volume 2 explains how to prepare resource bundles and how to attach them to a program. When requesting a logger, you can specify a resource bundle:
Logger logger = Logger.getLogger(loggerName, bundleName);
Then you specify the resource bundle key, not the actual message string, for the log message.
logger.info("reading_file");
Often, you need to include arguments into localized messages. Then the message should contain placeholders {0}, {1}, and so on. For example, you may want to include the file name with a "reading file" log message. The localized messages would be
Reading file {0}. Achtung! Datei {0} wird eingelesen.
For more information about these placeholders, see of Volume 2 or the API documentation of the MessageFormat class. There are convenience log methods that take a single parameter, or an array of parameters.
logger.log(Level.INFO, "reading_file", fileName); logger.log(Level.INFO, "renaming_file", new Object[] { oldName, newName });
Finally, there is a set of logrb methods (log with resource bundle) that let you specify the resource bundle name explicitly. You would need these methods if your localized strings are distributed over multiple bundles. For example,
logger.logrb(Level.INFO, "com.mycompany.mylib.Reader", "read", "log_messages", "reading_file", fileName);
Handlers
By default, loggers send records to a ConsoleHandler that prints them to the System.err stream. Specifically, the logger sends the record to the parent handler, and the ultimate ancestor (with name "") has a ConsoleHandler. Like loggers, handlers have a logging level. For a record to be logged, its logging level must be above the threshold of both the logger and the handler. The log manager configuration file sets the logging level of the default console handler as
java.util.logging.ConsoleHandler.level=INFO
To log records with level FINE, change both the default logger level and the handler level in the configuration. Alternatively, you can bypass the configuration file altogether and install your own handler.
Logger logger = Logger.getLogger("com.mycompany.myapp"); logger.setLevel(Level.FINE); logger.setUseParentHandlers(false); Handler handler = new ConsoleHandler(); handler.setLevel(Level.FINE); logger.addHandler(handler);
By default, a logger sends records both to its own handlers and the handlers of the parent. Our logger is a child of the primordial logger (with name "") that sends all records with level INFO or higher to the console. But we don't want to see those records twice. For that reason, we set the useParentHandlers property to false. To send log records elsewhere, add another handler. The logging API provides two useful handlers for this purpose, a FileHandler and a SocketHandler. The SocketHandler sends records to a specified host and port. Of greater interest is the FileHandler that collects records in a file. You can simply send records to a default file handler, like this:
FileHandler handler = new FileHandler(); logger.addHandler(handler);
The records are sent to a file javan.log in the user's home directory, where n is a number to make the file unique. If there is no concept of a user's home directory (for example, in Windows 95), then the file is stored in a default location such as C:\Windows. The records are formatted in XML. A typical log record has the form
<record> <date>2002-02-04T07:45:15</date> <millis>1012837515710</millis> <sequence>1</sequence> <logger>com.mycompany.myapp</logger> <level>INFO</level> <class>com.mycompany.mylib.Reader</class> <method>read</method> <thread>10</thread> <message>Reading file corejava.gif</message> </record>
You can modify the default behavior of the file handler by setting various parameters in the log manager configuration (see Table 11-2), or by using another constructor (see the API notes at the end of this section). You probably don't want to use the default log file name. Therefore, you should use another pattern, such as %h/myapp.log. (See Table 11-3 for an explanation of the pattern variables.) If multiple apps (or multiple copies of the same app) use the same log file, then you should turn the "append" flag on. Alternatively, use %u in the file name pattern so that each app creates a unique copy of the log. It is also a good idea to turn file rotation on. Log files are kept in a rotation sequence, such as myapp.log.0, myapp.log.1, myapp.log.2, and so on. Whenever a file exceeds the size limit, the oldest log is deleted, the other files are renamed, and a new file with generation number 0 is created.
Many programmers use logging as an aid for the technical support staff. If a program misbehaves in the field, then the user can send back the log files for inspection. In that case, you should turn the "append" flag on, use rotating logs, or both.
Table 11-2. File handler configuration parameters
Configuration Property |
Description |
Default | |||||||||||||||||||||||||||||||
---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|
java.util.logging. FileHandler.level | The handler level. | Level.ALL | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.append | Controls whether the handler should append to an existing file, or open a new file for each program run. | false | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.limit | The approximate maximum number of bytes to write in a file before opening another. (0 = no limit). | 0 (no limit) in the FileHandler class, 50000 in the default log manager configuration | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.pattern | The pattern for the log file name. See Table 11-3 for pattern variables. | %h/java%u.log | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.count | The number of logs in a rotation sequence. | 1 (no rotation) | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.filter | The filter class to use. | No filtering | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.encoding | The character encoding to use. | The platform encoding | |||||||||||||||||||||||||||||||
java.util.logging. FileHandler.formatter | The record formatter. | java.util.logging. XMLFormatterYou can also define your own handlers, by overriding the Handler or the StreamHandler class. We define such a handler in the example program at the end of this section. That handler displays the records in a window (see Screenshot-3).
A log handler that displays records in a window
Table 11-3. Log file pattern variables
|