[15767] dbg: logger: adding facilities: all
[15767] dbg: logger: logging level is DBG
[15767] dbg: logger: successfully opened file log/d.spamd_allow_user_rules/spamd.err.1.timestamped
[15767] dbg: logger: successfully added file method
[15767] dbg: spamd: will perform setuids? 0
[15767] dbg: spamd: creating INET socket:
[15767] dbg: spamd: Listen: 128
[15767] dbg: spamd: LocalAddr: 127.0.0.1
[15767] dbg: spamd: LocalPort: 38980
[15767] dbg: spamd: Proto: 6
[15767] dbg: spamd: ReuseAddr: 1
[15767] dbg: spamd: Type: 1
[15767] dbg: logger: adding facilities: all
[15767] dbg: logger: logging level is DBG
[15767] dbg: generic: SpamAssassin version 3.2.3
[15767] dbg: config: score set 0 chosen.
[15767] dbg: dns: no ipv6
[15767] dbg: dns: is Net::DNS::Resolver available? yes
[15767] dbg: dns: Net::DNS version: 0.48
[15767] dbg: learn: initializing learner
[15767] dbg: config: using "/home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp" for site rules pre files
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/init.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/v310.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/v312.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/v320.pre
[15767] dbg: config: using "/home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy" for sys rules pre files
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/init.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/v310.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/v312.pre
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/v320.pre
[15767] dbg: config: using "/home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy" for default rules dir
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/01_test_rules.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/10_default_prefs.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_advance_fee.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_body_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_compensate.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_dnsbl_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_drugs.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_dynrdns.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_fake_helo_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_head_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_html_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_imageinfo.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_meta_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_net_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_phrases.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_porn.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_ratware.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_uri_tests.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/20_vbounce.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/23_bayes.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_accessdb.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_antivirus.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_asn.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_dcc.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_dkim.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_domainkeys.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_hashcash.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_pyzor.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_razor2.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_replace.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_spf.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_textcat.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/25_uribl.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_de.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_fr.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_it.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_nl.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_pl.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/30_text_pt_br.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/50_scores.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_awl.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_shortcircuit.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_whitelist.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_whitelist_dk.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_whitelist_dkim.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_whitelist_spf.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/60_whitelist_subject.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/72_active.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/99_test_default.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/local.cf
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/test_rules_copy/regression_tests.cf
[15767] dbg: config: using "/home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp" for site rules dir
[15767] dbg: config: read file /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/00test.cf
[15767] dbg: config: using "/root/.spamassassin/user_prefs" for user prefs file
[15767] dbg: config: read file /root/.spamassassin/user_prefs
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
[15767] dbg: pyzor: local tests only, disabling Pyzor
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[15767] dbg: razor2: local tests only, skipping Razor
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
[15767] dbg: reporter: local tests only, disabling SpamCop
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDNSBL from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x251bd74), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Hashcash from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Hashcash=HASH(0x251bdc8), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::SPF from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::SPF=HASH(0x251bde0), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Pyzor from @INC
[15767] dbg: pyzor: local tests only, disabling Pyzor
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Pyzor=HASH(0x251bf54), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Razor2 from @INC
[15767] dbg: razor2: local tests only, skipping Razor
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Razor2=HASH(0x251bfa8), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::SpamCop from @INC
[15767] dbg: reporter: local tests only, disabling SpamCop
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::SpamCop=HASH(0x251bfc0), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::AWL from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::AWL=HASH(0x251dc54), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::AutoLearnThreshold from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::AutoLearnThreshold=HASH(0x251dc90), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::WhiteListSubject from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::WhiteListSubject=HASH(0x251bfc0), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEHeader from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::MIMEHeader=HASH(0x2785d20), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::ReplaceTags from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x251ddf8), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Check from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Check=HASH(0x251e89c), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTTPSMismatch from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HTTPSMismatch=HASH(0x251e8d8), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIDetail from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIDetail=HASH(0x251e8cc), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::Bayes from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::Bayes=HASH(0x251e944), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::BodyEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::BodyEval=HASH(0x251ddbc), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::DNSEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::DNSEval=HASH(0x251e968), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HTMLEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HTMLEval=HASH(0x251e95c), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::HeaderEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::HeaderEval=HASH(0x251e9a4), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::MIMEEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::MIMEEval=HASH(0x251e8fc), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::RelayEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::RelayEval=HASH(0x251e98c), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::URIEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::URIEval=HASH(0x251e9e0), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::WLBLEval from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::WLBLEval=HASH(0x251ea10), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::VBounce from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::VBounce=HASH(0x251ea40), already registered
[15767] dbg: plugin: loading Mail::SpamAssassin::Plugin::ImageInfo from @INC
[15767] dbg: plugin: did not register Mail::SpamAssassin::Plugin::ImageInfo=HASH(0x27a0b9c), already registered
[15767] dbg: config: allowing user rules!
[15767] dbg: config: fixed relative path: /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/../../data/testplugin.pm
[15767] dbg: plugin: loading myTestPlugin from /home/super/.cpan/build/Mail-SpamAssassin-3.2.3-uuS2xU/t/log/localrules.tmp/../../data/testplugin.pm
[15767] dbg: conf: finish parsing
[15767] dbg: plugin: Mail::SpamAssassin::Plugin::ReplaceTags=HASH(0x24fd6a0) implements 'finish_parsing_end', priority 0
[15767] dbg: replacetags: replacing tags
[15767] dbg: replacetags: done replacing tags
[15767] dbg: bayes: no dbs present, cannot tie DB R/O: ./log/user_state/bayes_toks
[15767] dbg: config: score set 0 chosen.
[15767] dbg: spamd: Preloading modules with HOME=/tmp/spamd-15767-init
[15767] dbg: plugin: Mail::SpamAssassin::Plugin::HeaderEval=HASH(0x273bd5c) implements 'compile_now_start', priority 0
[15767] dbg: ignore: test message to precompile patterns and load modules
[15767] dbg: message: main message type: text/plain
[15767] dbg: message: ---- MIME PARSER START ----
[15767] dbg: message: parsing normal part
[15767] dbg: message: ---- MIME PARSER END ----
[15767] dbg: plugin: Mail::SpamAssassin::Plugin::DNSEval=HASH(0x26d8ae8) implements 'check_start', priority 0
[15767] dbg: bayes: no dbs present, cannot tie DB R/O: ./log/user_state/bayes_toks
[15767] dbg: plugin: Mail::SpamAssassin::Plugin::Check=HASH(0x269bb90) implements 'check_main', priority 0
[15767] dbg: conf: trusted_networks are not configured; it is recommended that you configure trusted_networks manually
[15767] dbg: metadata: X-Spam-Relays-Trusted: 
[15767] dbg: metadata: X-Spam-Relays-Untrusted: 
[15767] dbg: metadata: X-Spam-Relays-Internal: 
[15767] dbg: metadata: X-Spam-Relays-External: 
[15767] dbg: plugin: myTestPlugin=HASH(0x27c4c14) implements 'extract_metadata', priority 0
[15767] dbg: message: no encoding detected
[15767] dbg: plugin: Mail::SpamAssassin::Plugin::URIDNSBL=HASH(0x2183ab8) implements 'parsed_metadata', priority 0
[15767] dbg: dns: is_dns_available() last checked 1187525164 seconds ago; re-checking
[15767] dbg: dns: is DNS available? 0
[15767] dbg: rules: local tests only, ignoring RBL eval
[15767] dbg: check: running tests for priority: -1000
[15767] dbg: rules: running head tests; score so far=0
[15767] dbg: rules: compiled head tests
[15767] dbg: eval: all '*From' addrs: ignore@compiling.spamassassin.taint.org
[15767] dbg: eval: all '*To' addrs: 
[15767] dbg: rules: running body tests; score so far=0
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=0
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=0
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=0
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=0
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: -950
[15767] dbg: rules: running head tests; score so far=0
[15767] dbg: rules: compiled head tests
[15767] dbg: rules: running body tests; score so far=0
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=0
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=0
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=0
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=0
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: -900
[15767] dbg: rules: running head tests; score so far=0
[15767] dbg: rules: compiled head tests
[15767] dbg: rules: running body tests; score so far=0
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=0
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=0
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=0
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=0
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: -400
[15767] dbg: rules: running head tests; score so far=0
[15767] dbg: rules: compiled head tests
[15767] dbg: rules: running body tests; score so far=0
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=0
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=0
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=0
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=0
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: 0
[15767] dbg: rules: running head tests; score so far=0
[15767] dbg: rules: compiled head tests
[15767] dbg: rules: ran header rule __HAS_MSGID ======> got hit: "<"
[15767] dbg: rules: ran header rule __SANE_MSGID ======> got hit: "<1187525164.51392@spamassassin_spamd_init>
[15767] dbg: rules: "
[15767] dbg: rules: ran header rule __MSGID_OK_DIGITS ======> got hit: "1187525164"
[15767] dbg: rules: ran header rule __MSGID_OK_HOST ======> got hit: "@spamassassin_spamd_init>"
[15767] dbg: rules: ran header rule __MSOE_MID_WRONG_CASE ======> got hit: "
[15767] dbg: rules: Message-Id: "
[15767] dbg: rules: ran header rule __MISSING_REF ======> got hit: "UNSET"
[15767] dbg: rules: ran header rule TEST_NOREALNAME ======> got hit: "ignore@compiling.spamassassin.taint.org
[15767] dbg: rules: "
[15767] dbg: rules: ran header rule MISSING_DATE ======> got hit: "UNSET"
[15767] dbg: rules: ran eval rule NO_RELAYS ======> got hit (1)
[15767] dbg: spf: cannot get Envelope-From, cannot use SPF
[15767] dbg: spf: spf_whitelist_from: could not find useable envelope sender
[15767] dbg: spf: checking to see if the message has a Received-SPF header that we can use
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: def_spf_whitelist_from: could not find useable envelope sender
[15767] dbg: rules: ran eval rule __UNUSABLE_MSGID ======> got hit (1)
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: spf: already checked for Received-SPF headers, proceeding with DNS based checks
[15767] dbg: rules: ran eval rule MISSING_HEADERS ======> got hit (1)
[15767] dbg: rules: running body tests; score so far=6.899
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: ran body rule __NONEMPTY_BODY ======> got hit: "I"
[15767] dbg: rules: running uri tests; score so far=6.899
[15767] dbg: rules: compiled uri tests
[15767] dbg: eval: stock info total: 0
[15767] dbg: rules: running rawbody tests; score so far=6.899
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=6.899
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=6.899
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: 500
[15767] dbg: rules: running head tests; score so far=6.899
[15767] dbg: rules: compiled head tests
[15767] dbg: rules: running body tests; score so far=6.899
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=6.899
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=6.899
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=6.899
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=6.899
[15767] info: rules: meta test HS_PHARMA_1 has dependency 'HS_SUBJ_ONLINE_PHARMACEUTICAL' with a zero score
[15767] info: rules: meta test FM_SEX_HOSTDDDD has dependency 'FH_HOST_EQ_D_D_D_D' with a zero score
[15767] dbg: rules: meta test DIGEST_MULTIPLE has undefined dependency 'DCC_CHECK'
[15767] info: rules: meta test FM_DDDD_TIMES_2 has dependency 'FH_HOST_EQ_D_D_D_D' with a zero score
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: running tests for priority: 1000
[15767] dbg: rules: running head tests; score so far=9.205
[15767] dbg: rules: compiled head tests
[15767] dbg: locker: safe_lock: created ./log/user_state/auto-whitelist.lock.chlorophyl.airplant.net.15767
[15767] dbg: locker: safe_lock: trying to get lock on ./log/user_state/auto-whitelist with 0 retries
[15767] dbg: locker: safe_lock: link to ./log/user_state/auto-whitelist.lock: link ok
[15767] dbg: auto-whitelist: tie-ing to DB file of type DB_File R/W in ./log/user_state/auto-whitelist
[15767] dbg: auto-whitelist: db-based ignore@compiling.spamassassin.taint.org|ip=none scores 0/0
[15767] dbg: auto-whitelist: AWL active, pre-score: 9.205, autolearn score: 9.205, mean: undef, IP: undef
[15767] dbg: auto-whitelist: DB addr list: untie-ing and unlocking
[15767] dbg: auto-whitelist: DB addr list: file locked, breaking lock
[15767] dbg: locker: safe_unlock: unlink ./log/user_state/auto-whitelist.lock
[15767] dbg: auto-whitelist: post auto-whitelist score: 9.205
[15767] dbg: rules: running body tests; score so far=9.205
[15767] dbg: rules: compiled body tests
[15767] dbg: rules: running uri tests; score so far=9.205
[15767] dbg: rules: compiled uri tests
[15767] dbg: rules: running rawbody tests; score so far=9.205
[15767] dbg: rules: compiled rawbody tests
[15767] dbg: rules: running full tests; score so far=9.205
[15767] dbg: rules: compiled full tests
[15767] dbg: rules: running meta tests; score so far=9.205
[15767] dbg: rules: compiled meta tests
[15767] dbg: check: is spam? score=9.205 required=5
[15767] dbg: check: tests=MISSING_DATE,MISSING_HEADERS,MISSING_SUBJECT,NO_RECEIVED,NO_RELAYS,TEST_NOREALNAME
[15767] dbg: check: subtests=__HAS_MSGID,__MISSING_REF,__MSGID_OK_DIGITS,__MSGID_OK_HOST,__MSOE_MID_WRONG_CASE,__NONEMPTY_BODY,__SANE_MSGID,__UNUSABLE_MSGID
[15767] dbg: plugin: myTestPlugin=HASH(0x27c4c14) implements 'per_msg_finish', priority 0
[15767] dbg: config: copying current conf to backup
[15767] info: spamd: server started on port 38980/tcp (running version 3.2.3)
[15767] info: spamd: server pid: 15767
[15767] info: spamd: server successfully spawned child process, pid 1937
[15767] dbg: prefork: child 1937: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: server successfully spawned child process, pid 28918
[15767] dbg: prefork: child 28918: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 5054
[15767] dbg: prefork: child 5054: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 6508
[15767] dbg: prefork: child 6508: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 28090
[15767] dbg: prefork: child 28090: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
[15767] dbg: prefork: child 5054: just exited
[15767] dbg: prefork: child 5054: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 5054 due to SIGCHLD
[15767] dbg: prefork: child 28918: just exited
[15767] dbg: prefork: child 28918: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 28918 due to SIGCHLD
[15767] dbg: prefork: child 1937: just exited
[15767] dbg: prefork: child 1937: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 1937 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 28090: just exited
[15767] dbg: prefork: child 28090: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 28090 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 18319
[15767] dbg: prefork: child 18319: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 29586
[15767] dbg: prefork: child 29586: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] dbg: prefork: child 6508: just exited
[15767] dbg: prefork: child 6508: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 6508 due to SIGCHLD
[15767] info: spamd: server successfully spawned child process, pid 2796
[15767] dbg: prefork: child 2796: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 1079
[15767] dbg: prefork: child 1079: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 29586: just exited
[15767] dbg: prefork: child 29586: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 29586 due to SIGCHLD
[15767] dbg: prefork: child 18319: just exited
[15767] dbg: prefork: child 18319: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 18319 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: no spare children to accept, waiting for one to complete
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 23158
[15767] dbg: prefork: child 23158: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 14386
[15767] dbg: prefork: child 14386: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 1079: just exited
[15767] dbg: prefork: child 1079: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 1079 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child 2796: just exited
[15767] dbg: prefork: child 2796: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 2796 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 25554
[15767] dbg: prefork: child 25554: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 23158: just exited
[15767] dbg: prefork: child 23158: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 23158 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 17783
[15767] dbg: prefork: child 17783: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 14386: just exited
[15767] dbg: prefork: child 14386: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 14386 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 17623
[15767] dbg: prefork: child 17623: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 25554: just exited
[15767] dbg: prefork: child 25554: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 25554 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 30298
[15767] dbg: prefork: child 30298: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 17783: just exited
[15767] dbg: prefork: child 17783: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 17783 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 17623: just exited
[15767] dbg: prefork: child 17623: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 17623 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] info: spamd: server successfully spawned child process, pid 905
[15767] dbg: prefork: child 905: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 12148
[15767] dbg: prefork: child 12148: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 1130
[15767] dbg: prefork: child 1130: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 684
[15767] dbg: prefork: child 684: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
[15767] dbg: prefork: child 12148: just exited
[15767] dbg: prefork: child 12148: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 12148 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 30298: just exited
[15767] dbg: prefork: child 30298: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 30298 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 1130: just exited
[15767] dbg: prefork: child 1130: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 1130 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 684: just exited
[15767] dbg: prefork: child 684: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 684 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] info: spamd: server successfully spawned child process, pid 26044
[15767] dbg: prefork: child 26044: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child 905: just exited
[15767] dbg: prefork: child 905: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 905 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: S
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 9130
[15767] dbg: prefork: child 9130: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 27884
[15767] dbg: prefork: child 27884: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 32457
[15767] dbg: prefork: child 32457: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 9130: just exited
[15767] dbg: prefork: child 9130: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 9130 due to SIGCHLD
[15767] dbg: prefork: child 26044: just exited
[15767] dbg: prefork: child 26044: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 26044 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 6392
[15767] dbg: prefork: child 6392: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 15550
[15767] dbg: prefork: child 15550: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 27884: just exited
[15767] dbg: prefork: child 27884: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 27884 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 32457: just exited
[15767] dbg: prefork: child 32457: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 32457 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 19942
[15767] dbg: prefork: child 19942: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 10155
[15767] dbg: prefork: child 10155: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 6392: just exited
[15767] dbg: prefork: child 6392: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 6392 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 15550: just exited
[15767] dbg: prefork: child 15550: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 15550 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 9765
[15767] dbg: prefork: child 9765: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 19942: just exited
[15767] dbg: prefork: child 19942: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 19942 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 637
[15767] dbg: prefork: child 637: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 29810
[15767] dbg: prefork: child 29810: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 10155: just exited
[15767] dbg: prefork: child 10155: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 10155 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 28663
[15767] dbg: prefork: child 28663: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child 9765: just exited
[15767] dbg: prefork: child 9765: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 9765 due to SIGCHLD
[15767] dbg: prefork: child 637: just exited
[15767] dbg: prefork: child 637: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 637 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 27285
[15767] dbg: prefork: child 27285: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 28663: just exited
[15767] dbg: prefork: child 28663: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 28663 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 25413
[15767] dbg: prefork: child 25413: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 29810: just exited
[15767] dbg: prefork: child 29810: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 29810 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 23394
[15767] dbg: prefork: child 23394: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child 27285: just exited
[15767] dbg: prefork: child 27285: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 27285 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 15072
[15767] dbg: prefork: child 15072: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 25413: just exited
[15767] dbg: prefork: child 25413: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 25413 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 12304
[15767] dbg: prefork: child 12304: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 26476
[15767] dbg: prefork: child 26476: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 15072: just exited
[15767] dbg: prefork: child 15072: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 15072 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 20295
[15767] dbg: prefork: child 20295: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 23394: just exited
[15767] dbg: prefork: child 23394: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 23394 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 19229
[15767] dbg: prefork: child 19229: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 14637
[15767] dbg: prefork: child 14637: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
[15767] dbg: prefork: child 26476: just exited
[15767] dbg: prefork: child 26476: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 26476 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 12304: just exited
[15767] dbg: prefork: child 12304: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 12304 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 20295: just exited
[15767] dbg: prefork: child 20295: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 20295 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 19229: just exited
[15767] dbg: prefork: child 19229: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 19229 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] info: spamd: server successfully spawned child process, pid 29679
[15767] dbg: prefork: child 29679: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 14637: just exited
[15767] dbg: prefork: child 14637: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 14637 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 30887
[15767] dbg: prefork: child 30887: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 8936
[15767] dbg: prefork: child 8936: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 2000
[15767] dbg: prefork: child 2000: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 24678
[15767] dbg: prefork: child 24678: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
[15767] dbg: prefork: child 30887: just exited
[15767] dbg: prefork: child 30887: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 30887 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 32506
[15767] dbg: prefork: child 32506: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSSS
[15767] info: prefork: server reached --max-children setting, consider raising it
[15767] dbg: prefork: child 29679: just exited
[15767] dbg: prefork: child 29679: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 29679 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 8936: just exited
[15767] dbg: prefork: child 8936: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 8936 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 2000: just exited
[15767] dbg: prefork: child 2000: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 2000 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 24678: just exited
[15767] dbg: prefork: child 24678: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 24678 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] info: spamd: server successfully spawned child process, pid 25782
[15767] dbg: prefork: child 25782: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 4815
[15767] dbg: prefork: child 4815: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 32506: just exited
[15767] dbg: prefork: child 32506: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 32506 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 18196
[15767] dbg: prefork: child 18196: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 25132
[15767] dbg: prefork: child 25132: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 25782: just exited
[15767] dbg: prefork: child 25782: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 25782 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 3119
[15767] dbg: prefork: child 3119: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] info: spamd: server successfully spawned child process, pid 2502
[15767] dbg: prefork: child 2502: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 4815: just exited
[15767] dbg: prefork: child 4815: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 4815 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 15647
[15767] dbg: prefork: child 15647: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: child 25132: just exited
[15767] dbg: prefork: child 25132: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 25132 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
[15767] dbg: prefork: child 18196: just exited
[15767] dbg: prefork: child 18196: entering state 4
[15767] dbg: prefork: new lowest idle kid: none
[15767] info: spamd: handled cleanup of child pid 18196 due to SIGCHLD
[15767] dbg: prefork: new lowest idle kid: none
[15767] dbg: prefork: select returned err Interrupted system call, probably signalled
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.
[15767] dbg: prefork: child closed connection
[15767] info: prefork: child states: SSS
[15767] dbg: prefork: adjust: increasing, not enough idle children (0 < 1)
[15767] info: spamd: server successfully spawned child process, pid 2014
[15767] dbg: prefork: child 2014: entering state 0
[15767] dbg: prefork: new lowest idle kid: none
perl in free(): warning: recursive call.
perl in malloc(): warning: recursive call.
Out of memory!
perl in malloc(): warning: recursive call.
Out of memory!
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in malloc(): warning: recursive call.
Out of memory!
perl in malloc(): warning: recursive call.
Out of memory!
perl in malloc(): warning: recursive call.
Out of memory!
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in free(): warning: recursive call.
perl in malloc(): warning: recursive call.
Out of memory!
setrgid() not implemented at ../../lib/POSIX.pm (autosplit into ../../lib/auto/POSIX/setgid.al) line 739.