And a similar thing just happened again with 21280. ASSP gets to the point where it can't open any more files, griplist can't be opened, and it goes into a shutdown process.
On Thu, Oct 7, 2021 at 2:50 PM K Post <nntp.p...@gmail.com> wrote: > Here's excerpts from my log. Odd stuff and concerning. > > > ASSP had been running for 24+ hours on 21279 without issue. Saw a bunch > of the got an unexpected TLSv1_2 handshake Client-Helo-Frame warnings > during that time, which ASSP seems to handle much better than with the > previous version. The transactions I saw are not dissimilar this this > sequence which happened just before things headed south: > > > Oct-07-21 11:52:03 Connected: session:AC2E2C40 212.102.59.230:53720 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:03 212.102.59.230 *warning: got an unexpected TLSv1_2 > handshake Client-Helo-Frame of version (3.3)* from IP '212.102.59.230' at > local IP '(assp internal ip)' and Port '25' - the connection will be closed > Oct-07-21 11:52:03 212.102.59.230 Message-Score: added 25 (etValencePB) > for EarlyTalker, total score for this message is now 25 > Oct-07-21 11:52:03 212.102.59.230 info: PB-IP-Score for '212.102.59.0' is > 200, added 25 in this session > Oct-07-21 11:52:03 212.102.59.230 disconnected: session:AC2E2C40 > 212.102.59.230 - processing time 0 seconds > Oct-07-21 11:52:03 Connected: session:71BAB808 212.102.59.230:53722 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:03 *Error: Worker_5 accept_SSL to client 212.102.59.230 > denied - the client failed before on SSL/TLS* > Oct-07-21 11:52:03 Error: Worker_5 accept_SSL to client 212.102.59.230 > denied - the client failed before on SSL/TLS > Oct-07-21 11:52:03 212.102.59.230 disconnected: session:71BAB808 > 212.102.59.230 - processing time 0 seconds > Oct-07-21 11:52:03 Connected: session:4DB26EA0 212.102.59.230:53726 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:04 Connected: session:3050E3F0 212.102.59.230:53728 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:04 212.102.59.230 disconnected: session:4DB26EA0 > 212.102.59.230 - processing time 1 seconds > Oct-07-21 11:52:05 Error: Worker_1 accept_SSL to client 212.102.59.230 > denied - the client failed before on SSL/TLS > Oct-07-21 11:52:05 212.102.59.230 disconnected: session:3050E3F0 > 212.102.59.230 - processing time 1 seconds > Oct-07-21 11:52:05 Error: Worker_1 accept_SSL to client 212.102.59.230 > denied - the client failed before on SSL/TLS > Oct-07-21 11:52:07 Connected: session:71BAB808 52.207.41.187:45398 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:08 Connected: session:52815DB8 52.207.41.187:45442 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:08 52.207.41.187 disconnected: session:71BAB808 > 52.207.41.187 - processing time 1 seconds > Oct-07-21 11:52:08 52.207.41.187 info: got STARTTLS request from > 52.207.41.187 > Oct-07-21 11:52:09 52.207.41.187 disconnected: session:52815DB8 > 52.207.41.187 - processing time 1 seconds > Oct-07-21 11:52:10 Connected: session:A91752E8 172.241.24.83:58278 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:10 172.241.24.83 disconnected: session:A91752E8 > 172.241.24.83 - processing time 0 seconds > Oct-07-21 11:52:10 Connected: session:8B034AF0 172.241.24.83:58392 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:11 172.241.24.83 info: got STARTTLS request from > 172.241.24.83 > Oct-07-21 11:52:12 172.241.24.83 disconnected: session:8B034AF0 > 172.241.24.83 - processing time 2 seconds > Oct-07-21 11:52:25 Connected: session:52C3B6D0 23.239.7.4:49106 > (assp > internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:25 23.239.7.4 disconnected: session:52C3B6D0 23.239.7.4 - > processing time 0 seconds > Oct-07-21 11:52:25 Connected: session:874B2E68 23.239.7.4:49688 > (assp > internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:25 23.239.7.4 info: got STARTTLS request from 23.239.7.4 > Oct-07-21 11:52:26 Connected: session:886EF9A0 192.82.209.81:9208 > (assp > internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:52:26 192.82.209.81 info: got STARTTLS request from > 192.82.209.81 > Oct-07-21 11:52:26 23.239.7.4 disconnected: session:874B2E68 23.239.7.4 - > processing time 1 seconds > > > Then, a bunch of email is received normally, then this: > > > Oct-07-21 11:54:01 179.26.113.249 info: injected STARTTLS request to (smtp > internal ip) *<-- injected STARTTTLS request? Haven't noticed that > before* > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 5 (fiphValencePB) for Suspicious HELO - contains IP: ' > r179-26-113-249.dialup.adsl.anteldata.net.uy', total score for this > message is now 5 > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > [scoring] (Suspicious HELO - contains IP: ' > r179-26-113-249.dialup.adsl.anteldata.net.uy') > Oct-07-21 11:54:02 msg25642-16992 [SpoofedSender] 179.26.113.249 < > unkn...@ourcharity.org> [scoring] (No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom') > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 25 (slValencePB) for No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom', total score for this message is > now 30 > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 35 (flValencePB) for No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom', total score for this message is > now 65 > Oct-07-21 11:54:02 msg25642-16992 [InvalidLocalSender] 179.26.113.249 < > unkn...@ourcharity.org> [spam found] Unknown Sender in Local Domain > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > [SMTP Error] 554 5.7.1 Mail rejected - Unknown Sender in Local Domain > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > info: PB-IP-Score for '179.26.113.0' is 65, added 65 in this session > Oct-07-21 11:54:02 msg25642-16992 179.26.113.249 <unkn...@ourcharity.org> > disconnected: session:7333DB30 179.26.113.249 - processing time 2 seconds > Oct-07-21 11:54:06 *Error: Worker_1 accept_SSL to client 194.61.24.155 > failed IO::Socket::SSL=GLOB(0xa6cf1bd8) (timeout: 6 s) : SSL accept attempt > failed* > > > (mail received, then this) > > Oct-07-21 11:54:58 IPNumTries: cleaning cache finished: IP's before=4, > deleted=3 > Oct-07-21 11:54:58 SMTPdomainIP: cleaning cache finished: domain's > before=4, deleted=0 > Oct-07-21 11:54:58 SSLfailedCache: cleaning cache finished: IP's > before=38, deleted=0 > Oct-07-21 11:54:58 SubjectFrequency: cleaning cache finished: subjects > before=3, deleted=3 > Oct-07-21 11:54:58 AUTHErrors: recalculated 1 IP counters > > > (more mail received just fine) > > > Oct-07-21 11:54:58 Saving config > Oct-07-21 11:54:58 Info: no configuration changes detected - nothing to > save - file d:/assp/assp.cfg is unchanged > Oct-07-21 11:54:58 Info: list Griplist is using 'BerkeleyDB' version 6.2 - > cachesize is 648.0kB > Oct-07-21 11:54:59 Info: *Griplist has 0 records in BerkeleyDB* *<-- > huh, zero?* > > > then it starts getting bad > > > Oct-07-21 11:55:15 Connected: session:EC48FA8 179.26.113.249:22784 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:55:15 **** (smtp internal ip):25 didn't work, trying > others... - Too many open files* > Oct-07-21 11:55:15 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:55:15 Error: couldn't create server socket to (smtp internal > ip):25 -- aborting connection > Oct-07-21 11:55:15 [SMTP Status] 421 <assp.ourcharity.org> service > temporarily unavailable, closing transmission > *Oct-07-21 11:55:16 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x37a2d950) (timeout: 2 s) : Too many open files* > *Oct-07-21 11:55:16 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x5bd06e10) (timeout: 2 s) : Too many open files* > *Oct-07-21 11:55:16 *** (smtp internal ip):25 didn't work, trying > others... - Too many open files* > *Oct-07-21 11:55:16 Error: couldn't create server socket to (smtp internal > ip):25 -- aborting connection* > *Oct-07-21 11:55:16 [SMTP Status] 421 <assp.ourcharity.org > <http://assp.ourcharity.org>> service temporarily unavailable, closing > transmission* > Oct-07-21 11:55:16 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x8b994718) (timeout: 2 s) : Too many open files > Oct-07-21 11:55:16 *** (smtp internal ip):25 didn't work, trying others... > - Too many open files > Oct-07-21 11:55:16 Error: couldn't create server socket to (smtp internal > ip):25 -- aborting connection > Oct-07-21 11:55:16 [SMTP Status] 421 <assp.ourcharity.org> service > temporarily unavailable, closing transmission > Oct-07-21 11:55:17 *179.26.113.249 info: injected STARTTLS request to > (smtp internal ip)* > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 5 (fiphValencePB) for Suspicious HELO - contains IP: ' > r179-26-113-249.dialup.adsl.anteldata.net.uy', total score for this > message is now 5 > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > [scoring] (Suspicious HELO - contains IP: ' > r179-26-113-249.dialup.adsl.anteldata.net.uy') > Oct-07-21 11:55:17 msg25717-06335 [SpoofedSender] 179.26.113.249 < > unkn...@ourcharity.org> [scoring] (No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom') > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 25 (slValencePB) for No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom', total score for this message is > now 30 > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > Message-Score: added 35 (flValencePB) for No Spoofing Allowed ' > unkn...@ourcharity.org' in 'mailfrom', total score for this message is > now 65 > Oct-07-21 11:55:17 msg25717-06335 [InvalidLocalSender] 179.26.113.249 < > unkn...@ourcharity.org> [spam found] Unknown Sender in Local Domain > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > [SMTP Error] 554 5.7.1 Mail rejected - Unknown Sender in Local Domain > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > info: PB-IP-Score for '179.26.113.0' is 260, added 65 in this session > Oct-07-21 11:55:17 msg25717-06335 179.26.113.249 <unkn...@ourcharity.org> > disconnected: session:EC48FA8 179.26.113.249 - processing time 2 seconds > > > more mail received, seemingly normally, then it gets really really bad, > > Oct-07-21 11:55:22 Connected: session:8B994718 23.108.190.195:54726 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:55:22 23.108.190.195 info: got STARTTLS request from > 23.108.190.195 > > > *Oct-07-21 11:55:23 BerkeleyDB-ENV-ERROR Griplist: Cannot open file > d:/assp/tmpDB/Griplist/BDB-error.txt: Too many open files at (eval 21432) > line 1 thread 6. - BDB:BDB0062 Successful return: 0* > Oct-07-21 11:55:23 *Info: shutdown*: *Worker_5: BerkeleyDB-ENV-ERROR > Griplist: Cannot open file d:/assp/tmpDB/Griplist/BDB-error.txt: Too many > open files* > at (eval 21432) line 1 thread 6. > - BDB:BDB0062 Successful return: 0 *<-- I didn't initiate a shutdown* > Oct-07-21 11:55:23 Worker_5 finished > Oct-07-21 11:55:23 23.108.190.195 disconnected: session:8B994718 > 23.108.190.195 - processing time 1 seconds > Oct-07-21 11:55:27 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:55:27 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 11:55:30 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:55:30 BerkeleyDB-ENV-ERROR Griplist: Cannot open file > d:/assp/tmpDB/Griplist/BDB-error.txt: Too many open files > at (eval 34125) line 1 thread 8. > - BDB:BDB0062 Successful return: 0 > Oct-07-21 11:55:30 Info: shutdown: Worker_7: BerkeleyDB-ENV-ERROR > Griplist: Cannot open file d:/assp/tmpDB/Griplist/BDB-error.txt: Too many > open files > at (eval 34125) line 1 thread 8. > - BDB:BDB0062 Successful return: 0 > Oct-07-21 11:55:30 Worker_7 finished > Oct-07-21 11:55:36 Info: Name Server int.ip.1.51: ResponseTime = 1 ms for > sourceforge.net *<-- all 3 dns servers return sourceforge.net > <http://sourceforge.net>* > Oct-07-21 11:55:36 Info: Name Server int.ip.1.53: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:55:36 Info: Name Server int.ip.1.52: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:55:36 Warning: Name Server int.ip.1.51: does not respond or > timed out *<-- but then assp thinks all 3 are down?* > Oct-07-21 11:55:36 Warning: Name Server int.ip.1.53: does not respond or > timed out > Oct-07-21 11:55:36 Warning: Name Server int.ip.1.52: does not respond or > timed out > Oct-07-21 11:55:36 *ERROR: !!!! no answering DNS-SERVER found !!!!* > Oct-07-21 11:55:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:55:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > > > > > > > > > > > *Oct-07-21 11:55:49 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x87770380) (timeout: 2 s) : Too many open > filesOct-07-21 11:55:53 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x881a0c40) (timeout: 2 s) : Too many open > filesOct-07-21 11:55:59 Warning: can't open file 'd:/assp/scheduleHistory' > to save hash (in saveHashToFile) - Too many open filesOct-07-21 11:56:04 > Error: Worker_1 accept to client failed IO::Socket::INET=GLOB(0x5eccd228) > (timeout: 2 s) : Too many open filesOct-07-21 11:56:07 Error: Worker_1 > accept to client failed IO::Socket::INET=GLOB(0x87770380) (timeout: 2 s) : > Too many open filesOct-07-21 11:56:07 Error: Worker_1 accept to client > failed IO::Socket::INET=GLOB(0x881a0c40) (timeout: 2 s) : Too many open > filesOct-07-21 11:56:11 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x5eccd228) (timeout: 2 s) : Too many open > filesOct-07-21 11:56:11 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x87770380) (timeout: 2 s) : Too many open > filesOct-07-21 11:56:14 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x881a0c40) (timeout: 2 s) : Too many open > filesOct-07-21 11:56:14 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x5eccd228) (timeout: 2 s) : Too many open > filesOct-07-21 11:56:14 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x87770380) (timeout: 2 s) : Too many open files* > Oct-07-21 11:56:30 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 11:56:33 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:33 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:33 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:33 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:33 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:36 Info: Name Server int.ip.1.51: ResponseTime = 1 ms for > sourceforge.net > Oct-07-21 11:56:36 Info: Name Server int.ip.1.53: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:56:36 Info: Name Server int.ip.1.52: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:56:36 Warning: Name Server int.ip.1.51: does not respond or > timed out > Oct-07-21 11:56:36 Warning: Name Server int.ip.1.53: does not respond or > timed out > Oct-07-21 11:56:36 Warning: Name Server int.ip.1.52: does not respond or > timed out > Oct-07-21 11:56:36 ERROR: !!!! no answering DNS-SERVER found !!!! > Oct-07-21 11:56:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000425.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000425.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:40 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:56:40 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x881a0c40) (timeout: 2 s) : Too many open files > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000425.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n1000000000425.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:56:43 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100001070.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 Error: unable to send file > d:/assp/messages/resendmail/n100001070.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:56:43 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:57:02 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 11:57:11 Error: Worker_1 accept to client failed > IO::Socket::INET=GLOB(0x881a0c40) (timeout: 2 s) : Too many open files > Oct-07-21 11:57:11 Info: try to renewed listening on port 0.0.0.0:25 - > after too many errors > Oct-07-21 11:57:11 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:57:11 Error: unable to close Socket > IO::Socket::INET=GLOB(0xd872058) - - Bad file descriptor > Oct-07-21 11:57:11 Info: Main_Thread is waiting until Workers finished > current SMTP-connections or 210 seconds - to renew Socket-Listener > Oct-07-21 11:57:14 ClamAV Down > Oct-07-21 11:57:14 Info: Main_Thread detected - all Workers are finished > current SMTP-connections > Oct-07-21 11:57:14 Info: renewed listening for SMTP connections on port > 0.0.0.0:25 - after too many errors > Oct-07-21 11:57:14 *** (smtp internal ip):25 didn't work, trying others... > - Too many open files > Oct-07-21 11:57:14 Error: couldn't create server socket to (smtp internal > ip):25 -- aborting connection > Oct-07-21 11:57:14 [SMTP Status] 421 <assp.ourcharity.org> service > temporarily unavailable, closing transmission > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n100000000010.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n1000000000276.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n1000000000312.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n1000000000425.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n100000996.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:14 Info: successfully sent file > d:/assp/messages/resendmail/n100001070.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:15 Connected: session:5C871788 23.108.190.195:60266 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:57:15 23.108.190.195 disconnected: session:5C871788 > 23.108.190.195 - processing time 0 seconds > Oct-07-21 11:57:15 Connected: session:881A0C40 23.108.190.195:60776 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:57:15 23.108.190.195 info: got STARTTLS request from > 23.108.190.195 > Oct-07-21 11:57:16 23.108.190.195 disconnected: session:881A0C40 > 23.108.190.195 - processing time 1 seconds > Oct-07-21 11:57:25 Connected: session:8B01C148 172.241.24.83:38534 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:57:25 172.241.24.83 disconnected: session:8B01C148 > 172.241.24.83 - processing time 0 seconds > Oct-07-21 11:57:25 Connected: session:8A2AF4D0 172.241.24.83:38780 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:57:26 172.241.24.83 info: got STARTTLS request from > 172.241.24.83 > Oct-07-21 11:57:27 172.241.24.83 disconnected: session:8A2AF4D0 > 172.241.24.83 - processing time 2 seconds > Oct-07-21 11:57:30 Connected: session:5D517940 149.28.50.248:51428 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:57:30 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:57:30 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x7e56c288) (timeout: 2 s) : Too many open files > Oct-07-21 11:57:30 149.28.50.248 disconnected: session:5D517940 > 149.28.50.248 - processing time 0 seconds > Oct-07-21 11:57:33 Info: successfully sent file > d:/assp/messages/resendmail/n200000799.txt to (smtp internal ip):25 > (smtpDestination) > Oct-07-21 11:57:36 Info: Name Server int.ip.1.51: ResponseTime = 8 ms for > sourceforge.net > Oct-07-21 11:57:36 Info: Name Server int.ip.1.53: ResponseTime = 13 ms for > sourceforge.net > Oct-07-21 11:57:36 Info: Name Server int.ip.1.52: ResponseTime = 1 ms for > sourceforge.net > Oct-07-21 11:57:36 Info: switched (DNS) nameserver order from > (1.)int.ip.1.51 , (2.)int.ip.1.53 to (1.)int.ip.1.52 , (2.)int.ip.1.51 > Oct-07-21 11:57:59 Connected: session:7B7372A0 40.107.100.59:54112 > > (assp internal ip):25 > (smtp internal ip):25 > Oct-07-21 11:58:00 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x88714f68) (timeout: 2 s) : Too many open files > Oct-07-21 11:58:00 40.107.100.59 info: got STARTTLS request from > 40.107.100.59 > > (several email messages arrived in these 20 seconds and seem to have been > delivered) > > Oct-07-21 11:58:21 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x74ddbf18) (timeout: 2 s) : Too many open files > > ( more mail comes in okay) > > Oct-07-21 11:58:36 Info: Name Server int.ip.1.51: ResponseTime = 1 ms for > sourceforge.net > Oct-07-21 11:58:36 Info: Name Server int.ip.1.53: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:58:36 Info: Name Server int.ip.1.52: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:58:36 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:58:36 Warning: Name Server int.ip.1.51: does not respond or > timed out > Oct-07-21 11:58:36 Warning: Name Server int.ip.1.53: does not respond or > timed out > Oct-07-21 11:58:36 Warning: Name Server int.ip.1.52: does not respond or > timed out > Oct-07-21 11:58:36 Info: switched (DNS) nameserver order from > (1.)int.ip.1.52 , (2.)int.ip.1.51 to (1.)int.ip.1.51 , (2.)int.ip.1.53 > Oct-07-21 11:58:36 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:58:36 ERROR: !!!! no answering DNS-SERVER found !!!! > Oct-07-21 11:58:36 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:58:36 Error: Worker_6 accept to client failed > IO::Socket::INET=GLOB(0x34bd9330) (timeout: 2 s) : Too many open files > > Oct-07-21 11:58:37 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 11:58:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:58:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:58:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:58:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > > ( more mail comes in ) > > Oct-07-21 11:59:08 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:59:08 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x88714f68) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:08 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x20f914c8) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:09 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x850d2ee8) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:09 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x886fd930) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:09 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 11:59:11 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:11 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:11 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n200000877.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n200000877.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:11 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:11 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:14 Error: Worker_6 accept to client failed > IO::Socket::INET=GLOB(0x34bd9330) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:15 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x20f914c8) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:15 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x850d2ee8) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:35 Error: Worker_2 accept to client failed > IO::Socket::INET=GLOB(0x886fd930) (timeout: 2 s) : Too many open files > Oct-07-21 11:59:36 Info: Name Server int.ip.1.51: ResponseTime = 1 ms for > sourceforge.net > Oct-07-21 11:59:36 Info: Name Server int.ip.1.53: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:59:36 Info: Name Server int.ip.1.52: ResponseTime = 0 ms for > sourceforge.net > Oct-07-21 11:59:36 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:59:36 Warning: Name Server int.ip.1.51: does not respond or > timed out > Oct-07-21 11:59:36 Warning: Name Server int.ip.1.53: does not respond or > timed out > Oct-07-21 11:59:36 Warning: Name Server int.ip.1.52: does not respond or > timed out > Oct-07-21 11:59:36 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 11:59:36 ERROR: !!!! no answering DNS-SERVER found !!!! > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000552.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n100000000065.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000764.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000764.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000861.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n1000000000861.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n200000877.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n200000877.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:39 Info: in resend_mail->email_send: send-result <Couldn't > connect to (smtp internal ip):25 using 'Net::SMTP'> > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 Error: unable to send file > d:/assp/messages/resendmail/n600000150.txt to (smtp internal ip):25 > (smtpDestination) - Couldn't connect to (smtp internal ip):25 using > 'Net::SMTP' > Oct-07-21 11:59:39 *** send to (smtp internal ip):25 (smtpDestination) > didn't work, trying others... > Oct-07-21 11:59:41 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 12:00:01 Connected: session:A9180A88 192.0.96.238:45541 > (assp > internal ip):25 > (smtp internal ip):25 > Oct-07-21 12:00:02 IPNumTries: cleaning cache finished: IP's before=1, > deleted=1 > Oct-07-21 12:00:02 SMTPdomainIP: cleaning cache finished: domain's > before=4, deleted=0 > Oct-07-21 12:00:02 SSLfailedCache: cleaning cache finished: IP's > before=38, deleted=0 > Oct-07-21 12:00:02 SubjectFrequency: cleaning cache finished: subjects > before=1, deleted=1 > Oct-07-21 12:00:02 AUTHErrors: recalculated 1 IP counters > Oct-07-21 12:00:01 192.0.96.238 info: got STARTTLS request from > 192.0.96.238 > Oct-07-21 12:00:02 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 12:00:02 Warning: can't open file 'd:/assp/scheduleHistory' to > save hash (in saveHashToFile) - Too many open files > Oct-07-21 12:00:02 Info: hourly scheduler running at 13:00 > Oct-07-21 12:00:02 Cleaning up whitelist database ... > main exception: BerkeleyDB-ENV-ERROR Griplist: Cannot open file > d:/assp/tmpDB/Griplist/BDB-error.txt: Too many open files > at (eval 3745) line 1. > - BDB:BDB0062 Successful return: 0 > > (an email is received ok) > > Oct-07-21 12:00:03 Saving config > Oct-07-21 12:00:03 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 12:00:03 Warning: unable to read the current config in > d:/assp/assp.cfg > Oct-07-21 12:00:03 Info: saved config to d:/assp/assp.cfg.tmp - which is > now renamed to d:/assp/assp.cfg > Oct-07-21 12:00:03 Info: notification message queued to sent to > a...@ourcharity.org > Oct-07-21 12:00:03 Error: unable to rename file d:/assp/assp.cfg.tmp to > d:/assp/assp.cfg - No such file or directory > Oct-07-21 12:00:03 Finished saving config > Oct-07-21 12:00:03 BerkeleyDB-ENV-ERROR Griplist: Cannot open file > d:/assp/tmpDB/Griplist/BDB-error.txt: Too many open files > at (eval 3745) line 1. > - BDB:BDB0062 Successful return: 0 > Oct-07-21 12:00:03 Initializing shutdown sequence > Oct-07-21 12:00:03 Cleaning whitelist database finished: keys > before=161004, deleted=24 > Oct-07-21 12:00:03 Consolidate whitelist database ... > Oct-07-21 12:00:03 Info: removing all SMTP and Proxy listeners > \Oct-07-21 12:00:03 Worker_6 finished > Oct-07-21 12:00:03 ClamAV Up > Oct-07-21 12:00:03 Waiting for all SMTP-Workers to be finished > Oct-07-21 12:00:03 Worker_2 finished > > ( one last message is received) > > Oct-07-21 12:00:04 Worker_1 finished > Oct-07-21 12:00:04 SMTP Workers finished > Oct-07-21 12:00:04 Waiting for high Workers to be finished > Oct-07-21 12:00:05 Consolidate whitelist database finished: keys > before=203956, deleted=0 > Oct-07-21 12:00:05 Worker_10000 finished > Oct-07-21 12:00:05 Info: RebuildSpamdb Scheduler stopped > Oct-07-21 12:00:05 Worker_10001 finished > Oct-07-21 12:00:06 High workers finished work > Oct-07-21 12:00:06 Saving ldaplist > Oct-07-21 12:00:06 Info: saving Stats in file asspstats.sav > Oct-07-21 12:00:06 Info: saving ScoreStats in file asspscorestats.sav > Oct-07-21 12:00:06 Info: saving confidence graphical Stats in file > d:/assp/logs/confidenceGraphStats-2021-10.txt > Oct-07-21 12:00:06 Info: saving internal Caches in to folder > d:/assp/tmpDB/files > Oct-07-21 12:00:06 Closing all databases > Oct-07-21 12:00:06 Info: removing all WEB listeners > Oct-07-21 12:00:06 *Info: shutdown reason was: try restarting ASSP on > exception* > Oct-07-21 12:00:06 ASSP finished work > > On Thu, Oct 7, 2021 at 1:53 PM K Post <nntp.p...@gmail.com> wrote: > >> Uh oh. I haven't moved away from the test version you provided >> yesterday yet, but I just got a complete ASSP crash, which was preceded by: >> >> error: Worker_1 accept to client failed IO::Socket::INET=GLOB(0x881a0c40) >> (timeout: 2 s) : Too many open files >> >> error: Worker_2 accept to client failed IO::Socket::INET=GLOB(0x82c3f108) >> (timeout: 2 s) : Too many open files >> >> error: unable to close Socket IO::Socket::INET=GLOB(0xd872058) - - Bad >> file descriptor >> >> ERROR: !!!! no answering DNS-SERVER found !!!! <--- all 3 of my >> internal dns servers are operational >> >> error: couldn't create server socket to (inernal smtp ip) -- >> abortion connection >> >> error: Worker_2 accept to client failed IO::Socket::INET=GLOB(0x7e56c288) >> (timeout: 2 s) : Too many open files >> >> >> I was able to restart the ASSP service, but immediately started getting >> the same error messages as before. >> >> I've restarted Windows, working on getting it to start now, but I wanted >> to post ASAP just in case this is related to the test version / new version >> you just released. >> >> >> >> >> >> On Thu, Oct 7, 2021 at 12:05 PM Thomas Eckardt < >> thomas.ecka...@thockar.com> wrote: >> >>> Hi all, >>> >>> fixed in assp 2.6.6 *SPAM-Evaporator* build 21280: >>> >>> >>> - if $fakeAUTHsuccess was set, the collected .eml files contained only >>> the X-Assp headers - not the spam mail data >>> >>> - build 21277 caused an error 'too many opened files' on windows, if >>> there were too many SSL-connection at a plain port (25) >>> >>> - the definition of an invalid regular expression in 'NotifyRe' may >>> caused a crash of the assp process >>> >>> >>> >>> >>> changed: >>> >>> - The default value for >>> >>> $ignoreEarlySSLClientHelo >>> # (0/1) 1 - unexpected early SSLv23/TLS handshake Client-Helo-Frames are >>> ignored , 0 - unexpected early SSLv23/TLS handshake Client-Helo-Frames are >>> NOT ignored and the connection will be closed >>> >>> is changed from 1 to 0 in assp.pl >>> >>> to recover the old setting, you may change assp.pl or you can set >>> $main::ignoreEarlySSLClientHelo = 1; in lib/CorrectASSPcfg.pm sub set{} >>> The setting '1' was used as default to ignore early SSL connections from >>> local clients. >>> >>> >>> - mails which are catched by 'fakeAUTHsuccess' are now counted for the >>> statistics in STATS:msgMaxErrors and SCORESTATS:MaxErrors >>> >>> >>> Thomas >>> >>> DISCLAIMER: >>> ******************************************************* >>> This email and any files transmitted with it may be confidential, >>> legally privileged and protected in law and are intended solely for the use >>> of the >>> individual to whom it is addressed. >>> This email was multiple times scanned for viruses. There should be no >>> known virus in this email! >>> ******************************************************* >>> >>> _______________________________________________ >>> Assp-test mailing list >>> Assp-test@lists.sourceforge.net >>> https://lists.sourceforge.net/lists/listinfo/assp-test >>> >>
_______________________________________________ Assp-test mailing list Assp-test@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/assp-test
