I wish
I had the answer but all I can do is confirm the same behavior. I've been
searching for an answer for several week so far w/o any
success.
-----Original Message-----
From: [EMAIL PROTECTED] [mailto:[EMAIL PROTECTED]On Behalf Of Osborne, George
Sent: Thursday, August 14, 2003 2:37 PM
To: [EMAIL PROTECTED]
Subject: [Razor-users] Unable to connect to truth.cloudmark.com:2703; Reason: Invalid argumentI have postfix running in chroot mode and amavisd-new-20030616-p4. I receive some errors when running amavisd debug-sa. Here is the debug output...Aug 14 15:34:20 ares.asellus.com amavisd[6458]: starting. amavisd at ares.asellus.com amavisd-new-20030616-p4, Unicode aware, LANG=en_US.iso885915
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Perl version 5.008
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Amavis::Conf 1.15
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Archive::Tar 1.02
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Archive::Zip 1.05
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Compress::Zlib 1.22
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Convert::TNEF 0.17
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Convert::UUlib 0.31
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module MIME::Entity 5.404
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module MIME::Parser 5.406
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module MIME::Tools 5.411
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Mail::Header 1.58
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Mail::Internet 1.58
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Mail::SpamAssassin 2.55
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Net::Cmd 2.24Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Net::DNS 0.38
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Net::SMTP 2.26
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Net::Server 0.85
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Time::HiRes 1.48
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Module Unix::Syslog 0.99
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Found myself: /usr/local/sbin/amavisd -c /etc/amavisd.conf
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Lookup::SQL code NOT loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Lookup::LDAP code NOT loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: AMCL-in protocol code NOT loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: SMTP-in protocol code loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: ANTI-VIRUS code NOT loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: ANTI-SPAM code loaded
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Net::Server: 2003/08/14-15:34:20 Amavis (type Net::Server::PreForkSimple) starting! pid(6458)
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Net::Server: Binding to TCP port 10024 on host 208.255.165.45
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Net::Server: Chrooting to /var/amavisd
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Net::Server: Setting gid to "3000 3000"
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Net::Server: Setting uid to "3000"
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Found $file at (chroot: /var/amavisd/) /usr/local/bin/file
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $arc, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Found $gzip at (chroot: /var/amavisd/) /usr/local/bin/gzip
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Found $bzip2 at (chroot: /var/amavisd/) /usr/local/bin/bzip2
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $lzop, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $lha, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $unarj, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: Found $uncompress at (chroot: /var/amavisd/) /usr/local/bin/gzip -d
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $unfreeze, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $unrar, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $zoo, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: No $cpio, not using it
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: SpamControl: initializing Mail::SpamAssassin
debug: Score set 0 chosen.
debug: running in taint mode? no
Aug 14 15:34:20 ares.asellus.com amavisd[6458]: SpamControl: turning on SA auto-whitelisting (AWL)
debug: ignore: test message to precompile patterns and load modules
debug: using "/usr/share/spamassassin" for default rules dir
debug: using "/etc/mail/spamassassin" for site rules dir
debug: using "/var/amavisd/.spamassassin/user_prefs" for user prefs file
debug: bayes: 6458 tie-ing to DB file R/O /var/amavisd/.spamassassin/bayes_toks
debug: bayes: 6458 tie-ing to DB file R/O /var/amavisd/.spamassassin/bayes_seen
debug: Score set 3 chosen.
debug: Initialising learner
debug: running header regexp tests; score so far=0
debug: running body-text per-line regexp tests; score so far=1.5
debug: Razor2 is available
debug: entering helper-app run mode
Razor-Log: Computed razorhome from env: /var/amavisd/.razor
Razor-Log: Found razorhome: /var/amavisd/.razor
Razor-Log: read_file: 16 items read from /var/amavisd/.razor/razor-agent.conf
Aug 14 15:34:20.836938 check[6458]: [ 1] [bootup] Logging initiated LogDebugLevel=9 to stdout
Aug 14 15:34:20.837558 check[6458]: [ 5] computed razorhome=/var/amavisd/.razor, conf=/var/amavisd/.razor/razor-agent.conf, ident=/var/amavisd/.razor/identity-ruNlcKbtfH
Aug 14 15:34:20.838065 check[6458]: [ 8] Client supported_engines: 1 2 3 4
Aug 14 15:34:20.838645 check[6458]: [ 8] prep_mail done: mail 1 headers=101, mime0=1376
Aug 14 15:34:20.839142 check[6458]: [ 5] read_file: 1 items read from /var/amavisd/.razor/servers.discovery.lst
Aug 14 15:34:20.839718 check[6458]: [ 5] read_file: 2 items read from /var/amavisd/.razor/servers.nomination.lst
Aug 14 15:34:20.840170 check[6458]: [ 5] read_file: 2 items read from /var/amavisd/.razor/servers.catalogue.lst
Aug 14 15:34:20.840636 check[6458]: [ 9] Assigning defaults to joy.cloudmark.com
Aug 14 15:34:20.841007 check[6458]: [ 9] Assigning defaults to folly.cloudmark.com
Aug 14 15:34:20.841269 check[6458]: [ 9] Assigning defaults to truth.cloudmark.com
Aug 14 15:34:20.841530 check[6458]: [ 9] Assigning defaults to stress.cloudmark.com
Aug 14 15:34:20.842416 check[6458]: [ 5] read_file: 11 items read from /var/amavisd/.razor/server.folly.cloudmark.com.conf
Aug 14 15:34:20.843300 check[6458]: [ 5] read_file: 12 items read from /var/amavisd/.razor/server.joy.cloudmark.com.conf
Aug 14 15:34:20.844107 check[6458]: [ 5] read_file: 12 items read from /var/amavisd/.razor/server.stress.cloudmark.com.conf
Aug 14 15:34:20.844455 check[6458]: [ 5] 151867 seconds before closest server discovery
Aug 14 15:34:20.844809 check[6458]: [ 6] truth.cloudmark.com is a Unknown-Type: Server srl -1; computed min_cf=0, Server se: 0F
Aug 14 15:34:20.845119 check[6458]: [ 8] Computed supported_engines: 1 2 3 4
Aug 14 15:34:20.845341 check[6458]: [ 8] Using next closest server truth.cloudmark.com:2703, cached info srl -1
Aug 14 15:34:20.845591 check[6458]: [ 8] mail 1 has no subject
Aug 14 15:34:20.847017 check[6458]: [ 6] preproc: mail 1.0 went from 1376 bytes to 1339
Aug 14 15:34:20.847281 check[6458]: [ 6] computing sigs for mail 1.0, len 1339
Aug 14 15:34:20.851049 check[6458]: [ 6] skipping whitelist file (empty?): /var/amavisd/.razor/razor-whitelist
Aug 14 15:34:20.851317 check[6458]: [ 5] Connecting to truth.cloudmark.com ...
Aug 14 15:34:20.852811 check[6458]: [ 3] Unable to connect to truth.cloudmark.com:2703; Reason: Invalid argument.
Aug 14 15:34:20.853196 check[6458]: [ 5] 168152 seconds before closest server discovery
Aug 14 15:34:20.853464 check[6458]: [ 6] stress.cloudmark.com is a Catalogue Server srl 68; computed min_cf=6, Server se: 58
Aug 14 15:34:20.853767 check[6458]: [ 8] Computed supported_engines: 4
Aug 14 15:34:20.854082 check[6458]: [ 8] Using next closest server stress.cloudmark.com:2703, cached info srl 68
Aug 14 15:34:20.854282 check[6458]: [ 5] Connecting to stress.cloudmark.com ...
Aug 14 15:34:20.855211 check[6458]: [ 3] Unable to connect to stress.cloudmark.com:2703; Reason: Invalid argument.
Aug 14 15:34:20.855571 check[6458]: [ 5] 155375 seconds before closest server discovery
Aug 14 15:34:20.855842 check[6458]: [ 5] 153176 seconds before closest server discoveryAug 14 15:34:20.855974 check[6458]: [ 5] forcing discovery
Aug 14 15:34:20.856124 check[6458]: [ 8] already have 1 discovery servers
Aug 14 15:34:20.856328 check[6458]: [ 8] Checking with Razor Discovery Server 216.52.3.2
Aug 14 15:34:20.856534 check[6458]: [ 5] Connecting to 216.52.3.2 ...
Aug 14 15:34:21.015915 check[6458]: [ 8] Connection established
Aug 14 15:34:21.016192 check[6458]: [ 4] 216.52.3.2 >> 35 server greeting: sn=D&srl=124&ep4=7542-10&a=l&a=cg
Aug 14 15:34:21.016491 check[6458]: [ 4] 216.52.3.2 << 12
Aug 14 15:34:21.016628 check[6458]: [ 6] a=g&pm=csl
Aug 14 15:34:21.099354 check[6458]: [ 4] 216.52.3.2 >> 54
Aug 14 15:34:21.099536 check[6458]: [ 6] response to sent.1
-csl=?
truth.cloudmark.com
stress.cloudmark.com
.
Aug 14 15:34:21.099797 check[6458]: [ 8] Discovery Server 216.52.3.2 replying with csl=truth.cloudmark.com
Aug 14 15:34:21.100050 check[6458]: [ 8] Discovery Server 216.52.3.2 replying with csl=stress.cloudmark.com
Aug 14 15:34:21.100303 check[6458]: [ 4] 216.52.3.2 << 12
Aug 14 15:34:21.100433 check[6458]: [ 6] a=g&pm=nsl
Aug 14 15:34:21.183350 check[6458]: [ 4] 216.52.3.2 >> 51
Aug 14 15:34:21.183517 check[6458]: [ 6] response to sent.2
-nsl=?
joy.cloudmark.com
folly.cloudmark.com
.
Aug 14 15:34:21.183763 check[6458]: [ 8] Discovery Server 216.52.3.2 replying with nsl=joy.cloudmark.com
Aug 14 15:34:21.184009 check[6458]: [ 8] Discovery Server 216.52.3.2 replying with nsl=folly.cloudmark.com
Aug 14 15:34:21.184830 check[6458]: [ 5] wrote 2 ARRAY items to file: /var/amavisd/.razor/servers.catalogue.lst
Aug 14 15:34:21.185358 check[6458]: [ 5] wrote 2 ARRAY items to file: /var/amavisd/.razor/servers.nomination.lst
Aug 14 15:34:21.185808 check[6458]: [ 6] truth.cloudmark.com is a Unknown-Type: Server srl -1; computed min_cf=0, Server se: 0F
Aug 14 15:34:21.186122 check[6458]: [ 8] Computed supported_engines: 1 2 3 4
Aug 14 15:34:21.186341 check[6458]: [ 8] Using next closest server truth.cloudmark.com:2703, cached info srl -1
Aug 14 15:34:21.186608 check[6458]: [ 6] losing old server connection, 216.52.3.2, for new server, truth.cloudmark.com
Aug 14 15:34:21.186758 check[6458]: [ 5] disconnecting from server 216.52.3.2
Aug 14 15:34:21.186970 check[6458]: [ 4] 216.52.3.2 << 5
Aug 14 15:34:21.187191 check[6458]: [ 6] a=q
Aug 14 15:34:21.187419 check[6458]: [ 5] Connecting to truth.cloudmark.com ...
Aug 14 15:34:21.188573 check[6458]: [ 3] Unable to connect to truth.cloudmark.com:2703; Reason: Invalid argument.
debug: razor2 check could not connect to any servers
debug: leaving helper-app run mode
debug: Razor2 results: spam? 0 highest cf score: 0
debug: bayes corpus size: nspam = 331, nham = 7028
debug: tokenize: header tokens for *F = "[EMAIL PROTECTED]"
debug: tokenize: header tokens for *m = " 1060889660 22678 spamassassin_spamd_init "
debug: bayes token 'N:H*m:NNNNNNNNNN' => 0.001
debug: bayes token 'N:H*m:NNNNN' => 0.001
debug: bayes token 'somewhat' => 0.00476190476190476
debug: bayes: score = 0.00225425352953645
debug: bayes: 6458 untie-ing
debug: bayes: 6458 untie-ing db_toks
debug: bayes: 6458 untie-ing db_seen
debug: running raw-body-text per-line regexp tests; score so far=1.5
debug: running uri tests; score so far=1.5
debug: uri tests: Done uriRE
debug: running full-text regexp tests; score so far=1.5
debug: Current PATH is: /usr/local/sbin:/usr/local/bin:/usr/sbin:/sbin:/usr/bin:/bin
debug: executable for dccproc was found at /usr/local/bin/dccproc
debug: DCC is available: /usr/local/bin/dccproc
debug: entering helper-app run mode
debug: leaving helper-app run mode
DCC -> check failed: no responsedebug: Razor2 is available
debug: all '*From' addrs: [EMAIL PROTECTED]
debug: all '*To' addrs:
debug: running meta tests; score so far=1.5
debug: lock: 6458 created /var/amavisd/.spamassassin/auto-whitelist.lock.ares.asellus.com.6458
debug: lock: 6458 trying to get lock on /var/amavisd/.spamassassin/auto-whitelist with 0 retries
debug: lock: 6458 link to /var/amavisd/.spamassassin/auto-whitelist.lock: link ok
debug: Tie-ing to DB file R/W in /var/amavisd/.spamassassin/auto-whitelist
debug: auto-whitelist (db-based): [EMAIL PROTECTED]|ip=none scores 0/0
debug: AWL active, pre-score: -3.7, mean: undef, originating-ip: undef
debug: Post AWL score: -3.7
debug: DB addr list: untie-ing and unlocking.
debug: DB addr list: file locked, breaking lock.
debug: unlock: 6458 unlink /var/amavisd/.spamassassin/auto-whitelist.lock
debug: is spam? score=-3.7 required=5 tests=BAYES_00,DATE_MISSING,NO_REAL_NAME
debug: bayes: 6458 untie-ing
Aug 14 15:34:21 ares.asellus.com amavisd[6458]: SpamControl: done
Aug 14 15:34:22 ares.asellus.com amavisd[6458]: Net::Server: 2003/08/14-15:34:22 Server closing!DCC and Razor do not work. They do work when I send a test spam message manually.Any help would be appreciated. Thanks.
