I thought I'd take a more detailed look at the log of the last occurrence, but nothing was obvious. It's as though ASSP at some random time decided to not to answer SMTP requests. All ASSP does is its hourly housecleaning. Stopping ASSP with its web interface fixes the problem, because I have a watchdog up to restart ASSP if its process is not present.
I have no way of knowing if this is the result of a DoS attack crashing part of ASSP, or if it's something internal to ASSP. Jan-18-09 13:45:25 [Main_Thread] info: Main_Thread got connection request Jan-18-09 13:45:25 [Worker_2] Worker_2 wakes up Jan-18-09 13:45:25 [Worker_2] Info: Worker_2 got connection from MainThread Jan-18-09 13:45:25 [Worker_2] Connected: 91.124.42.222:1269 -> 66.96.20.5:25 -> 127.0.0.1:125 Jan-18-09 13:45:25 [Main_Thread] Info: Main_Thread freed by idle Worker_2 in 0.007 seconds Jan-18-09 13:45:29 [Worker_4] 82.139.58.20 Connection idle for 180 secs - timeout Jan-18-09 13:45:29 [Worker_4] 82.139.58.20 [SMTP Error] 451 Connection timeout, try later Jan-18-09 13:45:29 [Worker_4] Disconnected: 82.139.58.20 Jan-18-09 13:45:29 id-04327-17713 [Worker_2] 91.124.42.222 <impactsoa...@club24.co.uk > to: abalone.dispo...@domain.com recipient delayed: > abalone.dispo...@alternate.com Jan-18-09 13:45:29 id-04327-17713 [Worker_2] 91.124.42.222 <impactsoa...@club24.co.uk > to: abalone.dispo...@domain.com [SMTP Error] 451 4.7.1 Please try again later Jan-18-09 13:45:29 [Worker_2] Disconnected: 91.124.42.222 Jan-18-09 13:45:29 [Worker_2] Worker_2 will sleep now Jan-18-09 13:45:32 [Worker_5] 95.78.203.139 Connection idle for 180 secs - timeout Jan-18-09 13:45:32 [Worker_5] 95.78.203.139 [SMTP Error] 451 Connection timeout, try later Jan-18-09 13:45:32 [Worker_5] Disconnected: 95.78.203.139 Jan-18-09 13:45:32 [Worker_5] Worker_5 will sleep now Jan-18-09 13:45:38 [Worker_1] 82.139.58.20 Connection idle for 180 secs - timeout Jan-18-09 13:45:38 [Worker_1] 82.139.58.20 [SMTP Error] 451 Connection timeout, try later Jan-18-09 13:45:38 [Worker_1] Disconnected: 82.139.58.20 Jan-18-09 13:45:38 [Worker_1] Worker_1 will sleep now Jan-18-09 13:45:47 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 13:47:37 [Worker_3] 194.186.53.213 Connection idle for 180 secs - timeout Jan-18-09 13:47:37 [Worker_3] 194.186.53.213 [SMTP Error] 451 Connection timeout, try later Jan-18-09 13:47:37 [Worker_3] Disconnected: 194.186.53.213 Jan-18-09 13:47:37 [Worker_3] Worker_3 will sleep now Jan-18-09 13:47:37 [Worker_4] 194.186.53.213 Connection idle for 180 secs - timeout Jan-18-09 13:47:37 [Worker_4] 194.186.53.213 [SMTP Error] 451 Connection timeout, try later Jan-18-09 13:47:37 [Worker_4] Disconnected: 194.186.53.213 Jan-18-09 13:47:37 [Worker_4] Worker_4 will sleep now Jan-18-09 13:50:49 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 13:55:27 [Worker_10000] Saving penalty records Jan-18-09 13:55:27 [Worker_10000] Info: 465 records of PBBlack saved Jan-18-09 13:55:27 [Worker_10000] Info: 199 records of PBWhite saved Jan-18-09 13:55:27 [Worker_10000] Info: 0 records of PBTrap saved Jan-18-09 13:55:27 [Worker_10000] Saving cache records Jan-18-09 13:55:27 [Worker_10000] Info: 25 records of RBLCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 13 records of URIBLCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 296 records of SPFCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 67 records of PTRCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 32 records of MXACache saved Jan-18-09 13:55:27 [Worker_10000] Info: 290 records of SBCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 0 records of RWLCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 10 records of DKIMCache saved Jan-18-09 13:55:27 [Worker_10000] Info: 0 records of BATVTag saved Jan-18-09 13:55:27 [Worker_10000] Info: 0 records of BackDNS saved Jan-18-09 13:55:27 [Worker_10000] Cleaning penalty records... Jan-18-09 13:55:27 [Worker_10000] PenaltyBox: cleaning BlackBox finished; IP's before=465, deleted=68 Jan-18-09 13:55:27 [Worker_10000] PenaltyBox: cleaning WhiteBox finished; IP's before=199, deleted=2 Jan-18-09 13:55:27 [Worker_10000] PBTrap: cleaning finished; before=0, deleted=0 Jan-18-09 13:55:52 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 13:56:22 [Worker_10000] Saving whitelist Jan-18-09 13:56:22 [Worker_10000] Info: 1055 records of Whitelist saved Jan-18-09 13:56:22 [Worker_10000] Saving redlist Jan-18-09 13:56:22 [Worker_10000] Info: 25 records of Redlist saved Jan-18-09 13:56:22 [Worker_10000] Cleaning up delaying databases ... Jan-18-09 13:56:22 [Worker_10000] Cleaning delaying database (triplets) finished; keys before=1688, deleted=76 Jan-18-09 13:56:22 [Worker_10000] Cleaning delaying database (safelisted tuplets) finished; keys before=75, deleted=0 Jan-18-09 13:56:22 [Worker_10000] Saving delaying records Jan-18-09 13:56:22 [Worker_10000] Info: 1612 records of Delay saved Jan-18-09 13:56:22 [Worker_10000] Info: 75 records of DelayWhite saved Jan-18-09 13:56:22 [Worker_10000] BATVTag: cleaning cache finished; BATVTag's before=0, deleted=0 Jan-18-09 13:56:22 [Worker_10000] Info: 0 records of BATVTag saved Jan-18-09 14:00:55 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:05:57 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:10:57 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:15:59 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:21:02 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:24:14 [Worker_10000] AdminUpdate: Name Server 208.67.220.220: does not respond or timed out Jan-18-09 14:26:05 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:31:08 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:36:11 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:41:13 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:46:16 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:51:19 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:55:01 [Worker_10000] Updating Griplist via direct HTTP connection Jan-18-09 14:55:20 [Worker_10000] Griplist Update complete Jan-18-09 14:55:21 [Main_Thread] Saving config Jan-18-09 14:55:21 [Main_Thread] Finished saving config Jan-18-09 14:55:21 [Worker_4] Worker_4 wakes up Jan-18-09 14:55:21 [Worker_1] Worker_1 wakes up Jan-18-09 14:55:21 [Worker_3] Worker_3 wakes up Jan-18-09 14:55:21 [Worker_2] Worker_2 wakes up Jan-18-09 14:55:21 [Worker_5] Worker_5 wakes up Jan-18-09 14:55:22 [Worker_5] Worker_5 finished reloading configuration Jan-18-09 14:55:22 [Worker_3] Worker_3 finished reloading configuration Jan-18-09 14:55:22 [Worker_5] Worker_5 will sleep now Jan-18-09 14:55:22 [Worker_2] Worker_2 finished reloading configuration Jan-18-09 14:55:22 [Worker_4] Worker_4 finished reloading configuration Jan-18-09 14:55:22 [Worker_3] Worker_3 will sleep now Jan-18-09 14:55:22 [Worker_1] Worker_1 finished reloading configuration Jan-18-09 14:55:22 [Worker_2] Worker_2 will sleep now Jan-18-09 14:55:22 [Worker_4] Worker_4 will sleep now Jan-18-09 14:55:22 [Worker_1] Worker_1 will sleep now Jan-18-09 14:55:25 [Worker_10001] Info: RebuildSpamdb Scheduler stopped Jan-18-09 14:55:25 [Worker_10001] Worker_10001 finished reloading configuration Jan-18-09 14:55:25 [Worker_10001] Info: starting RebuildSpamdb Scheduler with '35 2 * * *' Jan-18-09 14:55:25 [Worker_10000] Worker_10000 finished reloading configuration Jan-18-09 14:56:21 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 14:56:26 [Worker_10000] Saving whitelist Jan-18-09 14:56:26 [Worker_10000] Info: 1055 records of Whitelist saved Jan-18-09 14:56:26 [Worker_10000] Saving redlist Jan-18-09 14:56:26 [Worker_10000] Info: 25 records of Redlist saved Jan-18-09 14:56:26 [Worker_10000] Cleaning up delaying databases ... Jan-18-09 14:56:26 [Worker_10000] Cleaning delaying database (triplets) finished; keys before=1612, deleted=125 Jan-18-09 14:56:26 [Worker_10000] Cleaning delaying database (safelisted tuplets) finished; keys before=75, deleted=0 Jan-18-09 14:56:26 [Worker_10000] Saving delaying records Jan-18-09 14:56:26 [Worker_10000] Info: 1487 records of Delay saved Jan-18-09 14:56:26 [Worker_10000] Info: 75 records of DelayWhite saved Jan-18-09 14:56:26 [Worker_10000] BATVTag: cleaning cache finished; BATVTag's before=0, deleted=0 Jan-18-09 14:56:26 [Worker_10000] Info: 0 records of BATVTag saved Jan-18-09 15:01:23 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:06:26 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:11:29 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:16:31 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:21:34 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:26:36 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:31:39 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:36:42 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:41:45 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:46:48 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:51:51 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 15:54:52 [Worker_10000] Cleaning cache records... Jan-18-09 15:54:52 [Worker_10000] DNSBLCache: cleaning cache finished; IP's before=25, deleted=4 Jan-18-09 15:54:52 [Worker_10000] URIBLCache: cleaning cache finished; Domains before=13, deleted=0 Jan-18-09 15:54:52 [Worker_10000] PTRCache: cleaning cache finished; IP's before=67, deleted=8 Jan-18-09 15:54:52 [Worker_10000] MXACache: cleaning cache finished; IP's before=32, deleted=5 Jan-18-09 15:54:52 [Worker_10000] SPFCache: cleaning cache finished; IP's before=296, deleted=10 Jan-18-09 15:54:52 [Worker_10000] DKIMCache: cleaning cache finished; domains's before=10, deleted=0 Jan-18-09 15:54:52 [Worker_10000] SenderBaseCache: cleaning cache finished; IP's before=290, deleted=10 Jan-18-09 15:54:52 [Worker_10000] BackDNS: cleaning cache finished; IP's before=0, deleted=0 Jan-18-09 15:54:52 [Worker_10000] Info: 0 records of BackDNS saved Jan-18-09 15:55:07 [Worker_10000] Uploading stats via direct connection Jan-18-09 15:56:28 [Worker_10000] Saving whitelist Jan-18-09 15:56:28 [Worker_10000] Info: 1055 records of Whitelist saved Jan-18-09 15:56:28 [Worker_10000] Saving redlist Jan-18-09 15:56:28 [Worker_10000] Info: 25 records of Redlist saved Jan-18-09 15:56:28 [Worker_10000] Cleaning up delaying databases ... Jan-18-09 15:56:28 [Worker_10000] Cleaning delaying database (triplets) finished; keys before=1487, deleted=46 Jan-18-09 15:56:28 [Worker_10000] Cleaning delaying database (safelisted tuplets) finished; keys before=75, deleted=0 Jan-18-09 15:56:28 [Worker_10000] Saving delaying records Jan-18-09 15:56:28 [Worker_10000] Info: 1441 records of Delay saved Jan-18-09 15:56:28 [Worker_10000] Info: 75 records of DelayWhite saved Jan-18-09 15:56:28 [Worker_10000] BATVTag: cleaning cache finished; BATVTag's before=0, deleted=0 Jan-18-09 15:56:28 [Worker_10000] Info: 0 records of BATVTag saved Jan-18-09 15:56:54 [Worker_10000] Info: saving Stats in file asspstats.sav Jan-18-09 16:01:57 [Worker_10000] Info: saving Stats in file asspstats.sav : : : ------------------------------------------------------------------------------ This SF.net email is sponsored by: SourcForge Community SourceForge wants to tell your story. http://p.sf.net/sfu/sf-spreadtheword _______________________________________________ Assp-test mailing list Assp-test@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/assp-test
