1. Summary
  2. Files
  3. Support
  4. Report Spam
  5. Create account
  6. Log in

Percentage of inbound connections timeout

Moderators: pdreissen, fribo

Percentage of inbound connections timeout

Postby jeffnjnecom » Fri Jan 25, 2013 9:47 pm

I have an issue where a few inbound connections will stay connected for as long as my timeout setting (currently 180 sec) and then drop. There all a handful on domains/mail servers which are not able to pass the mail off. I do not know where to look. I was running Perl 5.10 and just upgraded to 5.16 but it didn't seem to have any effect. Does anyone have advice on how to trouble shhot this?

Here are some data points from the maillog.txt after a failure:

Jan-25-13 16:01:39 47506-02589 98.136.218.245 <XXXXXX@sbcglobal.net> Connection idle for 180 secs - timeout
Jan-25-13 16:01:39 47506-02589 98.136.218.245 <XXXXXX@sbcglobal.net> [SMTP Status] 451 Connection timeout, try later


This same sender will also timeout from the ISP's secondary SMTP server.

I have added the email to the whitelist, set the IP address to NoDelay, and whitelisted the IP address. Nothing has worked.

Below is the portion of the log when my system is starting in case it holds a clue and I've highlighted the lines that concern me.

I appreciate any suggestions. Thanks.

-jeff


======================================================

Jan-25-13 15:57:06 Starting as a service
Jan-25-13 15:57:14 ASSP-professional version 2.2.2(13020) (Perl 5.016002) (on MSWin32) initializing
Jan-25-13 15:57:14 AdminInfo: failed to open option list file for reading 'C:/ASSP/assp_sync.cfg' (syncConfigFile): No such file or directory
Jan-25-13 15:57:14 AdminInfo: failed to open option list file for reading 'C:/ASSP/files/MaxRealSize.txt' (MaxRealSizeAdr): No such file or directory
Jan-25-13 15:57:14 AdminInfo: failed to open option list file for reading 'C:/ASSP/files/MaxRealSizeExt.txt' (MaxRealSizeExternalAdr): No such file or directory
Jan-25-13 15:57:14 AdminInfo: failed to open option list file for reading 'C:/ASSP/files/MaxSizeExt.txt' (MaxSizeExternalAdr): No such file or directory
Jan-25-13 15:57:16 Info: enabled VRFY for domain(s) <List_of_Domains>
Jan-25-13 15:57:17 AdminInfo: failed to open option list file for reading 'C:/ASSP/files/preheaderre.txt' (preHeaderRe): No such file or directory
Jan-25-13 15:57:17 Info: Regex bombHeaderRe: 3 weighted regular expression defined
Jan-25-13 15:57:18 Info: switched (DNS) nameserver order from to 8.8.4.4 , 8.8.8.8
Jan-25-13 15:57:18 Info: removed 0 config change schedules
Jan-25-13 15:57:18 Info: registered encrypted POP3ConfigFile file C:/ASSP/files/pop3cfg.txt
Jan-25-13 15:57:18 Info: an ASSP restart will be done with:
Jan-25-13 15:57:18 ASSP version 2.2.2(13020) (Perl 5.016002) (on MSWin32)running on server: NAV_GW (XXX.XXX.XXX.51)
Jan-25-13 15:57:18 Info: unicode support is available on that system
Jan-25-13 15:57:18 Info: analysing windows system environment
Jan-25-13 15:57:18 Info: windows system environment looks OK
Jan-25-13 15:57:18 Threads module 1.86 installed
Jan-25-13 15:57:18 Threads::shared module 1.42 installed
Jan-25-13 15:57:18 Thread::Queue module 2.12 installed
Jan-25-13 15:57:18 IO::Poll module 0.09 installed
Jan-25-13 15:57:18 IO::Select module 1.21 installed
Jan-25-13 15:57:18 ASSP is using IOEngine - Poll
Jan-25-13 15:57:18 Thread::State module version 0.09 installed and available
Jan-25-13 15:57:18 IO::Socket::INET6 module is not detected (enableIPv6 is not set).
Jan-25-13 15:57:20 File::Scan::ClamAV module version 1.8 installed but not available, error: Cannot connect to unix socket '/tmp/clamd':
Jan-25-13 15:57:20 Net::LDAP module version 0.52 installed and available
Jan-25-13 15:57:20 Net::DNS module version 0.72 installed and available
Jan-25-13 15:57:20 Email::Valid module version 0.190 installed and available
Jan-25-13 15:57:20 Net::SMTP module version 2.31 installed and available
Jan-25-13 15:57:20 Net::SMTP::TLS module version 0.12 installed and available
Jan-25-13 15:57:20 NetSNMP::agent module is not installed.
Jan-25-13 15:57:20 Mail::SPF::Query module version 1.999001 installed and available
Jan-25-13 15:57:20 Mail::SPF module version 2.007 installed and available
Jan-25-13 15:57:20 Mail::SRS module version 0.31 installed - Sender Rewriting Scheme available
Jan-25-13 15:57:20 Compress::Zlib module version 2.055 installed - HTTP compression available
Jan-25-13 15:57:20 Digest::MD5 module version 2.52 installed - delaying can use MD5 keys for hashes
Jan-25-13 15:57:20 Digest::SHA1 module version 2.13 installed - BATV and FBMTV check available
Jan-25-13 15:57:20 File::ReadBackwards module version 1.05 installed - searching of log files enabled
Jan-25-13 15:57:20 Time::HiRes module version 1.9725 installed - CPU usage statistics available
Jan-25-13 15:57:20 Sys::Syslog module version 0.32 installed - Unix centralized logging enabled
Jan-25-13 15:57:20 Net::Syslog module version 0.04 installed - network Syslog logging enabled
Jan-25-13 15:57:20 Win32::Daemon module version 20110117 installed - can run as Win32 service
Jan-25-13 15:57:20 Win32::API::OutputDebugString module is not installed - unable to debug to Win32 API
Jan-25-13 15:57:20 Unicode::GCString module version 2012.10 installed - can detect east asian language strings as sequence of UAX #29 Grapheme Clusters to analyze Bayes and HMM
Jan-25-13 15:57:20 Win32::Unicode module is not installed - unable to write unicode filenames to OS
Jan-25-13 15:57:20 Convert::Scalar module version 1.1 installed - can use optimized memory handling
Jan-25-13 15:57:20 Tie::RDBM module version 0.73 installed - database usage available
Jan-25-13 15:57:20 DB_File module is not installed - DB_File (Berkeley V1) database usage not available
Jan-25-13 15:57:20 BerkeleyDB module version 0.51 installed - Berkeley database usage available
Jan-25-13 15:57:20 BerkeleyDB DB-version 5.3 / Berkeley DB 5.3.21: (May 11, 2012) is installed
Jan-25-13 15:57:20 Info: griplist is using basic 'orderedtie' in file C:/ASSP/griplist
Jan-25-13 15:57:20 Net::IP::Match::Regexp module version 1.01 installed - CIDR notation for IP range available
Jan-25-13 15:57:20 Net::CIDR::Lite module version 0.21 installed - hyphenated IP address range available
Jan-25-13 15:57:20 Net::SenderBase module version 1.01 installed - Query the senderbase service available
Jan-25-13 15:57:20 LWP::Simple module version 6.00 installed - procedural LWP interface available
Jan-25-13 15:57:20 Email::MIME::Modifier module version 1.911 installed - MIME charset decoding and conversion interface and attachment detection available
Jan-25-13 15:57:20 MIME::Types module version 1.38 installed - TNEF conversion may possible
Jan-25-13 15:57:20 Email::Send module version 2.198 installed - sending .eml files available
Jan-25-13 15:57:20 Convert::TNEF module version 0.18 installed - TNEF conversion is available
Jan-25-13 15:57:20 Mail::DKIM::Verifier module is not installed - DKIM verification not available
Jan-25-13 15:57:20 Schedule::Cron module version 0.97 installed - RebuildSpamdb Scheduler is available
Jan-25-13 15:57:20 Sys::MemInfo module version 0.91 installed - memory calculation is available
Jan-25-13 15:57:20 Authen::SASL module version 2.16 installed - SMTP AUTH is available
Jan-25-13 15:57:20 Regex::Optimizer module is not installed - Regular Expression Optimization is not available - regex processing will take approximately 6 times longer
Jan-25-13 15:57:20 AsspSelfLoader module is not installed - ASSP Code Load Optimization is not available
Jan-25-13 15:57:20 ASSP_WordStem module is not installed - ASSP multi lingual word stemming engine for Bayesian and HMM checks is not available
Jan-25-13 15:57:20 IO::Socket::SSL module version 1.77 installed - https and TLS/SSL is possible
Jan-25-13 15:57:20 Found valid certificate and private key file - https and TLS/SSL is available
Jan-25-13 15:57:20 Warning: There were module load errors detected - look in to file C:/ASSP/moduleLoadErrors.txt for more details. To solve this issue install the failed modules or disable them in the 'Module Setup' section in the GUI.
Jan-25-13 15:57:21 Info: Whitelist loaded from C:/ASSP/whitelist with 2036 records
Jan-25-13 15:57:21 Info: Whitelist contains 2036 records - it is recommended to use a database for 'whitelistdb'
Jan-25-13 15:57:21 Info: PersBlack loaded from C:/ASSP/persblack with 0 records
Jan-25-13 15:57:21 Info: Redlist loaded from C:/ASSP/redlist with 80 records
Jan-25-13 15:57:21 Info: Delay loaded from C:/ASSP/delaydb with 425 records
Jan-25-13 15:57:21 Info: DelayWhite loaded from C:/ASSP/delaydb.white with 851 records
Jan-25-13 15:57:21 Info: PBWhite loaded from C:/ASSP/pb/pbdb.white.db with 545 records
Jan-25-13 15:57:21 Info: PBBlack loaded from C:/ASSP/pb/pbdb.black.db with 276 records
Jan-25-13 15:57:21 Info: RBLCache loaded from C:/ASSP/pb/pbdb.rbl.db with 85 records
Jan-25-13 15:57:21 Info: URIBLCache loaded from C:/ASSP/pb/pbdb.uribl.db with 48 records
Jan-25-13 15:57:22 Info: PTRCache loaded from C:/ASSP/pb/pbdb.ptr.db with 435 records
Jan-25-13 15:57:22 Info: MXACache loaded from C:/ASSP/pb/pbdb.mxa.db with 385 records
Jan-25-13 15:57:22 Info: RWLCache loaded from C:/ASSP/pb/pbdb.rwl.db with 0 records
Jan-25-13 15:57:22 Info: SPFCache loaded from C:/ASSP/pb/pbdb.spf.db with 28 records
Jan-25-13 15:57:22 Info: SBCache loaded from C:/ASSP/pb/pbdb.sb.db with 1836 records
Jan-25-13 15:57:22 Info: SBCache contains 1836 records - it is recommended to use a database for 'pbdb'
Jan-25-13 15:57:22 Info: PBTrap loaded from C:/ASSP/pb/pbdb.trap.db with 112 records
Jan-25-13 15:57:22 Info: DKIMCache loaded from C:/ASSP/pb/pbdb.dkim.db with 0 records
Jan-25-13 15:57:22 Info: BATVTag loaded from C:/ASSP/pb/pbdb.batv.db with 0 records
Jan-25-13 15:57:22 Info: BackDNS loaded from C:/ASSP/pb/pbdb.back.db with 0 records
Jan-25-13 15:57:23 Info: Spamdb loaded from C:/ASSP/spamdb with 70091 records
Jan-25-13 15:57:23 Warning: Spamdb contains 70091 records (allocating at least 27.8MB) - it is highly recommended to use a database for 'spamdb'
Jan-25-13 15:57:23 Info: HeloBlack loaded from C:/ASSP/spamdb.helo with 145 records
Jan-25-13 15:57:23 Info: LDAPlist loaded from C:/ASSP/ldaplist with 121 records
Jan-25-13 15:57:23 Starting maintenance worker thread [10000] - ThreadCycleTime is set to 3000 microseconds
Jan-25-13 15:57:23 Info: switched (DNS) nameserver order from 8.8.4.4 , 8.8.8.8 to 8.8.8.8 , 8.8.4.4
Jan-25-13 15:57:23 Worker_10000 started
Jan-25-13 15:57:24 Info: 0 records of PersBlack saved
Jan-25-13 15:57:24 Starting rebuild SpamDB worker thread [10001] - ThreadCycleTime is set to 30 microseconds
Jan-25-13 15:57:25 Worker_10001 started
Jan-25-13 15:57:25 Starting SMTP-worker-threads with ThreadCycleTime set to 3000 microseconds
Jan-25-13 15:57:25 Starting communication worker threads [1 to 10]
Jan-25-13 15:57:25 Info: found module C:/ASSP/lib/rebuildspamdb.pm version 6.14
Jan-25-13 15:57:26 Worker_1 started
Jan-25-13 15:57:26 Worker_2 started
Jan-25-13 15:57:27 Worker_3 started
Jan-25-13 15:57:28 Worker_4 started
Jan-25-13 15:57:30 Worker_5 started
Jan-25-13 15:57:31 Worker_6 started
Jan-25-13 15:57:32 Worker_7 started
Jan-25-13 15:57:33 Worker_8 started
Jan-25-13 15:57:34 Worker_9 started
Jan-25-13 15:57:34 Worker_10 started
Jan-25-13 15:57:37 All Threads are started
Jan-25-13 15:57:37 Info: CPU priority changed for Worker_10000 from 0 to 2
Jan-25-13 15:57:37 Info: CPU priority changed for Worker_10001 from 0 to 2
Jan-25-13 15:57:37 Info: HMMdb is using 'BerkeleyDB' version 5.3 in file C:/ASSP/HMMdb.bdb, because HMMusesBDB is set to ON
Jan-25-13 15:57:40 The Hidden-Markov-Model-DB is empty - the HMM check is disabled
Jan-25-13 15:57:40 Info: saving Stats in file asspstats.sav
Jan-25-13 15:57:41 Listening for SMTP connections on XXX.XXX.XXX.51:25 XX.XXX.XXX.252:25
Jan-25-13 15:57:41 Listening for admin HTTP connections on 0.0.0.0:55555
Jan-25-13 15:57:41 Listening for stat HTTP connections on 0.0.0.0:55553
Jan-25-13 15:57:41 Listening for additional SMTP connections on 0.0.0.0:2526
Jan-25-13 15:57:41 Starting PID: 644
jeffnjnecom
 
Posts: 4
Joined: Tue Jan 12, 2010 7:40 pm

Re: Percentage of inbound connections timeout

Postby jeffnjnecom » Sat Jan 26, 2013 3:01 am

It looks like the problem was sendEHLO. When it was enabled a small percentage of connections would time out. After it was disabled, timeouts went to zero.
jeffnjnecom
 
Posts: 4
Joined: Tue Jan 12, 2010 7:40 pm


Return to Troubleshooting

Who is online

Users browsing this forum: No registered users and 2 guests