/etc/cron.daily/spamassassin: Aug 9 07:41:54.089 [26762] dbg: logger: adding facilities: all Aug 9 07:41:54.089 [26762] dbg: logger: logging level is DBG Aug 9 07:41:54.089 [26762] dbg: generic: SpamAssassin version 3.4.2 Aug 9 07:41:54.089 [26762] dbg: generic: Perl 5.028001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Aug 9 07:41:54.089 [26762] dbg: config: timing enabled Aug 9 07:41:54.091 [26762] dbg: config: score set 0 chosen. Aug 9 07:41:54.099 [26762] dbg: generic: sa-update version 3.4.2 / svn1840377 Aug 9 07:41:54.099 [26762] dbg: generic: using update directory: /var/lib/spamassassin/3.004002 Aug 9 07:41:54.243 [26762] dbg: diag: perl platform: 5.028001 linux Aug 9 07:41:54.243 [26762] dbg: diag: [...] module installed: Digest::SHA, version 6.01 Aug 9 07:41:54.243 [26762] dbg: diag: [...] module installed: HTML::Parser, version 3.72 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: Net::DNS, version 1.19 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: NetAddr::IP, version 4.079 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: Time::HiRes, version 1.9759 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: Archive::Tar, version 2.30 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: IO::Zlib, version 1.10 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module not installed: Digest::SHA1 ('require' failed) Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: MIME::Base64, version 3.15 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: DB_File, version 1.84 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: Net::SMTP, version 3.11 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: Mail::SPF, version v2.009 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module not installed: Geo::IP ('require' failed) Aug 9 07:41:54.244 [26762] dbg: diag: [...] module not installed: Net::CIDR::Lite ('require' failed) Aug 9 07:41:54.244 [26762] dbg: diag: [...] module not installed: Razor2::Client::Agent ('require' failed) Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: IO::Socket::IP, version 0.39 Aug 9 07:41:54.244 [26762] dbg: diag: [...] module installed: IO::Socket::INET6, version 2.72 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: IO::Socket::SSL, version 2.060 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: Compress::Zlib, version 2.074 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: Mail::DKIM, version 0.54 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: DBI, version 1.642 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: Getopt::Long, version 2.5 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: LWP::UserAgent, version 6.36 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: HTTP::Date, version 6.02 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module not installed: Encode::Detect::Detector ('require' failed) Aug 9 07:41:54.245 [26762] dbg: diag: [...] module not installed: Net::Patricia ('require' failed) Aug 9 07:41:54.245 [26762] dbg: diag: [...] module installed: Net::DNS::Nameserver, version 1692 Aug 9 07:41:54.245 [26762] dbg: diag: [...] module not installed: BSD::Resource ('require' failed) Aug 9 07:41:54.246 [26762] dbg: gpg: Searching for 'gpg' Aug 9 07:41:54.246 [26762] dbg: util: current PATH is: /usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin Aug 9 07:41:54.246 [26762] dbg: util: executable for gpg was found at /usr/bin/gpg Aug 9 07:41:54.246 [26762] dbg: gpg: found /usr/bin/gpg Aug 9 07:41:54.247 [26762] dbg: gpg: release trusted key id list: 0C2B1D7175B852C64B3CDC716C55397824F434CE 5E541DC959CB8BAC7C78DFDC4056A61A5244EC45 Aug 9 07:41:54.247 [26762] dbg: util: secure_tmpfile created a temporary file /tmp/.spamassassin26762hdSilJtmp Aug 9 07:41:54.248 [26762] dbg: channel: attempting channel updates.spamassassin.org Aug 9 07:41:54.248 [26762] dbg: channel: using existing directory /var/lib/spamassassin/3.004002/updates_spamassassin_org Aug 9 07:41:54.248 [26762] dbg: channel: channel cf file /var/lib/spamassassin/3.004002/updates_spamassassin_org.cf Aug 9 07:41:54.248 [26762] dbg: channel: channel pre file /var/lib/spamassassin/3.004002/updates_spamassassin_org.pre Aug 9 07:41:54.249 [26762] dbg: channel: metadata version = 1880604, from file /var/lib/spamassassin/3.004002/updates_spamassassin_org.cf Aug 9 07:41:54.276 [26762] dbg: dns: 2.4.3.updates.spamassassin.org => 1880698, parsed as 1880698 Aug 9 07:41:54.276 [26762] dbg: channel: preparing temp directory for new channel Aug 9 07:41:54.276 [26762] dbg: channel: created tmp directory /tmp/.spamassassin26762qDjYsdtmp Aug 9 07:41:54.276 [26762] dbg: generic: lint checking site pre files once before attempting channel updates Aug 9 07:41:54.277 [26762] dbg: generic: SpamAssassin version 3.4.2 Aug 9 07:41:54.277 [26762] dbg: generic: Perl 5.028001, PREFIX=/usr, DEF_RULES_DIR=/usr/share/spamassassin, LOCAL_RULES_DIR=/etc/spamassassin, LOCAL_STATE_DIR=/var/lib/spamassassin Aug 9 07:41:54.277 [26762] dbg: config: timing enabled Aug 9 07:41:54.279 [26762] dbg: config: score set 0 chosen. Aug 9 07:41:54.282 [26762] dbg: ignore: using a test message to lint rules Aug 9 07:41:54.283 [26762] dbg: config: using "/etc/spamassassin" for site rules pre files Aug 9 07:41:54.283 [26762] dbg: config: read file /etc/spamassassin/init.pre Aug 9 07:41:54.284 [26762] dbg: config: read file /etc/spamassassin/sa-compile.pre Aug 9 07:41:54.284 [26762] dbg: config: read file /etc/spamassassin/v310.pre Aug 9 07:41:54.285 [26762] dbg: config: read file /etc/spamassassin/v312.pre Aug 9 07:41:54.285 [26762] dbg: config: read file /etc/spamassassin/v320.pre Aug 9 07:41:54.285 [26762] dbg: config: read file /etc/spamassassin/v330.pre Aug 9 07:41:54.286 [26762] dbg: config: read file /etc/spamassassin/v340.pre Aug 9 07:41:54.286 [26762] dbg: config: read file /etc/spamassassin/v341.pre Aug 9 07:41:54.287 [26762] dbg: config: read file /etc/spamassassin/v342.pre Aug 9 07:41:54.287 [26762] dbg: config: using "/tmp/.spamassassin26762qDjYsdtmp/doesnotexist" for sys rules pre files Aug 9 07:41:54.287 [26762] dbg: config: using "/tmp/.spamassassin26762qDjYsdtmp/doesnotexist" for default rules dir Aug 9 07:41:54.288 [26762] dbg: config: using "/tmp/.spamassassin26762qDjYsdtmp/doesnotexist/doesnotexist" for user prefs file Aug 9 07:41:54.288 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC Aug 9 07:41:54.295 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC Aug 9 07:41:54.297 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC Aug 9 07:41:54.302 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Rule2XSBody from @INC Aug 9 07:41:54.306 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC Aug 9 07:41:54.309 [26762] dbg: pyzor: local tests only, disabling Pyzor Aug 9 07:41:54.309 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC Aug 9 07:41:54.313 [26762] dbg: razor2: local tests only, skipping Razor Aug 9 07:41:54.314 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC Aug 9 07:41:54.316 [26762] dbg: reporter: local tests only, disabling SpamCop Aug 9 07:41:54.317 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC Aug 9 07:41:54.318 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC Aug 9 07:41:54.320 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC Aug 9 07:41:54.321 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC Aug 9 07:41:54.323 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::DKIM from @INC Aug 9 07:41:54.332 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC Aug 9 07:41:54.344 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC Aug 9 07:41:54.346 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC Aug 9 07:41:54.348 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC Aug 9 07:41:54.363 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC Aug 9 07:41:54.365 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC Aug 9 07:41:54.369 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC Aug 9 07:41:54.372 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC Aug 9 07:41:54.381 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC Aug 9 07:41:54.386 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC Aug 9 07:41:54.389 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC Aug 9 07:41:54.390 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC Aug 9 07:41:54.394 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC Aug 9 07:41:54.396 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC Aug 9 07:41:54.399 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::FreeMail from @INC Aug 9 07:41:54.406 [26762] dbg: plugin: loading Mail::SpamAssassin::Plugin::AskDNS from @INC Aug 9 07:41:54.410 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x559b7188af88) implements 'finish_parsing_start', priority 0 Aug 9 07:41:54.410 [26762] dbg: config: finish parsing Aug 9 07:41:54.410 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0x559b7175dc38) implements 'finish_parsing_end', priority 0 Aug 9 07:41:54.410 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x559b7188af88) implements 'finish_parsing_end', priority 0 Aug 9 07:41:54.410 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::FreeMail=HASH(0x559b716451d0) implements 'finish_parsing_end', priority 0 Aug 9 07:41:54.411 [26762] dbg: zoom: loading compiled ruleset from /var/lib/spamassassin/compiled/5.028/3.004002 Aug 9 07:41:54.411 [26762] dbg: replacetags: replacing tags Aug 9 07:41:54.411 [26762] dbg: FreeMail: no freemail_domains entries defined, disabling plugin Aug 9 07:41:54.411 [26762] dbg: config: score set 0 chosen. Aug 9 07:41:54.411 [26762] dbg: dns: socket module IO::Socket::IP is available, but no host support for IPv6 Aug 9 07:41:54.412 [26762] dbg: dns: EDNS, UDP payload size 4096 Aug 9 07:41:54.412 [26762] dbg: dns: servers obtained from Net::DNS : [127.0.0.1]:53, [8.8.4.4]:53, [8.8.8.8]:53, [209.244.0.3]:53 Aug 9 07:41:54.412 [26762] dbg: dns: nameservers set to 127.0.0.1, 8.8.4.4, 8.8.8.8, 209.244.0.3 Aug 9 07:41:54.412 [26762] dbg: dns: using socket module: IO::Socket::IP version 0.39, forced IPv4 Aug 9 07:41:54.412 [26762] dbg: dns: is Net::DNS::Resolver available? yes Aug 9 07:41:54.412 [26762] dbg: dns: Net::DNS version: 1.19 Aug 9 07:41:54.413 [26762] dbg: config: time limit 300.0 s Aug 9 07:41:54.413 [26762] dbg: message: main message type: text/plain Aug 9 07:41:54.413 [26762] dbg: message: ---- MIME PARSER START ---- Aug 9 07:41:54.413 [26762] dbg: message: parsing normal part Aug 9 07:41:54.413 [26762] dbg: message: storing a body to memory Aug 9 07:41:54.413 [26762] dbg: message: ---- MIME PARSER END ---- Aug 9 07:41:54.414 [26762] dbg: check: pms new, time limit in 299.999 s Aug 9 07:41:54.414 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::Rule2XSBody=HASH(0x559b7175dc38) implements 'check_start', priority 0 Aug 9 07:41:54.414 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x559b71b5f390) implements 'check_start', priority 0 Aug 9 07:41:54.415 [26762] dbg: check: using scoreset 0 in M:S:Pms Aug 9 07:41:54.415 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x559b71644800) implements 'check_main', priority 0 Aug 9 07:41:54.415 [26762] dbg: config: trusted_networks are not configured; it is recommended that you configure trusted_networks manually Aug 9 07:41:54.415 [26762] dbg: metadata: X-Spam-Relays-Trusted: Aug 9 07:41:54.416 [26762] dbg: metadata: X-Spam-Relays-Untrusted: Aug 9 07:41:54.416 [26762] dbg: metadata: X-Spam-Relays-Internal: Aug 9 07:41:54.416 [26762] dbg: metadata: X-Spam-Relays-External: Aug 9 07:41:54.416 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::AskDNS=HASH(0x559b71645428) implements 'extract_metadata', priority 0 Aug 9 07:41:54.416 [26762] dbg: dns: is DNS available? 0 Aug 9 07:41:54.416 [26762] dbg: check: tagrun - tag AUTHORDOMAIN is now ready, value: compiling.spamassassin.taint.org Aug 9 07:41:54.416 [26762] dbg: check: tagrun - tag RELAYSTRUSTED is now ready, value: Aug 9 07:41:54.417 [26762] dbg: check: tagrun - tag RELAYSUNTRUSTED is now ready, value: Aug 9 07:41:54.417 [26762] dbg: check: tagrun - tag RELAYSINTERNAL is now ready, value: Aug 9 07:41:54.417 [26762] dbg: check: tagrun - tag RELAYSEXTERNAL is now ready, value: Aug 9 07:41:54.417 [26762] dbg: check: tagrun - tag LANGUAGES is now ready, value: Aug 9 07:41:54.417 [26762] dbg: message: no encoding detected Aug 9 07:41:54.417 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x559b70cf2e30) implements 'parsed_metadata', priority 0 Aug 9 07:41:54.417 [26762] dbg: rules: local tests only, ignoring RBL eval Aug 9 07:41:54.418 [26762] dbg: check: check_main, time limit in 299.995 s Aug 9 07:41:54.422 [26762] dbg: dns: harvest_dnsbl_queries Aug 9 07:41:54.422 [26762] dbg: check: is spam? score=0 required=5 Aug 9 07:41:54.422 [26762] dbg: check: tests= Aug 9 07:41:54.422 [26762] dbg: check: subtests= Aug 9 07:41:54.423 [26762] dbg: timing: total 139 ms - init: 130 (93.5%), parse: 0.91 (0.7%), extract_message_metadata: 2.2 (1.6%), get_uri_detail_list: 3.9 (2.8%) Aug 9 07:41:54.423 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x559b71644548) implements 'finish_tests', priority 0 Aug 9 07:41:54.423 [26762] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x559b71644800) implements 'finish_tests', priority 0 Aug 9 07:41:54.424 [26762] dbg: generic: lint check of site pre files succeeded, continuing with channel updates Aug 9 07:41:54.424 [26762] dbg: channel: protocol family available: inet Aug 9 07:41:54.424 [26762] dbg: channel: reading MIRRORED.BY file /var/lib/spamassassin/3.004002/updates_spamassassin_org/MIRRORED.BY Aug 9 07:41:54.425 [26762] dbg: channel: parsing MIRRORED.BY file for channel updates.spamassassin.org Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.dnswl.org/ weight=3 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://www.sa-update.pccc.com/ weight=5 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.secnap.net/ weight=5 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.space-pro.be/ weight=1 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.ena.com/ weight=5 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.razx.cloud/ weight=5 Aug 9 07:41:54.425 [26762] dbg: channel: found mirror http://sa-update.fossies.org/ weight=1 Aug 9 07:41:54.426 [26762] dbg: channel: found mirror http://sa-update.verein-clean.net/ weight=10 Aug 9 07:41:54.426 [26762] dbg: channel: found mirror http://sa-update.bitwell.fi/ weight=5 Aug 9 07:41:54.426 [26762] dbg: channel: found mirror http://sa-update.spamassassin.org/ weight=10 Aug 9 07:41:54.467 [26762] dbg: channel: selected mirror http://sa-update.bitwell.fi Aug 9 07:41:54.467 [26762] dbg: http: url: http://sa-update.bitwell.fi/1880698.tar.gz Aug 9 07:41:54.468 [26762] dbg: http: downloading to: /var/lib/spamassassin/3.004002/updates_spamassassin_org/1880698.tar.gz, new Aug 9 07:41:54.469 [26762] dbg: util: executable for curl was found at /usr/bin/curl Aug 9 07:41:54.469 [26762] dbg: http: /usr/bin/curl -s -L -O --remote-time -g --max-redirs 2 --connect-timeout 30 --max-time 300 --fail -o 1880698.tar.gz -- http://sa-update.bitwell.fi/1880698.tar.gz Aug 9 07:41:54.544 [26762] dbg: http: process [26765], exit status: exit 0 Aug 9 07:41:54.545 [26762] dbg: http: url: http://sa-update.bitwell.fi/1880698.tar.gz.sha512 Aug 9 07:41:54.545 [26762] dbg: http: downloading to: /var/lib/spamassassin/3.004002/updates_spamassassin_org/1880698.tar.gz.sha512, new Aug 9 07:41:54.545 [26762] dbg: util: executable for curl was found at /usr/bin/curl Aug 9 07:41:54.545 [26762] dbg: http: /usr/bin/curl -s -L -O --remote-time -g --max-redirs 2 --connect-timeout 30 --max-time 300 --fail -o 1880698.tar.gz.sha512 -- http://sa-update.bitwell.fi/1880698.tar.gz.sha512 Aug 9 07:41:54.657 [26762] dbg: http: process [26767], exit status: exit 0 Aug 9 07:41:54.658 [26762] dbg: http: url: http://sa-update.bitwell.fi/1880698.tar.gz.sha256 Aug 9 07:41:54.658 [26762] dbg: http: downloading to: /var/lib/spamassassin/3.004002/updates_spamassassin_org/1880698.tar.gz.sha256, new Aug 9 07:41:54.659 [26762] dbg: util: executable for curl was found at /usr/bin/curl Aug 9 07:41:54.659 [26762] dbg: http: /usr/bin/curl -s -L -O --remote-time -g --max-redirs 2 --connect-timeout 30 --max-time 300 --fail -o 1880698.tar.gz.sha256 -- http://sa-update.bitwell.fi/1880698.tar.gz.sha256 Aug 9 07:41:54.777 [26762] dbg: http: process [26769], exit status: exit 0 Aug 9 07:41:54.778 [26762] dbg: http: url: http://sa-update.bitwell.fi/1880698.tar.gz.asc Aug 9 07:41:54.778 [26762] dbg: http: downloading to: /var/lib/spamassassin/3.004002/updates_spamassassin_org/1880698.tar.gz.asc, new Aug 9 07:41:54.778 [26762] dbg: util: executable for curl was found at /usr/bin/curl Aug 9 07:41:54.778 [26762] dbg: http: /usr/bin/curl -s -L -O --remote-time -g --max-redirs 2 --connect-timeout 30 --max-time 300 --fail -o 1880698.tar.gz.asc -- http://sa-update.bitwell.fi/1880698.tar.gz.asc Aug 9 07:41:54.885 [26762] dbg: http: process [26771], exit status: exit 0 Aug 9 07:41:54.886 [26762] dbg: sha512: verification wanted: 3.4 Aug 9 07:41:54.886 [26762] dbg: sha512: verification result: 7a40d40346c5599e27d00c065ac20bc33a89d8af33fdc7476d681613dfa8ecf1cca7f2cc33379a94c0afcf86fda09f4baa11adbbed332333fefb49427400bccd channel: SHA512 verification failed, channel failed Aug 9 07:41:54.886 [26762] dbg: generic: cleaning up temporary directory/files Aug 9 07:41:54.886 [26762] dbg: generic: cleaning directory /tmp/.spamassassin26762qDjYsdtmp Aug 9 07:41:54.886 [26762] dbg: diag: updates complete, exiting with code 4 sa-update failed for unknown reasons