https://issues.apache.org/SpamAssassin/show_bug.cgi?id=6936
Frank Urban <[email protected]> changed: What |Removed |Added ---------------------------------------------------------------------------- CC| |[email protected] --- Comment #2 from Frank Urban <[email protected]> --- Yes. this seemed to be the same problem. But as far as I understand the bug will be fixed by turning the message from warn to debug so it will not be shown in the log anymore and that's all. But I see a real problem based on this . Exactly at this time a SPAM mail passed the filter without being scanned. The box has 32GByte RAM. Yesterday this box scanned 560.000 mails and I found these error in the log cat mail2.log |grep prefork: |grep failed May 21 01:12:11 mail2 spamd[1033]: prefork: sysread(9) failed after 300 secs at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 654. May 21 01:12:14 mail2 spamd[1034]: prefork: sysread(10) failed after 300 secs at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 654. May 21 08:27:20 mail2 spamd[1009]: prefork: killing rogue child 446, failed to write on fd : May 21 08:27:20 mail2 spamd[1009]: prefork: killing failed child 446 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 10:08:15 mail2 spamd[1009]: prefork: killing rogue child 328, failed to write on fd : May 21 10:08:15 mail2 spamd[1009]: prefork: killing failed child 328 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 10:38:43 mail2 spamd[1009]: prefork: killing rogue child 395, failed to write on fd : May 21 10:38:43 mail2 spamd[1009]: prefork: killing failed child 395 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 10:44:25 mail2 spamd[1009]: prefork: killing rogue child 515, failed to write on fd : May 21 10:44:25 mail2 spamd[1009]: prefork: killing failed child 515 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 11:00:19 mail2 spamd[1009]: prefork: killing rogue child 570, failed to write on fd : May 21 11:00:19 mail2 spamd[1009]: prefork: killing failed child 570 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 11:03:10 mail2 spamd[1009]: prefork: killing rogue child 415, failed to write on fd : May 21 11:03:10 mail2 spamd[1009]: prefork: killing failed child 415 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 11:37:48 mail2 spamd[1009]: prefork: killing rogue child 539, failed to write on fd : May 21 11:37:48 mail2 spamd[1009]: prefork: killing failed child 539 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 12:02:33 mail2 spamd[1009]: prefork: killing rogue child 491, failed to write on fd : May 21 12:02:33 mail2 spamd[1009]: prefork: killing failed child 491 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 13:00:24 mail2 spamd[1009]: prefork: killing rogue child 338, failed to write on fd : May 21 13:00:24 mail2 spamd[1009]: prefork: killing failed child 338 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 14:31:02 mail2 spamd[1009]: prefork: killing rogue child 354, failed to write on fd : May 21 14:31:02 mail2 spamd[1009]: prefork: killing failed child 354 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 14:55:47 mail2 spamd[1009]: prefork: killing rogue child 363, failed to write on fd : May 21 14:55:47 mail2 spamd[1009]: prefork: killing failed child 363 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 15:01:57 mail2 spamd[1009]: prefork: killing rogue child 4255, failed to write on fd : May 21 15:01:57 mail2 spamd[1009]: prefork: killing failed child 4255 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 15:21:27 mail2 spamd[1009]: prefork: killing rogue child 310, failed to write on fd : May 21 15:21:27 mail2 spamd[1009]: prefork: killing failed child 310 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 15:51:40 mail2 spamd[1009]: prefork: killing rogue child 567, failed to write on fd : May 21 15:51:40 mail2 spamd[1009]: prefork: killing failed child 567 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 16:11:26 mail2 spamd[1009]: prefork: killing rogue child 328, failed to write on fd : May 21 16:11:26 mail2 spamd[1009]: prefork: killing failed child 328 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 16:39:51 mail2 spamd[1009]: prefork: killing rogue child 642, failed to write on fd : May 21 16:39:51 mail2 spamd[1009]: prefork: killing failed child 642 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 17:27:27 mail2 spamd[1009]: prefork: killing rogue child 421, failed to write on fd : May 21 17:27:27 mail2 spamd[1009]: prefork: killing failed child 421 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 18:25:03 mail2 spamd[1009]: prefork: killing rogue child 324, failed to write on fd : May 21 18:25:03 mail2 spamd[1009]: prefork: killing failed child 324 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 18:36:08 mail2 spamd[1009]: prefork: killing rogue child 398, failed to write on fd : May 21 18:36:08 mail2 spamd[1009]: prefork: killing failed child 398 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 21:00:29 mail2 spamd[1009]: prefork: killing rogue child 436, failed to write on fd : May 21 21:00:29 mail2 spamd[1009]: prefork: killing failed child 436 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 21:20:01 mail2 spamd[1009]: prefork: killing rogue child 444, failed to write on fd : May 21 21:20:01 mail2 spamd[1009]: prefork: killing failed child 444 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. One of my users send me a SPAM mail that was not scanned at exactly this time (15:21:27) Yesterday I saw the same with another example and so I opened this bug report. I tried to filter out the interesting part of the log file at this time. Should be this here: May 21 15:21:25 mail2 postfix/smtpd[28254]: B16912DC103: client=unknown[109.167.225.72] May 21 15:21:27 mail2 postfix/cleanup[4454]: B16912DC103: message-id=<[email protected]> May 21 15:21:27 mail2 postfix/qmgr[18855]: B16912DC103: from=<>, size=676, nrcpt=1 (queue active) May 21 15:21:27 mail2 postfix/pipe[24380]: B16912DC103: to=<[email protected]>, relay=filter, delay=1.6, delays=1.4/0.04/0/0.13, dsn=2.0.0, status=sent (delivered via filter service) May 21 15:21:27 mail2 postfix/qmgr[18855]: B16912DC103: removed ay 21 15:21:27 mail2 spamd[1009]: prefork: adjust: 23 idle children more than 21 maximum idle children. Decreasing spamd children: 30321 killed. May 21 15:21:27 mail2 spamd[1009]: spamd: handled cleanup of child pid [30321] due to SIGCHLD: interrupted, signal 2 (0002) May 21 15:21:27 mail2 spamd[1009]: prefork: child states: BBBBBIBBBBBBBBBIBBBBBBBBBIBBBBBBBBBBBBBIBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBBIIIIIIIIIIIIIIIIII May 21 15:21:27 mail2 spamd[1009]: prefork: adjust: 22 idle children more than 21 maximum idle children. Decreasing spamd children: 30320 killed. May 21 15:21:27 mail2 spamd[1009]: spamd: handled cleanup of child pid [30320] due to SIGCHLD: interrupted, signal 2 (0002) May 21 15:21:27 mail2 spamd[1009]: syswrite() on closed filehandle GEN367324 at /usr/lib/perl5/5.10.0/x86_64-linux-thread-multi/IO/Handle.pm line 461. May 21 15:21:27 mail2 spamd[1009]: Use of uninitialized value in concatenation (.) or string at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 497. May 21 15:21:27 mail2 spamd[1009]: prefork: killing rogue child 310, failed to write on fd : May 21 15:21:27 mail2 spamd[1009]: prefork: killing failed child 310 fd=undefined at /usr/lib/perl5/site_perl/5.10.0/Mail/SpamAssassin/SpamdForkScaling.pm line 172. May 21 15:21:27 mail2 spamd[1009]: prefork: error closing socket: Bad file descriptor May 21 15:21:27 mail2 spamd[1009]: prefork: killed child 310 May 21 15:21:27 mail2 spamd[1009]: spamd: handled cleanup of child pid [310] due to SIGCHLD: interrupted, signal 2 (0002) May 21 15:21:27 mail2 postfix/pickup[23728]: 319512DC119: uid=1002 from=<MAILER-DAEMON> May 21 15:21:27 mail2 postfix/cleanup[25491]: 319512DC119: message-id=<[email protected]> May 21 15:21:27 mail2 postfix/qmgr[18855]: 319512DC119: from=<>, size=812, nrcpt=1 (queue active) May 21 15:21:27 mail2 postfix/smtp[29253]: 319512DC119: to=<[email protected]>, relay=intern.postfix.example.com[172.16.71.210]:25, delay=0.05, delays=0.02/0/0.02/0, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 3A4B420026E) May 21 15:21:27 mail2 postfix/qmgr[18855]: 319512DC119: removed -- You are receiving this mail because: You are the assignee for the bug.
