# HG changeset patch # User Sergey Kandaurov # Date 1400679822 -14400 # Node ID 997f5fe165454a1c63d8c9127021e688cfdedb4b # Parent 22f6aa0d113933bf72c62d7b5f5ed1d3132f6649 Tests: added syslog tests. diff --git a/debug_connection_syslog.t b/debug_connection_syslog.t new file mode 100644 --- /dev/null +++ b/debug_connection_syslog.t @@ -0,0 +1,123 @@ +#!/usr/bin/perl + +# (C) Nginx, Inc. + +# Tests for debug_connection with syslog. + +############################################################################### + +use warnings; +use strict; + +use Test::More; + +BEGIN { use FindBin; chdir($FindBin::Bin); } + +use lib 'lib'; +use Test::Nginx; + +############################################################################### + +select STDERR; $| = 1; +select STDOUT; $| = 1; + +my $t = Test::Nginx->new()->has(qw/http --with-debug ipv6 proxy/); + +plan(skip_all => 'no syslog') unless $t->has_version('1.7.1'); + +$t->write_file_expand('nginx.conf', <<'EOF'); + +%%TEST_GLOBALS%% + +daemon off; + +events { + debug_connection ::1; +} + +http { + %%TEST_GLOBALS_HTTP%% + + error_log syslog:server=127.0.0.1:8080 alert; + error_log syslog:server=127.0.0.1:8081 alert; + + server { + listen 127.0.0.1:8080; + listen [::1]:8080; + server_name localhost; + + location /debug { + proxy_pass http://[::1]:8080/; + } + } +} + +EOF + +eval { + open OLDERR, ">&", \*STDERR; close STDERR; + $t->run(); + open STDERR, ">&", \*OLDERR; +}; +plan(skip_all => 'no inet6 support') if $@; + +$t->plan(5); + +############################################################################### + +is(get_syslog('/', 8080), '', 'no debug_connection syslog 1'); +is(get_syslog('/', 8081), '', 'no debug_connection syslog 2'); + +my @msgs = get_syslog('/debug', 8080, 8081); +like($msgs[0], qr/\[debug\]/, 'debug_connection syslog 1'); +like($msgs[1], qr/\[debug\]/, 'debug_connection syslog 2'); +is($msgs[0], $msgs[1], 'debug_connection syslog1 syslog2 match'); + +############################################################################### + +sub get_syslog { + my ($uri, @port) = @_; + my (@s); + my $rfd = ''; + my @data; + + eval { + local $SIG{ALRM} = sub { die "timeout\n" }; + local $SIG{PIPE} = sub { die "sigpipe\n" }; + alarm(1); + map { + push @s, IO::Socket::INET->new( + Proto => 'udp', + LocalAddr => "127.0.0.1:$_" + ); + } (@port); + alarm(0); + }; + alarm(0); + if ($@) { + log_in("died: $@"); + return undef; + } + + http_get($uri); + + map { + my $data = ''; + vec($rfd, fileno($_), 1) = 1; + select $rfd, undef, undef, 1; + while (select($rfd, undef, undef, 0.1) > 0 + && vec($rfd, fileno($_), 1)) + { + my ($buffer); + sysread($_, $buffer, 4096); + $data .= $buffer; + } + push @data, $data; + $_->close(); + } (@s); + + return $data[0] if scalar @data == 1; + return @data; +} + +############################################################################### diff --git a/syslog.t b/syslog.t new file mode 100644 --- /dev/null +++ b/syslog.t @@ -0,0 +1,333 @@ +#!/usr/bin/perl + +# (C) Nginx, Inc. + +# Tests for syslog. +# Various log levels emitted with limit_req_log_level. + +############################################################################### + +use warnings; +use strict; + +use Test::More; + +BEGIN { use FindBin; chdir($FindBin::Bin); } + +use lib 'lib'; +use Test::Nginx; + +############################################################################### + +select STDERR; $| = 1; +select STDOUT; $| = 1; + +my $t = Test::Nginx->new()->has(qw/http limit_req/); + +plan(skip_all => 'no syslog') unless $t->has_version('1.7.1'); + +$t->plan(56)->write_file_expand('nginx.conf', <<'EOF'); + +%%TEST_GLOBALS%% + +error_log syslog:server=127.0.0.1:8083 info; +error_log %%TESTDIR%%/f_glob.log info; + +daemon off; + +events { +} + +http { + %%TEST_GLOBALS_HTTP%% + + limit_req_zone $binary_remote_addr zone=one:10m rate=1r/m; + + log_format empty ""; + log_format logf "$status"; + + error_log syslog:server=127.0.0.1:8084 info; + error_log %%TESTDIR%%/f_http.log info; + + server { + listen 127.0.0.1:8080; + server_name localhost; + + location /e { + error_log syslog:server=127.0.0.1:8080; + } + location /a { + access_log syslog:server=127.0.0.1:8080; + } + location /ef { + error_log syslog:server=127.0.0.1:8080,facility=user; + } + location /es { + error_log syslog:server=127.0.0.1:8080,severity=alert; + } + location /et { + error_log syslog:server=127.0.0.1:8080,tag=SEETHIS; + } + location /af { + access_log syslog:server=127.0.0.1:8080,facility=user; + } + location /as { + access_log syslog:server=127.0.0.1:8080,severity=alert; + } + location /at { + access_log syslog:server=127.0.0.1:8080,tag=SEETHIS; + } + location /e2 { + error_log syslog:server=127.0.0.1:8080; + error_log syslog:server=127.0.0.1:8080; + } + location /a2 { + access_log syslog:server=127.0.0.1:8080; + access_log syslog:server=127.0.0.1:8080; + } + location /a_logf { + access_log syslog:server=127.0.0.1:8080 logf; + } + + location /debug { + limit_req zone=one; + error_log syslog:server=127.0.0.1:8080 debug; + } + location /info { + limit_req zone=one; + limit_req_log_level info; + error_log syslog:server=127.0.0.1:8080 info; + } + location /notice { + limit_req zone=one; + limit_req_log_level notice; + error_log syslog:server=127.0.0.1:8080 notice; + } + location /warn { + limit_req zone=one; + limit_req_log_level warn; + error_log syslog:server=127.0.0.1:8080 warn; + } + location /error { + limit_req zone=one; + limit_req_log_level error; + error_log syslog:server=127.0.0.1:8080; + } + location /low { + error_log syslog:server=127.0.0.1:8080 warn; + error_log syslog:server=127.0.0.1:8080; + } + location /dup { + error_log syslog:server=127.0.0.1:8080; + error_log syslog:server=127.0.0.1:8080; + } + location /high { + error_log syslog:server=127.0.0.1:8080 emerg; + error_log syslog:server=127.0.0.1:8080; + } + } +} + +EOF + +$t->run_daemon(\&syslog_daemon, 8083, $t, 's_glob.log'); +$t->run_daemon(\&syslog_daemon, 8084, $t, 's_http.log'); + +$t->waitforfile($t->testdir . '/s_glob.log'); +$t->waitforfile($t->testdir . '/s_http.log'); + +$t->run(); + +############################################################################### + +parse_syslog_message('error_log', get_syslog('/e')); +parse_syslog_message('access_log', get_syslog('/a')); + +like(get_syslog('/ef'), qr/^<11>/, 'error_log facility'); +like(get_syslog('/es'), qr/^<187>/, 'error_log severity'); +like(get_syslog('/et'), qr/SEETHIS:/, 'error_log tag'); + +like(get_syslog('/af'), qr/^<14>/, 'access_log facility'); +like(get_syslog('/as'), qr/^<185>/, 'access_log severity'); +like(get_syslog('/at'), qr/SEETHIS:/, 'access_log tag'); + + +like(get_syslog('/e'), + qr/nginx: \d{4}\/\d{2}\/\d{2} \d{2}:\d{2}:\d{2} \[error\]/, + 'error_log format'); +like(get_syslog('/a_logf'), qr/nginx: 404$/, 'access_log log_format'); + +my @lines = split /<\d+>/, get_syslog('/a2'); +is($lines[1], $lines[2], 'access_log many'); + +@lines = split /<\d+>/, get_syslog('/e2'); +is($lines[1], $lines[2], 'error_log many'); + +# error_log log levels + +SKIP: { + +skip "no --with-debug", 1 unless $t->has_module('--with-debug'); + +isnt(syslog_lines('/debug', '[debug]'), 0, 'debug'); + +} + +# charge limit_req + +get_syslog('/info'); + +is(syslog_lines('/info', '[info]'), 1, 'info'); +is(syslog_lines('/notice', '[notice]'), 1, 'notice'); +is(syslog_lines('/warn', '[warn]'), 1, 'warn'); +is(syslog_lines('/error', '[error]'), 1, 'error'); + +# count log messages emitted with various error_log levels + +is(syslog_lines('/low', '[error]'), 2, 'low'); +is(syslog_lines('/dup', '[error]'), 2, 'dup'); +is(syslog_lines('/high', '[error'), 1, 'high'); + +# check for the presence of the syslog messages in the global and http contexts + +is_deeply(levels($t, 's_glob.log'), levels($t, 'f_glob.log'), 'master syslog'); +is_deeply(levels($t, 's_http.log'), levels($t, 'f_http.log'), 'http syslog'); + +############################################################################### + +sub syslog_lines { + my ($uri, $pattern, $port) = @_; + return map { $_ =~ /\Q$pattern\E/g } (get_syslog($uri, $port)); +} + +sub levels { + my ($t, $file) = @_; + my %levels_hash; + + map { $levels_hash{$_}++; } (read_file($t, $file) =~ /(\[\w+\])/g); + + return \%levels_hash; +} + +sub get_syslog { + my ($uri, $port) = @_; + my ($s); + my $rfd = ''; + my $data = ''; + + $port = 8080 unless defined $port; + + eval { + local $SIG{ALRM} = sub { die "timeout\n" }; + local $SIG{PIPE} = sub { die "sigpipe\n" }; + alarm(1); + $s = IO::Socket::INET->new( + Proto => 'udp', + LocalAddr => "127.0.0.1:$port" + ); + alarm(0); + }; + alarm(0); + if ($@) { + log_in("died: $@"); + return undef; + } + + http_get($uri); + + vec($rfd, fileno($s), 1) = 1; + select $rfd, undef, undef, 1; + while (select($rfd, undef, undef, 0.1) > 0 + && vec($rfd, fileno($s), 1)) + { + my $buffer; + sysread($s, $buffer, 4096); + $data .= $buffer; + } + $s->close(); + return $data; +} + +sub read_file { + my ($t, $file) = @_; + my $path = $t->testdir() . '/' . $file; + + open my $fh, '<', $path or return "$!"; + local $/; + my $content = <$fh>; + close $fh; + chop $content; + return $content; +} + +sub parse_syslog_message { + my ($desc, $line) = @_; + + unless ($line) { + fail("$desc timeout in receiving syslog"); + } + + my @months = ('Jan', 'Feb', 'Mar', 'Apr', 'May', 'Jun', 'Jul', 'Aug', + 'Sep', 'Oct', 'Nov', 'Dec'); + + my ($pri, $mon, $mday, $hour, $minute, $sec, $host, $tag, $msg) = + $line =~ /^<(\d{1,3})> # PRI + ([A-Z][a-z]{2})\s # mon + ([ \d]\d)\s(\d{2}):(\d{2}):(\d{2})\s # date + ([\S]*)\s # host + (\w{1,32}):\s # tag + (.*)/x; # MSG + + my $sev = $pri & 0x07; + my $fac = ($pri & 0x03f8) >> 3; + + ok(defined($pri), "$desc has PRI"); + ok($sev >= 0 && $sev <= 7, "$desc valid severity"); + ok($fac >= 0 && $fac < 24, "$desc valid facility"); + + ok(defined($mon), "$desc has month"); + ok((grep $mon, @months), "$desc valid month"); + + ok(defined($mday), "$desc has day"); + ok($mday <= 31, "$desc valid day"); + + ok(defined($hour), "$desc has hour"); + ok($hour < 24, "$desc valid hour"); + + ok(defined($minute), "$desc has minutes"); + ok($minute < 60, "$desc valid minutes"); + + ok(defined($sec), "$desc has seconds"); + ok($sec < 60, "$desc valid seconds"); + + ok(defined($host), "$desc has host"); + chomp(my $hostname = lc `hostname`); + is($host , $hostname, "$desc valid host"); + + ok(defined($tag), "$desc has tag"); + like($tag, qr'\w+', "$desc valid tag"); + + ok(length($msg) > 0, "$desc valid CONTENT"); +} + +############################################################################### + +sub syslog_daemon { + my ($port, $t, $file) = @_; + + my $s = IO::Socket::INET->new( + Proto => 'udp', + LocalAddr => "127.0.0.1:$port" + ); + + open my $fh, '>', $t->testdir() . '/' . $file; + select $fh; $| = 1; + + while (1) { + my $buffer; + $s->recv($buffer, 4096); + print $fh $buffer . "\n"; + } +} + +###############################################################################