> Fri, 02 Apr 2004 09:30:33 -0800:46026: e_v_r:email_quarantine_report took 0.052252 seconds to execute Fri, 02 Apr 2004 09:30:33 -0800:46026: cleanup: /bin/rm -rf /var/spool/qmailscan/tmp/pravda.smileglobal.com108092703357446026/ /var/spool/qmailscan/working/new/pravda.smileglobal.com1080927 03357446026 Fri, 02 Apr 2004 09:30:57 -0800:46026: --- all finished. Total of 24.215865 secs
Paul Theodoropoulos http://www.anastrophe.com
Just checked mine. Here is an example:
Fri, 02 Apr 2004 13:58:04 EST:24833: cleanup: /bin/rm -rf /var/qmail/qmailscan/tmp/gen108093228457424833/ /var/qmail/qmailscan/working/new/gen108093228457424833 Fri, 02 Apr 2004 13:58:14 EST:24833: --- all finished. Total of 9.679984 secs
Looks like most of the scanning time takes place when removing file. *OR* the date/time is not getting updated until the last line is written so it misleads you to think that a large time jump happens at the end. This is what I bet it is...
Ed.
Hi
Ed is right. I added some time ago some debugs line to track this 'strange delay', well it is only cosmetic. qmail-scanner use the same "nowtime" in all the log lines execpt the last one, have a look at these lines (from 1.20st but there is no diference in this):
03/04/2004 00:08:21:30185: +++ starting debugging for process 30185 by uid=81
03/04/2004 00:08:21:30185: w_c: elapsed time from start 0.007008 secs
03/04/2004 00:08:21:30185: return-path='xxxt', recips='xxx'
03/04/2004 00:08:21:30185: from='[EMAIL PROTECTED] (Cron Daemon)', subj='Cron <[EMAIL PROTECTED]> sh /usr/local/urbefilter/tools/logsmanagement /usr/local/urbefilter', via SMTP from 193.43.128.2
03/04/2004 00:08:21:30185: sophie: finished scan in 0.035839 secs
03/04/2004 00:08:21:30185: SA: REPORT hits = -2.9/6.5
-2.9 BAYES_00 BODY: Bayesian spam probability is 0 to 1%
-0.0 AWL AWL: Auto-whitelist adjustment
03/04/2004 00:08:21:30185: SA: required_hits=6.5 sa_quarantine=8.3 sa_delete=11
03/04/2004 00:08:21:30185: SA: finished scan in 12.515738 secs - hits=-2.9
03/04/2004 00:08:21:30185: p_s: finished scan in 0.0256729999999999 secs
03/04/2004 00:08:21:30185: ini_sc: finished scan of "/var/spool/qmailscan/tmp/apo136.usc.urbe.it108094370154730185"...
03/04/2004 00:08:21:30185: ini_sc: elapsed time from start 12.60511 secs
03/04/2004 00:08:34:30185: ------ Process 30185 finished. Total of 12.666797 secs
In this messages almost all the elapsed time was for spamassassin. With some messages working_copy (w_c) uses a long time.
Regards
Salvatore
------------------------------------------------------- This SF.Net email is sponsored by: IBM Linux Tutorials Free Linux tutorial presented by Daniel Robbins, President and CEO of GenToo technologies. Learn everything from fundamentals to system administration.http://ads.osdn.com/?ad_id=1470&alloc_id=3638&op=click _______________________________________________ Qmail-scanner-general mailing list [EMAIL PROTECTED] https://lists.sourceforge.net/lists/listinfo/qmail-scanner-general
