line |
stmt |
bran |
cond |
sub |
pod |
time |
code |
1
|
|
|
|
|
|
|
package Protocol::TLS::Trace; |
2
|
2
|
|
|
2
|
|
10
|
use strict; |
|
2
|
|
|
|
|
4
|
|
|
2
|
|
|
|
|
48
|
|
3
|
2
|
|
|
2
|
|
10
|
use warnings; |
|
2
|
|
|
|
|
3
|
|
|
2
|
|
|
|
|
53
|
|
4
|
2
|
|
|
2
|
|
841
|
use Time::HiRes qw(time); |
|
2
|
|
|
|
|
1470
|
|
|
2
|
|
|
|
|
25
|
|
5
|
|
|
|
|
|
|
|
6
|
2
|
|
|
2
|
|
370
|
use Exporter qw(import); |
|
2
|
|
|
|
|
4
|
|
|
2
|
|
|
|
|
1351
|
|
7
|
|
|
|
|
|
|
our @EXPORT_OK = qw(tracer bin2hex); |
8
|
|
|
|
|
|
|
|
9
|
|
|
|
|
|
|
my %levels = ( |
10
|
|
|
|
|
|
|
debug => 0, |
11
|
|
|
|
|
|
|
info => 1, |
12
|
|
|
|
|
|
|
notice => 2, |
13
|
|
|
|
|
|
|
warning => 3, |
14
|
|
|
|
|
|
|
error => 4, |
15
|
|
|
|
|
|
|
critical => 5, |
16
|
|
|
|
|
|
|
alert => 6, |
17
|
|
|
|
|
|
|
emergency => 7, |
18
|
|
|
|
|
|
|
); |
19
|
|
|
|
|
|
|
|
20
|
|
|
|
|
|
|
my $tracer_sngl = Protocol::TLS::Trace->_new( |
21
|
|
|
|
|
|
|
min_level => ( exists $ENV{TLS_DEBUG} && exists $levels{ $ENV{TLS_DEBUG} } ) |
22
|
|
|
|
|
|
|
? $levels{ $ENV{TLS_DEBUG} } |
23
|
|
|
|
|
|
|
: $levels{error} |
24
|
|
|
|
|
|
|
); |
25
|
|
|
|
|
|
|
my $start_time = 0; |
26
|
|
|
|
|
|
|
|
27
|
|
|
|
|
|
|
sub tracer { |
28
|
116
|
|
|
116
|
0
|
651
|
$tracer_sngl; |
29
|
|
|
|
|
|
|
} |
30
|
|
|
|
|
|
|
|
31
|
|
|
|
|
|
|
sub _new { |
32
|
2
|
|
|
2
|
|
8
|
my ( $class, %opts ) = @_; |
33
|
2
|
|
|
|
|
11
|
bless {%opts}, $class; |
34
|
|
|
|
|
|
|
} |
35
|
|
|
|
|
|
|
|
36
|
|
|
|
|
|
|
sub _log { |
37
|
116
|
|
|
116
|
|
219
|
my ( $self, $level, $message ) = @_; |
38
|
116
|
50
|
|
|
|
560
|
if ( $level >= $self->{min_level} ) { |
39
|
0
|
|
|
|
|
0
|
chomp($message); |
40
|
0
|
|
|
|
|
0
|
my @caller = map { s/Protocol::TLS:://; $_ } |
|
0
|
|
|
|
|
0
|
|
|
0
|
|
|
|
|
0
|
|
41
|
|
|
|
|
|
|
( ( caller(2) )[3], ( caller(1) )[2] ); |
42
|
0
|
|
|
|
|
0
|
my $now = time; |
43
|
0
|
0
|
|
|
|
0
|
if ( $now - $start_time < 60 ) { |
44
|
0
|
|
|
|
|
0
|
$message =~ s/\n/\n /g; |
45
|
0
|
|
|
|
|
0
|
printf "[%05.3f] [%s:%s] %s\n", $now - $start_time, @caller, |
46
|
|
|
|
|
|
|
$message; |
47
|
|
|
|
|
|
|
} |
48
|
|
|
|
|
|
|
else { |
49
|
0
|
|
|
|
|
0
|
my @t = ( localtime() )[ 5, 4, 3, 2, 1, 0 ]; |
50
|
0
|
|
|
|
|
0
|
$t[0] += 1900; |
51
|
0
|
|
|
|
|
0
|
$t[1]++; |
52
|
0
|
|
|
|
|
0
|
$message =~ s/\n/\n /g; |
53
|
0
|
|
|
|
|
0
|
printf "[%4d-%02d-%02d %02d:%02d:%02d] [%s:%s] %s\n", @t, |
54
|
|
|
|
|
|
|
@caller, $message; |
55
|
0
|
|
|
|
|
0
|
$start_time = $now; |
56
|
|
|
|
|
|
|
} |
57
|
|
|
|
|
|
|
} |
58
|
|
|
|
|
|
|
} |
59
|
|
|
|
|
|
|
|
60
|
|
|
|
|
|
|
sub debug { |
61
|
116
|
|
|
116
|
0
|
334
|
shift->_log( 0, @_ ); |
62
|
|
|
|
|
|
|
} |
63
|
|
|
|
|
|
|
|
64
|
|
|
|
|
|
|
sub info { |
65
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 1, @_ ); |
66
|
|
|
|
|
|
|
} |
67
|
|
|
|
|
|
|
|
68
|
|
|
|
|
|
|
sub notice { |
69
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 2, @_ ); |
70
|
|
|
|
|
|
|
} |
71
|
|
|
|
|
|
|
|
72
|
|
|
|
|
|
|
sub warning { |
73
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 3, @_ ); |
74
|
|
|
|
|
|
|
} |
75
|
|
|
|
|
|
|
|
76
|
|
|
|
|
|
|
sub error { |
77
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 4, @_ ); |
78
|
|
|
|
|
|
|
} |
79
|
|
|
|
|
|
|
|
80
|
|
|
|
|
|
|
sub critical { |
81
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 5, @_ ); |
82
|
|
|
|
|
|
|
} |
83
|
|
|
|
|
|
|
|
84
|
|
|
|
|
|
|
sub alert { |
85
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 6, @_ ); |
86
|
|
|
|
|
|
|
} |
87
|
|
|
|
|
|
|
|
88
|
|
|
|
|
|
|
sub emergency { |
89
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 7, @_ ); |
90
|
|
|
|
|
|
|
} |
91
|
|
|
|
|
|
|
|
92
|
|
|
|
|
|
|
sub bin2hex { |
93
|
10
|
|
|
10
|
0
|
18
|
my $bin = shift; |
94
|
10
|
|
|
|
|
18
|
my $c = 0; |
95
|
10
|
|
|
|
|
18
|
my $s; |
96
|
|
|
|
|
|
|
|
97
|
|
|
|
|
|
|
join "", map { |
98
|
10
|
|
|
|
|
83
|
$c++; |
|
200
|
|
|
|
|
246
|
|
99
|
200
|
100
|
|
|
|
480
|
$s = !( $c % 16 ) ? "\n" : ( $c % 2 ) ? "" : " "; |
|
|
100
|
|
|
|
|
|
100
|
200
|
|
|
|
|
456
|
$_ . $s |
101
|
|
|
|
|
|
|
} unpack( "(H2)*", $bin ); |
102
|
|
|
|
|
|
|
} |
103
|
|
|
|
|
|
|
|
104
|
|
|
|
|
|
|
1 |