Not accepting incoming connections
Printed From: LogSat Software
Category: Spam Filter ISP
Forum Name: Spam Filter ISP Support
Forum Description: General support for Spam Filter ISP
URL: https://www.logsat.com/spamfilter/forums/forum_posts.asp?TID=6558
Printed Date: 15 March 2025 at 7:55am
Topic: Not accepting incoming connections
Posted By: Helpdesk
Subject: Not accepting incoming connections
Date Posted: 15 September 2008 at 8:10pm
Hi,
We are running Spamfilter ISP v4.0.1.785 and we are having a problem with the filter accepting new connections after a period of time. The port is still open however if you telnet to port 25 it just disconnects. The logs for the Spamfilter stop processing mail and just repeat the bayesian thread loading of the corpus db and cleanup. To resolve we have to restart the server as the service will not respond to a stop/start or restart.
We have gradually increased the MaxInboundConnections to 500 however the issue continues to occur (more frequently of late). Below is the log that just continually repeats until we restart the server:
09/15/08 17:15:56:578 -- (3072) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:15:56:578 -- (3072) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:15:56:578 -- (3892) Blacklist cache - starting cleanup
09/15/08 17:15:56:640 -- (660) Saved GreyListAllowed.txt
09/15/08 17:15:56:640 -- (2560) Starting to process quarantine directory...
09/15/08 17:15:56:796 -- (3892) Hash cache - removed 2 entries during cleanup
09/15/08 17:16:30:328 -- (1420) Begin Sync Corpus.db
09/15/08 17:16:30:328 -- (1420) Sync Corpus.db pass 0a
09/15/08 17:16:30:328 -- (1420) Sync Corpus.db pass 0b
09/15/08 17:16:30:328 -- (1420) Sync Corpus.db pass 0c
09/15/08 17:16:30:328 -- (1420) Sync Corpus.db - 857432 - 678
09/15/08 17:16:31:109 -- (1420) Sync Corpus.db pass 1 (780)
09/15/08 17:16:31:125 -- (1420) Sync Corpus.db pass 2 (796)
09/15/08 17:16:31:125 -- (1420) Sync Corpus.db pass 3 (796)
09/15/08 17:16:31:125 -- (1420) Sync Corpus.db pass 4 (796)
09/15/08 17:16:31:359 -- (1420) Sync Corpus.db pass 5 (1030)
09/15/08 17:16:31:640 -- (1420) Sync Corpus.db pass 6 (1311)
09/15/08 17:16:31:640 -- (1420) Sync Corpus.db pass 7 (1311)
09/15/08 17:16:31:640 -- (1420) Sync Corpus.db pass 8 (1311)
09/15/08 17:16:31:640 -- (1420) End Sync Corpus.db (1311)
09/15/08 17:16:32:078 -- Bayesian Thread is not running - starting...
09/15/08 17:16:32:078 -- (3248) BayesianThread starting
09/15/08 17:16:32:078 -- (3248) TBayesianThread - Begin LoadFromFile for corpus.db (db.dat)
09/15/08 17:16:32:093 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - copied db.dat -> IndE90.tmp
09/15/08 17:16:32:109 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - copied db.dat.prb -> IndE91.tmp
09/15/08 17:16:32:218 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - setting Buffer size to 15134431
09/15/08 17:16:32:218 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - Reading Buffer in mem
09/15/08 17:16:32:234 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - loaded files in memory - IndE90.tmp
09/15/08 17:16:32:250 -- (3248) TBayesianThread - LoadFromFile for Corpus.db - loaded files in memory - IndE91.tmp
09/15/08 17:16:33:031 -- (3248) TBayesianThread - End LoadFromFile for corpus.db (db.dat) (812)
09/15/08 17:16:56:578 -- (3328) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:16:56:578 -- (3328) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:16:56:578 -- (2656) Blacklist cache - starting cleanup
09/15/08 17:16:56:687 -- (2656) IPcache Limbo - removed 1 entries during cleanup
09/15/08 17:16:56:796 -- (2656) Hash cache - removed 1 entries during cleanup
09/15/08 17:17:56:578 -- (1888) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:17:56:578 -- (1888) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:17:56:578 -- (2156) Blacklist cache - starting cleanup
09/15/08 17:17:56:796 -- (2156) Hash cache - removed 9 entries during cleanup
09/15/08 17:18:56:578 -- (2376) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:18:56:578 -- (2376) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:18:56:578 -- (2820) Blacklist cache - starting cleanup
09/15/08 17:18:56:687 -- (2820) IPcache Limbo - removed 1 entries during cleanup
09/15/08 17:18:56:796 -- (2820) Hash cache - removed 7 entries during cleanup
09/15/08 17:19:56:578 -- (3396) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:19:56:578 -- (3396) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:19:56:578 -- (3080) Blacklist cache - starting cleanup
09/15/08 17:19:56:687 -- (3080) IPcache Limbo - removed 2 entries during cleanup
09/15/08 17:19:56:796 -- (3080) Hash cache - removed 9 entries during cleanup
09/15/08 17:20:56:578 -- (3428) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:20:56:578 -- (3428) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:20:56:578 -- (3032) Blacklist cache - starting cleanup
09/15/08 17:20:56:640 -- (2508) Saved GreyListAllowed.txt
09/15/08 17:20:56:640 -- (3524) Starting to process quarantine directory...
09/15/08 17:20:56:671 -- (232) SpamFilterForm - Begin loading corpus.db
09/15/08 17:20:56:687 -- (3032) IPcache Limbo - removed 1 entries during cleanup
09/15/08 17:20:56:796 -- (232) TSpamFilterForm - Begin LoadFromFile for corpus.db (db.dat)
09/15/08 17:20:56:812 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - copied db.dat -> IndE92.tmp
09/15/08 17:20:56:843 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - copied db.dat.prb -> IndE93.tmp
09/15/08 17:20:56:953 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - setting Buffer size to 15134431
09/15/08 17:20:56:953 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - Reading Buffer in mem
09/15/08 17:20:56:968 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - loaded files in memory - IndE92.tmp
09/15/08 17:20:56:984 -- (232) TSpamFilterForm - LoadFromFile for Corpus.db - loaded files in memory - IndE93.tmp
09/15/08 17:20:58:109 -- (232) TSpamFilterForm - End LoadFromFile for corpus.db (db.dat) (1155)
09/15/08 17:20:58:109 -- (232) SpamFilterForm - End loading corpus.db
09/15/08 17:21:56:578 -- (2936) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:21:56:578 -- (2936) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:21:56:578 -- (1424) Blacklist cache - starting cleanup
09/15/08 17:21:56:796 -- (1424) Hash cache - removed 2 entries during cleanup
09/15/08 17:22:56:578 -- (3236) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:22:56:578 -- (3236) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:22:56:578 -- (644) Blacklist cache - starting cleanup
09/15/08 17:22:56:687 -- (644) IPcache Limbo - removed 4 entries during cleanup
09/15/08 17:22:56:796 -- (644) Hash cache - removed 1 entries during cleanup
09/15/08 17:23:56:578 -- (3160) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:23:56:578 -- (3160) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:23:56:578 -- (164) Blacklist cache - starting cleanup
09/15/08 17:23:56:687 -- (164) IPcache Limbo - removed 2 entries during cleanup
09/15/08 17:23:56:796 -- (164) Hash cache - removed 5 entries during cleanup
09/15/08 17:24:56:578 -- (3944) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:24:56:578 -- (3944) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:24:56:578 -- (3616) Blacklist cache - starting cleanup
09/15/08 17:24:56:796 -- (3616) Hash cache - removed 2 entries during cleanup
09/15/08 17:25:56:578 -- (3308) Running TTerminateIdleThreads - SFTC=0 - SFFC=0
09/15/08 17:25:56:578 -- (3308) Running TTerminateIdleThreads SSL - SFTC=0 - SFFC=0
09/15/08 17:25:56:578 -- (2280) Starting to process queue directory...
09/15/08 17:25:56:578 -- (2364) Blacklist cache - starting cleanup
09/15/08 17:25:56:640 -- (3972) Saved GreyListAllowed.txt
09/15/08 17:25:56:640 -- (2308) Starting to process quarantine directory...
09/15/08 17:25:56:796 -- (2364) Hash cache - removed 2 entries during cleanup
09/15/08 17:26:33:078 -- (3248) Begin Sync Corpus.db
09/15/08 17:26:33:078 -- (3248) Sync Corpus.db pass 0a
09/15/08 17:26:33:078 -- (3248) Sync Corpus.db pass 0b
09/15/08 17:26:33:078 -- (3248) Sync Corpus.db pass 0c
09/15/08 17:26:33:078 -- (3248) Sync Corpus.db - 858110 - 0
09/15/08 17:26:33:078 -- (3248) Sync Corpus.db pass 1 (0)
09/15/08 17:26:33:734 -- (3248) Sync Corpus.db pass 2 (656)
09/15/08 17:26:33:734 -- (3248) Sync Corpus.db pass 3 (656)
09/15/08 17:26:33:734 -- (3248) Sync Corpus.db pass 4 (656)
09/15/08 17:26:33:968 -- (3248) Sync Corpus.db pass 5 (890)
09/15/08 17:26:34:265 -- (3248) Sync Corpus.db pass 6 (1187)
09/15/08 17:26:34:265 -- (3248) Sync Corpus.db pass 7 (1187)
09/15/08 17:26:34:265 -- (3248) Sync Corpus.db pass 8 (1187)
09/15/08 17:26:34:265 -- (3248) End Sync Corpus.db (1187)
|
Replies:
Posted By: LogSat
Date Posted: 15 September 2008 at 9:39pm
Can you please zip and email us SpamFilter's entire activity log for a day this happened, so we can scan it for problems? If the file is over 5MB in size, please upload it to our FTP site. I'll be sending you a PM with the ftp login information.
-------------
Roberto Franceschetti
http://www.logsat.com" rel="nofollow - LogSat Software
http://www.logsat.com/sfi-spam-filter.asp" rel="nofollow - Spam Filter ISP
|
Posted By: Helpdesk
Date Posted: 15 September 2008 at 10:04pm
|
I've emailed the zipped logs
|
Posted By: LogSat
Date Posted: 16 September 2008 at 4:25pm
Your logs contain a few instances of these errors:
09/15/08 11:05:52:078 -- Exception occurred during PerformPostStartupTasks: Could not bind socket. Address and port are already in use.
09/15/08 11:09:22:468 -- Exception occurred during PerformPostStartupTasks: Could not bind socket. Address and port are already in use.
The issue is exactly the same we described in your earlier post at http://www.logsat.com/spamfilter/forums/forum_posts.asp?TID=6533 - http://www.logsat.com/spamfilter/forums/forum_posts.asp?TID=6533 . Multiple instances of SpamFilter (probably both the service and the standalone application) are being executed at the same time, thus rendering them unable to process emails. The cause and solution is described in the above post.
-------------
Roberto Franceschetti
http://www.logsat.com" rel="nofollow - LogSat Software
http://www.logsat.com/sfi-spam-filter.asp" rel="nofollow - Spam Filter ISP
|
|