line |
stmt |
bran |
cond |
sub |
pod |
time |
code |
1
|
|
|
|
|
|
|
package Protocol::TLS::Trace; |
2
|
2
|
|
|
2
|
|
10
|
use strict; |
|
2
|
|
|
|
|
3
|
|
|
2
|
|
|
|
|
68
|
|
3
|
2
|
|
|
2
|
|
10
|
use warnings; |
|
2
|
|
|
|
|
4
|
|
|
2
|
|
|
|
|
57
|
|
4
|
2
|
|
|
2
|
|
13786
|
use Time::HiRes qw(time); |
|
2
|
|
|
|
|
1999
|
|
|
2
|
|
|
|
|
13
|
|
5
|
|
|
|
|
|
|
|
6
|
2
|
|
|
2
|
|
440
|
use Exporter qw(import); |
|
2
|
|
|
|
|
5
|
|
|
2
|
|
|
|
|
1192
|
|
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
|
572
|
$tracer_sngl; |
29
|
|
|
|
|
|
|
} |
30
|
|
|
|
|
|
|
|
31
|
|
|
|
|
|
|
sub _new { |
32
|
2
|
|
|
2
|
|
7
|
my ( $class, %opts ) = @_; |
33
|
2
|
|
|
|
|
13
|
bless {%opts}, $class; |
34
|
|
|
|
|
|
|
} |
35
|
|
|
|
|
|
|
|
36
|
|
|
|
|
|
|
sub _log { |
37
|
116
|
|
|
116
|
|
140
|
my ( $self, $level, $message ) = @_; |
38
|
116
|
50
|
|
|
|
416
|
if ( $level >= $self->{min_level} ) { |
39
|
0
|
|
|
|
|
0
|
chomp($message); |
40
|
0
|
|
|
|
|
0
|
my $now = time; |
41
|
0
|
0
|
|
|
|
0
|
if ( $now - $start_time < 60 ) { |
42
|
0
|
|
|
|
|
0
|
$message =~ s/\n/\n /g; |
43
|
0
|
|
|
|
|
0
|
printf "[%05.3f] %s\n", $now - $start_time, $message; |
44
|
|
|
|
|
|
|
} |
45
|
|
|
|
|
|
|
else { |
46
|
0
|
|
|
|
|
0
|
my @t = ( localtime() )[ 5, 4, 3, 2, 1, 0 ]; |
47
|
0
|
|
|
|
|
0
|
$t[0] += 1900; |
48
|
0
|
|
|
|
|
0
|
$t[1]++; |
49
|
0
|
|
|
|
|
0
|
$message =~ s/\n/\n /g; |
50
|
0
|
|
|
|
|
0
|
printf "[%4d-%02d-%02d %02d:%02d:%02d] %s\n", @t, $message; |
51
|
0
|
|
|
|
|
0
|
$start_time = $now; |
52
|
|
|
|
|
|
|
} |
53
|
|
|
|
|
|
|
} |
54
|
|
|
|
|
|
|
} |
55
|
|
|
|
|
|
|
|
56
|
|
|
|
|
|
|
sub debug { |
57
|
116
|
|
|
116
|
0
|
244
|
shift->_log( 0, @_ ); |
58
|
|
|
|
|
|
|
} |
59
|
|
|
|
|
|
|
|
60
|
|
|
|
|
|
|
sub info { |
61
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 1, @_ ); |
62
|
|
|
|
|
|
|
} |
63
|
|
|
|
|
|
|
|
64
|
|
|
|
|
|
|
sub notice { |
65
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 2, @_ ); |
66
|
|
|
|
|
|
|
} |
67
|
|
|
|
|
|
|
|
68
|
|
|
|
|
|
|
sub warning { |
69
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 3, @_ ); |
70
|
|
|
|
|
|
|
} |
71
|
|
|
|
|
|
|
|
72
|
|
|
|
|
|
|
sub error { |
73
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 4, @_ ); |
74
|
|
|
|
|
|
|
} |
75
|
|
|
|
|
|
|
|
76
|
|
|
|
|
|
|
sub critical { |
77
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 5, @_ ); |
78
|
|
|
|
|
|
|
} |
79
|
|
|
|
|
|
|
|
80
|
|
|
|
|
|
|
sub alert { |
81
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 6, @_ ); |
82
|
|
|
|
|
|
|
} |
83
|
|
|
|
|
|
|
|
84
|
|
|
|
|
|
|
sub emergency { |
85
|
0
|
|
|
0
|
0
|
0
|
shift->_log( 7, @_ ); |
86
|
|
|
|
|
|
|
} |
87
|
|
|
|
|
|
|
|
88
|
|
|
|
|
|
|
sub bin2hex { |
89
|
10
|
|
|
10
|
0
|
14
|
my $bin = shift; |
90
|
10
|
|
|
|
|
16
|
my $c = 0; |
91
|
10
|
|
|
|
|
10
|
my $s; |
92
|
|
|
|
|
|
|
|
93
|
200
|
|
|
|
|
182
|
join "", map { |
94
|
10
|
|
|
|
|
69
|
$c++; |
95
|
200
|
100
|
|
|
|
376
|
$s = !( $c % 16 ) ? "\n" : ( $c % 2 ) ? "" : " "; |
|
|
100
|
|
|
|
|
|
96
|
200
|
|
|
|
|
313
|
$_ . $s |
97
|
|
|
|
|
|
|
} unpack( "(H2)*", $bin ); |
98
|
|
|
|
|
|
|
} |
99
|
|
|
|
|
|
|
|
100
|
|
|
|
|
|
|
1 |