[SOLVED] Zimbra don't work - zmmailboxd

[dmalherbe]

Hi,
OS : centos 5.1 zimbra : last version
My zimbra server works well for some week and after update zmmailboxd dans want to start anymore

my log zmmailboxd.out say that (probably must change my port 7025 ip binding, don't know how):
Zimbra server reserving server socket port=110 bindaddr=mail.edgeoya.com ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=true
Zimbra server reserving server socket port=7025 bindaddr=xxx.xxx.xxx.xxx ssl=false (where xxx.xxx.xxx.xxx is my server public adress not the local ip adress)
Fatal error: exception while binding to ports
java.net.BindException: Cannot assign requested address
at java.net.PlainSocketImpl.socketBind(Native Method)
at java.net.PlainSocketImpl.bind(PlainSocketImpl.java :359)
at java.net.ServerSocket.bind(ServerSocket.java:319)
at com.zimbra.common.util.NetUtil.newBoundServerSocke t(NetUtil.java:106)
at com.zimbra.common.util.NetUtil.bindServerSocket(Ne tUtil.java:165)
at com.zimbra.common.util.NetUtil.bindTcpServerSocket (NetUtil.java:52)
at com.zimbra.common.handlers.PrivilegedHandler.openP orts(PrivilegedHandler.java:124)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Nativ e Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Native MethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(De legatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.mortbay.xml.XmlConfiguration.call(XmlConfigura tion.java:534)
at org.mortbay.xml.XmlConfiguration.configure(XmlConf iguration.java:244)
at org.mortbay.xml.XmlConfiguration.configure(XmlConf iguration.java:206)
at org.mortbay.xml.XmlConfiguration.main(XmlConfigura tion.java:968)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Nativ e Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Native MethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(De legatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.mortbay.start.Main.invokeMain(Main.java:183)
at org.mortbay.start.Main.start(Main.java:497)
at org.mortbay.start.Main.main(Main.java:115)

My zimbra. log say that :
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: antispam: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: antivirus: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: ldap: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: logger: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: mailbox: Stopped
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: mta: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: snmp: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: spell: Running
Nov 20 10:18:04 mail zimbramon[30215]: 30215:info: 2008-11-20 10:18:01, STATUS: mail.edgeoya.com: stats: Running
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: status requested
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: assuming no other instance is running
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: assuming no other instance is running
Nov 20 10:18:38 mail zmmailboxdmgr[30858]: no manager process is running
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: status requested
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: assuming no other instance is running
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: assuming no other instance is running
Nov 20 10:18:38 mail zmmailboxdmgr[30918]: no manager process is running
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: status requested
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: assuming no other instance is running
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: assuming no other instance is running
Nov 20 10:19:45 mail zmmailboxdmgr[31287]: no manager process is running
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: status requested
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: assuming no other instance is running
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: assuming no other instance is running
Nov 20 10:19:45 mail zmmailboxdmgr[31347]: no manager process is running
Nov 20 10:20:02 mail zimbramon[31421]: 31421:info: 2008-11-20 10:20:01, QUEUE: 103 9
Nov 20 10:20:02 mail zimbramon[31429]: 31429:info: 2008-11-20 10:20:01, DISK: mail.edgeoya.com: dev: /dev/sda3, mp: /, tot: 47514, avail: 41495
Nov 20 10:20:02 mail zimbramon[31429]: 31429:info: 2008-11-20 10:20:01, DISK: mail.edgeoya.com: dev: /dev/sda1, mp: /boot, tot: 99, avail: 70
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: status requested
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: assuming no other instance is running
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: assuming no other instance is running
Nov 20 10:20:04 mail zmmailboxdmgr[31737]: no manager process is running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: antispam: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: antivirus: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: ldap: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: logger: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: mailbox: Stopped
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: mta: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: snmp: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: spell: Running
Nov 20 10:20:04 mail zimbramon[31430]: 31430:info: 2008-11-20 10:20:01, STATUS: mail.edgeoya.com: stats: Running
Nov 20 10:20:19 mail postfix/anvil[29769]: statistics: max connection rate 1/60s for (smtp:80.118.49.243) at Nov 20 10:16:51
Nov 20 10:20:19 mail postfix/anvil[29769]: statistics: max connection count 1 for (smtp:80.118.49.243) at Nov 20 10:16:51
Nov 20 10:20:19 mail postfix/anvil[29769]: statistics: max cache size 2 at Nov 20 10:16:55
Nov 20 10:20:33 mail postfix/qmgr[4344]: D9794A2BAF6: from=<forums@zimbra.com>, size=3361, nrcpt=1 (queue active)
Nov 20 10:20:33 mail postfix/qmgr[4344]: 829F9A2BAF5: from=<md2218-ovh@nichandle.ovh.net>, size=4552, nrcpt=1 (queue active)
Nov 20 10:20:33 mail postfix/lmtp[31868]: connect to mail.edgeoya.com[10.0.0.2]: Connection refused (port 7025)
Nov 20 10:20:33 mail postfix/lmtp[31869]: connect to mail.edgeoya.com[10.0.0.2]: Connection refused (port 7025)
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: status requested
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: assuming no other instance is running
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: assuming no other instance is running
Nov 20 10:20:52 mail zmmailboxdmgr[32163]: no manager process is running
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: status requested
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: stale pid 3675 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: assuming no other instance is running
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: assuming no other instance is running
Nov 20 10:20:52 mail zmmailboxdmgr[32223]: no manager process is running

Thanks for your help

[uxbod]

cat /etc/hosts
cat /etc/resolv.conf
dig yourdomainname mx
dig yourdomainname any
host `hostname` <- note backticks and not single quotes
zmcontrol stop
netstat -an

[dmalherbe]

thank's for your answer
what it's suppose to do ? nothing change after this command
do you know wher i can change lmtp server binding ? (conf file)

[uxbod]

Why do you want to change the binding port ? Please post the output of those commands so we can verify your setup.

[dmalherbe]

[root@mail ~]# cat /etc/hosts
# Do not remove the following line, or various programs
# that require network functionality will fail.
127.0.0.1 localhost.localdomain localhost
10.0.0.2 mail.edgeoya.com mail
::1 localhost6.localdomain6 localhost6
[root@mail ~]# cat /etc/resolv.conf
; generated by /sbin/dhclient-script
search edgeoya.com
nameserver 10.0.0.1
[root@mail ~]# dig edgeoya.com mx

; <<>> DiG 9.3.4-P1 <<>> edgeoya.com mx
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 21147
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1

;; QUESTION SECTION:
;edgeoya.com. IN MX

;; ANSWER SECTION:
edgeoya.com. 86400 IN MX 1 mail.edgeoya.com.

;; ADDITIONAL SECTION:
mail.edgeoya.com. 86400 IN A 91.121.51.143

;; Query time: 29 msec
;; SERVER: 10.0.0.1#53(10.0.0.1)
;; WHEN: Thu Nov 20 11:22:25 2008
;; MSG SIZE rcvd: 66
[root@mail ~]# dig edgeoya.com any

; <<>> DiG 9.3.4-P1 <<>> edgeoya.com any
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 7744
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1

;; QUESTION SECTION:
;edgeoya.com. IN ANY

;; ANSWER SECTION:
edgeoya.com. 86343 IN MX 1 mail.edgeoya.com.

;; ADDITIONAL SECTION:
mail.edgeoya.com. 86343 IN A 91.121.51.143

;; Query time: 451 msec
;; SERVER: 10.0.0.1#53(10.0.0.1)
;; WHEN: Thu Nov 20 11:23:22 2008
;; MSG SIZE rcvd: 66
[root@mail libexec]# netstat -an
Connexions Internet actives (serveurs et établies)
Proto Recv-Q Send-Q Local Address Foreign Address State
tcp 0 0 0.0.0.0:750 0.0.0.0:* LISTEN
tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN
tcp 0 0 0.0.0.0:10000 0.0.0.0:* LISTEN
tcp 0 0 :::22 :::* LISTEN
tcp 0 0 ::1:35371 ::1:52632 TIME_WAIT
tcp 0 0 ::ffff:10.0.0.2:22 ::ffff:81.56.45.58:63072 ESTABLISHED
tcp 0 0 ::1:56383 ::1:46220 TIME_WAIT
tcp 0 0 ::1:37725 ::1:53022 TIME_WAIT
tcp 0 0 ::ffff:10.0.0.2:22 ::ffff:81.56.45.58:63981 ESTABLISHED
tcp 0 0 ::ffff:10.0.0.2:22 ::ffff:81.56.45.58:63991 ESTABLISHED
tcp 0 0 ::1:47695 ::1:45147 TIME_WAIT
udp 0 0 0.0.0.0:10000 0.0.0.0:*
udp 0 0 0.0.0.0:68 0.0.0.0:*
udp 0 0 0.0.0.0:59205 0.0.0.0:*
udp 0 0 0.0.0.0:744 0.0.0.0:*
udp 0 0 0.0.0.0:5353 0.0.0.0:*
udp 0 0 0.0.0.0:747 0.0.0.0:*
udp 0 0 0.0.0.0:111 0.0.0.0:*
udp 0 0 :::5353 :::*
udp 0 0 :::49021 :::*
Sockets du domaine UNIX actives(serveurs et établies)
Proto RefCpt Indicatrs Type Etat I-Node Chemin
unix 2 [ ACC ] STREAM LISTENING 5537 @/var/run/hald/dbus-AVAN9OMCr4
unix 2 [ ACC ] STREAM LISTENING 5360 /tmp/.font-unix/fs7100
unix 2 [ ACC ] STREAM LISTENING 5001 /var/run/dbus/system_bus_socket
unix 2 [ ACC ] STREAM LISTENING 6397 @/tmp/fam-root-
unix 2 [ ACC ] STREAM LISTENING 5095 /var/run/pcscd.comm
unix 2 [ ACC ] STREAM LISTENING 5199 /var/run/acpid.socket
unix 11 [ ] DGRAM 4647 /dev/log
unix 2 [ ] DGRAM 1159 @/org/kernel/udev/udevd
unix 2 [ ACC ] STREAM LISTENING 5495 /var/run/avahi-daemon/socket
unix 2 [ ACC ] STREAM LISTENING 5536 @/var/run/hald/dbus-uNU7blsYqw
unix 2 [ ] DGRAM 5545 @/org/freedesktop/hal/udev_event
unix 2 [ ACC ] STREAM LISTENING 5307 /dev/gpmctl
unix 2 [ ACC ] STREAM LISTENING 4588 /var/run/audispd_events
unix 2 [ ] DGRAM 53213
unix 3 [ ] STREAM CONNECTE 52519
unix 3 [ ] STREAM CONNECTE 52518
unix 3 [ ] STREAM CONNECTE 52517
unix 3 [ ] STREAM CONNECTE 52516
unix 3 [ ] STREAM CONNECTE 10076
unix 3 [ ] STREAM CONNECTE 10075
unix 3 [ ] STREAM CONNECTE 10074
unix 3 [ ] STREAM CONNECTE 10073
unix 3 [ ] STREAM CONNECTE 6420 @/tmp/fam-root-
unix 3 [ ] STREAM CONNECTE 6408
unix 3 [ ] STREAM CONNECTE 6352 /var/run/dbus/system_bus_socket
unix 3 [ ] STREAM CONNECTE 6351
unix 2 [ ] DGRAM 6337
unix 3 [ ] STREAM CONNECTE 6238 /var/run/dbus/system_bus_socket
unix 3 [ ] STREAM CONNECTE 6237
unix 3 [ ] STREAM CONNECTE 6206 @/var/run/hald/dbus-uNU7blsYqw
unix 3 [ ] STREAM CONNECTE 6205
unix 3 [ ] STREAM CONNECTE 6071 @/var/run/hald/dbus-uNU7blsYqw
unix 3 [ ] STREAM CONNECTE 6069
unix 3 [ ] STREAM CONNECTE 6040 /var/run/acpid.socket
unix 3 [ ] STREAM CONNECTE 6039
unix 3 [ ] STREAM CONNECTE 6034 @/var/run/hald/dbus-uNU7blsYqw
unix 3 [ ] STREAM CONNECTE 6033
unix 3 [ ] STREAM CONNECTE 5540 @/var/run/hald/dbus-AVAN9OMCr4
unix 3 [ ] STREAM CONNECTE 5539
unix 3 [ ] STREAM CONNECTE 5498 /var/run/dbus/system_bus_socket
unix 3 [ ] STREAM CONNECTE 5497
unix 3 [ ] STREAM CONNECTE 5492
unix 3 [ ] STREAM CONNECTE 5491
unix 2 [ ] DGRAM 5489
unix 2 [ ] DGRAM 5309
unix 2 [ ] DGRAM 5296
unix 2 [ ] DGRAM 5151
unix 2 [ ] DGRAM 5094
unix 3 [ ] STREAM CONNECTE 5004
unix 3 [ ] STREAM CONNECTE 5003
unix 3 [ ] STREAM CONNECTE 4945
unix 3 [ ] STREAM CONNECTE 4944
unix 2 [ ] DGRAM 4795
unix 2 [ ] DGRAM 4677
unix 3 [ ] STREAM CONNECTE 4583
unix 3 [ ] STREAM CONNECTE 4582

[uxbod]

Your server is on a private IP hence it is returning your public when performing the host command. Therefore you will need to setup a Split DNS - Zimbra :: Wiki architecture.

[dmalherbe]

but it was working well till yesterday

[phoenix]

Originally Posted by dmalherbe

but it was working well till yesterday

That's irrelevant, you need to set-up a Split DNS.

[dmalherbe]

i made split dns and it's still the same.
when i dig mydomain mx it return my local ip but still have :
Zimbra server reserving server socket port=110 bindaddr=mail.edgeoya.com ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=true
Zimbra server reserving server socket port=7025 bindaddr=91.121.51.143 ssl=false --> my locla ip is 10.0.0.2

[phoenix]

Originally Posted by dmalherbe

i made split dns and it's still the same.
when i dig mydomain mx it return my local ip but still have :
Zimbra server reserving server socket port=110 bindaddr=mail.edgeoya.com ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=true
Zimbra server reserving server socket port=7025 bindaddr=91.121.51.143 ssl=false --> my locla ip is 10.0.0.2

Then you haven't done it correctly, search the forums for details on how to check your DNS (post the results of your findings in this thread) and go through the Split DNS article again.