System: OpenBSD based on the FlakShack howto (SA being called from AmavisD, w/ClamAV and Rules Du Jour in the mix). Recently upgraded to SA 3.0, then SA 3.01 via CPAN.
The majority of my email is culled from other accounts (primarily stickyc.com) using Fetchmail.
From looking at the debug, it appears that the SURBL is firing off, but not finding suspect addresses, the Bayes list is loading, but not being checked and none of the external rules are being evaluated. There's some errors during initialization where some of the rules complain about being the incorrect version:
configuration file "/usr/local/share/spamassassin/20_dnsbl_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
I've looked at these rules, and they do have the following line in them: require_version 3.000001
I'm at a total loss as to why this is failing (I also would expect SA to gracefully ignore the bad rules and at least use the good ones).
Here's my local.cf (lines commented out when I migrated from 2.65 to 3.0):
# This is the right place to customize your installation of SpamAssassin. # # See 'perldoc Mail::SpamAssassin::Conf' for details of what can be # tweaked. # ########################################################################### # #rewrite_subject 1
report_safe 0 #use_terse_report 0
lock_method flock
use_bayes 1 bayes_path /var/amavisd/.spamassassin/bayes #auto_learn 1
skip_rbl_checks 0 use_razor2 1 use_dcc 1 use_pyzor 1 #dcc_add_header 1
dns_available yes
header LOCAL_RCVD Received =~ /.*\(\S+\.casadelove\.com\s+\[.*\]\)/ describe LOCAL_RCVD Received from local machine #score LOCAL_RCVD -50
## Optional Score Increases score DCC_CHECK 4.000 score RAZOR2_CHECK 2.500 score BAYES_99 4.300 score BAYES_90 3.500 score BAYES_80 3.000
And here's a debug dump of launch and a single spam session:
# /usr/local/sbin/amavisd debug
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: starting. amavisd at postoffice.casadelove.com amavisd-new-20030616-p9, Unicode aware
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Perl version 5.008002
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Amavis::Conf 1.15
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Archive::Tar 1.09
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Archive::Zip 1.10
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Compress::Zlib 1.33
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Convert::TNEF 0.17
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Convert::UUlib 1.03
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module DB_File 1.810
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module MIME::Entity 5.404
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module MIME::Parser 5.406
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module MIME::Tools 5.411
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Mail::Header 1.62
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Mail::Internet 1.62
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Mail::SpamAssassin 3.000001
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Net::Cmd 2.25
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Net::DNS 0.47
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Net::SMTP 2.28
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Net::Server 0.87
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Time::HiRes 1.59
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Module Unix::Syslog 0.99
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found myself: /usr/local/sbin/amavisd -c /etc/amavisd.conf
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Lookup::SQL code NOT loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Lookup::LDAP code NOT loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: AMCL-in protocol code loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: SMTP-in protocol code loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: ANTI-VIRUS code loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: ANTI-SPAM code loaded
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: 2004/10/26-12:59:27 Amavis (type Net::Server::PreForkSimple) starting! pid(11831)
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Binding to UNIX socket file /var/amavisd/amavisd.sock using SOCK_STREAM
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Chrooting to /var/amavisd
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Setting gid to "3000 3000"
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Setting uid to "3000"
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Net::Server: Setting up serialization via flock
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $file at (chroot: /var/amavisd/) /usr/bin/file
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $arc at (chroot: /var/amavisd/) /usr/bin/arc
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $gzip at (chroot: /var/amavisd/) /usr/bin/gzip
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $bzip2 at (chroot: /var/amavisd/) /usr/bin/bzip2
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: No $lzop, not using it
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $lha at (chroot: /var/amavisd/) /usr/bin/lha
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $unarj at (chroot: /var/amavisd/) /usr/bin/unarj
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $uncompress at (chroot: /var/amavisd/) /usr/bin/gzip -d
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: No $unfreeze, not using it
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $unrar at (chroot: /var/amavisd/) /usr/bin/unrar
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $zoo at (chroot: /var/amavisd/) /usr/bin/zoo
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found $cpio at (chroot: /var/amavisd/) /usr/bin/cpio
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Using internal av scanner code for (primary) Clam Antivirus-clamd
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: Found secondary av scanner Clam Antivirus - clamscan at (chroot: /var/amavisd/) /usr/bin/clamscan
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: SpamControl: initializing Mail::SpamAssassin
debug: SpamAssassin version 3.0.1
debug: Score set 0 chosen.
debug: running in taint mode? yes
debug: Running in taint mode, removing unsafe env vars, and resetting PATH
debug: PATH included '/usr/local/sbin', which doesn't exist, dropping.
debug: PATH included '/usr/local/bin', which doesn't exist, dropping.
debug: PATH included '/usr/sbin', keeping.
debug: PATH included '/sbin', which doesn't exist, dropping.
debug: PATH included '/usr/bin', keeping.
debug: PATH included '/bin', keeping.
debug: Final PATH set to: /usr/sbin:/usr/bin:/bin
Oct 26 12:59:27 postoffice.casadelove.com amavisd[11831]: SpamControl: turning on SA auto-whitelisting (AWL)
debug: ignore: test message to precompile patterns and load modules
debug: using "/etc/mail/spamassassin/init.pre" for site rules init.pre
debug: config: read file /etc/mail/spamassassin/init.pre
debug: using "/usr/local/share/spamassassin" for default rules dir
debug: config: read file /usr/local/share/spamassassin/10_misc.cf
debug: config: read file /usr/local/share/spamassassin/20_anti_ratware.cf
debug: config: read file /usr/local/share/spamassassin/20_body_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_compensate.cf
debug: config: read file /usr/local/share/spamassassin/20_dnsbl_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_drugs.cf
debug: config: read file /usr/local/share/spamassassin/20_fake_helo_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_head_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_html_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_meta_tests.cf
debug: config: read file /usr/local/share/spamassassin/20_phrases.cf
debug: config: read file /usr/local/share/spamassassin/20_porn.cf
debug: config: read file /usr/local/share/spamassassin/20_ratware.cf
debug: config: read file /usr/local/share/spamassassin/20_uri_tests.cf
debug: config: read file /usr/local/share/spamassassin/23_bayes.cf
debug: config: read file /usr/local/share/spamassassin/25_body_tests_es.cf
debug: config: read file /usr/local/share/spamassassin/25_body_tests_pl.cf
debug: config: read file /usr/local/share/spamassassin/25_hashcash.cf
debug: config: read file /usr/local/share/spamassassin/25_head_tests_es.cf
debug: config: read file /usr/local/share/spamassassin/25_head_tests_pl.cf
debug: config: read file /usr/local/share/spamassassin/25_spf.cf
debug: config: read file /usr/local/share/spamassassin/25_uribl.cf
debug: config: read file /usr/local/share/spamassassin/30_text_de.cf
debug: config: read file /usr/local/share/spamassassin/30_text_es.cf
debug: config: read file /usr/local/share/spamassassin/30_text_fr.cf
debug: config: read file /usr/local/share/spamassassin/30_text_it.cf
debug: config: read file /usr/local/share/spamassassin/30_text_nl.cf
debug: config: read file /usr/local/share/spamassassin/30_text_pl.cf
debug: config: read file /usr/local/share/spamassassin/30_text_sk.cf
debug: config: read file /usr/local/share/spamassassin/50_scores.cf
debug: config: read file /usr/local/share/spamassassin/60_whitelist.cf
debug: using "/etc/mail/spamassassin" for site rules dir
debug: config: read file /etc/mail/spamassassin/70_sare_adult.cf
debug: config: read file /etc/mail/spamassassin/70_sare_oem.cf
debug: config: read file /etc/mail/spamassassin/70_sare_random.cf
debug: config: read file /etc/mail/spamassassin/70_sare_ratware.cf
debug: config: read file /etc/mail/spamassassin/72_sare_bml_post25x.cf
debug: config: read file /etc/mail/spamassassin/99_sare_fraud_post25x.cf
debug: config: read file /etc/mail/spamassassin/antidrug.cf
debug: config: read file /etc/mail/spamassassin/backhair.cf
debug: config: read file /etc/mail/spamassassin/bogus-virus-warnings.cf
debug: config: read file /etc/mail/spamassassin/chickenpox.cf
debug: config: read file /etc/mail/spamassassin/evilnumbers.cf
debug: config: read file /etc/mail/spamassassin/local.cf
debug: config: read file /etc/mail/spamassassin/random.current.cf
debug: config: read file /etc/mail/spamassassin/tripwire.cf
debug: config: read file /etc/mail/spamassassin/uribl.cf
debug: using "/var/amavisd/.spamassassin/user_prefs" for user prefs file
debug: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514)
debug: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::Hashcash=HASH(0x3d51afe4)
debug: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
debug: plugin: registered Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8)
configuration file "/usr/local/share/spamassassin/20_body_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_compensate.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_dnsbl_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_drugs.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_fake_helo_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_head_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_html_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_meta_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_phrases.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_porn.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/20_uri_tests.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
configuration file "/usr/local/share/spamassassin/23_bayes.cf" requires version 3.000000 of SpamAssassin, but this is code version 3.000001. Maybe you need to use the -C switch, or remove the old config files? Skipping this file at /usr/local/libdata/perl5/site_perl/Mail/SpamAssassin/Conf/Parser.pm line 329.
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) implements 'parse_config'
debug: plugin: Mail::SpamAssassin::Plugin::Hashcash=HASH(0x3d51afe4) implements 'parse_config'
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) inhibited further callbacks
debug: bayes: 11831 tie-ing to DB file R/O /var/amavisd/.spamassassin/bayes_toks
debug: bayes: 11831 tie-ing to DB file R/O /var/amavisd/.spamassassin/bayes_seen
debug: bayes: found bayes db version 3
debug: Score set 3 chosen.
debug: ---- MIME PARSER START ----
debug: main message type: text/plain
debug: parsing normal part
debug: added part, type: text/plain
debug: ---- MIME PARSER END ----
debug: metadata: X-Spam-Relays-Trusted:
debug: metadata: X-Spam-Relays-Untrusted:
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) implements 'parsed_metadata'
debug: dns_available set to yes in config file, skipping test
debug: decoding: no encoding detected
debug: URIDNSBL: domains to query:
debug: Running tests for priority: 0
debug: running header regexp tests; score so far=0
debug: registering glue method for check_hashcash_value (Mail::SpamAssassin::Plugin::Hashcash=HASH(0x3d51afe4))
debug: registering glue method for check_uridnsbl (Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514))
debug: registering glue method for check_for_spf_helo_softfail (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: SPF: message was delivered entirely via trusted relays, not required
debug: registering glue method for check_hashcash_double_spend (Mail::SpamAssassin::Plugin::Hashcash=HASH(0x3d51afe4))
debug: registering glue method for check_for_spf_softfail (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: SPF: message was delivered entirely via trusted relays, not required
debug: registering glue method for check_for_spf_helo_pass (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: all '*From' addrs: [EMAIL PROTECTED]
debug: all '*To' addrs:
debug: registering glue method for check_for_spf_fail (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: registering glue method for check_for_spf_helo_fail (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: registering glue method for check_for_spf_pass (Mail::SpamAssassin::Plugin::SPF=HASH(0x3d507ae8))
debug: running body-text per-line regexp tests; score so far=0
debug: running uri tests; score so far=0
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) implements 'check_tick'
debug: running raw-body-text per-line regexp tests; score so far=0
debug: running full-text regexp tests; score so far=0
debug: Running tests for priority: 500
debug: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x3d4e6514) implements 'check_post_dnsbl'
debug: running meta tests; score so far=0
debug: running header regexp tests; score so far=0
debug: running body-text per-line regexp tests; score so far=0
debug: running uri tests; score so far=0
debug: running raw-body-text per-line regexp tests; score so far=0
debug: running full-text regexp tests; score so far=0
debug: Running tests for priority: 1000
debug: running meta tests; score so far=0
debug: running header regexp tests; score so far=0
debug: lock: 11831 created /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: lock: 11831 trying to get lock on /var/amavisd/.spamassassin/auto-whitelist with 30 timeout
debug: lock: 11831 link to /var/amavisd/.spamassassin/auto-whitelist.mutex: link ok
debug: Tie-ing to DB file R/W in /var/amavisd/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=none scores 0/0
debug: AWL active, pre-score: 0, autolearn score: 0, mean: undef, IP: undef
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 11831 unlocked /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: Post AWL score: 0
debug: running body-text per-line regexp tests; score so far=0
debug: running uri tests; score so far=0
debug: running raw-body-text per-line regexp tests; score so far=0
debug: running full-text regexp tests; score so far=0
debug: is spam? score=0 required=5
debug: tests=
debug: subtests=__UNUSABLE_MSGID
debug: bayes: 11831 untie-ing
debug: bayes: 11831 untie-ing db_toks
debug: bayes: 11831 untie-ing db_seen
Oct 26 12:59:34 postoffice.casadelove.com amavisd[11831]: SpamControl: done
Oct 26 12:59:34 postoffice.casadelove.com amavisd[11831]: Net::Server: Beginning prefork (8 processes)
Oct 26 12:59:34 postoffice.casadelove.com amavisd[11831]: Net::Server: Starting "8" children
Oct 26 12:59:34 postoffice.casadelove.com amavisd[11831]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[10701]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[8043]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[17249]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[26002]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[15678]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[15948]: Oct 26 12:59:34 postoffice.casadelove.com amavisd[20794]: Net::Server: Parent ready for children.Oct 26 12:59:34 postoffice.casadelove.com amavisd[4854]: Net::Server: Child Preforked (10701)Net::Server: Child Preforked (8043)Net::Server: Child Preforked (17249)Net::Server: Child Preforked (26002)Net::Server: Child Preforked (15678)Net::Server: Child Preforked (15948)Net::Server: Child Preforked (20794)
Net::Server: Child Preforked (4854)
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: Net::Server: 2004/10/26-13:00:40 CONNECT TCP Peer: "127.0.0.1:49106" Local: "127.0.0.1:10024"
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: lookup_ip_acl: key="127.0.0.1" matches "127.0.0.1", result=1
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: prolong_timer after new request - timer reset: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: prolong_timer after reading SMTP command: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: SMTP< EHLO postoffice.casadelove.com\r\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP> 250-[127.0.0.1]
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP> 250-PIPELINING
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP> 250-SIZE
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP> 250-8BITMIME
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP> 250 ENHANCEDSTATUSCODES
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: prolong_timer after reading SMTP command: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: ESMTP< MAIL FROM:<[EMAIL PROTECTED]> SIZE=6693\r\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: prolong_timer after MAIL FROM received - timer reset: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prepare_tempdir: creating directory /var/amavisd/tmp/amavis-20041026T130040-10701
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prepare_tempdir: creating file /var/amavisd/tmp/amavis-20041026T130040-10701/email.txt
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP> 250 2.1.0 Sender [EMAIL PROTECTED] OK
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after reading SMTP command: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP< RCPT TO:<[EMAIL PROTECTED]>\r\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP> 250 2.1.5 Recipient [EMAIL PROTECTED] OK
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after reading SMTP command: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP< DATA\r\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after DATA received - timer reset: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP::10024 /var/amavisd/tmp/amavis-20041026T130040-10701: <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]> Received: SIZE=6693 from postoffice.casadelove.com ([127.0.0.1]) by localhost (postoffice.casadelove.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 10701-01 for <[EMAIL PROTECTED]>; Tue, 26 Oct 2004 13:00:40 -0700 (PDT)
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP< .\r\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) body hash: 54c087a6692a171d0ad932a5034aefb5
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Original mail size: 6702; quota set to: 3351000 bytes
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Checking: <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Extracting mime components
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Issued a new file name: part-00001
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Issued a new file name: part-00002
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) mime_decode: Content-type: multipart/alternative
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) mime_decode_epilogue: 2 lines
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) mime_decode: Content-type: text/plain, name:
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Charging 2001 bytes to remaining quota 3351000 (out of 3351000, (0%)) - by mime_decode
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) mime_decode: Content-type: text/html, name:
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Charging 3490 bytes to remaining quota 3348999 (out of 3351000, (0%)) - by mime_decode
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after mime_decode-1: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Checking for banned MIME types and names
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned - mime-type: multipart/alternative
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="multipart/alternative", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned - mime-type: text/plain
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="text/plain", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned - mime-type: text/html
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="text/html", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) decode_parts: level=1, #parts=2 : part-00001, part-00002
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) run_command: [24559] /usr/bin/file /var/amavisd/tmp/amavis-20041026T130040-10701/parts/part-00001 </dev/null 2>/dev/null
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) File-type of part-00001: English text
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) run_command: [25687] /usr/bin/file /var/amavisd/tmp/amavis-20041026T130040-10701/parts/part-00002 </dev/null 2>/dev/null
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) File-type of part-00002: ASCII text; (.asc)
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Checking for banned (contents-based) file types, 2 parts
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned (part-00001) - file type: English text
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="English text", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned (part-00002) - file type: .asc
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key=".asc", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) check_for_banned (part-00002) - file type: ASCII text
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="ASCII text", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) decompose_part: part-00001 - atomic
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) do_ascii: Decoding part part-00002 (0 items)
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) decompose_part: part-00002 - atomic
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after decoding: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="MAIL", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Using Clam Antivirus-clamd: (built-in interface)
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Clam Antivirus-clamd: Connecting to socket /var/amavisd /var/amavisd/clamd.sock
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Clam Antivirus-clamd: Sending CONTSCAN /var/amavisd/tmp/amavis-20041026T130040-10701/parts\n to UNIX socket /var/amavisd/clamd.sock
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) Clam Antivirus-clamd result: /var/amavisd/tmp/amavis-20041026T130040-10701/parts: OK\n
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after virus_scan: remaining time = 1200 s
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) white_black_list: checking sender <[EMAIL PROTECTED]>
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_RE: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key="amandalefter21395326x39@", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key="bmcp.biz", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key=".bmcp.biz", no match
Oct 26 13:00:40 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key=".biz", no match
Oct 26 13:00:41 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_hash: key=".", no match
Oct 26 13:00:41 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:41 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:41 postoffice.casadelove.com amavisd[10701]: (10701-01) calling SA parse, SA version 3.0.1
Oct 26 13:00:41 postoffice.casadelove.com amavisd[10701]: (10701-01) CALLING SA check
debug: received-header: parsed as [ ip=127.0.0.1 rdns=localhost.casadelove.com helo=ice.he.net by=postoffice.casadelove.com ident= envfrom= intl=0 id= ]
debug: is Net::DNS::Resolver available? yes
debug: Net::DNS version: 0.47
debug: looking up PTR record for '65.19.165.181'
debug: PTR for '65.19.165.181': 'stickyc.com'
debug: received-header: parsed as [ ip=65.19.165.181 rdns=stickyc.com helo=stickyc.com by=ice.he.net ident= envfrom= intl=0 id= ]
debug: looking up PTR record for '193.125.233.135'
debug: PTR for '193.125.233.135': 'mail.bmcp.biz'
debug: received-header: parsed as [ ip=193.125.233.135 rdns=mail.bmcp.biz helo=mail.bmcp.biz by=stickyc.com ident= envfrom= intl=0 id= ]
debug: looking up A records for 'postoffice.casadelove.com'
debug: A records for 'postoffice.casadelove.com':
debug: received-header: 'from' 127.0.0.1 has reserved IP
debug: looking up A records for 'postoffice.casadelove.com'
debug: A records for 'postoffice.casadelove.com':
debug: received-header: relay 127.0.0.1 trusted? yes internal? no
debug: looking up A records for 'ice.he.net'
debug: A records for 'ice.he.net': 65.19.165.2
debug: received-header: 'by' ice.he.net has public IP 65.19.165.2
debug: received-header: relay 65.19.165.181 trusted? no internal? no
debug: received-header: relay 193.125.233.135 trusted? no internal? no
debug: metadata: X-Spam-Relays-Trusted: [ ip=127.0.0.1 rdns=localhost.casadelove.com helo=ice.he.net by=postoffice.casadelove.com ident= envfrom= intl=0 id= ]
debug: metadata: X-Spam-Relays-Untrusted: [ ip=65.19.165.181 rdns=stickyc.com helo=stickyc.com by=ice.he.net ident= envfrom= intl=0 id= ] [ ip=193.125.233.135 rdns=mail.bmcp.biz helo=mail.bmcp.biz by=stickyc.com ident= envfrom= intl=0 id= ]
debug: ---- MIME PARSER START ----
debug: main message type: multipart/alternative
debug: parsing multipart, got boundary: ----=_Part_299380_243173.1098818968286
debug: found part of type text/plain, boundary: ----=_Part_299380_243173.1098818968286
debug: parsing normal part
debug: added part, type: text/plain
debug: found part of type text/html, boundary: ----=_Part_299380_243173.1098818968286
debug: parsing normal part
debug: added part, type: text/html
debug: ---- MIME PARSER END ----
debug: decoding: other encoding type (8bit), ignoring
debug: decoding: other encoding type (8bit), ignoring
debug: uri found: http://www.bmcp.biz/pic/sam3_u.gif
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_05.gif
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_04.gif
debug: uri found: http://www.bmcp.biz/trobwfcMcKardpdKclaJacbKdqblepeGaJemdgcHeTeGaobedvaSdTdLcJddcbdJewemax.gif
debug: uri found: spacer.gif
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_07.gif
debug: uri found: http://www.bmcp.biz/pic/sam3_p.gif
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_03.gif
debug: uri found: http://www.bmcp.biz/trcbwfcMcKardpdKclaJaceedMdteLcTdodUeocHeTeGaobedvaSdTdLcJddcbdJewemax/
debug: uri found: http://www.bmcp.biz/privacy.html
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_06.gif
debug: uri found: http://www.bmcp.biz/trubwfcMcKardpdKclaJacdidwcqbDdYeQawareRetdZaOeedwdJbwcGccewcYcNckdmbqefcEdOcfaseVbJds/
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_01.jpg
debug: uri found: http://www.bmcp.biz/trcbwfcMcKardpdKclaJaccWdxbzdfdLewdgeecHeTeGaobedvaSdTdLcJddcbdJewemax/
debug: uri found: http://www.bmcp.biz/pics/toys250_emc_d4f2_02.jpg
debug: URIDNSBL: domains to query: bmcp.biz
debug: Running tests for priority: 0
debug: running header regexp tests; score so far=0
debug: SPF: checking HELO (helo=stickyc.com, ip=65.19.165.181)
debug: SPF: trimmed HELO down to 'stickyc.com'
debug: SPF: query for /65.19.165.181/stickyc.com: result: none, comment: SPF: domain of sender stickyc.com does not designate mailers
debug: SPF: relayed through one or more trusted relays, cannot use header-based Envelope-From, skipping
debug: all '*From' addrs: [EMAIL PROTECTED]
debug: all '*To' addrs: [EMAIL PROTECTED] [EMAIL PROTECTED]
debug: running body-text per-line regexp tests; score so far=1
debug: running uri tests; score so far=1
debug: URIDNSBL: queries completed: 1 started: 2
debug: URIDNSBL: queries active: at Tue Oct 26 13:00:42 2004
debug: running raw-body-text per-line regexp tests; score so far=1
debug: running full-text regexp tests; score so far=1
debug: Running tests for priority: 500
debug: URIDNSBL: queries completed: 2 started: 0
debug: URIDNSBL: queries active: at Tue Oct 26 13:00:43 2004
debug: running meta tests; score so far=1
debug: running header regexp tests; score so far=1
debug: running body-text per-line regexp tests; score so far=1
debug: running uri tests; score so far=1
debug: running raw-body-text per-line regexp tests; score so far=1
debug: running full-text regexp tests; score so far=1
debug: Running tests for priority: 1000
debug: running meta tests; score so far=1
debug: running header regexp tests; score so far=1
debug: lock: 10701 created /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: lock: 10701 trying to get lock on /var/amavisd/.spamassassin/auto-whitelist with 30 timeout
debug: lock: 10701 link to /var/amavisd/.spamassassin/auto-whitelist.mutex: link ok
debug: Tie-ing to DB file R/W in /var/amavisd/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=193.125 scores 0/0
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=none scores 0/0
debug: AWL active, pre-score: 1, autolearn score: 1, mean: undef, IP: 193.125.233.135
debug: add_score: New count: 1, new totscore: 1
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 10701 unlocked /var/amavisd/.spamassassin/auto-whitelist.mutex
debug: Post AWL score: 1
debug: running body-text per-line regexp tests; score so far=1
debug: running uri tests; score so far=1
debug: running raw-body-text per-line regexp tests; score so far=1
debug: running full-text regexp tests; score so far=1
debug: auto-learn: currently using scoreset 3, recomputing score based on scoreset 1.
debug: auto-learn: message score: 1, computed score for autolearn: 1
debug: auto-learn? ham=0.1, spam=12, body-points=0, head-points=1, learned-points=0
debug: auto-learn? no: inside auto-learn thresholds, not considered ham or spam
debug: is spam? score=1 required=5
debug: tests=LOCAL_RCVD
debug: subtests=__CTYPE_HAS_BOUNDARY,__MIME_HTML,__TAG_EXISTS_BODY,__TAG_EXISTS_HEAD,__TAG_EXISTS_HTML,__TAG_EXISTS_META
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) RETURNED FROM NoMailAudit::check, time left: 0 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after spam_scan_SA: remaining time = 1200 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) spam_scan: hits=1 tests=LOCAL_RCVD
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after spam_scan: remaining time = 1200 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup: (scalar) matches, result="6.3"
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) header: Received: from postoffice.casadelove.com ([127.0.0.1])\n by localhost (postoffice.casadelove.com [127.0.0.1]) (amavisd-new, port 10024)\n with ESMTP id 10701-01 for <[EMAIL PROTECTED]>;\n Tue, 26 Oct 2004 13:00:40 -0700 (PDT)\n
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) header: X-Virus-Scanned: by amavisd-new at casadelove.com\n
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]", no match
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup_acl: key="[EMAIL PROTECTED]" matches "localhost", result=1
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup: (scalar) matches, result="3"
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) lookup: (scalar) matches, result="6.3"
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) headers CLUSTERING: NEW CLUSTER <[EMAIL PROTECTED]>: hits=1.0, tag=0, tag2=0, subj=0, subj_u=0, local=1, bl=0
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) headers CLUSTERING: done all 1 recips in one go
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) FWD via SMTP: [127.0.0.1]:10025 <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) Remote host introduces itself as: postoffice.casadelove.com
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-connect: remaining time = 1200 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-mail-from: remaining time = 1200 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-rcpt-to: remaining time = 1200 s
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) response to DATA: "354 End data with <CR><LF>.<CR><LF>"
Oct 26 13:00:43 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-data: remaining time = 1200 s
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-data-end: remaining time = 1200 s
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) response to data end: "250 Ok: queued as 4B4098CE9F"
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after fwd-rundown-1: remaining time = 1200 s
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) mail_via_smtp: 250 2.6.0 Ok, id=10701-01, from MTA: 250 Ok: queued as 4B4098CE9F
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after forwarding: remaining time = 1200 s
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) one_response_for_all <[EMAIL PROTECTED]>: success, dsn_needed=0, '250 2.6.0 Ok, id=10701-01, from MTA: 250 Ok: queued as 4B4098CE9F'
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) warnsender_with_pass=(,,,), dsn_needed=0, exit=0, 250 2.6.0 Ok, id=10701-01, from MTA: 250 Ok: queued as 4B4098CE9F
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) Passed, <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, Message-ID: <[EMAIL PROTECTED]>, Hits: 1
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) sending SMTP response: "250 2.6.0 Ok, id=10701-01, from MTA: 250 Ok: queued as 4B4098CE9F"
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) timer stopped after DATA end
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) rmdir_recursively: /var/amavisd/tmp/amavis-20041026T130040-10701/parts, excl=1
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) TIMING [total 4007 ms] - SMTP EHLO: 10 (0%), SMTP pre-MAIL: 3 (0%), mkdir tempdir: 1 (0%), create email.txt: 2 (0%), SMTP pre-DATA-flush: 14 (0%), SMTP DATA: 394 (10%), body hash: 2 (0%), mkdir parts: 4 (0%), mime_decode: 56 (1%), get-file-type: 34 (1%), get-file-type: 24 (1%), decompose_part: 8 (0%), decompose_part: 4 (0%), parts: 0 (0%), AV-scan-1: 50 (1%), SA msg read: 380 (9%), SA parse: 8 (0%), SA check: 2039 (51%), fwd-connect: 28 (1%), fwd-mail-from: 3 (0%), fwd-rcpt-to: 63 (2%), write-header: 7 (0%), fwd-data: 1 (0%), fwd-data-end: 848 (21%), fwd-rundown: 5 (0%), unlink-2-files: 19 (0%), rundown: 2 (0%)
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP> 250 2.6.0 Ok, id=10701-01, from MTA: 250 Ok: queued as 4B4098CE9F
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) prolong_timer after reading SMTP command: remaining time = 0 s
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP< QUIT\r\n
Oct 26 13:00:44 postoffice.casadelove.com amavisd[10701]: (10701-01) ESMTP> 221 2.0.0 [127.0.0.1] (amavisd) closing transmission channel
-- This message has been scanned for viruses and dangerous content, and is believed to be clean.
