Here we go .
I did an rsync backup of Release 7.1.2_GA_3268.RHEL6_64_20110804131406 CentOS6_64 FOSS edition.
Installed a new server ( centos 6.3 instead of 6.0, on the same ip as the old server , took over the config files for users , hosts , dns , etc .
Everything is oke there.
I installed zimbra with the -s option on the new server . Then did a rsync of the backup to /opt/zimbra and executed fixperms .
Started up zimbra and .... Mails stay in the deferred queue .
"Sep 21 13:10:52 mail postfix/smtp[26072]: 93716380766: to=<marc.bueds@isopix.be>, relay=127.0.0.1[127.0.0.1]:10024, delay=1458, delays=1458/0.01/0/0.02, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to RCPT TO command))" is the message in /var/log/maillog file
Because my logging wasn't working since an upgrade from a 6 version to 7.1.3 , i had no info in /var/log/zimbra.log.
So first i tried to repair by just using the log files in /opt/zimbra/log for info and the deferred queue info.Seems as if the smtp server can't hand down theh message to/from smtp-amavis on port 10024 when i understand well . Disabling antivirus/antisām makes the mail work . All the articles around this subject didn' t help me out ( refreshing clamav etc .
However , in the meanwhile i got logging up and running again and i found this error message during zimbtra startup :
Sep 21 15:22:34 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:34 mail slapd[1979]: slap_queue_csn: queing 0x7fc2466bf3a0 20120921132234.799541Z#000000#000#000000
Sep 21 15:22:34 mail slapd[1979]: slap_graduate_commit_csn: removing 0x32cb5a0 20120921132234.799541Z#000000#000#000000
Sep 21 15:22:34 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:34 mail slapd[1979]: slap_queue_csn: queing 0x7fc2456bd3a0 20120921132234.969536Z#000000#000#000000
Sep 21 15:22:34 mail slapd[1979]: slap_graduate_commit_csn: removing 0x1a625d0 20120921132234.969536Z#000000#000#000000
Sep 21 15:22:34 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:34 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:35 mail slapd[1979]: slap_queue_csn: queing 0x7fc2456bd3a0 20120921132235.054956Z#000000#000#000000
Sep 21 15:22:35 mail slapd[1979]: slap_graduate_commit_csn: removing 0x32cb210 20120921132235.054956Z#000000#000#000000
Sep 21 15:22:35 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:35 mail slapd[1979]: connection_read(13): no connection!
Sep 21 15:22:35 mail slapd[1979]: slap_queue_csn: queing 0x7fc245ebe3a0 2
Seems like there is something wrong with ldap . I remember now that during the upgrade on then new server , the spam and antivirus user could not be verified automatically .
Something to do with this ldap error ??
And amavis can't connect to slapd , is written in thehn zimbra log file ( see under )
Can this be repaired !!!!
=========zimbra.log file afetr shutdown and startup ====
Sep 21 15:27:51 mail zimbramon[7032]: 7032:info: Stopping ldap via zmcontrol
Sep 21 15:27:51 mail slapd[1979]: daemon: shutdown requested and initiated.
Sep 21 15:27:51 mail slapd[1979]: slapd shutdown: waiting for 0 operations/tasks to finish
Sep 21 15:27:51 mail slapd[1979]: slapd stopped.
Sep 21 15:28:24 mail clamd[7941]: Pid file removed.
Sep 21 15:28:24 mail clamd[7941]: --- Stopped at Fri Sep 21 15:28:24 2012
Sep 21 15:28:41 mail amavis[7830]: Net::Server: 2012/09/21-15:28:41 Server closing!
Sep 21 15:28:54 mail zimbramon[8012]: 8012:info: Starting services initiated by zmcontrol
Sep 21 15:28:54 mail slapd[8070]: @(#) $OpenLDAP: slapd 2.4.25 (Jul 6 2011 13:31:49) $#012#011build@zre-rhel6-64.eng.vmware.com:/home/build/p4/HELIX/ThirdParty/openldap/openldap-2.4.25.4z/servers/slapd
Sep 21 15:28:54 mail slapd[8071]: slapd starting
Sep 21 15:28:59 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:02 mail zimbramon[8012]: 8012:info: Starting zmconfigd via zmcontrol
Sep 21 15:29:10 mail slapd[8071]: slap_queue_csn: queing 0x7fe73632b3a0 20120921132910.724619Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: slap_graduate_commit_csn: removing 0x27f9270 20120921132910.724619Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: slap_queue_csn: queing 0x7fe735b2a3a0 20120921132910.854923Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: slap_graduate_commit_csn: removing 0x27c5c00 20120921132910.854923Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:10 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:10 mail slapd[8071]: slap_queue_csn: queing 0x7fe735b2a3a0 20120921132910.980862Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: slap_graduate_commit_csn: removing 0x27c5d20 20120921132910.980862Z#000000#000#000000
Sep 21 15:29:10 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:10 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
........
Sep 21 15:29:11 mail slapd[8071]: slap_queue_csn: queing 0x7fe734b283a0 20120921132911.650395Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: slap_graduate_commit_csn: removing 0xb5eb40 20120921132911.650395Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: slap_queue_csn: queing 0x7fe734b283a0 20120921132911.736345Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: slap_graduate_commit_csn: removing 0xb5eb10 20120921132911.736345Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: connection_read(13): no connection!
Sep 21 15:29:11 mail slapd[8071]: slap_queue_csn: queing 0x7fe734b283a0 20120921132911.822835Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: slap_graduate_commit_csn: removing 0xb5eb40 20120921132911.822835Z#000000#000#000000
Sep 21 15:29:11 mail slapd[8071]: slap_queue_csn: queing 0x7fe7353293a0 20120921132911.996648Z#000000#000#000000
............
Sep 21 15:29:41 mail zimbramon[8012]: 8012:info: Starting logger via zmcontrol
Sep 21 15:29:44 mail zimbramon[8012]: 8012:info: Starting mailbox via zmcontrol
Sep 21 15:29:46 mail zmmailboxdmgr[9283]: status requested
Sep 21 15:29:46 mail zmmailboxdmgr[9283]: file /opt/zimbra/log/zmmailboxd_manager.pid does not exist
...................
Sep 21 15:29:46 mail zmmailboxdmgr[9295]: wrote manager pid 9295 to /opt/zimbra/log/zmmailboxd_manager.pid
Sep 21 15:29:46 mail zmmailboxdmgr[9295]: manager started mailboxd/JVM with pid 9296
Sep 21 15:29:46 mail zmmailboxdmgr[9296]: wrote java pid 9296 to /opt/zimbra/log/zmmailboxd_java.pid
Sep 21 15:29:46 mail zimbramon[8012]: 8012:info: Starting antispam via zmcontrol
Sep 21 15:29:46 mail amavis[9364]: starting. /opt/zimbra/amavisd/sbin/amavisd at mail.isopix.be amavisd-new-2.6.6 (20110518), Unicode aware, LC_ALL="C", LANG="C"
Sep 21 15:29:46 mail amavis[9364]: user=10091, EUID: 10091 (10091); group=, EGID: 10092 4 5 10092 10093 (10092 4 5 10092 10093)
Sep 21 15:29:46 mail amavis[9364]: Perl version 5.010001
Sep 21 15:29:46 mail amavis[9364]: SpamControl: init_pre_chroot on SpamAssassin done
Sep 21 15:29:46 mail amavis[9369]: Net::Server: Process Backgrounded
Sep 21 15:29:46 mail amavis[9369]: Net::Server: 2012/09/21-15:29:46 Amavis (type Net::Server::PreForkSimple) starting! pid(9369)
Sep 21 15:29:46 mail amavis[9369]: Net::Server: Using default listen value of 128
.................................................. ...........................
Sep 21 15:29:46 mail amavis[9369]: Using primary internal av scanner code for ClamAV-clamd
Sep 21 15:29:46 mail amavis[9369]: initializing Mail::SpamAssassin
Sep 21 15:29:47 mail zimbramon[8012]: 8012:info: Starting antivirus via zmcontrol
Sep 21 15:29:47 mail clamd[9467]: clamd daemon 0.97.1-broken-compiler (OS: linux-gnu, ARCH: x86_64, CPU: x86_64)
Sep 21 15:29:47 mail clamd[9467]: Log file size limited to 20971520 bytes.
Sep 21 15:29:47 mail clamd[9467]: Reading databases from /opt/zimbra/data/clamav/db
Sep 21 15:29:47 mail clamd[9467]: Not loading PUA signatures.
Sep 21 15:29:47 mail clamd[9467]: Bytecode: Security mode set to "TrustSigned".
Sep 21 15:29:47 mail amavis[9369]: SpamControl: init_pre_fork on SpamAssassin done
Sep 21 15:29:47 mail amavis[9369]: extra modules loaded after daemonizing/chrooting: Mail/SpamAssassin/Plugin/FreeMail.pm
Sep 21 15:29:52 mail clamd[9467]: Loaded 1302628 signatures.
Sep 21 15:29:52 mail clamd[9467]: TCP: Bound to address 127.0.0.1 on port 3310
Sep 21 15:29:52 mail clamd[9467]: TCP: Setting connection queue length to 200
Sep 21 15:29:52 mail clamd[9534]: Limits: Global size limit set to 102400000 bytes.
Sep 21 15:29:52 mail clamd[9534]: Limits: File size limit set to 102400000 bytes.
Sep 21 15:29:52 mail clamd[9534]: Limits: Recursion level limit set to 16.
Sep 21 15:29:52 mail clamd[9534]: Limits: Files limit set to 10000.
Sep 21 15:29:52 mail clamd[9534]: Archive support enabled.
Sep 21 15:29:52 mail clamd[9534]: Archive: Blocking encrypted archives.
Sep 21 15:29:52 mail clamd[9534]: Algorithmic detection enabled.
Sep 21 15:29:52 mail clamd[9534]: Portable Executable support enabled.
Sep 21 15:29:52 mail clamd[9534]: ELF support enabled.
Sep 21 15:29:52 mail clamd[9534]: Mail files support enabled.
Sep 21 15:29:52 mail clamd[9534]: OLE2 support enabled.
Sep 21 15:29:52 mail clamd[9534]: PDF support enabled.
Sep 21 15:29:52 mail clamd[9534]: HTML support enabled.
Sep 21 15:29:52 mail clamd[9534]: Self checking every 600 seconds.
Sep 21 15:29:57 mail zimbramon[8012]: 8012:info: Starting snmp via zmcontrol
Sep 21 15:29:57 mail zimbramon[8012]: 8012:info: Starting spell via zmcontrol
Sep 21 15:29:57 mail zimbramon[8012]: 8012:info: Starting mta via zmcontrol
Sep 21 15:29:58 mail postfix/postfix-script[9750]: warning: not owned by root: /opt/zimbra/postfix-2.7.4.2z/conf/main.cf
Sep 21 15:29:58 mail postfix/postfix-script[9751]: warning: not owned by root: /opt/zimbra/postfix-2.7.4.2z/conf/master.cf
Sep 21 15:29:58 mail postfix/postfix-script[9752]: warning: not owned by root: /opt/zimbra/postfix-2.7.4.2z/conf/master.cf.in
Sep 21 15:29:58 mail postfix/postfix-script[9769]: starting the Postfix mail system
Sep 21 15:29:58 mail saslauthd[9776]: detach_tty : master pid is: 9776
Sep 21 15:29:58 mail saslauthd[9776]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.23.3z/state/mux
Sep 21 15:29:58 mail zimbramon[8012]: 8012:info: Starting stats via zmcontrol
Sep 21 15:29:58 mail postfix/master[9770]: daemon started -- version 2.7.4, configuration /opt/zimbra/postfix-2.7.4.2z/conf
Sep 21 15:30:13 mail zmmailboxdmgr[10787]: status requested
Sep 21 15:30:13 mail zmmailboxdmgr[10787]: status OK
Sep 21 15:30:13 mail zmmailboxdmgr[10796]: status requested
Sep 21 15:30:13 mail zmmailboxdmgr[10796]: status OK
Sep 21 15:30:18 mail clamd[9534]: Reading databases from /opt/zimbra/data/clamav/db
Sep 21 15:30:20 mail postfix/smtpd[10965]: connect from unknown[223.164.255.170]
Sep 21 15:30:21 mail postfix/smtpd[10965]: F3523380760: client=unknown[223.164.255.170]
Sep 21 15:30:22 mail clamd[9534]: Database correctly reloaded (1044423 signatures)
Sep 21 15:30:22 mail postfix/cleanup[10970]: F3523380760: message-id=<AC6EEEE731F4DA501F4DA52525252C6E@HAYDENMYRTLE>
Sep 21 15:30:22 mail postfix/qmgr[9785]: F3523380760: from=<KonnerDeyon@covad.net>, size=2131, nrcpt=1 (queue active)
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!)connect_to_ldap: unable to connect to host ldap://mail.isopix.be:389
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!!)TROUBLE in process_request: connect_to_ldap: unable to connect at (eval 98) line 104, <GEN14> line 5.
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!)Requesting process rundown after fatal error
Sep 21 15:30:23 mail postfix/smtp[10971]: F3523380760: to=<bernadette.lepers@isopix.be>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=1/0/0/0.02, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to RCPT TO command))
Sep 21 15:30:23 mail postfix/smtpd[10965]: disconnect from unknown[223.164.255.170]
Sep 21 15:31:14 mail zmmailboxdmgr[11127]: status requested
Sep 21 15:31:14 mail zmmailboxdmgr[11127]: status OK
Sep 21 15:31:14 mail zmmailboxdmgr[11136]: status requested
Sep 21 15:31:14 mail zmmailboxdmgr[11136]: status OK
Sep 21 15:30:18 mail clamd[9534]: Reading databases from /opt/zimbra/data/clamav/db
Sep 21 15:30:20 mail postfix/smtpd[10965]: connect from unknown[223.164.255.170]
Sep 21 15:30:21 mail postfix/smtpd[10965]: F3523380760: client=unknown[223.164.255.170]
Sep 21 15:30:22 mail clamd[9534]: Database correctly reloaded (1044423 signatures)
Sep 21 15:30:22 mail postfix/cleanup[10970]: F3523380760: message-id=<AC6EEEE731F4DA501F4DA52525252C6E@HAYDENMYRTLE>
Sep 21 15:30:22 mail postfix/qmgr[9785]: F3523380760: from=<KonnerDeyon@covad.net>, size=2131, nrcpt=1 (queue active)
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!)connect_to_ldap: unable to connect to host ldap://mail.isopix.be:389
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!!)TROUBLE in process_request: connect_to_ldap: unable to connect at (eval 98) line 104, <GEN14> line 5.
Sep 21 15:30:23 mail amavis[9472]: (09472-01) (!)Requesting process rundown after fatal error
Sep 21 15:30:23 mail postfix/smtp[10971]: F3523380760: to=<bernadette.lepers@isopix.be>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=1/0/0/0.02, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to RCPT TO command))
Sep 21 15:30:23 mail postfix/smtpd[10965]: disconnect from unknown[223.164.255.170]
Sep 21 15:31:14 mail zmmailboxdmgr[11127]: status requested
Sep 21 15:31:14 mail zmmailboxdmgr[11127]: status OK
Sep 21 15:31:14 mail zmmailboxdmgr[11136]: status requested
Sep 21 15:31:14 mail zmmailboxdmgr[11136]: status OK
Sep 21 15:31:38 mail postfix/smtpd[10965]: connect from rtbf-smtp.infomaniak.ch[84.16.66.83]
Sep 21 15:31:38 mail postfix/smtpd[10965]: 570623807B1: client=rtbf-smtp.infomaniak.ch[84.16.66.83]
Sep 21 15:31:38 mail postfix/cleanup[10970]: 570623807B1: message-id=<201209211332.q8LDW23P001909@smtp-ml.infomaniak.ch>
Sep 21 15:31:38 mail postfix/qmgr[9785]: 570623807B1: from=<presse@rtbf.be>, size=1818, nrcpt=1 (queue active)
Sep 21 15:31:38 mail amavis[9473]: (09473-01) (!)connect_to_ldap: unable to connect to host ldap://mail.isopix.be:389
Sep 21 15:31:38 mail amavis[9473]: (09473-01) (!!)TROUBLE in process_request: connect_to_ldap: unable to connect at (eval 98) line 104, <GEN14> line 5.
Sep 21 15:31:38 mail amavis[9473]: (09473-01) (!)Requesting process rundown after fatal error
Sep 21 15:31:38 mail postfix/smtp[10971]: 570623807B1: to=<gino@isopix.be>, orig_to=<infos@isopix.be>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.08, delays=0.07/0/0/0.01, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to RCPT TO command))
Sep 21 15:31:38 mail postfix/smtpd[10965]: disconnect from rtbf-smtp.infomaniak.ch[84.16.66.83]
Sep 21 15:31:41 mail clamd[9534]: Reading databases from /opt/zimbra/data/clamav/db
Sep 21 15:31:44 mail clamd[9534]: Database correctly reloaded (1044423 signatures)
Sep 21 15:31:54 mail postfix/smtpd[10965]: connect from unknown[200.0.0.163]
Sep 21 15:31:54 mail postfix/smtpd[10965]: 7D11A38076C: client=unknown[200.0.0.163]
Sep 21 15:31:54 mail postfix/cleanup[10970]: 7D11A38076C: message-id=<20120921133154.7D11A38076C@mail.isopix.be>
Sep 21 15:31:54 mail postfix/qmgr[9785]: 7D11A38076C: from=<KRISTOF@isopix.be>, size=36676, nrcpt=53 (queue active)
Sep 21 15:31:54 mail postfix/smtpd[10965]: 87C6C3807E6: client=unknown[200.0.0.163]
Sep 21 15:31:54 mail postfix/cleanup[10970]: 87C6C3807E6: message-id=<20120921133154.87C6C3807E6@mail.isopix.be>
Sep 21 15:31:54 mail amavis[9474]: (09474-01) (!)connect_to_ldap: unable to connect to host ldap://mail.isopix.be:389
Sep 21 15:31:54 mail amavis[9474]: (09474-01) (!!)TROUBLE in process_request: connect_to_ldap: unable to connect at (eval 98) line 104, <GEN14> line 5.
Sep 21 15:31:54 mail postfix/qmgr[9785]: 87C6C3807E6: from=<KRISTOF@isopix.be>, size=36662, nrcpt=1 (queue active)
Sep 21 15:31:54 mail amavis[9474]: (09474-01) (!)Requesting process rundown after fatal error
Sep 21 15:31:54 mail postfix/smtp[10971]: 7D11A38076C: to=<m.ginfray@belgomedia.be>, orig_to=<send-people-all@isopix.be>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.05, delays=0.04/0/0/0.01, dsn=4.3.2, status=deferred (host 127.0.0.1[127.0.0.1] said: 421 4.3.2 Service shutting down, closing channel (in reply to RCPT TO command))
S


LinkBack URL
About LinkBacks

