Oct 4 06:25:09.465 [3293] dbg: logger: adding facilities: all
Oct 4 06:25:09.465 [3293] dbg: logger: logging level is DBG
Oct 4 06:25:09.465 [3293] dbg: generic: SpamAssassin version 3.4.2
Oct 4 06:25:09.465 [3293] dbg: generic: Perl 5.020002, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin
Oct 4 06:25:09.465 [3293] dbg: config: timing enabled
Oct 4 06:25:09.469 [3293] dbg: config: score set 0 chosen.
Oct 4 06:25:09.480 [3293] dbg: generic: sa-update version 3.4.2 / svn1840377
Oct 4 06:25:09.480 [3293] dbg: generic: using update directory: /var/lib/spamassassin/3.004002
Oct 4 06:25:09.728 [3293] dbg: diag: perl platform: 5.020002 linux
Oct 4 06:25:09.728 [3293] dbg: diag: [...] module installed: Digest::SHA, version 5.93
Oct 4 06:25:09.728 [3293] dbg: diag: [...] module installed: HTML::Parser, version 3.71
Oct 4 06:25:09.728 [3293] dbg: diag: [...] module installed: Net::DNS, version 0.81
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: NetAddr::IP, version 4.075
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Time::HiRes, version 1.9726
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Archive::Tar, version 1.96
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: IO::Zlib, version 1.10
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module not installed: Digest::SHA1 ('require' failed)
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: MIME::Base64, version 3.14
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: DB_File, version 1.831
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Net::SMTP, version 2.33
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Mail::SPF, version v2.009
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module not installed: Geo::IP ('require' failed)
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Net::CIDR::Lite, version 0.21
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Razor2::Client::Agent, version 2.84
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: IO::Socket::IP, version 0.29
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.72
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: IO::Socket::SSL, version 2.002
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Compress::Zlib, version 2.064
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Mail::DKIM, version 0.4
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: DBI, version 1.631
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Getopt::Long, version 2.42
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: LWP::UserAgent, version 6.06
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: HTTP::Date, version 6.02
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Encode::Detect::Detector, version 1.01
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Net::Patricia, version 1.22
Oct 4 06:25:09.729 [3293] dbg: diag: [...] module installed: Net::DNS::Nameserver, version 1276
Oct 4 06:25:09.730 [3293] dbg: diag: [...] module not installed: BSD::Resource ('require' failed)
Oct 4 06:25:09.731 [3293] dbg: gpg: Searching for 'gpg'
Oct 4 06:25:09.731 [3293] dbg: util: current PATH is: /usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin
Oct 4 06:25:09.732 [3293] dbg: util: executable for gpg was found at /usr/bin/gpg
Oct 4 06:25:09.732 [3293] dbg: gpg: found /usr/bin/gpg
Oct 4 06:25:09.732 [3293] dbg: gpg: release trusted key id list: 0C2B1D7175B852C64B3CDC716C55397824F434CE 5E541DC959CB8BAC7C78DFDC4056A61A5244EC45
Oct 4 06:25:09.733 [3293] dbg: util: secure_tmpfile created a temporary file /tmp/.spamassassin3293OuxaNHtmp
Oct 4 06:25:09.733 [3293] dbg: channel: attempting channel updates.spamassassin.org
Oct 4 06:25:09.733 [3293] dbg: channel: using existing directory /var/lib/spamassassin/3.004002/updates_spamassassin_org
Oct 4 06:25:09.733 [3293] dbg: channel: channel cf file /var/lib/spamassassin/3.004002/updates_spamassassin_org.cf
Oct 4 06:25:09.733 [3293] dbg: channel: channel pre file /var/lib/spamassassin/3.004002/updates_spamassassin_org.pre
Oct 4 06:25:09.737 [3293] dbg: channel: metadata version = 1842603, from file /var/lib/spamassassin/3.004002/updates_spamassassin_org.cf
Oct 4 06:25:09.751 [3293] dbg: dns: 2.4.3.updates.spamassassin.org => 1842690, parsed as 1842690
Oct 4 06:25:09.751 [3293] dbg: channel: preparing temp directory for new channel
Oct 4 06:25:09.751 [3293] dbg: channel: created tmp directory /tmp/.spamassassin3293HowL2Ntmp
Oct 4 06:25:09.751 [3293] dbg: generic: lint checking site pre files once before attempting channel updates
Oct 4 06:25:09.752 [3293] dbg: generic: SpamAssassin version 3.4.2
Oct 4 06:25:09.752 [3293] dbg: generic: Perl 5.020002, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/mail/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin
Oct 4 06:25:09.752 [3293] dbg: config: timing enabled
Oct 4 06:25:09.756 [3293] dbg: config: score set 0 chosen.
Oct 4 06:25:09.762 [3293] dbg: ignore: using a test message to lint rules
Oct 4 06:25:09.763 [3293] dbg: config: using "/etc/mail/spamassassin" for site rules pre files
Oct 4 06:25:09.765 [3293] dbg: config: read file /etc/mail/spamassassin/init.pre
Oct 4 06:25:09.766 [3293] dbg: config: read file /etc/mail/spamassassin/v310.pre
Oct 4 06:25:09.767 [3293] dbg: config: read file /etc/mail/spamassassin/v312.pre
Oct 4 06:25:09.768 [3293] dbg: config: read file /etc/mail/spamassassin/v320.pre
Oct 4 06:25:09.768 [3293] dbg: config: read file /etc/mail/spamassassin/v330.pre
Oct 4 06:25:09.769 [3293] dbg: config: read file /etc/mail/spamassassin/v340.pre
Oct 4 06:25:09.770 [3293] dbg: config: read file /etc/mail/spamassassin/v341.pre
Oct 4 06:25:09.770 [3293] dbg: config: read file /etc/mail/spamassassin/v342.pre
Oct 4 06:25:09.770 [3293] dbg: config: using "/tmp/.spamassassin3293HowL2Ntmp/doesnotexist" for sys rules pre files
Oct 4 06:25:09.771 [3293] dbg: config: using "/tmp/.spamassassin3293HowL2Ntmp/doesnotexist" for default rules dir
Oct 4 06:25:09.771 [3293] dbg: config: using "/tmp/.spamassassin3293HowL2Ntmp/doesnotexist/doesnotexist" for user prefs file
Oct 4 06:25:09.772 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
Oct 4 06:25:09.781 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
Oct 4 06:25:09.786 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
Oct 4 06:25:09.792 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
Oct 4 06:25:09.796 [3293] dbg: pyzor: local tests only, disabling Pyzor
Oct 4 06:25:09.797 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
Oct 4 06:25:09.801 [3293] dbg: razor2: local tests only, skipping Razor
Oct 4 06:25:09.801 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
Oct 4 06:25:09.805 [3293] dbg: reporter: local tests only, disabling SpamCop
Oct 4 06:25:09.805 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
Oct 4 06:25:09.808 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
Oct 4 06:25:09.810 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
Oct 4 06:25:09.820 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
Oct 4 06:25:09.823 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC
Oct 4 06:25:09.833 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
Oct 4 06:25:09.845 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
Oct 4 06:25:09.847 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
Oct 4 06:25:09.849 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
Oct 4 06:25:09.861 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
Oct 4 06:25:09.865 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
Oct 4 06:25:09.869 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
Oct 4 06:25:09.875 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
Oct 4 06:25:09.890 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
Oct 4 06:25:09.898 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
Oct 4 06:25:09.903 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
Oct 4 06:25:09.905 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
Oct 4 06:25:09.910 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
Oct 4 06:25:09.912 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC
Oct 4 06:25:09.914 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC
Oct 4 06:25:09.922 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::AskDNS from @INC
Oct 4 06:25:09.927 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::HashBL from @INC
Oct 4 06:25:09.937 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::FromNameSpoof from @INC
Oct 4 06:25:09.940 [3293] dbg: plugin: loading Mail::SpamAssassin::Plugin::Phishing from @INC
Oct 4 06:25:09.942 [3293] dbg: config: finish parsing
Oct 4 06:25:09.943 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x49f0c00) implements 'finish_parsing_end', priority 0
Oct 4 06:25:09.943 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x47cc2c0) implements 'finish_parsing_end', priority 0
Oct 4 06:25:09.943 [3293] dbg: replacetags: replacing tags
Oct 4 06:25:09.943 [3293] dbg: replacetags: done replacing tags
Oct 4 06:25:09.943 [3293] dbg: FreeMail: no freemail_domains entries defined, disabling plugin
Oct 4 06:25:09.943 [3293] dbg: config: score set 0 chosen.
Oct 4 06:25:09.944 [3293] dbg: dns: EDNS, UDP payload size 4096
Oct 4 06:25:09.944 [3293] dbg: dns: servers obtained from Net::DNS : [195.8.195.8]:53, [195.135.195.135]:53
Oct 4 06:25:09.944 [3293] dbg: dns: nameservers set to 195.8.195.8, 195.135.195.135
Oct 4 06:25:09.944 [3293] dbg: dns: using socket module: IO::Socket::IP version 0.29
Oct 4 06:25:09.944 [3293] dbg: dns: is Net::DNS::Resolver available? yes
Oct 4 06:25:09.944 [3293] dbg: dns: Net::DNS version: 0.81
Oct 4 06:25:09.945 [3293] dbg: config: time limit 300.0 s
Oct 4 06:25:09.945 [3293] dbg: message: main message type: text/plain
Oct 4 06:25:09.945 [3293] dbg: message: ---- MIME PARSER START ----
Oct 4 06:25:09.945 [3293] dbg: message: parsing normal part
Oct 4 06:25:09.945 [3293] dbg: message: storing a body to memory
Oct 4 06:25:09.945 [3293] dbg: message: ---- MIME PARSER END ----
Oct 4 06:25:09.946 [3293] dbg: check: pms new, time limit in 299.999 s
Oct 4 06:25:09.946 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x4c4dbe8) implements 'check_start', priority 0
Oct 4 06:25:09.946 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::Phishing=HASH(0x5035e78) implements 'check_start', priority 0
Oct 4 06:25:09.947 [3293] dbg: check: using scoreset 0 in M:S:Pms
Oct 4 06:25:09.947 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x47cb900) implements 'check_main', priority 0
Oct 4 06:25:09.948 [3293] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually
Oct 4 06:25:09.948 [3293] dbg: metadata: X-Spam-Relays-Trusted:
Oct 4 06:25:09.948 [3293] dbg: metadata: X-Spam-Relays-Untrusted:
Oct 4 06:25:09.948 [3293] dbg: metadata: X-Spam-Relays-Internal:
Oct 4 06:25:09.948 [3293] dbg: metadata: X-Spam-Relays-External:
Oct 4 06:25:09.948 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::AskDNS=HASH(0x47cc248) implements 'extract_metadata', priority 0
Oct 4 06:25:09.948 [3293] dbg: dns: is DNS available? 0
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag AUTHORDOMAIN is now ready, value: compiling.spamassassin.taint.org
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag RELAYSTRUSTED is now ready, value:
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag RELAYSUNTRUSTED is now ready, value:
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag RELAYSINTERNAL is now ready, value:
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag RELAYSEXTERNAL is now ready, value:
Oct 4 06:25:09.949 [3293] dbg: check: tagrun - tag LANGUAGES is now ready, value: <UNDEF>
Oct 4 06:25:09.949 [3293] dbg: message: no encoding detected
Oct 4 06:25:09.949 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x47cadb0) implements 'parsed_metadata', priority 0
Oct 4 06:25:09.950 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::FromNameSpoof=HASH(0x47cd968) implements 'parsed_metadata', priority 0
Oct 4 06:25:09.950 [3293] dbg: check: tagrun - action 0 blocking on tags DKIMDOMAIN
Oct 4 06:25:09.950 [3293] dbg: rules: local tests only, ignoring RBL eval
Oct 4 06:25:09.950 [3293] dbg: check: check_main, time limit in 299.995 s
Oct 4 06:25:09.956 [3293] dbg: dns: harvest_dnsbl_queries
Oct 4 06:25:09.957 [3293] dbg: check: is spam? score=0 required=5
Oct 4 06:25:09.957 [3293] dbg: check: tests=
Oct 4 06:25:09.957 [3293] dbg: check: subtests=
Oct 4 06:25:09.958 [3293] dbg: check: tagrun - tag DKIMDOMAIN is still blocking action 0
Oct 4 06:25:09.958 [3293] dbg: timing: total 194 ms - init: 182 (94.0%), parse: 1.08 (0.6%), extract_message_metadata: 2.2 (1.1%), get_uri_detail_list: 6 (3.2%)
Oct 4 06:25:09.958 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x47cb678) implements 'finish_tests', priority 0
Oct 4 06:25:09.958 [3293] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x47cb900) implements 'finish_tests', priority 0
Oct 4 06:25:09.960 [3293] dbg: generic: lint check of site pre files succeeded, continuing with channel updates
Oct 4 06:25:09.960 [3293] dbg: channel: protocol family available: inet,inet6
Oct 4 06:25:09.960 [3293] dbg: channel: reading MIRRORED.BY file /var/lib/spamassassin/3.004002/updates_spamassassin_org/MIRRORED.BY
Oct 4 06:25:09.961 [3293] dbg: channel: parsing MIRRORED.BY file for channel updates.spamassassin.org
Oct 4 06:25:09.961 [3293] dbg: channel: found mirror http://sa-update.dnswl.org/ weight=3
Oct 4 06:25:09.961 [3293] dbg: channel: found mirror http://www.sa-update.pccc.com/ weight=5
Oct 4 06:25:09.961 [3293] dbg: channel: found mirror http://sa-update.secnap.net/ weight=5
Oct 4 06:25:09.961 [3293] dbg: channel: found mirror http://sa-update.space-pro.be/ weight=1
Oct 4 06:25:09.961 [3293] dbg: channel: found mirror http://sa-update.ena.com/ weight=5
Oct 4 06:25:09.962 [3293] dbg: channel: found mirror http://sa-update.razx.cloud/ weight=5
Oct 4 06:25:09.962 [3293] dbg: channel: found mirror http://sa-update.fossies.org/ weight=1
Oct 4 06:25:09.962 [3293] dbg: channel: found mirror http://sa-update.bitwell.fi/ weight=5
Oct 4 06:25:09.962 [3293] dbg: channel: found mirror http://sa-update.spamassassin.org/ weight=10
Oct 4 06:25:09.965 [3293] dbg: channel: selected mirror http://sa-update.spamassassin.org
Oct 4 06:25:09.965 [3293] dbg: http: url: http://sa-update.spamassassin.org/1842690.tar.gz
Oct 4 06:25:09.965 [3293] dbg: http: downloading to: /var/lib/spamassassin/3.004002/updates_spamassassin_org/1842690.tar.gz, new
Oct 4 06:25:09.966 [3293] dbg: util: executable for curl was found at /usr/local/bin/curl
Oct 4 06:25:09.966 [3293] dbg: http: /usr/local/bin/curl -s -L -O --remote-time -g --max-redirs 2 --connect-timeout 30 --max-time 300 --fail -o 1842690.tar.gz -- http://sa-update.spamassassin.org/1842690.tar.gz
Oct 4 06:26:18.829 [3293] dbg: http: process [3294], exit status: exit 56
Cannot open file /var/lib/spamassassin/3.004002/updates_spamassassin_org/1842690.tar.gz: No such file or directory at /usr/bin/sa-update line 1603.