server dropped connection

[ferra]

Ok. Thanks a lot.

Here are the logo files. They are to big, so I'm posting the final part I hope is enough.

/var/log/zimbra.log


Oct 24 22:39:00 mail postfix/smtp[23976]: connect to smtp.ci.uc.pt[193.137.200.35]: read timeout (port 25)
Oct 24 22:39:00 mail postfix/smtp[24108]: connect to relay.net4b.pt[195.245.176.7]: read timeout (port 25)
Oct 24 22:39:00 mail postfix/smtp[24074]: connect to mail.calibre12.pt[81.84.240.151]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:00 mail postfix/smtp[24010]: connect to mail3.cm-abrantes.pt[83.240.159.99]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:00 mail postfix/smtp[24108]: C660B14686A4: to=, relay=none, delay=40542, status=deferred (connect to relay.net4b.pt[195.245.176.7]: read timeout)
Oct 24 22:39:00 mail postfix/smtp[24108]: C660B14686A4: to=, relay=none, delay=40542, status=deferred (connect to relay.net4b.pt[195.245.176.7]: read timeout)
Oct 24 22:39:01 mail postfix/smtp[24074]: C660B14686A4: to=, relay=none, delay=40543, status=deferred (connect to mail.calibre12.pt[81.84.240.151]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/smtp[24074]: C660B14686A4: to=, relay=none, delay=40543, status=deferred (connect to mail.calibre12.pt[81.84.240.151]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/smtp[24191]: connect to relay1.telepac.pt[194.65.79.73]: read timeout (port 25)
Oct 24 22:39:01 mail postfix/smtp[24172]: connect to smtp.dnsexit.com[64.182.102.191]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:01 mail postfix/smtp[24196]: connect to mail2.iese.net[62.22.104.78]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:01 mail postfix/smtp[24155]: connect to smtp2.artelecom.pt[213.141.25.62]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:01 mail postfix/smtp[24172]: 983BC1468595: to=, relay=none, delay=106831, status=deferred (connect to smtp.dnsexit.com[64.182.102.191]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/smtp[24155]: 5F42C1468694: to=, relay=none, delay=40494, status=deferred (connect to smtp2.artelecom.pt[213.141.25.62]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/smtp[24191]: 484B314685F8: to=, relay=none, delay=47871, status=deferred (connect to relay1.telepac.pt[194.65.79.73]: read timeout)
Oct 24 22:39:01 mail postfix/smtp[24196]: 06F7C14683E3: to=, relay=none, delay=191838, status=deferred (connect to mail2.iese.net[62.22.104.78]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/qmgr[23960]: 4FEAF1468574: to=, relay=none, delay=40971, status=deferred (delivery temporarily suspended: connect to smtp2.artelecom.pt[213.141.25.62]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/qmgr[23960]: 963D014683FC: to=, relay=none, delay=40471, status=deferred (delivery temporarily suspended: connect to smtp2.artelecom.pt[213.141.25.62]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/qmgr[23960]: 5D7F71468578: to=, relay=none, delay=107916, status=deferred (delivery temporarily suspended: connect to smtp2.artelecom.pt[213.141.25.62]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:01 mail postfix/smtp[23976]: C660B14686A4: to=, relay=none, delay=40543, status=deferred (connect to smtp.ci.uc.pt[193.137.200.35]: read timeout)
Oct 24 22:39:02 mail postfix/smtpd[23520]: connect from pool-71-170-87-231.dllstx.fios.verizon.net[71.170.87.231]
Oct 24 22:39:02 mail postfix/smtp[24168]: connect to smtp.KPNQwest.pt[193.126.4.60]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:02 mail postfix/smtp[23991]: connect to exchange.cm-ansiao.pt[62.48.195.20]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:02 mail postfix/smtp[24119]: connect to mail-relay.esoterica.pt[195.22.17.16]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:02 mail postfix/smtp[23994]: connect to mxav1.cm-baiao.pt[62.193.206.39]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:02 mail postfix/smtp[23994]: C660B14686A4: to=, relay=none, delay=40544, status=deferred (connect to mxav1.cm-baiao.pt[62.193.206.39]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:02 mail postfix/smtpd[23520]: NOQUEUE: reject: RCPT from pool-71-170-87-231.dllstx.fios.verizon.net[71.170.87.231]: 550 : Recipient address rejected: dgrf.min-agricultura.pt; from= to= proto=ESMTP helo=
Oct 24 22:39:03 mail postfix/smtpd[23520]: lost connection after DATA from pool-71-170-87-231.dllstx.fios.verizon.net[71.170.87.231]
Oct 24 22:39:03 mail postfix/smtpd[23520]: disconnect from pool-71-170-87-231.dllstx.fios.verizon.net[71.170.87.231]
Oct 24 22:39:03 mail postfix/smtp[23991]: C660B14686A4: to=, relay=none, delay=4
0545, status=deferred (connect to exchange.cm-ansiao.pt[62.48.195.20]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:03 mail postfix/smtp[24022]: connect to relay1.telepac.pt[194.65.79.73]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:03 mail postfix/smtp[24139]: connect to a.mx.portugalmail.pt[195.245.179.176]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:03 mail postfix/smtp[24192]: connect to smtp02.wallonie.be[157.164.136.6]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:03 mail postfix/smtp[24178]: connect to mx1.tasp.PT[194.65.14.187]: server dropped connection without sending the initial SMTP greeting (port 25)
Oct 24 22:39:03 mail postfix/smtp[24192]: EDF2614684C5: to=, relay=none, delay=125323, status=deferred (connect to smtp02.wallonie.be[157.164.136.6]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:03 mail postfix/smtp[24022]: 39A85146873B: to=, relay=none, delay=25215, status=deferred (connect to relay1.telepac.pt[194.65.79.73]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:03 mail postfix/smtp[24178]: 2824414684EF: to=, relay=none, delay=123999, status=deferred (connect to mx1.tasp.PT[194.65.14.187]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:03 mail postfix/smtp[24139]: C7D5D146857B: to=, relay=none, delay=107894, status=deferred (connect to a.mx.portugalmail.pt[195.245.179.176]: server dropped connection without sending the initial SMTP greeting)
Oct 24 22:39:05 mail postfix/smtp[24107]: connect to mx1.hotmail.com[65.54.244.8]: read timeout (port 25)
Oct 24 22:39:07 mail postfix/smtp[24200]: connect to dna.yr.com.s200b2.psmtp.com[207.126.147.14]: read timeout (port 25)
Oct 24 22:39:07 mail postfix/smtp[24200]: B8ECE1468547: to=, relay=none, delay=40820, status=deferred (connect to dna.yr.com.s200b2.psmtp.com[207.126.147.14]: read timeout)
Oct 24 22:39:24 mail postfix/smtpd[23518]: connect from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:24 mail postfix/smtpd[23518]: lost connection after EHLO from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:24 mail postfix/smtpd[23518]: disconnect from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:24 mail postfix/smtpd[18603]: connect from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:25 mail postfix/smtpd[18603]: lost connection after EHLO from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:25 mail postfix/smtpd[18603]: disconnect from 89-180-88-134.net.novis.pt[89.180.88.134]
Oct 24 22:39:29 mail postfix/smtpd[15656]: connect from smtp200.oninetspeed.pt[195.245.128.200]
Oct 24 22:39:29 mail postfix/smtpd[15656]: 9DF051468711: client=smtp200.oninetspeed.pt[195.245.128.200]
Oct 24 22:39:29 mail postfix/cleanup[18607]: 9DF051468711: message-id=<001901c81686$29891ea0$0201a8c0@Casa>
Oct 24 22:39:35 mail postfix/qmgr[23960]: 9DF051468711: from=, size=2139362, nrcpt=1 (queue active)
Oct 24 22:39:35 mail amavis[19188]: (19188-13) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T221749-19188: -> SIZE=2139362 Received: from mail.sede.dgrf.min-agricultura.pt ([127.0.0.1]) by localhost (mail.sede.dgrf.min-agricultura.pt [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Wed, 24 Oct 2007 22:39:35 +0100 (WEST)
Oct 24 22:39:35 mail postfix/smtpd[15656]: disconnect from smtp200.oninetspeed.pt[195.245.128.200]
Oct 24 22:39:35 mail amavis[19188]: (19188-13) Checking: Sj1x3DsmnOVp [195.245.128.200] ->
Oct 24 22:39:36 mail amavis[19188]: (19188-13) spam_scan: not wasting time on SA, message longer than 524288 bytes: 1179+2110595
Oct 24 22:39:36 mail postfix/smtpd[27479]: connect from localhost.localdomain[127.0.0.1]
Oct 24 22:39:36 mail postfix/smtpd[27479]: 8DB8D14687A2: client=localhost.localdomain[127.0.0.1]
Oct 24 22:39:36 mail postfix/cleanup[16891]: 8DB8D14687A2: message-id=<001901c81686$29891ea0$0201a8c0@Casa>
Oct 24 22:39:36 mail postfix/smtpd[27479]: disconnect from localhost.localdomain[127.0.0.1]
Oct 24 22:39:36 mail postfix/qmgr[23960]: 8DB8D14687A2: from=, size=2139997, nrcpt=1 (queue active)
Oct 24 22:39:36 mail amavis[19188]: (19188-13) FWD via SMTP: -> ,BODY=7BIT 250 2.6.0 Ok, id=19188-13, from MTA([127.0.0.1]:10025): 250 Ok: queued as 8DB8D14687A2
Oct 24 22:39:36 mail amavis[19188]: (19188-13) Passed CLEAN, [195.245.128.200] [89.26.183.71] -> , Message-ID: <001901c81686$29891ea0$0201a8c0@Casa>, mail_id: Sj1x3DsmnOVp, Hits: -, size: 2139362, queued_as: 8DB8D14687A2, 1423 ms
Oct 24 22:39:36 mail postfix/smtp[26315]: 9DF051468711: to=, relay=12
7.0.0.1[127.0.0.1], delay=7, status=sent (250 Ok: queued as 8DB8D14687A2)
Oct 24 22:39:36 mail postfix/qmgr[23960]: 9DF051468711: removed
Oct 24 22:39:37 mail postfix/lmtp[27480]: 8DB8D14687A2: to=, relay=mail.sede.dgrf.min-agricultura.pt[192.168.1.1], delay=1, status=sent (250 2.1.5 OK)
Oct 24 22:39:37 mail postfix/qmgr[23960]: 8DB8D14687A2: removed
Oct 24 22:39:39 mail postfix/smtpd[23520]: connect from pool-141-150-255-234.cmdnnj.east.verizon.net[141.150.255.234]
Oct 24 22:39:41 mail postfix/smtpd[23520]: NOQUEUE: reject: RCPT from pool-141-150-255-234.cmdnnj.east.verizon.net[141.150.255.234]: 550 : Recipient address rejected: dgrf.min-agricultura.pt; from= to= proto=ESMTP helo=
Oct 24 22:39:43 mail postfix/smtpd[23520]: lost connection after DATA from pool-141-150-255-234.cmdnnj.east.verizon.net[141.150.255.234]
Oct 24 22:39:43 mail postfix/smtpd[23520]: disconnect from pool-141-150-255-234.cmdnnj.east.verizon.net[141.150.255.234]
Oct 24 22:39:46 mail postfix/smtpd[18606]: connect from 88-109-9-248.dynamic.dsl.as9105.com[88.109.9.248]
Oct 24 22:39:47 mail postfix/smtpd[18606]: 33D8E1468776: client=88-109-9-248.dynamic.dsl.as9105.com[88.109.9.248]
Oct 24 22:39:48 mail postfix/cleanup[23521]: 33D8E1468776: message-id=<000a01c81686$03db1833$6034a08b@orciwtti>
Oct 24 22:39:48 mail postfix/qmgr[23960]: 33D8E1468776: from=, size=4449, nrcpt=1 (queue active)
Oct 24 22:39:48 mail amavis[23388]: (23388-07) ESMTP::10024 /opt/zimbra/amavisd/tmp/amavis-20071024T222901-23388: -> SIZE=4449 Received: from mail.sede.dgrf.min-agricultura.pt ([127.0.0.1]) by localhost (mail.sede.dgrf.min-agricultura.pt [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for ; Wed, 24 Oct 2007 22:39:48 +0100 (WEST)
Oct 24 22:39:48 mail amavis[23388]: (23388-07) Checking: bZU3Zskrzp3o [88.109.9.248] ->
Oct 24 22:39:48 mail postfix/smtpd[18606]: disconnect from 88-109-9-248.dynamic.dsl.as9105.com[88.109.9.248]
Oct 24 22:39:52 mail amavis[23388]: (23388-07) Blocked SPAM, [88.109.9.248] [88.109.9.248] Oct 24 22:39:52 mail amavis[23388]: (23388-07) Blocked SPAM, [88.109.9.248] [88.109.9.248] -> , Message-ID: <000a01c81686$03db1833$6034a08b@orciwtti>, mail_id: bZU3Zskrzp3o, Hits: 25.179, size: 4449, 3682 ms
Oct 24 22:39:52 mail postfix/smtp[24226]: 33D8E1468776: to=, orig_to=, relay=127.0.0.1[127.0.0.1], delay=5, status=sent (250 2.5.0 Ok, id=23388-07, DISCARD(bounce.suppressed))
Oct 24 22:39:52 mail postfix/qmgr[23960]: 33D8E1468776: removed
Oct 24 22:40:01 mail zimbramon[29175]: 29175:info: 2007-10-24 22:40:01, QUEUE: 504217 726
Oct 24 22:40:02 mail zimbramon[29181]: 29181:info: 2007-10-24 22:40:01, DISK: mail.sede.dgrf.min-agricultura.pt: dev: /dev/mapper/VolGroup00-LogVol00, mp: /, tot: 448290, avail: 406853
Oct 24 22:40:02 mail zimbramon[29181]: 29181:info: 2007-10-24 22:40:01, DISK: mail.sede.dgrf.min-agricultura.pt: dev: /dev/sda1, mp: /boot, tot: 99, avail: 84
Oct 24 22:40:03 mail zmtomcatmgr[29384]: status requested
Oct 24 22:40:03 mail zmtomcatmgr[29384]: status OK
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: antispam: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: antivirus: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: ldap: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: logger: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: mailbox: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: mta: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: snmp: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: spell: Running
Oct 24 22:40:04 mail zimbramon[29184]: 29184:info: 2007-10-24 22:40:01, STATUS: mail.sede.dgrf.min-agricultura.pt: stats: Running

[ferra]

And,

/opt/zimbra/log/mailbox.log

2007-10-24 22:03:03,673 INFO [LmtpServer-12] [] LmtpHandler - [192.168.1.1] connected
2007-10-24 22:03:03,786 INFO [LmtpServer-12] [name=mariahelena@dgrf.min-agricultura.pt;] mailbox - Added message id=282 digest=MvjObxrAdu79uj76SURDSEHJ5F0= mailbox=653 rcpt=mariahelena@dgrf.min-agricultura.pt
2007-10-24 22:03:05,114 INFO [LmtpServer-13] [] LmtpHandler - [192.168.1.1] connected
2007-10-24 22:03:05,159 INFO [LmtpServer-12] [name=pedroquintela@dgrf.min-agricultura.pt;] mailbox - Added message id=441 digest=lMz+pzVfmrMDyhSXTWfIud8O2f0= mailbox=8 rcpt=pedroquintela@dgrf.min-agricultura.pt
2007-10-24 22:03:05,260 INFO [LmtpServer-13] [name=ruirocha@dgrf.min-agricultura.pt;] mailbox - Added message id=404 digest=VWz2TCfC,gxbVC1hvBTceOxdNxc= mailbox=46 rcpt=ruirocha@dgrf.min-agricultura.pt
2007-10-24 22:03:06,343 INFO [LmtpServer-12] [name=victorlouro@dgrf.min-agricultura.pt;] mailbox - Added message id=386 digest=ElKhZMxPism7ekZpP68zp0HY2Rc= mailbox=129 rcpt=victorlouro@dgrf.min-agricultura.pt
2007-10-24 22:03:11,719 INFO [LmtpServer-13] [] cache - Initializing folder and tag caches for mailbox 13
2007-10-24 22:03:11,966 INFO [LmtpServer-13] [name=joseantonio@dgrf.min-agricultura.pt;] mailbox - Added message id=300 digest=8oxpZuSaQejMGHP7,3Y,0vQPwyU= mailbox=13 rcpt=joseantonio@dgrf.min-agricultura.pt
2007-10-24 22:03:16,629 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=18, closed=1, after=17 (24ms)
2007-10-24 22:03:46,143 INFO [LmtpServer-12] [name=vitaleitao@dgrf.min-agricultura.pt;] mailbox - Added message id=281 digest=k1KSM,dBrWZUnT8oM9nxtE3aWU0= mailbox=678 rcpt=vitaleitao@dgrf.min-agricultura.pt
2007-10-24 22:03:46,654 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=18, closed=2, after=16 (44ms)
2007-10-24 22:03:46,921 INFO [Pop3Server-74] [] pop - connected
2007-10-24 22:03:46,983 INFO [Pop3Server-74] [name=joaoperpetua@dgrf.min-agricultura.pt;ip=192.168.0.204;] pop - quit from client
2007-10-24 22:03:46,984 INFO [Pop3Server-74] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:03:48,503 INFO [LmtpServer-13] [name=andreiaoliveira@dgrf.min-agricultura.pt;] mailbox - Added message id=321 digest=R83dYGeuKqEGyabpDQYxgkWsdXQ= mailbox=644 rcpt=andreiaoliveira@dgrf.min-agricultura.pt
2007-10-24 22:03:59,425 INFO [Pop3Server-75] [] pop - connected

2007-10-24 22:04:00,711 INFO [Pop3Server-75] [name=logicworks@dgrf.min-agricultura.pt;ip=194.38.148.237;] pop - quit from client
2007-10-24 22:04:00,711 INFO [Pop3Server-75] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:04:15,889 INFO [LmtpServer-12] [] cache - Initializing folder and tag caches for mailbox 193
2007-10-24 22:04:15,956 INFO [LmtpServer-12] [name=gracalouro@dgrf.min-agricultura.pt;] mailbox - Added message id=301 digest=058TkKk,Dio+smutL8wrwsuw7yk= mailbox=627 rcpt=gracalouro@dgrf.min-agricultura.pt
2007-10-24 22:04:16,028 INFO [LmtpServer-12] [name=gracasacadura@dgrf.min-agricultura.pt;] mailbox - Added message id=320 digest=058TkKk,Dio+smutL8wrwsuw7yk= mailbox=193 rcpt=gracasacadura@dgrf.min-agricultura.pt
2007-10-24 22:04:16,615 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=19, closed=0, after=19 (0ms)
2007-10-24 22:04:30,256 INFO [LmtpServer-13] [name=ruirocha@dgrf.min-agricultura.pt;] mailbox - Added message id=405 digest=2zqdXI7bBp8sBuVVLOMYMXnuKNM= mailbox=46 rcpt=ruirocha@dgrf.min-agricultura.pt
2007-10-24 22:04:31,164 INFO [LmtpServer-12] [name=mariahelena@dgrf.min-agricultura.pt;] mailbox - Added message id=283 digest=F2i4itibyCGuUXzQlBdobDy++eE= mailbox=653 rcpt=mariahelena@dgrf.min-agricultura.pt
2007-10-24 22:04:46,619 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=19, closed=0, after=19 (0ms)
2007-10-24 22:05:08,982 INFO [LmtpServer-13] [] cache - Initializing folder and tag caches for mailbox 701
2007-10-24 22:05:09,210 INFO [LmtpServer-13] [] cache - Initializing folder and tag caches for mailbox 682
2007-10-24 22:05:09,393 INFO [LmtpServer-13] [name=joaquimproenca@dgrf.min-agricultura.pt;] mailbox - Added message id=280 digest=8ER8uIw7D9HtxsdZl9sliBIfGCo= mailbox=701 rcpt=joaquimproenca@dgrf.min-agricultura.pt
2007-10-24 22:05:09,494 INFO [LmtpServer-13] [name=mariagalvao@dgrf.min-agricultura.pt;] mailbox - Added message id=260 digest=8ER8uIw7D9HtxsdZl9sliBIfGCo= mailbox=682 rcpt=mariagalvao@dgrf.min-agricultura.pt
2007-10-24 22:05:16,628 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=21, closed=0, after=21 (0ms)

2007-10-24 22:05:41,666 INFO [LmtpServer-12] [name=emidiosantos@dgrf.min-agricultura.pt;] mailbox - Added message id=361 digest=E,o2oCjobfkVonO+y5rXLbyNT3s= mailbox=178 rcpt=emidiosantos@dgrf.min-agricultura.pt
2007-10-24 22:05:46,639 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=22, closed=1, after=21 (6ms)
2007-10-24 22:06:00,066 INFO [http-443-Processor49] [ip=213.13.231.71;ua=ZimbraWebClient - IE6 (Win);] cache - Initializing folder and tag caches for mailbox 122
2007-10-24 22:06:16,642 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=21, closed=0, after=21 (0ms)
2007-10-24 22:06:18,676 INFO [LmtpServer-13] [] cache - Initializing folder and tag caches for mailbox 593
2007-10-24 22:06:18,715 INFO [LmtpServer-13] [name=conceicaobarros@dgrf.min-agricultura.pt;] mailbox - Added message id=320 digest=zRouymLKQHZfWN+Bdh4IQM+TU2g= mailbox=593 rcpt=conceicaobarros@dgrf.min-agricultura.pt
2007-10-24 22:06:47,356 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=22, closed=2, after=20 (705ms)
2007-10-24 22:06:47,444 INFO [LmtpServer-12] [] cache - Initializing folder and tag caches for mailbox 592
2007-10-24 22:06:47,568 INFO [LmtpServer-12] [name=antonioferreira@dgrf.min-agricultura.pt;] mailbox - Added message id=320 digest=vMTQ4tgqyy8TmnFwZd6w8lCu2pk= mailbox=592 rcpt=antonioferreira@dgrf.min-agricultura.pt
2007-10-24 22:07:04,116 INFO [Pop3Server-76] [] pop - connected
2007-10-24 22:07:12,828 INFO [LmtpServer-13] [name=ruirocha@dgrf.min-agricultura.pt;] mailbox - Added message id=406 digest=ChQMMhLLZqM8yqT4me229EvENdI= mailbox=46 rcpt=ruirocha@dgrf.min-agricultura.pt
2007-10-24 22:07:16,673 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=21, closed=2, after=19 (14ms)
2007-10-24 22:07:31,196 INFO [http-443-Processor47] [name=cristinacamilo@dgrf.min-agricultura.pt;mid=122;ip=213.13.231.71;ua=ZimbraW ebClient - IE6 (Win)/4.5.8_GA_1394.FC5;] cache - Loading flagset cache
2007-10-24 22:07:31,197 INFO [http-443-Processor47] [name=cristinacamilo@dgrf.min-agricultura.pt;mid=122;ip=213.13.231.71;ua=ZimbraW ebClient - IE6 (Win)/4.5.8_GA_1394.FC5;] cache - Loading tagset cache
2007-10-24 22:07:46,668 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=19, closed=0, after=19 (0ms)

2007-10-24 22:07:57,633 INFO [LmtpServer-12] [name=luciorosario@dgrf.min-agricultura.pt;] mailbox - Added message id=300 digest=G9s+YpIvKOkKPsFmhVTARs665nM= mailbox=196 rcpt=luciorosario@dgrf.min-agricultura.pt
2007-10-24 22:08:14,233 INFO [Pop3Server-77] [] pop - connected
2007-10-24 22:08:14,297 INFO [Pop3Server-77] [name=claudiaviliotis@dgrf.min-agricultura.pt;ip=192.168.0.23;] pop - quit from client
2007-10-24 22:08:14,298 INFO [Pop3Server-77] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:08:14,302 INFO [Pop3Server-78] [] pop - connected
2007-10-24 22:08:14,320 INFO [Pop3Server-78] [name=prolunp@dgrf.min-agricultura.pt;ip=192.168.0.23;] pop - quit from client
2007-10-24 22:08:14,320 INFO [Pop3Server-78] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:08:16,690 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=19, closed=2, after=17 (17ms)
2007-10-24 22:08:46,682 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=17, closed=0, after=17 (1ms)
2007-10-24 22:08:47,870 INFO [Pop3Server-79] [] pop - connected
2007-10-24 22:08:47,915 INFO [Pop3Server-79] [name=joaoperpetua@dgrf.min-agricultura.pt;ip=192.168.0.204;] pop - quit from client
2007-10-24 22:08:47,915 INFO [Pop3Server-79] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:08:52,418 INFO [LmtpServer-13] [] LmtpHandler - [192.168.1.1] quit from client
2007-10-24 22:08:52,418 INFO [LmtpServer-13] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:09:16,693 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=17, closed=1, after=16 (7ms)
2007-10-24 22:09:18,293 INFO [Pop3Server-80] [] pop - connected
2007-10-24 22:09:18,846 INFO [Pop3Server-80] [name=logicworks@dgrf.min-agricultura.pt;ip=194.38.148.237;] pop - quit from client
2007-10-24 22:09:18,846 INFO [Pop3Server-80] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:09:37,419 INFO [LmtpServer-12] [] LmtpHandler - [192.168.1.1] quit from client
2007-10-24 22:09:37,419 INFO [LmtpServer-12] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:09:46,691 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=16, closed=0, after=16 (0ms)

2007-10-24 22:09:57,856 INFO [Pop3Server-76] [name=luiscortereal@dgrf.min-agricultura.pt;ip=89.214.129.144;] pop - quit from client
2007-10-24 22:09:57,857 INFO [Pop3Server-76] [] ProtocolHandler - Handler exiting normally
2007-10-24 22:10:07,210 INFO [LmtpServer-14] [] LmtpHandler - [192.168.1.1] connected
2007-10-24 22:10:07,314 INFO [LmtpServer-14] [name=anafontes@dgrf.min-agricultura.pt;] mailbox - Added message id=321 digest=7BBG2y4tyfcy4A6ID4TNYFN9aQs= mailbox=47 rcpt=anafontes@dgrf.min-agricultura.pt
2007-10-24 22:10:16,700 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=17, closed=0, after=17 (0ms)
2007-10-24 22:10:19,294 INFO [LmtpServer-14] [] cache - Initializing folder and tag caches for mailbox 433
2007-10-24 22:10:19,341 INFO [LmtpServer-14] [name=pauloalbino@dgrf.min-agricultura.pt;] mailbox - Added message id=320 digest=VAlaG6ChSywFZ2rDeIdMQtXJhMA= mailbox=433 rcpt=pauloalbino@dgrf.min-agricultura.pt
2007-10-24 22:10:46,709 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=18, closed=0, after=18 (0ms)
2007-10-24 22:11:16,721 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=18, closed=1, after=17 (8ms)
2007-10-24 22:11:46,718 INFO [IndexWritersSweeperThread] [] MailboxIndex - open index writers sweep: before=17, closed=0, after=17 (0ms)
2007-10-24 22:11:48,829 WARN [http-443-Processor50] [] [SoapServlet] - Servlet.service() for servlet SoapServlet threw exception
java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream. java:129)
at com.sun.net.ssl.internal.ssl.InputRecord.readFully (InputRecord.java:293)
at com.sun.net.ssl.internal.ssl.InputRecord.read(Inpu tRecord.java:331)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRec ord(SSLSocketImpl.java:789)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDat aRecord(SSLSocketImpl.java:746)
at com.sun.net.ssl.internal.ssl.AppInputStream.read(A ppInputStream.java:75)
at org.apache.coyote.http11.InternalInputBuffer.fill( InternalInputBuffer.java:747)
at org.apache.coyote.http11.InternalInputBuffer$Input StreamInputBuffer.doRead(InternalInputBuffer.java: 777)

at org.apache.coyote.http11.InternalInputBuffer$Input StreamInputBuffer.doRead(InternalInputBuffer.java: 777)
at org.apache.coyote.http11.filters.IdentityInputFilt er.doRead(IdentityInputFilter.java:115)
at org.apache.coyote.http11.InternalInputBuffer.doRea d(InternalInputBuffer.java:712)
at org.apache.coyote.Request.doRead(Request.java:423)
at org.apache.catalina.connector.InputBuffer.realRead Bytes(InputBuffer.java:283)
at org.apache.tomcat.util.buf.ByteChunk.substract(Byt eChunk.java:404)
at org.apache.catalina.connector.InputBuffer.read(Inp utBuffer.java:298)
at org.apache.catalina.connector.CoyoteInputStream.re ad(CoyoteInputStream.java:192)
at com.zimbra.cs.servlet.ZimbraServlet.readFully(Zimb raServlet.java:174)
at com.zimbra.soap.SoapServlet.doPost(SoapServlet.jav a:199)
at javax.servlet.http.HttpServlet.service(HttpServlet .java:709)
at com.zimbra.cs.servlet.ZimbraServlet.service(Zimbra Servlet.java:152)
at javax.servlet.http.HttpServlet.service(HttpServlet .java:802)
at org.apache.catalina.core.ApplicationFilterChain.in ternalDoFilter(ApplicationFilterChain.java:252)
at org.apache.catalina.core.ApplicationFilterChain.do Filter(ApplicationFilterChain.java:173)
at org.apache.catalina.core.StandardWrapperValve.invo ke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invo ke(StandardContextValve.java:178)
at org.apache.catalina.core.StandardHostValve.invoke( StandardHostValve.java:126)
at org.apache.catalina.valves.ErrorReportValve.invoke (ErrorReportValve.java:105)
at org.apache.catalina.core.StandardEngineValve.invok e(StandardEngineValve.java:107)
at org.apache.catalina.valves.AccessLogValve.invoke(A ccessLogValve.java:541)
at org.apache.catalina.connector.CoyoteAdapter.servic e(CoyoteAdapter.java:148)
at org.apache.coyote.http11.Http11Processor.process(H ttp11Processor.java:869)
at org.apache.coyote.http11.Http11BaseProtocol$Http11 ConnectionHandler.processConnection(Http11BaseProt ocol.java:667)
at org.apache.tomcat.util.net.PoolTcpEndpoint.process Socket(PoolTcpEndpoint.java:527)
at org.apache.tomcat.util.net.LeaderFollowerWorkerThr ead.runIt(LeaderFollowerWorkerThread.java:80)
at org.apache.tomcat.util.threads.ThreadPool$ControlR unnable.run(ThreadPool.java:684)
at java.lang.Thread.run(Thread.java:619)

[jholder]

I reopened bug : Bug 19129 - java.net.SocketTimeoutException: Read timed out

Do you have a slower network connection?

Also, if you stop Zimbra and start it, does it fix it?

[ferra]

the connection is not to slow. Some times the internet connection is a bit slow.

I did a
zmcontrol stop
zmcontrol start
tomcat restart

but still the same problem

[jholder]

try a couple of these solutions:site:zimbra.com/forums "server dropped connection without sending the initial SMTP greeting" - Google Search

[ferra]

I cannot find amavis.conf.in in the system to try

I have solved the problem but it's very ugly.

i have add in the amavis.conf.in
@inet_acl = qw( 127.0.0.1 192.168.1.6 );


because when i made a telnet in 192.168.1.6 amavis refused me the connexion... but now it's ok but the correction is ugly... :/
Reply With Quote

[ferra]

I have checked the solutions in the forum but still no luck.

I have in the Service host name the name of the local name server not the public name server. do you think this could be a problem?

[ferra]

Now after doing

zmcontrol stop
zmcontrol start
tomcat restart

I got this error and the system is down:

[root@mail named]# tail -n 30 /opt/zimbra/log/mailbox.log
at org.apache.catalina.util.LifecycleSupport.fireLife cycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1020)
at org.apache.catalina.core.StandardHost.start(Standa rdHost.java:718)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1012)
at org.apache.catalina.core.StandardEngine.start(Stan dardEngine.java:442)
at org.apache.catalina.core.StandardService.start(Sta ndardService.java:450)
at org.apache.catalina.core.StandardServer.start(Stan dardServer.java:700)
at org.apache.catalina.startup.Catalina.start(Catalin a.java:551)
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:597)
at org.apache.catalina.startup.Bootstrap.start(Bootst rap.java:275)
at org.apache.catalina.startup.Bootstrap.main(Bootstr ap.java:406)
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:597)
at com.zimbra.cs.launcher.TomcatLauncher.start(Tomcat Launcher.java:42)
at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatL auncher.java:64)
2007-10-24 23:57:27,120 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-10-24 23:57:27,170 INFO [main] [] mailbox - Servlet UserServlet starting up
2007-10-24 23:57:27,172 INFO [main] [] account - Servlet PreAuthServlet starting up
2007-10-24 23:57:27,177 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2007-10-24 23:57:27,186 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-10-24 23:57:27,190 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-10-24 23:57:34,735 INFO [http-443-Processor50] [] [SoapServlet] - Servlet SoapServlet is currently unavailable
2007-10-24 23:57:37,142 INFO [http-443-Processor50] [] [SoapServlet] - Servlet SoapServlet is currently unavailable
2007-10-24 23:57:48,008 INFO [http-7071-Processor50] [] [AdminServlet] - Servlet AdminServlet is currently unavailable
2007-10-24 23:57:52,110 INFO [http-7071-Processor50] [] [AdminServlet] - Servlet AdminServlet is currently unavailable
[root@mail named]# tail -n 40 /opt/zimbra/log/mailbox.log
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:3915)
at org.apache.catalina.core.StandardContext.start(Sta ndardContext.java:4176)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:759)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:739)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:524)
at org.apache.catalina.startup.HostConfig.deployDirec tory(HostConfig.java:910)
at org.apache.catalina.startup.HostConfig.deployDirec tories(HostConfig.java:873)
at org.apache.catalina.startup.HostConfig.deployApps( HostConfig.java:474)
at org.apache.catalina.startup.HostConfig.start(HostC onfig.java:1118)
at org.apache.catalina.startup.HostConfig.lifecycleEv ent(HostConfig.java:310)
at org.apache.catalina.util.LifecycleSupport.fireLife cycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1020)
at org.apache.catalina.core.StandardHost.start(Standa rdHost.java:718)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1012)
at org.apache.catalina.core.StandardEngine.start(Stan dardEngine.java:442)
at org.apache.catalina.core.StandardService.start(Sta ndardService.java:450)
at org.apache.catalina.core.StandardServer.start(Stan dardServer.java:700)
at org.apache.catalina.startup.Catalina.start(Catalin a.java:551)
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:597)
at org.apache.catalina.startup.Bootstrap.start(Bootst rap.java:275)
at org.apache.catalina.startup.Bootstrap.main(Bootstr ap.java:406)
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:597)
at com.zimbra.cs.launcher.TomcatLauncher.start(Tomcat Launcher.java:42)
at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatL auncher.java:64)
2007-10-24 23:57:27,120 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-10-24 23:57:27,170 INFO [main] [] mailbox - Servlet UserServlet starting up
2007-10-24 23:57:27,172 INFO [main] [] account - Servlet PreAuthServlet starting up
2007-10-24 23:57:27,177 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2007-10-24 23:57:27,186 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-10-24 23:57:27,190 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-10-24 23:57:34,735 INFO [http-443-Processor50] [] [SoapServlet] - Servlet SoapServlet is currently unavailable
2007-10-24 23:57:37,142 INFO [http-443-Processor50] [] [SoapServlet] - Servlet SoapServlet is currently unavailable
2007-10-24 23:57:48,008 INFO [http-7071-Processor50] [] [AdminServlet] - Servlet AdminServlet is currently unavailable
2007-10-24 23:57:52,110 INFO [http-7071-Processor50] [] [AdminServlet] - Servlet AdminServlet is currently unavailable


Any ideas?

[ferra]

I have reboot it the server but it keeps down:

[root@mail ~]# tail -n 40 /opt/zimbra/log/mailbox.log
at javax.servlet.GenericServlet.init(GenericServlet.j ava:211)
at org.apache.catalina.core.StandardWrapper.loadServl et(StandardWrapper.java:1105)
at org.apache.catalina.core.StandardWrapper.load(Stan dardWrapper.java:932)
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:3915)
at org.apache.catalina.core.StandardContext.start(Sta ndardContext.java:4176)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:759)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:739)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:524)
at org.apache.catalina.startup.HostConfig.deployDirec tory(HostConfig.java:910)
at org.apache.catalina.startup.HostConfig.deployDirec tories(HostConfig.java:873)
at org.apache.catalina.startup.HostConfig.deployApps( HostConfig.java:474)
at org.apache.catalina.startup.HostConfig.start(HostC onfig.java:1118)
at org.apache.catalina.startup.HostConfig.lifecycleEv ent(HostConfig.java:310)
at org.apache.catalina.util.LifecycleSupport.fireLife cycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1020)
at org.apache.catalina.core.StandardHost.start(Standa rdHost.java:718)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1012)
at org.apache.catalina.core.StandardEngine.start(Stan dardEngine.java:442)
at org.apache.catalina.core.StandardService.start(Sta ndardService.java:450)
at org.apache.catalina.core.StandardServer.start(Stan dardServer.java:700)
at org.apache.catalina.startup.Catalina.start(Catalin a.java:551)
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:597)
at org.apache.catalina.startup.Bootstrap.start(Bootst rap.java:275)
at org.apache.catalina.startup.Bootstrap.main(Bootstr ap.java:406)
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:597)
at com.zimbra.cs.launcher.TomcatLauncher.start(Tomcat Launcher.java:42)
at com.zimbra.cs.launcher.TomcatLauncher.main(TomcatL auncher.java:64)
2007-12-23 00:13:06,100 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-12-23 00:13:06,270 INFO [main] [] mailbox - Servlet UserServlet starting up
2007-12-23 00:13:06,283 INFO [main] [] account - Servlet PreAuthServlet starting up
2007-12-23 00:13:06,301 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2007-12-23 00:13:06,357 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-12-23 00:13:06,363 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-12-23 00:16:01,751 INFO [http-443-Processor50] [] [SoapServlet] - Servlet SoapServlet is currently unavailable


What can I do?

[ferra]

I have imported users from an old ldap system. It could be the problem?

Interesting is I have installed zimbra in another server for test and start having the same problem.

The live system as been working for about 4 days, without sending email from outside word and now starts doing these erros.

Any fix?