[LOG4J2-441] logger level configuration does not work correct

• Type: Bug
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Component/s: Core
• Assignee: Ralph Goers
• Reporter: Andreas Rytina
• Created: 06/Nov/13 17:20
• Updated: 11/Jul/14 06:44
• Resolved: 11/Jul/14 06:44

Description

Configuring the loglevel in the logger does not work in combination with configuring the loglevel at the appender-refs. I analyzed this with the beta9. See the table which lists various loglevel configurations for the 3 loggers. The column format for the table is:
Column 1-6 are the logger with their level config (level@logger1, level@appender-ref)(level@logger2, level@appender-ref)(level@logger3, level@appender-ref)
Column 7 is the actual output
Column 8 is the expected Output

For instance the first row of the table shows a logging result which I not expected in that way. You can take log4j2.xml.row1 to reproduce the first row. The first logger is named "com". The second logger is named "com.log4jtest" and the third logger is named "com.log4jtest.test". This looks like a bug in log4j 2.0:
Logger1 considers the levels

{info, debug, warning}

but not the level trace. What is the default level, if there is no level defined at the logger header and also not at the appender-ref? Here it seems that the Logger1 has the level debug as default which is not clear for me. For me it would make sense if the default level is either OFF or ALL when there is no explicit level set. But either way there should be a clear rule for this. The other rows are showing similar inconsistent logging behaviour.

1	2	3	4	5	6	7	8
level 1 logger	level 1 appender-ref	level 2 logger	level 2 appender-ref	level 3 logger	level 3 appender-ref	actual output	expected output
-	-	-	info	debug	debug	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger1: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger	Logger3: info logger Logger2: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger
-	-	-	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger
-	-	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger
fatal	fatal	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger
info	info	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger
debug	debug	info	info		debug	no output	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger
info	debug	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger
debug	info	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger
debug	trace	info	info	-	debug	no output	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger
-	-	info	info	fatal	debug	no output	Logger2: info logger Logger2: warning logger
-	-	info	info	trace	debug	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger1: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger Logger1: trace logger	Logger3: info logger Logger2: info logger Logger3: debug logger Logger2: warning logger
-	-	-	info	trace	debug	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger1: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger Logger1: trace logger	Logger3: info logger Logger2: info logger Logger3: debug logger Logger2: warning logger
-	-	info	-	trace	debug	Logger3: info logger Logger2: info logger Logger1: info logger Logger3: debug logger Logger1: debug logger Logger3: warning logger Logger2: warning logger Logger1: warning logger Logger1: trace logger	Logger3: info logger Logger2: info logger Logger3: debug logger Logger2: warning logger

Here is the log4j2.xml:

<?xml version="1.0" encoding="UTF-8" ?>
<configuration>
	<appenders>
		<console name="TestConsole0">
			<PatternLayout pattern="RootLogger: %msg%n"/>
		</console>
		<console name="TestConsole1">
			<PatternLayout pattern="Logger1: %msg%n"/>
		</console>
		<console name="TestConsole2">
			<PatternLayout pattern="Logger2: %msg%n"/>
		</console>
		<console name="TestConsole3">
			<PatternLayout pattern="Logger3: %msg%n"/>
		</console>
	</appenders>
	
	<loggers>
		<logger name="com" >
			<appender-ref ref="TestConsole1" />
		</logger>
		
		<logger name ="com.log4jtest" level="info">
			<appender-ref ref="TestConsole2" level="info"/>
		</logger>
		
		<logger name ="com.log4jtest.test" level="fatal">
			<appender-ref ref="TestConsole3" level="debug"/>
		</logger>
		
		<root level="error">
			<appender-ref ref="TestConsole0" level="error"/>
		</root>
	</loggers>
</configuration>

And here are the logging calls:

Log4j2LogLevelTest.java

package com.log4jtest.test;

import static org.junit.Assert.*;

import org.apache.logging.log4j.LogManager;
import org.apache.logging.log4j.Logger;
import org.junit.Test;

public class Log4j2LogLevelTest {

	private static final Logger logger = LogManager.getLogger(Log4j2LogLevelTest.class);
	
	@Test
	public void testLogLevel() {
		logger.info("info logger");
		logger.debug("debug logger");
		logger.warn("warning logger");
		logger.trace("trace logger");
	}

}