Line 0
Link Here
|
0 |
- |
1 |
# Copyright 2016 KohaSuomi |
|
|
2 |
# |
3 |
# This file is part of Koha. |
4 |
# |
5 |
# Koha is free software; you can redistribute it and/or modify it under the |
6 |
# terms of the GNU General Public License as published by the Free Software |
7 |
# Foundation; either version 3 of the License, or (at your option) any later |
8 |
# version. |
9 |
# |
10 |
# Koha is distributed in the hope that it will be useful, but WITHOUT ANY |
11 |
# WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS FOR |
12 |
# A PARTICULAR PURPOSE. See the GNU General Public License for more details. |
13 |
# |
14 |
# You should have received a copy of the GNU General Public License along |
15 |
# with Koha; if not, write to the Free Software Foundation, Inc., |
16 |
# 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA. |
17 |
|
18 |
use Modern::Perl; |
19 |
use Test::More; |
20 |
|
21 |
use Log::Log4perl; |
22 |
use Time::HiRes; |
23 |
|
24 |
use C4::Context; |
25 |
use Koha::Logger; |
26 |
|
27 |
C4::Context->interface('intranet'); |
28 |
|
29 |
my $acceptedDelay; #This is baselined from the vanilla Log4perl subtest |
30 |
my $iterations = 1000; |
31 |
my $acceptedPerformanceLoss = 1.33; #33% |
32 |
|
33 |
=head1 IN THIS FILE: Performance tests for the Koha::Logger vs the Log::Log4perl |
34 |
|
35 |
0. Create custom logger configuration so we know where we log and can clean up afterwards. |
36 |
1. First we sample performance on this platform for vanilla Log4perl and establish a performance baseline. |
37 |
2. Then we sample performance on Koha::Logger, accepting a \$acceptedPerformanceLoss performance loss against the established baseline. |
38 |
|
39 |
=cut |
40 |
|
41 |
#Initialize the Log4perl to write to /tmp/log4perl_test.log so we can clean it later |
42 |
my $conf = join("\n",<DATA>); |
43 |
Log::Log4perl::init(\$conf); |
44 |
|
45 |
|
46 |
subtest "Log4perl vanilla, 10000 errors", \&Log4perlVanilla; |
47 |
sub Log4perlVanilla { |
48 |
my $startTime = Time::HiRes::time(); |
49 |
Log::Log4perl::init_once( C4::Context->config("log4perl_conf") ); |
50 |
foreach my $i (1..$iterations) { |
51 |
my $logger = Log::Log4perl->get_logger(C4::Context->interface().".vanu$i"); |
52 |
_logErrors($logger); |
53 |
} |
54 |
my $testDuration = Time::HiRes::time() - $startTime; |
55 |
$acceptedDelay = $testDuration * $acceptedPerformanceLoss; #Set the performance baseline for further tests in this suite |
56 |
ok($testDuration < $acceptedDelay, "Test duration $testDuration < $acceptedDelay"); |
57 |
verifyThatLogWasWritten(); |
58 |
} |
59 |
|
60 |
subtest "Log4perl Koha::Logger from KOHA_CONF, 10000 errors", \&Log4perlKohaLogger; |
61 |
sub Log4perlKohaLogger { |
62 |
my $startTime = Time::HiRes::time(); |
63 |
foreach my $i (1..$iterations) { |
64 |
my $logger = Koha::Logger->get({category => "jarr$i"}); |
65 |
_logErrors($logger); |
66 |
} |
67 |
my $testDuration = Time::HiRes::time() - $startTime; |
68 |
ok($testDuration < $acceptedDelay, "Test duration $testDuration < $acceptedDelay"); |
69 |
verifyThatLogWasWritten(); |
70 |
} |
71 |
|
72 |
$ENV{"LOG4PERL_CONF"} = C4::Context->config("log4perl_conf"); |
73 |
subtest "Log4perl Koha::Logger from ENV, 10000 errors", \&Log4perlKohaLogger2; |
74 |
sub Log4perlKohaLogger2 { #Redefine the same subroutine 'Log4perlKohaLogger', otherwise perl compiler will distort the test results |
75 |
my $startTime = Time::HiRes::time(); |
76 |
foreach my $i (1..$iterations) { |
77 |
my $logger = Koha::Logger->get({category => "argh$i"}); |
78 |
_logErrors($logger); |
79 |
} |
80 |
my $testDuration = Time::HiRes::time() - $startTime; |
81 |
ok($testDuration < $acceptedDelay, "Test duration $testDuration < $acceptedDelay"); |
82 |
verifyThatLogWasWritten(); |
83 |
} |
84 |
|
85 |
done_testing(); |
86 |
|
87 |
sub _logErrors { |
88 |
my ($logger) = @_; |
89 |
$logger->error('The incredible burden of building good logging faculties'); |
90 |
$logger->error('The incredible burden of building good logging faculties'); |
91 |
$logger->error('The incredible burden of building good logging faculties'); |
92 |
$logger->error('The incredible burden of building good logging faculties'); |
93 |
$logger->error('The incredible burden of building good logging faculties'); |
94 |
$logger->error('The incredible burden of building good logging faculties'); |
95 |
$logger->error('The incredible burden of building good logging faculties'); |
96 |
$logger->error('The incredible burden of building good logging faculties'); |
97 |
$logger->error('The incredible burden of building good logging faculties'); |
98 |
$logger->error('The incredible burden of building good logging faculties'); |
99 |
} |
100 |
|
101 |
sub verifyThatLogWasWritten { |
102 |
#Verify that we actually wrote something and the Koha::Logger configurations work |
103 |
open(my $FH, '<', '/tmp/log4perl_test.log') or die $!; |
104 |
my $firstRow = <$FH>; |
105 |
close($FH); |
106 |
ok($firstRow =~ /The incredible burden of building good logging faculties/, "Log writing confirmed"); |
107 |
#Clean up the temp log file or it will grow big quickly |
108 |
open($FH, '>', '/tmp/log4perl_test.log') or die $!; |
109 |
close($FH); |
110 |
} |
111 |
|
112 |
__DATA__ |
113 |
log4perl.logger.intranet = WARN, INTRANET |
114 |
log4perl.appender.INTRANET=Log::Log4perl::Appender::File |
115 |
log4perl.appender.INTRANET.filename=/tmp/log4perl_test.log |
116 |
log4perl.appender.INTRANET.mode=append |
117 |
log4perl.appender.INTRANET.layout=PatternLayout |
118 |
log4perl.appender.INTRANET.layout.ConversionPattern=[%d] [%p] %m %l %n |
119 |
|
120 |
log4perl.logger.opac = WARN, OPAC |
121 |
log4perl.appender.OPAC=Log::Log4perl::Appender::File |
122 |
log4perl.appender.OPAC.filename=/tmp/log4perl_test.log |
123 |
log4perl.appender.OPAC.mode=append |
124 |
log4perl.appender.OPAC.layout=PatternLayout |
125 |
log4perl.appender.OPAC.layout.ConversionPattern=[%d] [%p] %m %l %n |