[BlueOnyx:16540] 5106 FTP and AM strangeness.

[George F. Nemeyer]

I thought the previous issues with the new FTP being wierd were taken care
of, but I just noticed something that apparently begain just a couple days
ago:

Both FTP and Email Server Active Monitors were red-balled, as was the main
top-bar light.  Of the various e-mail services, only the top one (main
SMTP server) was red.

Both FTP and SMTP were actively running and seeming to work ok with
external connections.

Long story short:

 - The only way to regain green email ball is to
    Turn off ALL email services (just SMTP didn't work)
    Turn off ALL FTP services (FTP ball goes grey)
    Turn on desired email services. (Mail ball goes green)

 - Turning on FTP (with or without SFTP), will soon result
   in BOTH FTP and MAIL going red again.

 - Just turning off FTP will NOT restore email to green.  Mail
   services must a be turned off and back on.

 - Turning on FTP alone (with or without mail services, and with or
   without SFTP) leaves the FTP indicator ball gray, but it evenually
   goes red.

 - Either Mail or FTP alone will go red after a time.  At first, Mail
   seemd to be going red only when FTP was turned on, but I have seen
   either one alone eventually go red even if the other is turned off.
   I *think* the fact that FTP appeared to affect Mail was due to just
   the balls on the AM staus page are not being updated unless the page
   gets reloaded.

I went digging in the logs, and found this issue began between 14:15 and
14:30 on 19 November.  Before, all seemed happy.  After, the issues
persist.  Nothing particualrly special happened that day/time that I can
find evidence of.  Below are some grepped-out log exerpts of a few things
that begin to show up in the logs at the time first observed:


base-ftp   (Note base-ftp.amNotResponding)
------------------------------------------
Nov 19 14:00:02 bobcat cced(smd)[31156]: client 0:[0:31152]: SET 18 . FTP
lastChange = 1416423602 currentState = G currentMessage =
"[[base-ftp.amStatusOK]]"

Nov 19 14:15:03 bobcat cced(smd)[32098]: client 0:[0:32094]: SET 18 . FTP
lastChange = 1416424503 currentState = G
currentMessage = "[[base-ftp.amStatusOK]]"

Nov 19 14:30:13 bobcat cced(smd)[609]: client 0:[0:605]: SET 18 . FTP
lastChange = 1416425413 currentState = R currentMessage =
"[[base-ftp.amNotResponding]]"

Nov 19 14:45:12 bobcat cced(smd)[1607]: client 0:[0:1603]: SET 18 . FTP
lastChange = 1416426312 currentState = R currentMessage =
"[[base-ftp.amNotResponding]]"


base-email  (Note base-email.amSMTPNotRunning and base-email.amEmailRed)
------------------------------------------------------------------------
Nov 19 14:15:03 bobcat cced(smd)[32098]: client 0:[0:32094]: SET 18 .
SMTP lastChange = 1416424503 currentState = G currentMessage =
"[[base-email.amSMTPStatusOK]]"

Nov 19 14:15:03 bobcat cced(smd)[32098]: client 0:[0:32094]: SET 18 .
IMAP lastChange = 1416424503 currentSta te = G currentMessage =
"[[base-email.amIMAPStatusOK]]"

Nov 19 14:15:03 bobcat cced(smd)[32098]: client 0:[0:32094]: SET 18 .
SMTPAuth lastChange = 1416424503 curren tState = G currentMessage =
"[[base-email.amSMTPAuthStatusOK]]"

Nov 19 14:15:03 bobcat cced(smd)[32098]: client 0:[0:32094]: SET 18 .
Email lastChange = 1416424503 currentSt ate = G currentMessage =
"[[base-email.amEmailGreen]]"

Nov 19 14:30:12 bobcat cced(smd)[609]: client 0:[0:605]: SET 18 . SMTP
lastChange = 1416425412 currentState = R currentMessage =
"[[base-email.amSMTPNotRunning]]"

Nov 19 14:30:12 bobcat cced(smd)[609]: client 0:[0:605]: SET 18 . IMAP
lastChange = 1416425412 currentState = G currentMessage =
"[[base-email.amIMAPStatusOK]]"

Nov 19 14:30:12 bobcat cced(smd)[609]: client 0:[0:605]: SET 18 .
SMTPAuth lastChange = 1416425412 currentState = G currentMessage =
"[[base-email.amSMTPAuthStatusOK]]"

Nov 19 14:30:12 bobcat cced(smd)[609]: client 0:[0:605]: SET 18 . Email
lastChange = 1416425412 currentState = R currentMessage =
"[[base-email.amEmailRed]]"


xinted  (Note libwrap refused connection to ftp (libwrap=in.proftpd) )
----------------------------------------------------------------------
Nov 19 14:00:02 bobcat xinetd[1692]: START: ftp pid=31203 from=127.0.0.1
Nov 19 14:00:02 bobcat xinetd[1692]: EXIT: ftp status=0 pid=31203
duration=0(sec)
Nov 19 14:15:03 bobcat xinetd[1692]: START: ftp pid=32145 from=127.0.0.1
Nov 19 14:15:03 bobcat xinetd[1692]: EXIT: ftp status=0 pid=32145
duration=0(sec)
Nov 19 14:30:13 bobcat xinetd[1692]: START: ftp pid=668 from=127.0.0.1
Nov 19 14:30:13 bobcat xinetd[668]: libwrap refused connection to ftp
(libwrap=in.proftpd) from 127.0.0.1
Nov 19 14:30:13 bobcat xinetd[668]: FAIL: ftp libwrap from=127.0.0.1
Nov 19 14:30:13 bobcat xinetd[1692]: EXIT: ftp status=0 pid=668
duration=0(sec)
Nov 19 14:45:12 bobcat xinetd[1692]: START: ftp pid=1656 from=127.0.0.1
Nov 19 14:45:12 bobcat xinetd[1656]: libwrap refused connection to ftp
(libwrap=in.proftpd) from 127.0.0.1
Nov 19 14:45:12 bobcat xinetd[1656]: FAIL: ftp libwrap from=127.0.0.1
Nov 19 14:45:12 bobcat xinetd[1692]: EXIT: ftp status=0 pid=1656
duration=0(sec)

Is there some test or debugging I can switch on to help try to figure out
what's going on?

=^_^=  Tigerwolf