Spam Filter ISP Support Forum

  New Posts New Posts RSS Feed - Not accepting incoming connections
  FAQ FAQ  Forum Search   Register Register  Login Login

Not accepting incoming connections

 Post Reply Post Reply
Author
Helpdesk View Drop Down
Newbie
Newbie


Joined: 12 December 2006
Location: Australia
Status: Offline
Points: 2
Post Options Post Options   Thanks (0) Thanks(0)   Quote Helpdesk Quote  Post ReplyReply Direct Link To This Post Topic: Not accepting incoming connections
    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)


Back to Top
LogSat View Drop Down
Admin Group
Admin Group
Avatar

Joined: 25 January 2005
Location: United States
Status: Offline
Points: 4104
Post Options Post Options   Thanks (0) Thanks(0)   Quote LogSat Quote  Post ReplyReply Direct Link To This Post 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

LogSat Software

Spam Filter ISP
Back to Top
Helpdesk View Drop Down
Newbie
Newbie


Joined: 12 December 2006
Location: Australia
Status: Offline
Points: 2
Post Options Post Options   Thanks (0) Thanks(0)   Quote Helpdesk Quote  Post ReplyReply Direct Link To This Post Posted: 15 September 2008 at 10:04pm
I've emailed the zipped logs
Back to Top
LogSat View Drop Down
Admin Group
Admin Group
Avatar

Joined: 25 January 2005
Location: United States
Status: Offline
Points: 4104
Post Options Post Options   Thanks (0) Thanks(0)   Quote LogSat Quote  Post ReplyReply Direct Link To This Post 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. 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

LogSat Software

Spam Filter ISP
Back to Top
 Post Reply Post Reply
  Share Topic   

Forum Jump Forum Permissions View Drop Down



This page was generated in 0.135 seconds.