Mail stuck in inbound queue after update.

Report bugs and workarounds
Post Reply
Ariannus
Posts: 4
Joined: 09 Mar 2016 16:58

Mail stuck in inbound queue after update.

Post by Ariannus »

I just updated from 3.0.0.8 to 3.0.0.9 and all my email is stuck in the inbound queue.
User avatar
shawniverson
Posts: 3649
Joined: 13 Jan 2014 23:30
Location: Indianapolis, Indiana USA
Contact:

Re: Mail stuck in inbound queue after update.

Post by shawniverson »

Rebooted after updating?
Ariannus
Posts: 4
Joined: 09 Mar 2016 16:58

Re: Mail stuck in inbound queue after update.

Post by Ariannus »

multiple times
User avatar
shawniverson
Posts: 3649
Joined: 13 Jan 2014 23:30
Location: Indianapolis, Indiana USA
Contact:

Re: Mail stuck in inbound queue after update.

Post by shawniverson »

Ok.

1) What are you observing in /var/log/messages and /var/log/maillog?
2) Is mailscanner running? (service MailScanner status)
3) Is clam running? (service clamd status)
Ariannus
Posts: 4
Joined: 09 Mar 2016 16:58

Re: Mail stuck in inbound queue after update.

Post by Ariannus »

I'm not sure what happened but I was able to get it working again. I did go through all the options in the configuration program and set them again and rebooted a few times but I don't know what fixed it. In case it helps anyone else here are snippets from the logs from when it wasn't working.

In /var/log/messages it just kept repeating
Mar 21 13:07:11 efa MailScanner: Process did not exit cleanly, returned 13 with signal 0
Mar 21 13:07:16 efa MailScanner: Process did not exit cleanly, returned 13 with signal 0


here is a snippet from /var/log/maillog

Code: Select all

Mar 21 12:43:09 efa MailScanner[12625]: MailScanner E-Mail Virus Scanner version 4.84.6 starting...
Mar 21 12:43:09 efa MailScanner[12625]: Reading configuration file /etc/MailScanner/MailScanner.conf
Mar 21 12:43:09 efa MailScanner[12625]: Reading configuration file /etc/MailScanner/conf.d/README
Mar 21 12:43:09 efa MailScanner[12625]: Read 1500 hostnames from the phishing whitelist
Mar 21 12:43:10 efa MailScanner[12625]: Read 23370 hostnames from the phishing blacklists
Mar 21 12:43:10 efa MailScanner[12625]: Config: calling custom init function SQLBlacklist
Mar 21 12:43:10 efa MailScanner[12625]: Starting up SQL Blacklist
Mar 21 12:43:10 efa MailScanner[12625]: Read 37 blacklist entries
Mar 21 12:43:10 efa MailScanner[12625]: Config: calling custom init function MailWatchLogging
Mar 21 12:43:10 efa MailScanner[12625]: Started SQL Logging child
Mar 21 12:43:10 efa MailScanner[12625]: Config: calling custom init function SQLWhitelist
Mar 21 12:43:10 efa MailScanner[12625]: Starting up SQL Whitelist
Mar 21 12:43:10 efa MailScanner[12625]: Read 39 whitelist entries
Mar 21 12:43:10 efa MailScanner[12625]: Using SpamAssassin results cache
Mar 21 12:43:10 efa MailScanner[12625]: Connected to SpamAssassin cache database
Mar 21 12:43:10 efa MailScanner[12625]: Enabling SpamAssassin auto-whitelist functionality...
Mar 21 12:43:14 efa MailScanner[12633]: MailScanner E-Mail Virus Scanner version 4.84.6 starting...
Mar 21 12:43:14 efa MailScanner[12633]: Reading configuration file /etc/MailScanner/MailScanner.conf
Mar 21 12:43:14 efa MailScanner[12633]: Reading configuration file /etc/MailScanner/conf.d/README
Mar 21 12:43:14 efa MailScanner[12633]: Read 1500 hostnames from the phishing whitelist
Mar 21 12:43:15 efa MailScanner[12633]: Read 23370 hostnames from the phishing blacklists
Mar 21 12:43:15 efa MailScanner[12633]: Config: calling custom init function SQLBlacklist
Mar 21 12:43:15 efa MailScanner[12633]: Starting up SQL Blacklist
Mar 21 12:43:15 efa MailScanner[12633]: Read 37 blacklist entries
Mar 21 12:43:15 efa MailScanner[12633]: Config: calling custom init function MailWatchLogging
Mar 21 12:43:15 efa MailScanner[12633]: Started SQL Logging child
Mar 21 12:43:15 efa MailScanner[12633]: Config: calling custom init function SQLWhitelist
Mar 21 12:43:15 efa MailScanner[12633]: Starting up SQL Whitelist
Mar 21 12:43:15 efa MailScanner[12633]: Read 39 whitelist entries
Mar 21 12:43:15 efa MailScanner[12633]: Using SpamAssassin results cache
Mar 21 12:43:15 efa MailScanner[12633]: Connected to SpamAssassin cache database
Mar 21 12:43:15 efa MailScanner[12633]: Enabling SpamAssassin auto-whitelist functionality...
Mar 21 12:43:18 efa postfix/smtpd[11797]: warning: hostname hosted-by.snel.com does not resolve to address 128.204.205.24
Mar 21 12:43:18 efa postfix/smtpd[11797]: connect from unknown[128.204.205.24]
Mar 21 12:43:18 efa postfix/smtpd[11797]: E468B1002EF: client=unknown[128.204.205.24]
Mar 21 12:43:19 efa postfix/cleanup[11902]: E468B1002EF: hold: header Received: from specialoffice.review (unknown [128.204.205.24])??by mail.example.com (Postfix) with ESMTP id E468B1002EF??for <$
Mar 21 12:43:19 efa postfix/cleanup[11902]: E468B1002EF: message-id=<201603211240.5198148@specialoffice.review>
Mar 21 12:43:22 efa postfix/smtpd[12395]: connect from nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/smtpd[11797]: disconnect from unknown[128.204.205.24] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Mar 21 12:43:22 efa postfix/smtpd[12395]: 2E45B1002F0: client=nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/cleanup[11902]: 2E45B1002F0: hold: header Received: from bl10.eachgive.com (nzsale.supportnry.com [104.148.31.68])??by mail.example.com (Postfix) with ESMTP id 2E45B100$
Mar 21 12:43:22 efa postfix/cleanup[11902]: 2E45B1002F0: message-id=<3548b454575398848843345a53cc4ee7@bl10.eachgive.com>
Mar 21 12:43:22 efa postfix/smtpd[12395]: 5DEBD1002F1: client=nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/cleanup[11902]: 5DEBD1002F1: hold: header Received: from bl10.eachgive.com (nzsale.supportnry.com [104.148.31.68])??by mail.example.com (Postfix) with ESMTP id 5DEBD100$
Mar 21 12:43:22 efa postfix/cleanup[11902]: 5DEBD1002F1: message-id=<02169207534607551540f17ac8cf7a44@bl10.eachgive.com>
Mar 21 12:43:22 efa postfix/smtpd[12395]: 7E5421002F3: client=nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/cleanup[11902]: 7E5421002F3: hold: header Received: from bl10.eachgive.com (nzsale.supportnry.com [104.148.31.68])??by mail.example.com (Postfix) with ESMTP id 7E542100$
Mar 21 12:43:22 efa postfix/cleanup[11902]: 7E5421002F3: message-id=<021692012342515515594cfd0524e9a9@bl10.eachgive.com>
Mar 21 12:43:22 efa postfix/smtpd[11797]: connect from ils-wk302.example.com[192.168.0.68]
Mar 21 12:43:22 efa postfix/smtpd[11797]: warning: ils-wk302.example.com[192.168.0.68]: SASL LOGIN authentication failed: authentication failure
Mar 21 12:43:22 efa postfix/smtpd[11797]: lost connection after AUTH from ils-wk302.example.com[192.168.0.68]
Mar 21 12:43:22 efa postfix/smtpd[11797]: disconnect from ils-wk302.example.com[192.168.0.68] ehlo=1 auth=0/1 commands=1/2
Mar 21 12:43:22 efa postfix/smtpd[12395]: 9FAA11002F4: client=nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/cleanup[11902]: 9FAA11002F4: hold: header Received: from bl10.eachgive.com (nzsale.supportnry.com [104.148.31.68])??by mail.example.com (Postfix) with ESMTP id 9FAA1100$
Mar 21 12:43:22 efa postfix/cleanup[11902]: 9FAA11002F4: message-id=<021692134434055515598af509b234a6@bl10.eachgive.com>
Mar 21 12:43:22 efa postfix/smtpd[12395]: BFDD61002F5: client=nzsale.supportnry.com[104.148.31.68]
Mar 21 12:43:22 efa postfix/cleanup[11902]: BFDD61002F5: hold: header Received: from bl10.eachgive.com (nzsale.supportnry.com [104.148.31.68])??by mail.example.com (Postfix) with ESMTP id BFDD6100$
Mar 21 12:43:22 efa postfix/cleanup[11902]: BFDD61002F5: message-id=<3549c5343c6a8b884889cd51807fa0ae@bl10.eachgive.com>
Mar 21 12:43:22 efa postfix/smtpd[12395]: disconnect from nzsale.supportnry.com[104.148.31.68] ehlo=1 mail=5 rcpt=5 data=5 quit=1 commands=17
Mar 21 12:43:23 efa postfix/smtpd[11797]: connect from pretencive.e1qlike.top[103.204.108.94]
Mar 21 12:43:24 efa postfix/smtpd[11797]: 198A01002F6: client=pretencive.e1qlike.top[103.204.108.94]
Mar 21 12:43:24 efa postfix/cleanup[11902]: 198A01002F6: hold: header Received: from pretencive.e1qlike.top (pretencive.e1qlike.top [103.204.108.94])??by mail.example.com (Postfix) with ESMTP id 1$
Mar 21 12:43:24 efa postfix/cleanup[11902]: 198A01002F6: message-id=<201603211143.1458578604178b75762ea40e046528421_ac24f056@pretencive.e1qlike.top>
Mar 21 12:43:24 efa postfix/smtpd[12395]: warning: hostname 0x52b4fc8f.generic.sagitta.dk does not resolve to address 82.180.252.143: Name or service not known
Mar 21 12:43:24 efa postfix/smtpd[12395]: connect from unknown[82.180.252.143]
Mar 21 12:43:24 efa postfix/smtpd[11797]: disconnect from pretencive.e1qlike.top[103.204.108.94] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Mar 21 12:43:25 efa postfix/smtpd[12395]: 440581002F7: client=unknown[82.180.252.143]
Mar 21 12:43:25 efa postfix/cleanup[11902]: 440581002F7: hold: header Received: from rental.yourjetcharteroptions.eu (unknown [82.180.252.143])??by mail.example.com (Postfix) with ESMTP id 4405810$
Mar 21 12:43:25 efa postfix/cleanup[11902]: 440581002F7: message-id=<08113557783w13557783-2226037fjsenn@example.com33>
Mar 21 12:43:25 efa postfix/smtpd[11797]: warning: hostname ourparent.review does not resolve to address 72.9.101.102
Mar 21 12:43:26 efa postfix/smtpd[12395]: disconnect from unknown[82.180.252.143] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Mar 21 12:43:26 efa postfix/smtpd[11797]: 156131002F8: client=unknown[72.9.101.102]
Mar 21 12:43:26 efa postfix/cleanup[11902]: 156131002F8: hold: header Received: from ourparent.review (unknown [72.9.101.102])??by mail.example.com (Postfix) with ESMTP id 156131002F8??for <sjones$
Mar 21 12:43:26 efa postfix/cleanup[11902]: 156131002F8: message-id=<201603211240.4603245@ourparent.review>
Mar 21 12:43:28 efa postfix/smtpd[11797]: disconnect from unknown[72.9.101.102] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Mar 21 12:43:28 efa postfix/smtpd[12395]: connect from unknown[14.1.29.93]
Mar 21 12:43:29 efa postfix/smtpd[12395]: 0D9731002F9: client=unknown[14.1.29.93]
Mar 21 12:43:29 efa postfix/cleanup[11902]: 0D9731002F9: hold: header Received: from excise.batrbub.download (unknown [14.1.29.93])??by mail.example.com (Postfix) with ESMTP id 0D9731002F9??for <r$
Mar 21 12:43:29 efa postfix/cleanup[11902]: 0D9731002F9: message-id=<4c0e48b17e0e1899b3beed8f1c152f7c@m.batrbub.download>
Mar 21 12:43:29 efa postfix/smtpd[12395]: disconnect from unknown[14.1.29.93] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Mar 21 12:43:29 efa postfix/smtpd[11797]: warning: hostname specialteam.review does not resolve to address 216.169.117.41
Mar 21 12:43:29 efa postfix/smtpd[11797]: connect from unknown[216.169.117.41]
Mar 21 12:43:29 efa postfix/smtpd[12395]: connect from pretencive.e1qlike.top[103.204.108.94]
Mar 21 12:43:29 efa postfix/smtpd[11797]: C523B1002FA: client=unknown[216.169.117.41]
Mar 21 12:43:29 efa postfix/cleanup[12641]: warning: database /etc/postfix/virtual.db is older than source file /etc/postfix/virtual
Mar 21 12:43:29 efa postfix/smtpd[12395]: D17891002FB: client=pretencive.e1qlike.top[103.204.108.94]
Mar 21 12:43:29 efa postfix/cleanup[11902]: C523B1002FA: hold: header Received: from specialteam.review (unknown [216.169.117.41])??by mail.example.com (Postfix) with ESMTP id C523B1002FA??for <tm$
Mar 21 12:43:29 efa postfix/cleanup[11902]: C523B1002FA: message-id=<201603211240.5396359@specialteam.review>
Mar 21 12:43:30 efa postfix/cleanup[12641]: D17891002FB: hold: header Received: from pretencive.e1qlike.top (pretencive.e1qlike.top [103.204.108.94])??by mail.example.com (Postfix) with ESMTP id D$
Mar 21 12:43:30 efa postfix/cleanup[12641]: D17891002FB: message-id=<201603211143.14585786081785c09960740e046b78f21_b024f056@pretencive.e1qlike.top>
Mar 21 12:43:30 efa postfix/smtpd[12395]: disconnect from pretencive.e1qlike.top[103.204.108.94] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Mar 21 12:43:30 efa postfix/smtpd[12395]: warning: hostname hosted-by.snel.com does not resolve to address 128.204.205.24
Mar 21 12:43:30 efa postfix/smtpd[12395]: connect from unknown[128.204.205.24]
Mar 21 12:43:31 efa postfix/smtpd[12395]: 662D11002FD: client=unknown[128.204.205.24]
Mar 21 12:43:31 efa postfix/cleanup[11902]: 662D11002FD: hold: header Received: from specialoffice.review (unknown [128.204.205.24])??by mail.example.com (Postfix) with ESMTP id 662D11002FD??for <$
Mar 21 12:43:31 efa postfix/cleanup[11902]: 662D11002FD: message-id=<201603211240.806371@specialoffice.review>
Mar 21 12:43:31 efa postfix/smtpd[11797]: disconnect from unknown[216.169.117.41] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Mar 21 12:43:34 efa postfix/smtpd[12395]: disconnect from unknown[128.204.205.24] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Mar 21 12:43:34 efa postfix/smtpd[11797]: warning: hostname ourparent.review does not resolve to address 72.9.101.102
Mar 21 12:43:34 efa postfix/smtpd[11797]: connect from unknown[72.9.101.102]
Mar 21 12:43:34 efa postfix/smtpd[11797]: 72A6B1002FE: client=unknown[72.9.101.102]
Mar 21 12:43:34 efa postfix/cleanup[12641]: 72A6B1002FE: hold: header Received: from ourparent.review (unknown [72.9.101.102])??by mail.example.com (Postfix) with ESMTP id 72A6B1002FE??for <pandel$
Mar 21 12:43:34 efa postfix/cleanup[12641]: 72A6B1002FE: message-id=<201603211240.3693680@ourparent.review>
Mar 21 12:43:36 efa postfix/smtpd[12395]: warning: hostname hosted-by.snel.com does not resolve to address 37.148.160.11
Mar 21 12:43:36 efa postfix/smtpd[12395]: connect from unknown[37.148.160.11]
Mar 21 12:43:37 efa postfix/smtpd[12395]: 0FD0B100300: client=unknown[37.148.160.11]
Mar 21 12:43:37 efa postfix/smtpd[11797]: disconnect from unknown[72.9.101.102] ehlo=1 mail=1 rcpt=1 data=1 commands=4
Mar 21 12:43:37 efa postfix/cleanup[11902]: 0FD0B100300: hold: header Received: from youryear.review (unknown [37.148.160.11])??by mail.example.com (Postfix) with ESMTP id 0FD0B100300??for <rcarr@$
Mar 21 12:43:37 efa postfix/cleanup[11902]: 0FD0B100300: message-id=<201603211240.471253@youryear.review>
Mar 21 12:43:38 efa MailScanner[11808]: Connected to Processing Attempts Database
Mar 21 12:43:38 efa MailScanner[11808]: Found 0 messages in the Processing Attempts Database
Mar 21 12:43:38 efa MailScanner[12644]: MailScanner E-Mail Virus Scanner version 4.84.6 starting...
Mar 21 12:43:38 efa MailScanner[12644]: Reading configuration file /etc/MailScanner/MailScanner.conf
Mar 21 12:43:38 efa MailScanner[12644]: Reading configuration file /etc/MailScanner/conf.d/README
Mar 21 12:43:38 efa MailScanner[12644]: Read 1500 hostnames from the phishing whitelist
Mar 21 12:43:38 efa MailScanner[12644]: Read 23370 hostnames from the phishing blacklists
Mar 21 12:43:38 efa MailScanner[12644]: Config: calling custom init function SQLBlacklist
Mar 21 12:43:38 efa MailScanner[12644]: Starting up SQL Blacklist
Mar 21 12:43:38 efa MailScanner[12644]: Read 37 blacklist entries
Mar 21 12:43:38 efa MailScanner[12644]: Config: calling custom init function MailWatchLogging
Mar 21 12:43:39 efa MailScanner[12644]: Started SQL Logging child
Mar 21 12:43:39 efa MailScanner[12644]: Config: calling custom init function SQLWhitelist
Mar 21 12:43:39 efa MailScanner[12644]: Starting up SQL Whitelist
Mar 21 12:43:39 efa MailScanner[12644]: Read 39 whitelist entries
Mar 21 12:43:39 efa MailScanner[12644]: Using SpamAssassin results cache
Mar 21 12:43:39 efa MailScanner[12644]: Connected to SpamAssassin cache database
Mar 21 12:43:39 efa MailScanner[12644]: Enabling SpamAssassin auto-whitelist functionality...

User avatar
BruceLeeRoy
Posts: 47
Joined: 01 May 2015 13:27

Re: Mail stuck in inbound queue after update.

Post by BruceLeeRoy »

I'm having the same problem after updating to EFA-3.0.1.8 Won't process mail, Here's some entries from the logs:

/var/log/maillog
Feb 26 11:07:45 mail MailScanner[3501]: Enabling SpamAssassin auto-whitelist functionality...
Feb 26 11:07:48 mail MailScanner[3501]: Connected to Processing Attempts Database
Feb 26 11:07:48 mail MailScanner[3501]: Found 0 messages in the Processing Attempts Database
Feb 26 11:07:48 mail MailScanner[3501]: Using locktype = flock
Feb 26 11:51:25 mail sendmail[13465]: NOQUEUE: SYSERR(apache): can not chdir(/var/spool/mqueue/): Permission denied
Feb 26 11:51:34 mail sendmail[13478]: NOQUEUE: SYSERR(apache): can not chdir(/var/spool/mqueue/): Permission denied
Feb 26 11:51:43 mail sendmail[13490]: NOQUEUE: SYSERR(apache): can not chdir(/var/spool/mqueue/): Permission denied
Feb 26 11:52:14 mail sendmail[13505]: NOQUEUE: SYSERR(apache): can not chdir(/var/spool/mqueue/): Permission denied
Feb 26 11:52:37 mail sendmail[13536]: NOQUEUE: SYSERR(apache): can not chdir(/var/spool/mqueue/): Permission denied

/var/log/messages
Feb 26 11:58:11 mail saslauthd[1925]: ipc_init : listening on socket: /var/run/saslauthd/mux
Feb 26 11:58:13 mail MailScanner: MailScanner setting GID to postfix (89)
Feb 26 11:58:13 mail MailScanner: MailScanner setting UID to postfix (89)
Feb 26 11:59:22 mail savd: savscan.log: On-demand scan started.
Feb 26 11:59:28 mail savd: savscan.log: On-demand scan finished.
Feb 26 12:01:01 mail php: PHP Notice: Undefined variable: _relay in /var/www/html/mailscanner/postfix_relay.php on line 240
Feb 26 12:01:01 mail php: PHP Notice: Undefined variable: _dsn in /var/www/html/mailscanner/postfix_relay.php on line 240
Feb 26 12:01:01 mail php: PHP Notice: Undefined variable: _delay in /var/www/html/mailscanner/postfix_relay.php on line 240
Feb 26 12:08:09 mail clamd[1757]: SelfCheck: Database status OK.
Feb 26 12:18:09 mail clamd[1757]: SelfCheck: Database status OK.



Here is the /var/spool directory:

drwxr-xr-x 2 root root 4096 Sep 23 2011 lpd
drwxr-xr-x 2 root root 4096 Aug 11 2014 plymouth
drwxrwxr-x 2 root mail 4096 Apr 13 2015 mail
drwxr-xr-x 23 root root 4096 Nov 7 2015 ..
drwx------ 2 root root 4096 Aug 23 2016 cron
drwxrwxrwt 2 root root 4096 Aug 23 2016 samba
drwxr-xr-x 11 root root 4096 Oct 3 20:14 .
drwxr-xr-x 7 root root 4096 Oct 3 20:14 MailScanner
drwxr-xr-x 19 root root 4096 Feb 26 10:38 postfix
drwx------ 2 root mail 4096 Feb 26 12:01 mqueue
drwxrwx--- 2 smmsp smmsp 4096 Feb 26 12:01 clientmqueue

I tried to chmod 777 mqueue but didnt make a difference, so I set it back.
User avatar
shawniverson
Posts: 3649
Joined: 13 Jan 2014 23:30
Location: Indianapolis, Indiana USA
Contact:

Re: Mail stuck in inbound queue after update.

Post by shawniverson »

/var/spool/mqueue and everything in it should be owner postfix and group postfix, which 755 for the directory

Code: Select all

sudo chown -R postfix:postfix /var/spool/mqueue
sudo chmod 755 /var/spool/mqueue
User avatar
BruceLeeRoy
Posts: 47
Joined: 01 May 2015 13:27

Re: Mail stuck in inbound queue after update.

Post by BruceLeeRoy »

For some reason sendmail was running so I stopped that and started postfix then it started processing mail,
changed owner of mqueue as you suggested. still getting emails from root:

/etc/cron.hourly/mailwatch_relay.sh:


Notice: Undefined variable: _relay in /var/www/html/mailscanner/postfix_relay.php on
line 240

Notice: Undefined variable: _dsn in /var/www/html/mailscanner/postfix_relay.php on
line 240

Notice: Undefined variable: _delay in /var/www/html/mailscanner/postfix_relay.php on
line 240
Post Reply