mta Stopped postfix...
 
Notifications
Clear all

mta Stopped postfix is not running

6 Posts
3 Users
0 Reactions
59 Views
(@gpgp01)
Joined: 4 months ago
Posts: 24
Topic starter  

Hi everyone, Carbon CE 24.3.0, it is the second time in a few days that postfix stops: "mta Stopped postfix is not running" ubuntu 22.x - CPU 4 vCPU - 16GB ram - HD 200. what can I check?

thanks


   
Quote
(@sharif)
Admin
Joined: 2 years ago
Posts: 433
 

@gpgp01

Hi,

What does the log say?

Regards.

Sharif


   
ReplyQuote
(@gpgp01)
Joined: 4 months ago
Posts: 24
Topic starter  

@sharif 

HI,
Which log should I look at exactly?

Thank you


   
ReplyQuote
(@gpgp01)
Joined: 4 months ago
Posts: 24
Topic starter  

@sharif 

this is the stop log  /var/log/mail.log

May 26 12:26:33 mail postfix/smtpd[467610]: lost connection after CONNECT from localhost[127.0.0.1]
May 26 12:26:33 mail postfix/smtpd[467610]: disconnect from localhost[127.0.0.1] commands=0/0
May 26 15:23:20 mail postfix/postscreen[475040]: CONNECT from [127.0.0.1]:57740 to [127.0.0.1]:25
May 26 15:23:20 mail postfix/postscreen[475040]: ALLOWLISTED [127.0.0.1]:57740
May 26 15:23:20 mail postfix/postscreen[475040]: CONNECT from [127.0.0.1]:57742 to [127.0.0.1]:25
May 26 15:23:20 mail postfix/postscreen[475040]: ALLOWLISTED [127.0.0.1]:57742
May 26 15:23:20 mail postfix/postscreen[475040]: fatal: watchdog timeout
May 26 15:23:20 mail postfix/master[1957771]: warning: process /opt/zextras/common/libexec/postscreen pid 475040 exit status 1
May 26 15:23:21 mail postfix/master[1957771]: warning: unix_trigger_event: read timeout for service public/qmgr
May 26 15:23:22 mail postfix/postqueue[475112]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:22 mail postfix/smtpd[475078]: warning: connect #1 to subsystem private/proxymap: Connection refused
May 26 15:23:22 mail postfix/postqueue[475568]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:22 mail postfix/postscreen[475110]: CONNECT from [3.95.45.248]:4237 to [10.188.1.24]:25
May 26 15:23:22 mail postfix/postscreen[475672]: HANGUP after 0.02 from [3.95.45.248]:4237 in tests before SMTP handshake
May 26 15:23:22 mail postfix/postqueue[475673]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:22 mail postfix/postscreen[475672]: DISCONNECT [3.95.45.248]:4237
May 26 15:23:22 mail postfix/postqueue[475665]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:22 mail postfix/postqueue[475681]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:29 mail postfix/postqueue[476356]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:30 mail postfix/postqueue[476351]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:30 mail postfix/postqueue[476398]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:30 mail postfix/postqueue[476408]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:30 mail postfix/smtpd[475078]: warning: connect #2 to subsystem private/proxymap: Connection refused
May 26 15:23:31 mail postfix/postqueue[476386]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:31 mail postfix/postqueue[476419]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:31 mail postfix/postqueue[476387]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:32 mail postfix/postqueue[476533]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:32 mail postfix/postqueue[476519]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:32 mail postfix/postqueue[476495]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:33 mail postfix/postqueue[476628]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:33 mail postfix/postqueue[476570]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:33 mail postfix/postqueue[476606]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:39 mail /postfix-script[477090]: the Postfix mail system is not running
May 26 15:23:40 mail postfix/smtpd[475078]: warning: connect #3 to subsystem private/proxymap: Connection refused
May 26 15:23:50 mail postfix/smtpd[475078]: warning: connect #4 to subsystem private/proxymap: Connection refused
May 26 15:23:50 mail postfix/postqueue[477854]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:50 mail postfix/postqueue[477859]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:50 mail postfix/postqueue[477862]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:23:54 mail /postfix-script[478478]: the Postfix mail system is not running
May 26 15:24:00 mail postfix/smtpd[475078]: warning: connect #5 to subsystem private/proxymap: Connection refused
May 26 15:24:08 mail /postfix-script[479012]: the Postfix mail system is not running
May 26 15:24:10 mail postfix/smtpd[475078]: warning: connect #6 to subsystem private/proxymap: Connection refused
May 26 15:24:20 mail postfix/smtpd[475078]: warning: connect #7 to subsystem private/proxymap: Connection refused
May 26 15:24:20 mail postfix/postqueue[479172]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:24:20 mail postfix/postqueue[479177]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:24:20 mail postfix/postqueue[479180]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:24:30 mail postfix/smtpd[475078]: warning: connect #8 to subsystem private/proxymap: Connection refused
May 26 15:24:40 mail postfix/smtpd[475078]: warning: connect #9 to subsystem private/proxymap: Connection refused
May 26 15:24:49 mail /postfix-script[479674]: the Postfix mail system is not running
May 26 15:24:50 mail postfix/smtpd[475078]: warning: connect #10 to subsystem private/proxymap: Connection refused
May 26 15:24:50 mail postfix/postqueue[479759]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:24:50 mail postfix/postqueue[479764]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:24:50 mail postfix/postqueue[479768]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:00 mail postfix/smtpd[475078]: fatal: connect #11 to subsystem private/proxymap: Connection refused
May 26 15:25:20 mail postfix/postqueue[479945]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:20 mail postfix/postqueue[479950]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:20 mail postfix/postqueue[479953]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:50 mail postfix/postqueue[480148]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:50 mail postfix/postqueue[480153]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:50 mail postfix/postqueue[480156]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:25:56 mail /postfix-script[480485]: the Postfix mail system is not running
May 26 15:26:09 mail /postfix-script[481011]: the Postfix mail system is not running
May 26 15:26:20 mail postfix/postqueue[481166]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:26:20 mail postfix/postqueue[481171]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:26:20 mail postfix/postqueue[481174]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:26:50 mail postfix/postqueue[481370]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:26:50 mail postfix/postqueue[481375]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:26:50 mail postfix/postqueue[481378]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 26 15:27:03 mail /postfix-script[481745]: the Postfix mail system is not running

this is the restart command

May 28 06:57:01 mail /postfix-script[2425421]: the Postfix mail system is not running
May 28 06:57:28 mail postfix/postqueue[2425687]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 28 06:57:28 mail postfix/postqueue[2425692]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 28 06:57:28 mail postfix/postqueue[2425695]: fatal: Queue report unavailable - mail system is down (Connect to the Postfix showq service:
Connection refused)
May 28 06:57:37 mail /postfix-script[2426084]: the Postfix mail system is not running
May 28 06:57:49 mail zmmailboxdmgr[2426295]: stop requested
May 28 06:57:49 mail zmmailboxdmgr[2426295]: waiting for manager process 1956827 to die
May 28 06:57:49 mail zmmailboxdmgr[1956827]: shutdown requested, sending TERM signal to 1956828
May 28 06:57:50 mail zmmailboxdmgr[1956827]: mailboxd/JVM process exited (waitpid expected 1956828 got 1956828)
May 28 06:57:50 mail zmmailboxdmgr[1956827]: manager woke up from wait on mailboxd/JVM with pid 1956828
May 28 06:57:51 mail zmmailboxdmgr[2426295]: manager process 1956827 died, shutdown completed
May 28 06:57:56 mail /postfix-script[2426462]: fatal: the Postfix mail system is not running
May 28 06:58:07 mail amavis-mc[2119834]: Master process shutting down
May 28 06:58:09 mail amavis-mc[2119834]: Master process exiting: TERM
May 28 06:58:14 mail zmmailboxdmgr[2426919]: file /opt/zextras/log/zmmailboxd_manager.pid does not exist
May 28 06:58:14 mail zmmailboxdmgr[2426919]: assuming no other instance is running
May 28 06:58:14 mail zmmailboxdmgr[2426919]: file /opt/zextras/log/zmmailboxd.pid does not exist
May 28 06:58:14 mail zmmailboxdmgr[2426919]: assuming no other instance is running
May 28 06:58:14 mail zmmailboxdmgr[2426919]: no manager process is running
May 28 06:58:15 mail /postfix-script[2427024]: the Postfix mail system is not running
May 28 06:58:15 mail zmmailboxdmgr[2427043]: file /opt/zextras/log/zmmailboxd_manager.pid does not exist
May 28 06:58:15 mail zmmailboxdmgr[2427043]: assuming no other instance is running
May 28 06:58:15 mail zmmailboxdmgr[2427043]: file /opt/zextras/log/zmmailboxd.pid does not exist
May 28 06:58:15 mail zmmailboxdmgr[2427043]: assuming no other instance is running
May 28 06:58:15 mail zmmailboxdmgr[2427043]: no manager process is running
May 28 06:58:26 mail zmmailboxdmgr[2427359]: file /opt/zextras/log/zmmailboxd_manager.pid does not exist
May 28 06:58:26 mail zmmailboxdmgr[2427359]: assuming no other instance is running
May 28 06:58:26 mail zmmailboxdmgr[2427359]: file /opt/zextras/log/zmmailboxd.pid does not exist
May 28 06:58:26 mail zmmailboxdmgr[2427359]: assuming no other instance is running
May 28 06:58:26 mail zmmailboxdmgr[2427359]: no manager process is running
May 28 06:58:46 mail zmmailboxdmgr[2428852]: file /opt/zextras/log/zmmailboxd_manager.pid does not exist
May 28 06:58:46 mail zmmailboxdmgr[2428852]: assuming no other instance is running
May 28 06:58:46 mail zmmailboxdmgr[2428852]: file /opt/zextras/log/zmmailboxd.pid does not exist
May 28 06:58:46 mail zmmailboxdmgr[2428852]: assuming no other instance is running
May 28 06:58:46 mail zmmailboxdmgr[2428852]: no manager process is running
May 28 06:58:46 mail zmmailboxdmgr[2428863]: start requested
May 28 06:58:46 mail zmmailboxdmgr[2428863]: checking if another instance of manager is already running
May 28 06:58:46 mail zmmailboxdmgr[2428863]: file /opt/zextras/log/zmmailboxd_manager.pid does not exist
May 28 06:58:46 mail zmmailboxdmgr[2428863]: assuming no other instance is running
May 28 06:58:46 mail zmmailboxdmgr[2428863]: file /opt/zextras/log/zmmailboxd.pid does not exist
May 28 06:58:46 mail zmmailboxdmgr[2428863]: assuming no other instance is running
May 28 06:58:46 mail zmmailboxdmgr[2428865]: wrote manager pid 2428865 to /opt/zextras/log/zmmailboxd_manager.pid
May 28 06:58:46 mail zmmailboxdmgr[2428865]: manager started mailboxd/JVM with pid 2428866
May 28 06:58:46 mail zmmailboxdmgr[2428866]: wrote java pid 2428866 to /opt/zextras/log/zmmailboxd_java.pid
May 28 06:59:05 mail amavis-mc[2429331]: amavis master process starting. daemonized as PID [2429331], perl 5.034000
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429333] started: /opt/zextras/common/sbin/amavis-services msg-forwarder
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429336] started: /opt/zextras/common/sbin/amavis-services childproc-minder
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429337] started: /opt/zextras/common/sbin/amavis-services snmp-responder
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429333] exited (/opt/zextras/common/sbin/amavis-services msg-forwarder) after 0.0 s: exit
2
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429337] exited (/opt/zextras/common/sbin/amavis-services snmp-responder) after 0.0 s: exit
2
May 28 06:59:05 mail amavis-mc[2429331]: Process [2429336] exited (/opt/zextras/common/sbin/amavis-services childproc-minder) after 0.0 s: ex
it 2
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429511] started: /opt/zextras/common/sbin/amavis-services msg-forwarder
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429512] started: /opt/zextras/common/sbin/amavis-services childproc-minder
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429513] started: /opt/zextras/common/sbin/amavis-services snmp-responder
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429512] exited (/opt/zextras/common/sbin/amavis-services childproc-minder) after 0.0 s: ex
it 2
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429513] exited (/opt/zextras/common/sbin/amavis-services snmp-responder) after 0.0 s: exit
2
May 28 06:59:10 mail amavis-mc[2429331]: Process [2429511] exited (/opt/zextras/common/sbin/amavis-services msg-forwarder) after 0.1 s: exit
2
May 28 06:59:15 mail amavis-mc[2429331]: Process [2429567] started: /opt/zextras/common/sbin/amavis-services msg-forwarder
May 28 06:59:15 mail amavis-mc[2429331]: Process [2429568] started: /opt/zextras/common/sbin/amavis-services childproc-minder
May 28 06:59:15 mail amavis-mc[2429331]: Process [2429569] started: /opt/zextras/common/sbin/amavis-services snmp-responder
May 28 06:59:16 mail amavis-mc[2429331]: Process [2429567] exited (/opt/zextras/common/sbin/amavis-services msg-forwarder) after 0.0 s: exit
2
May 28 06:59:16 mail amavis-mc[2429331]: Process [2429568] exited (/opt/zextras/common/sbin/amavis-services childproc-minder) after 0.0 s: ex
it 2
May 28 06:59:16 mail amavis-mc[2429331]: Process [2429569] exited (/opt/zextras/common/sbin/amavis-services snmp-responder) after 0.0 s: exit
2
May 28 06:59:19 mail /postfix-script[2429751]: the Postfix mail system is not running
May 28 06:59:20 mail /postfix-script[2429838]: starting the Postfix mail system
May 28 06:59:20 mail postfix/master[2429840]: daemon started -- version 3.8.4, configuration /opt/zextras/common/conf
May 28 06:59:20 mail postfix/qmgr[2429842]: 32522FD16F: from=<contatti@asmeform.it>, size=3396, nrcpt=1 (queue active)
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429910] started: /opt/zextras/common/sbin/amavis-services msg-forwarder
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429911] started: /opt/zextras/common/sbin/amavis-services childproc-minder
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429912] started: /opt/zextras/common/sbin/amavis-services snmp-responder
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429911] exited (/opt/zextras/common/sbin/amavis-services childproc-minder) after 0.0 s: ex
it 2
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429910] exited (/opt/zextras/common/sbin/amavis-services msg-forwarder) after 0.0 s: exit
2
May 28 06:59:21 mail amavis-mc[2429331]: Process [2429912] exited (/opt/zextras/common/sbin/amavis-services snmp-responder) after 0.1 s: exit
2
May 28 06:59:21 mail postfix/postscreen[2429922]: CONNECT from [127.0.0.1]:42362 to [127.0.0.1]:25
May 28 06:59:21 mail postfix/postscreen[2429922]: ALLOWLISTED [127.0.0.1]:42362
May 28 06:59:21 mail postfix/postscreen[2429922]: cache lmdb:/opt/zextras/data/postfix/data/postscreen_cache full cleanup: retained=425 dropp
ed=38 entries
May 28 06:59:21 mail postfix/smtpd[2429924]: connect from localhost[127.0.0.1]
May 28 06:59:21 mail postfix/smtpd[2429924]: warning: connect to Milter service inet:127.0.0.1:7026: Connection refused
May 28 06:59:21 mail postfix/smtpd[2429924]: NOQUEUE: milter-reject: CONNECT from localhost[127.0.0.1]: 451 4.7.1 Service unavailable - try a
gain later; proto=SMTP
May 28 06:59:21 mail postfix/smtpd[2429924]: lost connection after CONNECT from localhost[127.0.0.1]
May 28 06:59:21 mail postfix/smtpd[2429924]: disconnect from localhost[127.0.0.1] commands=0/0
May 28 06:59:22 mail postfix/smtp[2429934]: warning: DNSSEC validation may be unavailable
May 28 06:59:22 mail postfix/smtp[2429934]: warning: reason: dnssec_probe 'ns:.' received a response that is not DNSSEC validated
May 28 06:59:22 mail postfix/smtp[2429934]: 32522FD16F: to=<izetta.stow43@erpin.org>, relay=mail.erpin.org[107.191.101.160]:25, delay=185192,
delays=185190/1.8/0.4/0, dsn=4.0.0, status=deferred (host mail.erpin.org[107.191.101.160] refused to talk to me: 421 Too many concurrent SMT
P connections; please try again later.)

 

 

thanks

 


   
ReplyQuote
(@stefanodavid)
Joined: 3 years ago
Posts: 167
 

You can check (as zextras user) the output of zmcontrol status

 

Also (as root) journalctl -u carbonio-mta and journalctl -u carbonio-postfix  will display thelog messages. To check the status of the service, use systemctl status carbonio-mta and systemctl status carbonio-postfix


   
ReplyQuote
(@sharif)
Admin
Joined: 2 years ago
Posts: 433
 

@gpgp01

Hi,

Could you please share the output of the following commands:

# cat /etc/hostname

AND

# cat /etc/hosts

AND

# su - zextras -c "postfix check"

AND

$ zmmtactl restart (And the log during the restart)

 

BTW, what is your OS and Carbonio CE version?

 

Regards,

Sharif


   
ReplyQuote