Thanks for your advice. Well, the connections are dropped after 60s, I can see that from the connection log, as there are different ips on the connections. What makes me curios is, that james is not recovering, i got aware of the problem 6 hours after the first connections problem and it did not spooled even one message. I would believe that after the connections timed out, some would connect to one of the james services.
-----Ursprüngliche Nachricht----- Von: Norman Maurer [mailto:[EMAIL PROTECTED] Gesendet: Sonntag, 27. August 2006 12:45 An: [email protected] Betreff: Re: James running out of connections For me it looks like someone open many connections to your server and not close them probaly. Maybe you want to "drop" the timeout. bye Norman Am Sonntag, den 27.08.2006, 11:58 +0200 schrieb Rudolf Schmidt: > I am runnning James since 2004 and never got such serious problems. > Occasionally James runs out of connections (thats what the connection > log is > saying) and from that point whether pop3 or smtp are working anymore > (connection Timeout is 60000ms). I tried to migrate to 2.3, increased > the max connections to 50. Another message I sometimes got is, that > not enough components could be created, so I already increased the threadcount. > > Here are some snippets from my logs, it happened last night at 4:02 so > I would think, it's not because of high traffic. Could this be an > unfriendly attack? Could this be an database issue, I am running on > MySQL 4.1 with > 3.1.13 Connectors using Modred connection pooling (dhcp want work, > states port already in use after some minutes). > > The server is not recovering, so I always have to restart james. > > Thanks for help > > SMTP-Server Log: > > 27/08/06 04:02:39 INFO smtpserver: Connection from 125.136.22.132 > (125.136.22.132) > 27/08/06 04:02:42 INFO smtpserver: Successfully spooled mail > Mail1156644162243-4279 from [EMAIL PROTECTED] on > 125.136.22.132 for [EMAIL PROTECTED] > 27/08/06 04:02:53 INFO smtpserver: Connection from > host109-57.pool8710.interbusiness.it (87.10.57.109) > 27/08/06 04:03:11 INFO smtpserver: Connection from > lns-bzn-28-82-250-146-127.adsl.proxad.net (82.250.146.127) > 27/08/06 04:04:36 INFO smtpserver: Connection from 190.40.245.30 > (190.40.245.30) > 27/08/06 04:05:07 INFO smtpserver: Connection from vps.9ads.com.au > (207.58.137.176) > 27/08/06 04:05:16 INFO smtpserver: Connection from > renoirpc58.sdnet.com.br > (200.195.3.58) > 27/08/06 04:05:46 ERROR smtpserver: Unknown error occurred while > processing DATA. > javax.mail.MessagingException: Unable to retrieve the data: Read timed out; > nested exception is: > java.net.SocketTimeoutException: Read timed out > at > org.apache.james.core.MimeMessageInputStreamSource.<init>(MimeMessageI > nputSt > reamSource.java:99) > at org.apache.james.core.MailImpl.<init>(MailImpl.java:179) > at > org.apache.james.smtpserver.DataCmdHandler.processMail(DataCmdHandler. > java:2 > 64) > at > org.apache.james.smtpserver.DataCmdHandler.doDATA(DataCmdHandler.java:131) > at > org.apache.james.smtpserver.DataCmdHandler.onCommand(DataCmdHandler.java:79) > at > org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.j > ava:39 > 1) > at > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er.run > (ServerConnection.java:422) > at > org.apache.excalibur.thread.impl.ExecutableRunnable.execute(Executable > Runnab > le.java:55) > at > org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:11 > 6) Caused by: java.net.SocketTimeoutException: Read timed out > at java.net.SocketInputStream.socketRead0(Native Method) > at java.net.SocketInputStream.read(SocketInputStream.java:129) > at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) > at java.io.BufferedInputStream.read(BufferedInputStream.java:235) > at > org.apache.james.util.CharTerminatedInputStream.read(CharTerminatedInp > utStre > am.java:98) > at > org.apache.james.util.watchdog.BytesReadResetInputStream.read(BytesRea > dReset > InputStream.java:96) > at > org.apache.james.util.DotStuffingInputStream.read(DotStuffingInputStre > am.jav > a:48) > at java.io.SequenceInputStream.read(SequenceInputStream.java:135) > at java.io.SequenceInputStream.read(SequenceInputStream.java:135) > at > org.apache.james.core.MimeMessageInputStreamSource.<init>(MimeMessageI > nputSt > reamSource.java:76) > ... 8 more > 27/08/06 04:05:50 ERROR smtpserver: Socket to 190.40.245.30 > (190.40.245.30) closed remotely. > java.net.SocketException: Connection reset > at java.net.SocketInputStream.read(SocketInputStream.java:168) > at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) > at java.io.BufferedInputStream.read(BufferedInputStream.java:235) > at > org.apache.james.util.CRLFTerminatedReader.read(CRLFTerminatedReader.j > ava:15 > 1) > at > org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedRead > er.jav > a:111) > at > org.apache.james.smtpserver.SMTPHandler.readCommandLine(SMTPHandler.ja > va:749 > ) > at > org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.j > ava:37 > 0) > at > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er.run > (ServerConnection.java:422) > at > org.apache.excalibur.thread.impl.ExecutableRunnable.execute(Executable > Runnab > le.java:55) > at > org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:11 > 6) > 27/08/06 04:06:03 INFO smtpserver: Connection from 221.196.124.79 > (221.196.124.79) > 27/08/06 04:06:12 INFO smtpserver: Connection from > 85-250-132-198.bb.netvision.net.il (85.250.132.198) > 27/08/06 04:06:53 INFO smtpserver: Connection from 221.139.120.41 > (221.139.120.41) > 27/08/06 04:07:04 INFO smtpserver: Connection from > OL51-196.fibertel.com.ar > (24.232.196.51) > 27/08/06 04:07:11 INFO smtpserver: Connection from 189.166.102.207 > (189.166.102.207) > 27/08/06 04:07:22 INFO smtpserver: Connection from > sal69-1-82-231-94-116.fbx.proxad.net (82.231.94.116) > 27/08/06 04:07:24 INFO smtpserver: Connection from 201.254.209.149 > (201.254.209.149) > 27/08/06 04:07:58 INFO smtpserver: Connection from 69.79.176.166 > (69.79.176.166) > 27/08/06 04:08:15 INFO smtpserver: Connection from 24.49.116.62 > (24.49.116.62) > 27/08/06 04:08:35 INFO smtpserver: Connection from 124.80.115.248 > (124.80.115.248) > 27/08/06 04:09:00 INFO smtpserver: Connection from > pool-71-101-142-254.tampfl.dsl-w.verizon.net (71.101.142.254) > 27/08/06 04:09:30 INFO smtpserver: Connection from > 119.Red-83-46-39.dynamicIP.rima-tde.net (83.46.39.119) > 27/08/06 04:09:47 INFO smtpserver: Connection from 201.96.142.225 > (201.96.142.225) > 27/08/06 04:10:06 INFO smtpserver: Connection from > AAmiens-157-1-123-218.w86-215.abo.wanadoo.fr (86.215.182.218) > 27/08/06 04:10:44 INFO smtpserver: Connection from 218.239.87.58 > (218.239.87.58) > 27/08/06 04:10:54 ERROR smtpserver: Unknown error occurred while > processing DATA. > javax.mail.MessagingException: Unable to retrieve the data: Read timed out; > nested exception is: > java.net.SocketTimeoutException: Read timed out > at > org.apache.james.core.MimeMessageInputStreamSource.<init>(MimeMessageI > nputSt > reamSource.java:99) > at org.apache.james.core.MailImpl.<init>(MailImpl.java:179) > at > org.apache.james.smtpserver.DataCmdHandler.processMail(DataCmdHandler. > java:2 > 64) > at > org.apache.james.smtpserver.DataCmdHandler.doDATA(DataCmdHandler.java:131) > at > org.apache.james.smtpserver.DataCmdHandler.onCommand(DataCmdHandler.java:79) > at > org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.j > ava:39 > 1) > at > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er.run > (ServerConnection.java:422) > at > org.apache.excalibur.thread.impl.ExecutableRunnable.execute(Executable > Runnab > le.java:55) > at > org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:11 > 6) Caused by: java.net.SocketTimeoutException: Read timed out > at java.net.SocketInputStream.socketRead0(Native Method) > at java.net.SocketInputStream.read(SocketInputStream.java:129) > at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) > at java.io.BufferedInputStream.read(BufferedInputStream.java:235) > at > org.apache.james.util.CharTerminatedInputStream.read(CharTerminatedInp > utStre > am.java:98) > at > org.apache.james.util.watchdog.BytesReadResetInputStream.read(BytesRea > dReset > InputStream.java:96) > at > org.apache.james.util.DotStuffingInputStream.read(DotStuffingInputStre > am.jav > a:48) > at java.io.SequenceInputStream.read(SequenceInputStream.java:135) > at java.io.SequenceInputStream.read(SequenceInputStream.java:135) > at > org.apache.james.core.MimeMessageInputStreamSource.<init>(MimeMessageI > nputSt > reamSource.java:76) > ... 8 more > 27/08/06 04:10:58 ERROR smtpserver: Socket to 201.96.142.225 > (201.96.142.225) closed remotely. > java.net.SocketException: Connection reset > at java.net.SocketInputStream.read(SocketInputStream.java:168) > at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) > at java.io.BufferedInputStream.read(BufferedInputStream.java:235) > at > org.apache.james.util.CRLFTerminatedReader.read(CRLFTerminatedReader.j > ava:15 > 1) > at > org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedRead > er.jav > a:111) > at > org.apache.james.smtpserver.SMTPHandler.readCommandLine(SMTPHandler.ja > va:749 > ) > at > org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.j > ava:37 > 0) > at > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er.run > (ServerConnection.java:422) > at > org.apache.excalibur.thread.impl.ExecutableRunnable.execute(Executable > Runnab > le.java:55) > at > org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:11 > 6) > 27/08/06 04:11:07 INFO smtpserver: Connection from > 200-122-123-81.dsl.prima.net.ar (200.122.123.81) > 27/08/06 04:11:23 INFO smtpserver: Connection from > bzq-84-109-17-148.red.bezeqint.net (84.109.17.148) > 27/08/06 04:11:31 INFO smtpserver: Connection from > 84-122-21-48.onocable.ono.com (84.122.21.48) > 27/08/06 04:11:46 INFO smtpserver: Connection from > host45-172.pool8257.interbusiness.it (82.57.172.45) > 27/08/06 04:11:53 INFO smtpserver: Connection from 58.230.81.192 > (58.230.81.192) > 27/08/06 04:12:22 INFO smtpserver: Connection from > 200-127-23-147.prima.net.ar (200.127.23.147) > 27/08/06 04:12:29 INFO smtpserver: Connection from 59.38.67.2 > (59.38.67.2) > 27/08/06 04:12:31 INFO smtpserver: Connection from > bl5-213-143.dsl.telepac.pt (82.154.213.143) > 27/08/06 04:12:37 INFO smtpserver: Connection from > lns-bzn-31-82-252-239-226.adsl.proxad.net (82.252.239.226) > 27/08/06 04:12:57 INFO smtpserver: Connection from 59.38.67.2 > (59.38.67.2) > 27/08/06 04:13:07 INFO smtpserver: Connection from > bl7-44-82.dsl.telepac.pt > (85.240.44.82) > 27/08/06 04:13:13 INFO smtpserver: Connection from > cm-85-152-180-75.telecable.es (85.152.180.75) > 27/08/06 04:13:23 INFO smtpserver: Connection from 59.38.67.2 > (59.38.67.2) > 27/08/06 04:13:30 INFO smtpserver: Connection from > adsl-69-224-54-89.dsl.irvnca.pacbell.net (69.224.54.89) > 27/08/06 04:13:48 INFO smtpserver: Connection from > CPE-65-28-109-55.kc.res.rr.com (65.28.109.55) > 27/08/06 04:13:50 INFO smtpserver: Connection from 59.38.67.2 > (59.38.67.2) > 27/08/06 04:14:06 INFO smtpserver: Connection from > dyn-83-152-193-15.ppp.tiscali.fr (83.152.193.15) > 27/08/06 04:14:18 INFO smtpserver: Connection from > bzq-84-108-220-187.cablep.bezeqint.net (84.108.220.187) > 27/08/06 04:14:18 INFO smtpserver: Connection from 59.38.67.2 > (59.38.67.2) > 27/08/06 04:14:19 INFO smtpserver: Connection from > CPE-65-28-109-55.kc.res.rr.com (65.28.109.55) > 27/08/06 04:14:37 INFO smtpserver: Connection from > 66.49.93.146.nw.nuvox.net (66.49.93.146) > 27/08/06 04:14:45 INFO smtpserver: Connection from 213.197.128.166 > (213.197.128.166) > 27/08/06 04:14:50 INFO smtpserver: Connection from > CPE-65-28-109-55.kc.res.rr.com (65.28.109.55) > 27/08/06 04:14:53 ERROR smtpserver: SMTP Connection has idled out. > 27/08/06 04:15:02 INFO smtpserver: Connection from > 173.41.187.220.broad.zs.zj.dynamic.cndata.com (220.187.41.173) > 27/08/06 04:15:04 ERROR smtpserver: Socket to > 173.41.187.220.broad.zs.zj.dynamic.cndata.com (220.187.41.173) closed > remotely. > java.net.SocketException: Connection reset > at java.net.SocketInputStream.read(SocketInputStream.java:168) > at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) > at java.io.BufferedInputStream.read(BufferedInputStream.java:235) > at > org.apache.james.util.CRLFTerminatedReader.read(CRLFTerminatedReader.j > ava:15 > 1) > at > org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedRead > er.jav > a:111) > > POP3 Log: > > 27/08/06 03:52:14 INFO pop3server: Connection for > thorsten..famile-hinrichs.de from > dslc-082-082-188-072.pools.arcor-ip.net > (82.82.188.72) closed. > 27/08/06 03:52:19 INFO pop3server: Connection for > mail..famile-hinrichs.de from dslc-082-082-188-072.pools.arcor-ip.net (82.82.188.72) closed. > 27/08/06 07:52:43 INFO pop3server: Connection from > dslb-082-083-225-217.pools.arcor-ip.net (82.83.225.217) > 27/08/06 07:56:43 ERROR pop3server: POP3 Connection has idled out. > 27/08/06 08:27:46 INFO pop3server: Connection from > dslb-082-083-225-217.pools.arcor-ip.net (82.83.225.217) > 27/08/06 08:31:46 ERROR pop3server: POP3 Connection has idled out. > 27/08/06 08:32:45 INFO pop3server: Connection from > dslb-082-083-225-217.pools.arcor-ip.net (82.83.225.217) > 27/08/06 08:36:45 ERROR pop3server: POP3 Connection has idled out. > > > Connection Log: > > 27/08/06 04:19:45 WARN connections: Maximum number of open > connections exceeded - refusing connection. Current number of > connections is 60 > 27/08/06 04:19:45 INFO connections: Connections: > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=host109-57.pool8710.interbusiness.it/87.10.57.109,port=165 > 2,loca lport=25] on Thread[default Worker #11,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=lns-bzn-28-82-250-146-127.adsl.proxad.net/82.250.146.127,p > ort=39 82,localport=25] on Thread[default Worker #23,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=renoirpc58.sdnet.com.br/200.195.3.58,port=3661,localport=2 > 5] on Thread[default Worker #14,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=221.196.124.79/221.196.124.79,port=63592,localport=25] on > Thread[default Worker #21,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=85-250-132-198.bb.netvision.net.il/85.250.132.198,port=459 > 5,loca lport=25] on Thread[default Worker #7,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=221.139.120.41/221.139.120.41,port=2575,localport=25] on > Thread[default Worker #25,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=OL51-196.fibertel.com.ar/24.232.196.51,port=4833,localport > =25] on Thread[default Worker #19,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=189.166.102.207/189.166.102.207,port=62403,localport=25] > on Thread[default Worker #9,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=sal69-1-82-231-94-116.fbx.proxad.net/82.231.94.116,port=19 > 61,loc alport=25] on Thread[default Worker #16,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=201.254.209.149/201.254.209.149,port=1560,localport=25] on > Thread[default Worker #22,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for Socket[addr=69.79.176.166/69.79.176.166,port=3683,localport=25] > on Thread[default Worker #24,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for Socket[addr=24.49.116.62/24.49.116.62,port=4978,localport=25] > on Thread[default Worker #8,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=124.80.115.248/124.80.115.248,port=3764,localport=25] on > Thread[default Worker #13,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=pool-71-101-142-254.tampfl.dsl-w.verizon.net/71.101.142.25 > 4,port =50681,localport=25] on Thread[default Worker #30,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=119.Red-83-46-39.dynamicIP.rima-tde.net/83.46.39.119,port= > 1870,l ocalport=25] on Thread[default Worker #32,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=AAmiens-157-1-123-218.w86-215.abo.wanadoo.fr/86.215.182.21 > 8,port =3238,localport=25] on Thread[default Worker #36,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for Socket[addr=218.239.87.58/218.239.87.58,port=2982,localport=25] > on Thread[default Worker #38,5,default] > 27/08/06 04:19:45 INFO connections: > org.apache.james.util.connection.ServerConnection$ClientConnectionRunn > er for > Socket[addr=200-122-123-81.dsl.prima.net.ar/200.122.123.81,port=55451, > localp ort=25] on Thread[default Worker #35,5,default] .... > > !EXCUBATOR:1,44f16cea45111065217252! --------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED]
