← Index
NYTProf Performance Profile   « line view »
For /usr/local/bin/sa-learn
  Run on Sun Nov 5 02:36:06 2017
Reported on Sun Nov 5 02:56:20 2017

Filename/usr/local/lib/perl5/site_perl/Mail/SpamAssassin/Locker/UnixNFSSafe.pm
StatementsExecuted 149 statements in 6.75ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
111992µs3.86msMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@26Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@26
222692µs2.63msMail::SpamAssassin::Locker::UnixNFSSafe::::safe_lockMail::SpamAssassin::Locker::UnixNFSSafe::safe_lock
531629µs629µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:unlinkMail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink (opcode)
211610µs610µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:sysopenMail::SpamAssassin::Locker::UnixNFSSafe::CORE:sysopen (opcode)
222606µs5.64sMail::SpamAssassin::Locker::UnixNFSSafe::::safe_unlockMail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock
211416µs416µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:openMail::SpamAssassin::Locker::UnixNFSSafe::CORE:open (opcode)
421247µs247µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:printMail::SpamAssassin::Locker::UnixNFSSafe::CORE:print (opcode)
211139µs139µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:linkMail::SpamAssassin::Locker::UnixNFSSafe::CORE:link (opcode)
421137µs137µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:lstatMail::SpamAssassin::Locker::UnixNFSSafe::CORE:lstat (opcode)
11158µs74µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@20Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@20
42144µs44µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:closeMail::SpamAssassin::Locker::UnixNFSSafe::CORE:close (opcode)
11135µs107µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@23Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@23
11133µs267µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@28Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@28
11131µs65µsMail::SpamAssassin::Locker::UnixNFSSafe::::newMail::SpamAssassin::Locker::UnixNFSSafe::new
11130µs135µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@27Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@27
11130µs186µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@30Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@30
11126µs220µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@57Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@57
11125µs32µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@22Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@22
11123µs63µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@21Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@21
11121µs100µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@33Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@33
11120µs3.10msMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@31Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@31
21118µs18µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:statMail::SpamAssassin::Locker::UnixNFSSafe::CORE:stat (opcode)
11117µs17µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@29Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@29
11116µs16µsMail::SpamAssassin::Locker::UnixNFSSafe::::BEGIN@25Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@25
42110µs10µsMail::SpamAssassin::Locker::UnixNFSSafe::::CORE:umaskMail::SpamAssassin::Locker::UnixNFSSafe::CORE:umask (opcode)
0000s0sMail::SpamAssassin::Locker::UnixNFSSafe::::refresh_lockMail::SpamAssassin::Locker::UnixNFSSafe::refresh_lock
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1# <@LICENSE>
2# Licensed to the Apache Software Foundation (ASF) under one or more
3# contributor license agreements. See the NOTICE file distributed with
4# this work for additional information regarding copyright ownership.
5# The ASF licenses this file to you under the Apache License, Version 2.0
6# (the "License"); you may not use this file except in compliance with
7# the License. You may obtain a copy of the License at:
8#
9# http://www.apache.org/licenses/LICENSE-2.0
10#
11# Unless required by applicable law or agreed to in writing, software
12# distributed under the License is distributed on an "AS IS" BASIS,
13# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
14# See the License for the specific language governing permissions and
15# limitations under the License.
16# </@LICENSE>
17
18package Mail::SpamAssassin::Locker::UnixNFSSafe;
19
20272µs289µs
# spent 74µs (58+16) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@20 which was called: # once (58µs+16µs) by Mail::SpamAssassin::BEGIN@2 at line 20
use strict;
# spent 74µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@20 # spent 16µs making 1 call to strict::import
21264µs2104µs
# spent 63µs (23+40) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@21 which was called: # once (23µs+40µs) by Mail::SpamAssassin::BEGIN@2 at line 21
use warnings;
# spent 63µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@21 # spent 40µs making 1 call to warnings::import
22269µs239µs
# spent 32µs (25+7) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@22 which was called: # once (25µs+7µs) by Mail::SpamAssassin::BEGIN@2 at line 22
use bytes;
# spent 32µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@22 # spent 7µs making 1 call to bytes::import
23269µs2178µs
# spent 107µs (35+72) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@23 which was called: # once (35µs+72µs) by Mail::SpamAssassin::BEGIN@2 at line 23
use re 'taint';
# spent 107µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@23 # spent 72µs making 1 call to re::import
24
25256µs116µs
# spent 16µs within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@25 which was called: # once (16µs+0s) by Mail::SpamAssassin::BEGIN@2 at line 25
use Mail::SpamAssassin;
# spent 16µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@25
262280µs13.86ms
# spent 3.86ms (992µs+2.87) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@26 which was called: # once (992µs+2.87ms) by Mail::SpamAssassin::BEGIN@2 at line 26
use Mail::SpamAssassin::Locker;
# spent 3.86ms making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@26
27271µs2240µs
# spent 135µs (30+105) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@27 which was called: # once (30µs+105µs) by Mail::SpamAssassin::BEGIN@2 at line 27
use Mail::SpamAssassin::Util;
# spent 135µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@27 # spent 105µs making 1 call to Exporter::import
28269µs2501µs
# spent 267µs (33+234) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@28 which was called: # once (33µs+234µs) by Mail::SpamAssassin::BEGIN@2 at line 28
use Mail::SpamAssassin::Logger;
# spent 267µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@28 # spent 234µs making 1 call to Exporter::import
29253µs117µs
# spent 17µs within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@29 which was called: # once (17µs+0s) by Mail::SpamAssassin::BEGIN@2 at line 29
use File::Spec;
# spent 17µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@29
30271µs2343µs
# spent 186µs (30+157) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@30 which was called: # once (30µs+157µs) by Mail::SpamAssassin::BEGIN@2 at line 30
use Time::Local;
# spent 186µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@30 # spent 157µs making 1 call to Exporter::import
31277µs26.18ms
# spent 3.10ms (20µs+3.08) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@31 which was called: # once (20µs+3.08ms) by Mail::SpamAssassin::BEGIN@2 at line 31
use Fcntl qw(:DEFAULT :flock);
# spent 3.10ms making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@31 # spent 3.08ms making 1 call to Exporter::import
32
3312µs
# spent 100µs (21+79) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@33 which was called: # once (21µs+79µs) by Mail::SpamAssassin::BEGIN@2 at line 35
use vars qw{
34 @ISA
351164µs2180µs};
# spent 100µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@33 # spent 79µs making 1 call to vars::import
36
37115µs@ISA = qw(Mail::SpamAssassin::Locker);
38
39###########################################################################
40
41
# spent 65µs (31+34) within Mail::SpamAssassin::Locker::UnixNFSSafe::new which was called: # once (31µs+34µs) by Mail::SpamAssassin::create_locker at line 3 of (eval 33)[Mail/SpamAssassin.pm:475]
sub new {
4212µs my $class = shift;
43110µs134µs my $self = $class->SUPER::new(@_);
# spent 34µs making 1 call to Mail::SpamAssassin::Locker::new
4419µs $self;
45}
46
47###########################################################################
48# NFS-safe locking (I hope!):
49# Attempt to create a file lock, using NFS-safe locking techniques.
50#
51# Locking code adapted from code by Alexis Rosen <alexis@panix.com>
52# by Kelsey Cummings <kgc@sonic.net>, with mods by jm and quinlan
53#
54# A good implementation of Alexis' code, for reference, is here:
55# http://mail-index.netbsd.org/netbsd-bugs/1996/04/17/0002.html
56
5722.14ms2414µs
# spent 220µs (26+194) within Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@57 which was called: # once (26µs+194µs) by Mail::SpamAssassin::BEGIN@2 at line 57
use constant LOCK_MAX_AGE => 600; # seconds
# spent 220µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::BEGIN@57 # spent 194µs making 1 call to constant::import
58
59
# spent 2.63ms (692µs+1.93) within Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock which was called 2 times, avg 1.31ms/call: # once (438µs+1.22ms) by Mail::SpamAssassin::DBBasedAddrList::new_checker at line 71 of Mail/SpamAssassin/DBBasedAddrList.pm # once (254µs+718µs) by Mail::SpamAssassin::BayesStore::DBM::tie_db_writable at line 280 of Mail/SpamAssassin/BayesStore/DBM.pm
sub safe_lock {
6027µs my ($self, $path, $max_retries, $mode) = @_;
6125µs my $is_locked = 0;
6224µs my @stat;
63
6424µs $max_retries ||= 30;
6524µs $mode ||= "0700";
6628µs $mode = (oct $mode) & 0666;
67218µs215µs dbg ("locker: mode is $mode");
# spent 15µs making 2 calls to Mail::SpamAssassin::Logger::dbg, avg 8µs/call
68
6927µs my $lock_file = "$path.lock";
70219µs2340µs my $hname = Mail::SpamAssassin::Util::fq_hostname();
# spent 340µs making 2 calls to Mail::SpamAssassin::Util::fq_hostname, avg 170µs/call
71229µs2219µs my $lock_tmp = Mail::SpamAssassin::Util::untaint_file_path
# spent 219µs making 2 calls to Mail::SpamAssassin::Util::untaint_file_path, avg 110µs/call
72 ($path.".lock.".$hname.".".$$);
73
74 # keep this for unlocking
75216µs $self->{lock_tmp} = $lock_tmp;
76
77228µs26µs my $umask = umask(~$mode);
# spent 6µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:umask, avg 3µs/call
782444µs2416µs if (!open(LTMP, ">$lock_tmp")) {
# spent 416µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:open, avg 208µs/call
79 umask $umask; # just in case
80 die "locker: safe_lock: cannot create tmp lockfile $lock_tmp for $lock_file: $!\n";
81 }
82218µs24µs umask $umask;
# spent 4µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:umask, avg 2µs/call
83237µs2280µs autoflush LTMP 1;
# spent 280µs making 2 calls to IO::Handle::autoflush, avg 140µs/call
84222µs215µs dbg("locker: safe_lock: created $lock_tmp");
# spent 15µs making 2 calls to Mail::SpamAssassin::Logger::dbg, avg 7µs/call
85
86212µs for (my $retries = 0; $retries < $max_retries; $retries++) {
8724µs if ($retries > 0) { $self->jittery_one_second_sleep(); }
882149µs2120µs print LTMP "$hname.$$\n" or warn "Error writing to $lock_tmp: $!";
# spent 120µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:print, avg 60µs/call
89221µs214µs dbg("locker: safe_lock: trying to get lock on $path with $retries retries");
# spent 14µs making 2 calls to Mail::SpamAssassin::Logger::dbg, avg 7µs/call
902161µs2139µs if (link($lock_tmp, $lock_file)) {
# spent 139µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:link, avg 70µs/call
91217µs216µs dbg("locker: safe_lock: link to $lock_file: link ok");
# spent 16µs making 2 calls to Mail::SpamAssassin::Logger::dbg, avg 8µs/call
9224µs $is_locked = 1;
9327µs last;
94 }
95 # link _may_ return false even if the link _is_ created
96 @stat = lstat($lock_tmp);
97 @stat or warn "locker: error accessing $lock_tmp: $!";
98 if (defined $stat[3] && $stat[3] > 1) {
99 dbg("locker: safe_lock: link to $lock_file: stat ok");
100 $is_locked = 1;
101 last;
102 }
103 # check age of lockfile ctime
104 my $now = ($#stat < 11 ? undef : $stat[10]);
105 @stat = lstat($lock_file);
106 @stat or warn "locker: error accessing $lock_file: $!";
107 my $lock_age = ($#stat < 11 ? undef : $stat[10]);
108 if (defined($lock_age) && defined($now) && ($now - $lock_age) > LOCK_MAX_AGE)
109 {
110 # we got a stale lock, break it
111 dbg("locker: safe_lock: breaking stale $lock_file: age=" .
112 (defined $lock_age ? $lock_age : "undef") . " now=$now");
113 unlink($lock_file)
114 or warn "locker: safe_lock: unlink of lock file $lock_file failed: $!\n";
115 }
116 }
117
118239µs220µs close LTMP or die "error closing $lock_tmp: $!";
# spent 20µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:close, avg 10µs/call
1192305µs2236µs unlink($lock_tmp)
# spent 236µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink, avg 118µs/call
120 or warn "locker: safe_lock: unlink of temp lock $lock_tmp failed: $!\n";
121
122 # record this for safe unlocking
123210µs if ($is_locked) {
1242152µs295µs @stat = lstat($lock_file);
# spent 95µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:lstat, avg 48µs/call
12524µs @stat or warn "locker: error accessing $lock_file: $!";
12629µs my $lock_ctime = ($#stat < 11 ? undef : $stat[10]);
127
128211µs $self->{lock_ctimes} ||= { };
129211µs $self->{lock_ctimes}->{$path} = $lock_ctime;
130 }
131
132236µs return $is_locked;
133}
134
135###########################################################################
136
137
# spent 5.64s (606µs+5.64) within Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock which was called 2 times, avg 2.82s/call: # once (409µs+5.64s) by Mail::SpamAssassin::DBBasedAddrList::finish at line 113 of Mail/SpamAssassin/DBBasedAddrList.pm # once (197µs+652µs) by Mail::SpamAssassin::BayesStore::DBM::untie_db at line 627 of Mail/SpamAssassin/BayesStore/DBM.pm
sub safe_unlock {
138210µs my ($self, $path) = @_;
139
140213µs my $lock_file = "$path.lock";
14128µs my $lock_tmp = $self->{lock_tmp};
14225µs if (!$lock_tmp) {
143 dbg("locker: safe_unlock: $path.lock never locked");
144 return;
145 }
146
147 # 1. Build a temp file and stat that to get an idea of what the server
148 # thinks the current time is (our_tmp.st_ctime). note: do not use time()
149 # directly because the server's clock may be out of sync with the client's.
150
15125µs my @stat_ourtmp;
1522654µs2610µs if (!defined sysopen(LTMP, $lock_tmp, O_CREAT|O_WRONLY|O_EXCL, 0700)) {
# spent 610µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:sysopen, avg 305µs/call
153 warn "locker: safe_unlock: failed to create lock tmpfile $lock_tmp: $!";
154 return;
155 } else {
156235µs2325µs autoflush LTMP 1;
# spent 325µs making 2 calls to IO::Handle::autoflush, avg 163µs/call
1572151µs2128µs print LTMP "\n" or warn "Error writing to $lock_tmp: $!";
# spent 128µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:print, avg 64µs/call
158
159258µs218µs if (!(@stat_ourtmp = stat(LTMP)) || (scalar(@stat_ourtmp) < 11)) {
# spent 18µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:stat, avg 9µs/call
160 @stat_ourtmp or warn "locker: error accessing $lock_tmp: $!";
161 warn "locker: safe_unlock: failed to create lock tmpfile $lock_tmp";
162 close LTMP or die "error closing $lock_tmp: $!";
163 unlink($lock_tmp)
164 or warn "locker: safe_lock: unlink of lock file failed: $!\n";
165 return;
166 }
167 }
168
16925µs my $ourtmp_ctime = $stat_ourtmp[10]; # paranoia
17025µs if (!defined $ourtmp_ctime) {
171 die "locker: safe_unlock: stat failed on $lock_tmp";
172 }
173
174244µs224µs close LTMP or die "error closing $lock_tmp: $!";
# spent 24µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:close, avg 12µs/call
1752304µs2283µs unlink($lock_tmp)
# spent 283µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink, avg 142µs/call
176 or warn "locker: safe_lock: unlink of lock file failed: $!\n";
177
178 # 2. If the ctime hasn't been modified, unlink the file and return. If the
179 # lock has expired, sleep the usual random interval before returning. If we
180 # didn't sleep, there could be a race if the caller immediately tries to
181 # relock the file.
182
183210µs my $lock_ctime = $self->{lock_ctimes}->{$path};
18424µs if (!defined $lock_ctime) {
185 warn "locker: safe_unlock: no ctime recorded for $lock_file";
186 return;
187 }
188
189268µs242µs my @stat_lock = lstat($lock_file);
# spent 42µs making 2 calls to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:lstat, avg 21µs/call
190262µs @stat_lock or warn "locker: error accessing $lock_file: $!";
191
19225µs my $now_ctime = $stat_lock[10];
193
19426µs if (defined $now_ctime && $now_ctime == $lock_ctime)
195 {
196 # things are good: the ctimes match so it was our lock
1971120µs1110µs unlink($lock_file)
# spent 110µs making 1 call to Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink
198 or warn "locker: safe_unlock: unlink failed: $lock_file\n";
199111µs18µs dbg("locker: safe_unlock: unlink $lock_file");
# spent 8µs making 1 call to Mail::SpamAssassin::Logger::dbg
200
20116µs if ($ourtmp_ctime >= $lock_ctime + LOCK_MAX_AGE) {
202 # the lock has expired, so sleep a bit; use some randomness
203 # to avoid race conditions.
204110µs16µs dbg("locker: safe_unlock: lock expired on $lock_file expired safely; sleeping");
# spent 6µs making 1 call to Mail::SpamAssassin::Logger::dbg
205249µs my $i; for ($i = 0; $i < 5; $i++) {
2065113µs55.64s $self->jittery_one_second_sleep();
# spent 5.64s making 5 calls to Mail::SpamAssassin::Locker::jittery_one_second_sleep, avg 1.13s/call
207 }
208 }
209140µs return;
210 }
211
212 # 4. Either ctime has been modified, or the entire lock file is missing.
213 # If the lock should still be ours, based on the ctime of the temp
214 # file, warn it was stolen. If not, then our lock is expired and
215 # someone else has grabbed the file, so warn it was lost.
216116µs if ($ourtmp_ctime < $lock_ctime + LOCK_MAX_AGE) {
217 warn "locker: safe_unlock: lock on $lock_file was stolen";
218 } else {
219110µs warn "locker: safe_unlock: lock on $lock_file was lost due to expiry";
220 }
221}
222
223###########################################################################
224
225sub refresh_lock {
226 my($self, $path) = @_;
227
228 return unless $path;
229
230 # this could arguably read the lock and make sure the same process
231 # owns it, but this shouldn't, in theory, be an issue.
232 # TODO: in NFS, it definitely may be one :(
233
234 my $lock_file = "$path.lock";
235 utime time, time, $lock_file;
236
237 # update the lock_ctimes entry
238 my @stat = lstat($lock_file);
239 @stat or warn "locker: error accessing $lock_file: $!";
240
241 my $lock_ctime = ($#stat < 11 ? undef : $stat[10]);
242 $self->{lock_ctimes}->{$path} = $lock_ctime;
243
244 dbg("locker: refresh_lock: refresh $path.lock");
245}
246
247###########################################################################
248
24918µs1;
 
# spent 44µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:close which was called 4 times, avg 11µs/call: # 2 times (24µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 174, avg 12µs/call # 2 times (20µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 118, avg 10µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:close; # opcode
# spent 139µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:link which was called 2 times, avg 70µs/call: # 2 times (139µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 90, avg 70µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:link; # opcode
# spent 137µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:lstat which was called 4 times, avg 34µs/call: # 2 times (95µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 124, avg 48µs/call # 2 times (42µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 189, avg 21µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:lstat; # opcode
# spent 416µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:open which was called 2 times, avg 208µs/call: # 2 times (416µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 78, avg 208µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:open; # opcode
# spent 247µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:print which was called 4 times, avg 62µs/call: # 2 times (128µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 157, avg 64µs/call # 2 times (120µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 88, avg 60µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:print; # opcode
# spent 18µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:stat which was called 2 times, avg 9µs/call: # 2 times (18µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 159, avg 9µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:stat; # opcode
# spent 610µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:sysopen which was called 2 times, avg 305µs/call: # 2 times (610µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 152, avg 305µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:sysopen; # opcode
# spent 10µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:umask which was called 4 times, avg 3µs/call: # 2 times (6µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 77, avg 3µs/call # 2 times (4µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 82, avg 2µs/call
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:umask; # opcode
# spent 629µs within Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink which was called 5 times, avg 126µs/call: # 2 times (283µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 175, avg 142µs/call # 2 times (236µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_lock at line 119, avg 118µs/call # once (110µs+0s) by Mail::SpamAssassin::Locker::UnixNFSSafe::safe_unlock at line 197
sub Mail::SpamAssassin::Locker::UnixNFSSafe::CORE:unlink; # opcode
Report produced by the NYTProf 6.04 Perl profiler, developed by Tim Bunce based on work by Adam Kaplan and Salvador Fandiño García.