[lttng-tools.git] / tests / regression / ust / java-log4j / test_java_log4j

#!/bin/bash
#
# Copyright (C) - 2014 David Goulet <dgoulet@efficios.com>
#
# This program is free software; you can redistribute it and/or modify it under
# the terms of the GNU General Public License, version 2 only, as published by
# the Free Software Foundation.
#
# This program is distributed in the hope that it will be useful, but WITHOUT
# ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS
# FOR A PARTICULAR PURPOSE.  See the GNU General Public License for more
# details.
#
# You should have received a copy of the GNU General Public License along with
# this program; if not, write to the Free Software Foundation, Inc., 51
# Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA.

TEST_DESC="Java LOG4J support"

CURDIR=$(dirname $0)/
TESTDIR=$CURDIR/../../..
NR_ITER=6
NR_MSEC_WAIT=1000
TESTAPP_NAME="JTestLTTng"
TESTAPP_BIN="$TESTAPP_NAME.java"
TESTAPP_PATH="$CURDIR/$TESTAPP_NAME"
SESSION_NAME="log4j"
EVENT_NAME="log4j-event"
EVENT_NAME2="log4j-event-2"
JAVA_CP="$CURDIR:/usr/local/share/java/liblttng-ust-agent.jar:/usr/share/java/*"
OUTPUT_DEST="/dev/null"

NUM_TESTS=155

source $TESTDIR/utils/utils.sh

function run_app
{
	local debug_tp=$1
	local fire_second_tp=$2

	java -cp $JAVA_CP -Djava.library.path="/usr/local/lib:/usr/lib" $TESTAPP_NAME $NR_ITER $NR_MSEC_WAIT $debug_tp $fire_second_tp >/dev/null 2>&1
}

function run_app_background
{
	run_app $@ &
}

function enable_log4j_loglevel_only()
{
	sess_name=$1
	event_name="$2"
	loglevel=$3
	channel_name=$4

	if [ -z $channel_name ]; then
		# default channel if none specified
		chan=""
	else
		chan="-c $channel_name"
	fi

	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event --loglevel-only $loglevel "$event_name" $chan -s $sess_name -l >/dev/null 2>&1
	ok $? "Enable LOG4J event $event_name for session $sess_name with loglevel-only $loglevel"
}

function enable_log4j_filter()
{
	local sess_name="$1"
	local event_name="$2"
	local filter="$3"

	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event "$event_name" -s $sess_name -l --filter "$filter" >/dev/null 2>&1
	ok $? "Enable event $event_name with filter $filter for session $sess_name"
}

function enable_log4j_filter_loglevel_only()
{
	local sess_name="$1"
	local event_name="$2"
	local filter="$3"
	local loglevel="$4"

	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event --loglevel-only $loglevel "$event_name" -s $sess_name -l --filter "$filter" >/dev/null 2>&1
	ok $? "Enable event $event_name with filter \"$filter\" and loglevel-only $loglevel for session $sess_name"
}

# MUST set TESTDIR before calling those functions

function test_log4j_before_start ()
{
	diag "Test LOG4J application BEFORE tracing starts"
	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME

	# Run 5 times with a 1 second delay
	run_app_background

	start_lttng_tracing $SESSION_NAME

	# Wait for the applications started in background
	wait

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_after_start ()
{
	diag "Test LOG4J application AFTER tracing starts"

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_loglevel ()
{
	diag "Test LOG4J application with loglevel"

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_INFO"
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi

	diag "Test LOG4J applications with lower loglevel"

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_FATAL"
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting 0 events.
	trace_match_only $EVENT_NAME 0 $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi

	diag "Test LOG4J applications with higher loglevel"

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_TRACE"
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	return $?
}

function test_log4j_loglevel_multiple ()
{
	diag "Test LOG4J application with multiple loglevel"

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_INFO"
	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_DEBUG"
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire two TP.
	run_app 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events times two.
	trace_match_only $EVENT_NAME $(($NR_ITER * 2)) $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi

	create_lttng_session $SESSION_NAME $TRACE_PATH
	enable_log4j_lttng_event_loglevel $SESSION_NAME '*' "LOG4J_INFO"
	enable_log4j_lttng_event_loglevel $SESSION_NAME '*' "LOG4J_DEBUG"
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire two TP.
	run_app 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting all events times two.
	trace_match_only $EVENT_NAME $(($NR_ITER * 2)) $TRACE_PATH
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_multi_session_loglevel()
{
	diag "Test LOG4J with multiple session"

	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
	enable_log4j_loglevel_only $SESSION_NAME-1 '*' "LOG4J_INFO"
	start_lttng_tracing $SESSION_NAME-1

	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
	enable_log4j_loglevel_only $SESSION_NAME-2 '*' "LOG4J_DEBUG"
	start_lttng_tracing $SESSION_NAME-2

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 1 1

	stop_lttng_tracing $SESSION_NAME-1
	stop_lttng_tracing $SESSION_NAME-2
	destroy_lttng_session $SESSION_NAME-1
	destroy_lttng_session $SESSION_NAME-2

	# Expecting 6 events being the main event plus the second tp.
	trace_match_only $EVENT_NAME $(($NR_ITER + 1)) $TRACE_PATH/$SESSION_NAME-1
	if [ $? -ne 0 ]; then
		return $?
	fi

	# Expectin 5 events being the debug TP.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-2
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_multi_session_disable()
{
	diag "Test LOG4J with multiple session with disabled event"

	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME2
	disable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
	start_lttng_tracing $SESSION_NAME-1

	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
	enable_log4j_lttng_event $SESSION_NAME-2 $EVENT_NAME2
	start_lttng_tracing $SESSION_NAME-2

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME-1
	stop_lttng_tracing $SESSION_NAME-2
	destroy_lttng_session $SESSION_NAME-1
	destroy_lttng_session $SESSION_NAME-2

	# Validate test. Expecting one event of the second TP.
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-1
	if [ $? -ne 0 ]; then
		return $?
	fi

	# Validate test. Expecting one event of the second TP.
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-2
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_multi_session_disable_wildcard()
{
	diag "Test LOG4J with multiple session with disabled wildcard event"

	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
	enable_log4j_lttng_event $SESSION_NAME-1 '*'

	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
	enable_log4j_lttng_event $SESSION_NAME-2 '*'

	disable_log4j_lttng_event $SESSION_NAME-1 '*'

	start_lttng_tracing $SESSION_NAME-1
	start_lttng_tracing $SESSION_NAME-2

	run_app

	stop_lttng_tracing $SESSION_NAME-1
	stop_lttng_tracing $SESSION_NAME-2
	destroy_lttng_session $SESSION_NAME-1
	destroy_lttng_session $SESSION_NAME-2

	# Validate test. Expecting NO event of the first TP.
	trace_match_only $EVENT_NAME 0 $TRACE_PATH/$SESSION_NAME-1
	if [ $? -ne 0 ]; then
		return $?
	fi

	# Validate test. Expecting all events of the first TP.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-2
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_disable_all()
{
	diag "Test LOG4J with multiple session with disabled all event"

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	enable_log4j_lttng_event $SESSION_NAME '*'
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2

	disable_log4j_lttng_event $SESSION_NAME '*'

	start_lttng_tracing $SESSION_NAME

	run_app 0 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting NO event of the first TP and second TP.
	trace_match_only $EVENT_NAME 0 $TRACE_PATH/$SESSION_NAME
	trace_match_only $EVENT_NAME2 0 $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_multi_session()
{
	diag "Test LOG4J with multiple session"

	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
	start_lttng_tracing $SESSION_NAME-1

	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
	enable_log4j_lttng_event $SESSION_NAME-2 $EVENT_NAME2
	start_lttng_tracing $SESSION_NAME-2

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME-1
	stop_lttng_tracing $SESSION_NAME-2
	destroy_lttng_session $SESSION_NAME-1
	destroy_lttng_session $SESSION_NAME-2

	# Validate test. Expecting all events of first TP
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-1
	if [ $? -ne 0 ]; then
		return $?
	fi

	# Validate test. Expecting one event of the second TP.
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-2
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_destroy_session()
{
	diag "Test LOG4J two session with destroy"

	create_lttng_session $SESSION_NAME $TRACE_PATH/first-sess
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app_background 0 1
	# Wait for the applications started in background
	wait

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting at least one event num 1
	validate_trace $EVENT_NAME $TRACE_PATH/first-sess
	if [ $? -ne 0 ]; then
		return $?
	fi

	create_lttng_session $SESSION_NAME $TRACE_PATH/second-sess
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay
	run_app_background 0 1
	# Wait for the applications started in background
	wait

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting only one event num 2
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/second-sess
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_filtering()
{
	diag "Test LOG4J filtering"

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	# Enable all event with a filter.
	enable_log4j_filter $SESSION_NAME '*' 'msg == "LOG4J second logger fired"'
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting one event of the second TP only.
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	# Enable first Logger but filter msg payload for the INFO one while
	# triggering the debug and second TP.
	enable_log4j_filter $SESSION_NAME $EVENT_NAME 'msg == "LOG4J tp fired!"'
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay, fire debug and second TP.
	run_app 1 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting NR_ITER event of the main INFO tp.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_disable()
{
	diag "Test LOG4J disable event"

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	# Enable all event with a filter.
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2
	disable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting one event of the second TP only.
	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_disable_enable()
{
	diag "Test LOG4J disable event followed by an enable"

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	# Enable all event with a filter.
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	disable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting NR_ITER event of the main INFO tp.
	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi
}

function test_log4j_filter_loglevel()
{
	local BOGUS_EVENT_NAME="not_a_real_event"
	local FILTER="int_loglevel > 700 || int_loglevel < 700"
	local ALL_EVENTS="."

	diag "Test LOG4J a filter with a loglevel"

	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	# Enable an event with a filter and the loglevel-only option.
	enable_log4j_filter_loglevel_only $SESSION_NAME $BOGUS_EVENT_NAME "$FILTER" LOG4J_INFO
	disable_log4j_lttng_event $SESSION_NAME $BOGUS_EVENT_NAME
	enable_log4j_filter_loglevel_only $SESSION_NAME $BOGUS_EVENT_NAME "$FILTER" LOG4J_INFO
	start_lttng_tracing $SESSION_NAME

	# Run 5 times with a 1 second delay and fire second TP.
	run_app 0 1

	stop_lttng_tracing $SESSION_NAME
	destroy_lttng_session $SESSION_NAME

	# Validate test. Expecting no events.
	trace_match_only $ALL_EVENTS 0 $TRACE_PATH/$SESSION_NAME
	if [ $? -ne 0 ]; then
		return $?
	fi
}

plan_tests $NUM_TESTS

print_test_banner "$TEST_DESC"

if [ ! -f "$TESTAPP_PATH.class" ]; then
	withapp=0
else
	withapp=1
fi

skip $withapp "LOG4J support is needed. Skipping all tests." $NUM_TESTS ||
{
	start_lttng_sessiond

	tests=(
		test_log4j_multi_session_disable_wildcard
		test_log4j_multi_session_disable
		test_log4j_disable
		test_log4j_disable_enable
		test_log4j_disable_all
		test_log4j_filtering
		test_log4j_multi_session_loglevel
		test_log4j_destroy_session
		test_log4j_loglevel
		test_log4j_loglevel_multiple
		test_log4j_before_start
		test_log4j_after_start
		test_log4j_multi_session
		test_log4j_filter_loglevel
	)

	for fct_test in ${tests[@]};
	do
		TRACE_PATH=$(mktemp -d)

		${fct_test}
		if [ $? -ne 0 ]; then
			break;
		fi
		rm -rf $TRACE_PATH
	done

	stop_lttng_sessiond
}
Commit	Line	Data
504d4ace DG	1	#!/bin/bash
	2	#
	3	# Copyright (C) - 2014 David Goulet <dgoulet@efficios.com>
	4	#
	5	# This program is free software; you can redistribute it and/or modify it under
	6	# the terms of the GNU General Public License, version 2 only, as published by
	7	# the Free Software Foundation.
	8	#
	9	# This program is distributed in the hope that it will be useful, but WITHOUT
	10	# ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS
	11	# FOR A PARTICULAR PURPOSE. See the GNU General Public License for more
	12	# details.
	13	#
	14	# You should have received a copy of the GNU General Public License along with
	15	# this program; if not, write to the Free Software Foundation, Inc., 51
	16	# Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA.
	17
	18	TEST_DESC="Java LOG4J support"
	19
	20	CURDIR=$(dirname $0)/
	21	TESTDIR=$CURDIR/../../..
	22	NR_ITER=6
	23	NR_MSEC_WAIT=1000
	24	TESTAPP_NAME="JTestLTTng"
	25	TESTAPP_BIN="$TESTAPP_NAME.java"
	26	TESTAPP_PATH="$CURDIR/$TESTAPP_NAME"
	27	SESSION_NAME="log4j"
	28	EVENT_NAME="log4j-event"
	29	EVENT_NAME2="log4j-event-2"
	30	JAVA_CP="$CURDIR:/usr/local/share/java/liblttng-ust-agent.jar:/usr/share/java/*"
	31	OUTPUT_DEST="/dev/null"
	32
	33	NUM_TESTS=155
	34
	35	source $TESTDIR/utils/utils.sh
	36
	37	function run_app
	38	{
	39	local debug_tp=$1
	40	local fire_second_tp=$2
	41
	42	java -cp $JAVA_CP -Djava.library.path="/usr/local/lib:/usr/lib" $TESTAPP_NAME $NR_ITER $NR_MSEC_WAIT $debug_tp $fire_second_tp >/dev/null 2>&1
	43	}
	44
	45	function run_app_background
	46	{
	47	run_app $@ &
	48	}
	49
	50	function enable_log4j_loglevel_only()
	51	{
	52	sess_name=$1
	53	event_name="$2"
	54	loglevel=$3
	55	channel_name=$4
	56
	57	if [ -z $channel_name ]; then
	58	# default channel if none specified
	59	chan=""
	60	else
	61	chan="-c $channel_name"
	62	fi
	63
	64	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event --loglevel-only $loglevel "$event_name" $chan -s $sess_name -l >/dev/null 2>&1
65	ok $? "Enable LOG4J event $event_name for session $sess_name with loglevel-only $loglevel"
66	}
67
68	function enable_log4j_filter()
69	{
70	local sess_name="$1"
71	local event_name="$2"
72	local filter="$3"
73
74	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event "$event_name" -s $sess_name -l --filter "$filter" >/dev/null 2>&1
75	ok $? "Enable event $event_name with filter $filter for session $sess_name"
76	}
77
78	function enable_log4j_filter_loglevel_only()
79	{
80	local sess_name="$1"
81	local event_name="$2"
82	local filter="$3"
83	local loglevel="$4"
84
85	$TESTDIR/../src/bin/lttng/$LTTNG_BIN enable-event --loglevel-only $loglevel "$event_name" -s $sess_name -l --filter "$filter" >/dev/null 2>&1
86	ok $? "Enable event $event_name with filter \"$filter\" and loglevel-only $loglevel for session $sess_name"
87	}
88
89	# MUST set TESTDIR before calling those functions
90
91	function test_log4j_before_start ()
92	{
93	diag "Test LOG4J application BEFORE tracing starts"
94	create_lttng_session $SESSION_NAME $TRACE_PATH
95	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
96
97	# Run 5 times with a 1 second delay
98	run_app_background
99
100	start_lttng_tracing $SESSION_NAME
101
102	# Wait for the applications started in background
0fc2834c	103	wait
504d4ace DG	104
	105	stop_lttng_tracing $SESSION_NAME
	106	destroy_lttng_session $SESSION_NAME
	107
	108	# Validate test. Expecting all events.
	109	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	110	if [ $? -ne 0 ]; then
	111	return $?
	112	fi
	113	}
	114
	115	function test_log4j_after_start ()
	116	{
	117	diag "Test LOG4J application AFTER tracing starts"
	118
	119	create_lttng_session $SESSION_NAME $TRACE_PATH
	120	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	121	start_lttng_tracing $SESSION_NAME
	122
	123	# Run 5 times with a 1 second delay
	124	run_app
	125
	126	stop_lttng_tracing $SESSION_NAME
	127	destroy_lttng_session $SESSION_NAME
	128
	129	# Validate test. Expecting all events.
	130	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	131	if [ $? -ne 0 ]; then
	132	return $?
	133	fi
	134	}
	135
	136	function test_log4j_loglevel ()
	137	{
	138	diag "Test LOG4J application with loglevel"
	139
	140	create_lttng_session $SESSION_NAME $TRACE_PATH
	141	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_INFO"
	142	start_lttng_tracing $SESSION_NAME
	143
	144	# Run 5 times with a 1 second delay
	145	run_app
	146
	147	stop_lttng_tracing $SESSION_NAME
	148	destroy_lttng_session $SESSION_NAME
	149
	150	# Validate test. Expecting all events.
	151	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
	152	if [ $? -ne 0 ]; then
	153	return $?
	154	fi
	155
	156	diag "Test LOG4J applications with lower loglevel"
	157
	158	create_lttng_session $SESSION_NAME $TRACE_PATH
	159	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_FATAL"
	160	start_lttng_tracing $SESSION_NAME
	161
	162	# Run 5 times with a 1 second delay
	163	run_app
	164
	165	stop_lttng_tracing $SESSION_NAME
	166	destroy_lttng_session $SESSION_NAME
	167
168	# Validate test. Expecting 0 events.
169	trace_match_only $EVENT_NAME 0 $TRACE_PATH
170	if [ $? -ne 0 ]; then
171	return $?
172	fi
173
174	diag "Test LOG4J applications with higher loglevel"
175
176	create_lttng_session $SESSION_NAME $TRACE_PATH
177	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_TRACE"
178	start_lttng_tracing $SESSION_NAME
179
180	# Run 5 times with a 1 second delay
181	run_app
182
183	stop_lttng_tracing $SESSION_NAME
184	destroy_lttng_session $SESSION_NAME
185
186	# Validate test. Expecting all events.
187	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH
188	return $?
189	}
190
191	function test_log4j_loglevel_multiple ()
192	{
193	diag "Test LOG4J application with multiple loglevel"
194
195	create_lttng_session $SESSION_NAME $TRACE_PATH
196	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_INFO"
197	enable_log4j_lttng_event_loglevel $SESSION_NAME $EVENT_NAME "LOG4J_DEBUG"
198	start_lttng_tracing $SESSION_NAME
199
200	# Run 5 times with a 1 second delay and fire two TP.
201	run_app 1
202
203	stop_lttng_tracing $SESSION_NAME
204	destroy_lttng_session $SESSION_NAME
205
206	# Validate test. Expecting all events times two.
207	trace_match_only $EVENT_NAME $(($NR_ITER * 2)) $TRACE_PATH
208	if [ $? -ne 0 ]; then
209	return $?
210	fi
211
212	create_lttng_session $SESSION_NAME $TRACE_PATH
213	enable_log4j_lttng_event_loglevel $SESSION_NAME '*' "LOG4J_INFO"
214	enable_log4j_lttng_event_loglevel $SESSION_NAME '*' "LOG4J_DEBUG"
215	start_lttng_tracing $SESSION_NAME
216
217	# Run 5 times with a 1 second delay and fire two TP.
218	run_app 1
219
220	stop_lttng_tracing $SESSION_NAME
221	destroy_lttng_session $SESSION_NAME
222
223	# Validate test. Expecting all events times two.
224	trace_match_only $EVENT_NAME $(($NR_ITER * 2)) $TRACE_PATH
225	if [ $? -ne 0 ]; then
226	return $?
227	fi
228	}
229
230	function test_log4j_multi_session_loglevel()
231	{
232	diag "Test LOG4J with multiple session"
233
234	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
235	enable_log4j_loglevel_only $SESSION_NAME-1 '*' "LOG4J_INFO"
236	start_lttng_tracing $SESSION_NAME-1
237
238	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
239	enable_log4j_loglevel_only $SESSION_NAME-2 '*' "LOG4J_DEBUG"
240	start_lttng_tracing $SESSION_NAME-2
241
242	# Run 5 times with a 1 second delay and fire second TP.
243	run_app 1 1
244
245	stop_lttng_tracing $SESSION_NAME-1
246	stop_lttng_tracing $SESSION_NAME-2
247	destroy_lttng_session $SESSION_NAME-1
248	destroy_lttng_session $SESSION_NAME-2
249
250	# Expecting 6 events being the main event plus the second tp.
251	trace_match_only $EVENT_NAME $(($NR_ITER + 1)) $TRACE_PATH/$SESSION_NAME-1
252	if [ $? -ne 0 ]; then
253	return $?
254	fi
255
256	# Expectin 5 events being the debug TP.
257	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-2
258	if [ $? -ne 0 ]; then
259	return $?
260	fi
261	}
262
263	function test_log4j_multi_session_disable()
264	{
265	diag "Test LOG4J with multiple session with disabled event"
266
267	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
268	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
269	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME2
270	disable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
271	start_lttng_tracing $SESSION_NAME-1
272
273	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
274	enable_log4j_lttng_event $SESSION_NAME-2 $EVENT_NAME2
275	start_lttng_tracing $SESSION_NAME-2
276
277	# Run 5 times with a 1 second delay and fire second TP.
278	run_app 0 1
279
280	stop_lttng_tracing $SESSION_NAME-1
281	stop_lttng_tracing $SESSION_NAME-2
282	destroy_lttng_session $SESSION_NAME-1
283	destroy_lttng_session $SESSION_NAME-2
284
285	# Validate test. Expecting one event of the second TP.
286	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-1
287	if [ $? -ne 0 ]; then
288	return $?
289	fi
290
291	# Validate test. Expecting one event of the second TP.
292	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-2
293	if [ $? -ne 0 ]; then
294	return $?
295	fi
296	}
297
298	function test_log4j_multi_session_disable_wildcard()
299	{
300	diag "Test LOG4J with multiple session with disabled wildcard event"
301
302	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
303	enable_log4j_lttng_event $SESSION_NAME-1 '*'
304
305	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
306	enable_log4j_lttng_event $SESSION_NAME-2 '*'
307
308	disable_log4j_lttng_event $SESSION_NAME-1 '*'
309
310	start_lttng_tracing $SESSION_NAME-1
311	start_lttng_tracing $SESSION_NAME-2
312
313	run_app
314
315	stop_lttng_tracing $SESSION_NAME-1
316	stop_lttng_tracing $SESSION_NAME-2
317	destroy_lttng_session $SESSION_NAME-1
318	destroy_lttng_session $SESSION_NAME-2
319
320	# Validate test. Expecting NO event of the first TP.
321	trace_match_only $EVENT_NAME 0 $TRACE_PATH/$SESSION_NAME-1
322	if [ $? -ne 0 ]; then
323	return $?
324	fi
325
326	# Validate test. Expecting all events of the first TP.
327	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-2
328	if [ $? -ne 0 ]; then
329	return $?
330	fi
331	}
332
333	function test_log4j_disable_all()
334	{
335	diag "Test LOG4J with multiple session with disabled all event"
336
337	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
338	enable_log4j_lttng_event $SESSION_NAME '*'
339	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
340	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2
341
342	disable_log4j_lttng_event $SESSION_NAME '*'
343
344	start_lttng_tracing $SESSION_NAME
345
346	run_app 0 1
347
348	stop_lttng_tracing $SESSION_NAME
349	destroy_lttng_session $SESSION_NAME
350
351	# Validate test. Expecting NO event of the first TP and second TP.
352	trace_match_only $EVENT_NAME 0 $TRACE_PATH/$SESSION_NAME
353	trace_match_only $EVENT_NAME2 0 $TRACE_PATH/$SESSION_NAME
354	if [ $? -ne 0 ]; then
355	return $?
356	fi
357	}
358
359	function test_log4j_multi_session()
360	{
361	diag "Test LOG4J with multiple session"
362
363	create_lttng_session $SESSION_NAME-1 $TRACE_PATH/$SESSION_NAME-1
364	enable_log4j_lttng_event $SESSION_NAME-1 $EVENT_NAME
365	start_lttng_tracing $SESSION_NAME-1
366
367	create_lttng_session $SESSION_NAME-2 $TRACE_PATH/$SESSION_NAME-2
368	enable_log4j_lttng_event $SESSION_NAME-2 $EVENT_NAME2
369	start_lttng_tracing $SESSION_NAME-2
370
371	# Run 5 times with a 1 second delay and fire second TP.
372	run_app 0 1
373
374	stop_lttng_tracing $SESSION_NAME-1
375	stop_lttng_tracing $SESSION_NAME-2
376	destroy_lttng_session $SESSION_NAME-1
377	destroy_lttng_session $SESSION_NAME-2
378
379	# Validate test. Expecting all events of first TP
380	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME-1
381	if [ $? -ne 0 ]; then
382	return $?
383	fi
384
385	# Validate test. Expecting one event of the second TP.
386	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME-2
387	if [ $? -ne 0 ]; then
388	return $?
389	fi
390	}
391
392	function test_log4j_destroy_session()
393	{
394	diag "Test LOG4J two session with destroy"
395
396	create_lttng_session $SESSION_NAME $TRACE_PATH/first-sess
397	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
398	start_lttng_tracing $SESSION_NAME
399
400	# Run 5 times with a 1 second delay
401	run_app_background 0 1
0fc2834c MD	402	# Wait for the applications started in background
0fc2834c MD	403	wait
504d4ace DG	404
	405	stop_lttng_tracing $SESSION_NAME
	406	destroy_lttng_session $SESSION_NAME
	407
	408	# Validate test. Expecting at least one event num 1
	409	validate_trace $EVENT_NAME $TRACE_PATH/first-sess
	410	if [ $? -ne 0 ]; then
	411	return $?
	412	fi
	413
	414	create_lttng_session $SESSION_NAME $TRACE_PATH/second-sess
	415	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2
	416	start_lttng_tracing $SESSION_NAME
	417
0fc2834c MD	418	# Run 5 times with a 1 second delay
0fc2834c MD	419	run_app_background 0 1
504d4ace	420	# Wait for the applications started in background
0fc2834c	421	wait
504d4ace DG	422
	423	stop_lttng_tracing $SESSION_NAME
	424	destroy_lttng_session $SESSION_NAME
	425
	426	# Validate test. Expecting only one event num 2
	427	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/second-sess
	428	if [ $? -ne 0 ]; then
	429	return $?
	430	fi
	431	}
	432
	433	function test_log4j_filtering()
	434	{
	435	diag "Test LOG4J filtering"
	436
	437	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	438	# Enable all event with a filter.
	439	enable_log4j_filter $SESSION_NAME '*' 'msg == "LOG4J second logger fired"'
	440	start_lttng_tracing $SESSION_NAME
	441
	442	# Run 5 times with a 1 second delay and fire second TP.
	443	run_app 0 1
	444
	445	stop_lttng_tracing $SESSION_NAME
	446	destroy_lttng_session $SESSION_NAME
	447
	448	# Validate test. Expecting one event of the second TP only.
	449	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME
	450	if [ $? -ne 0 ]; then
	451	return $?
	452	fi
	453
	454	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	455	# Enable first Logger but filter msg payload for the INFO one while
	456	# triggering the debug and second TP.
	457	enable_log4j_filter $SESSION_NAME $EVENT_NAME 'msg == "LOG4J tp fired!"'
	458	start_lttng_tracing $SESSION_NAME
	459
	460	# Run 5 times with a 1 second delay, fire debug and second TP.
	461	run_app 1 1
	462
	463	stop_lttng_tracing $SESSION_NAME
	464	destroy_lttng_session $SESSION_NAME
	465
	466	# Validate test. Expecting NR_ITER event of the main INFO tp.
	467	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME
	468	if [ $? -ne 0 ]; then
	469	return $?
	470	fi
	471	}
	472
	473	function test_log4j_disable()
	474	{
	475	diag "Test LOG4J disable event"
	476
	477	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
	478	# Enable all event with a filter.
	479	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	480	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME2
	481	disable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
	482	start_lttng_tracing $SESSION_NAME
	483
	484	# Run 5 times with a 1 second delay and fire second TP.
	485	run_app 0 1
486
487	stop_lttng_tracing $SESSION_NAME
488	destroy_lttng_session $SESSION_NAME
489
490	# Validate test. Expecting one event of the second TP only.
491	trace_match_only $EVENT_NAME2 1 $TRACE_PATH/$SESSION_NAME
492	if [ $? -ne 0 ]; then
493	return $?
494	fi
495	}
496
497	function test_log4j_disable_enable()
498	{
499	diag "Test LOG4J disable event followed by an enable"
500
501	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
502	# Enable all event with a filter.
503	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
504	disable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
505	enable_log4j_lttng_event $SESSION_NAME $EVENT_NAME
506	start_lttng_tracing $SESSION_NAME
507
508	# Run 5 times with a 1 second delay and fire second TP.
509	run_app 0 1
510
511	stop_lttng_tracing $SESSION_NAME
512	destroy_lttng_session $SESSION_NAME
513
514	# Validate test. Expecting NR_ITER event of the main INFO tp.
515	trace_match_only $EVENT_NAME $NR_ITER $TRACE_PATH/$SESSION_NAME
516	if [ $? -ne 0 ]; then
517	return $?
518	fi
519	}
520
521	function test_log4j_filter_loglevel()
522	{
523	local BOGUS_EVENT_NAME="not_a_real_event"
524	local FILTER="int_loglevel > 700 \|\| int_loglevel < 700"
525	local ALL_EVENTS="."
526
527	diag "Test LOG4J a filter with a loglevel"
528
529	create_lttng_session $SESSION_NAME $TRACE_PATH/$SESSION_NAME
530	# Enable an event with a filter and the loglevel-only option.
531	enable_log4j_filter_loglevel_only $SESSION_NAME $BOGUS_EVENT_NAME "$FILTER" LOG4J_INFO
532	disable_log4j_lttng_event $SESSION_NAME $BOGUS_EVENT_NAME
533	enable_log4j_filter_loglevel_only $SESSION_NAME $BOGUS_EVENT_NAME "$FILTER" LOG4J_INFO
534	start_lttng_tracing $SESSION_NAME
535
536	# Run 5 times with a 1 second delay and fire second TP.
537	run_app 0 1
538
539	stop_lttng_tracing $SESSION_NAME
540	destroy_lttng_session $SESSION_NAME
541
542	# Validate test. Expecting no events.
543	trace_match_only $ALL_EVENTS 0 $TRACE_PATH/$SESSION_NAME
544	if [ $? -ne 0 ]; then
545	return $?
546	fi
547	}
548
549	plan_tests $NUM_TESTS
550
551	print_test_banner "$TEST_DESC"
552
553	if [ ! -f "$TESTAPP_PATH.class" ]; then
554	withapp=0
555	else
556	withapp=1
557	fi
558
559	skip $withapp "LOG4J support is needed. Skipping all tests." $NUM_TESTS \|\|
560	{
561	start_lttng_sessiond
562
563	tests=(
564	test_log4j_multi_session_disable_wildcard
565	test_log4j_multi_session_disable
566	test_log4j_disable
567	test_log4j_disable_enable
568	test_log4j_disable_all
569	test_log4j_filtering
570	test_log4j_multi_session_loglevel
571	test_log4j_destroy_session
572	test_log4j_loglevel
573	test_log4j_loglevel_multiple
574	test_log4j_before_start
575	test_log4j_after_start
576	test_log4j_multi_session
577	test_log4j_filter_loglevel
578	)
579
580	for fct_test in ${tests[@]};
581	do
582	TRACE_PATH=$(mktemp -d)
583
584	${fct_test}
585	if [ $? -ne 0 ]; then
586	break;
587	fi
588	rm -rf $TRACE_PATH
589	done
590
591	stop_lttng_sessiond
592	}