You are viewing a plain text version of this content. The canonical link for it is here.
Posted to server-user@james.apache.org by Rudolf Schmidt <we...@gmx.de> on 2006/08/27 11:58:08 UTC
James running out of connections
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 support-ref000391365vr@volksbank.de on
125.136.22.132 for [mail@werbdesign.de]
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>(MimeMessageInputSt
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.java:39
1)
at
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner.run
(ServerConnection.java:422)
at
org.apache.excalibur.thread.impl.ExecutableRunnable.execute(ExecutableRunnab
le.java:55)
at
org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:116)
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(CharTerminatedInputStre
am.java:98)
at
org.apache.james.util.watchdog.BytesReadResetInputStream.read(BytesReadReset
InputStream.java:96)
at
org.apache.james.util.DotStuffingInputStream.read(DotStuffingInputStream.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>(MimeMessageInputSt
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.java:15
1)
at
org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedReader.jav
a:111)
at
org.apache.james.smtpserver.SMTPHandler.readCommandLine(SMTPHandler.java:749
)
at
org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.java:37
0)
at
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner.run
(ServerConnection.java:422)
at
org.apache.excalibur.thread.impl.ExecutableRunnable.execute(ExecutableRunnab
le.java:55)
at
org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:116)
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>(MimeMessageInputSt
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.java:39
1)
at
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner.run
(ServerConnection.java:422)
at
org.apache.excalibur.thread.impl.ExecutableRunnable.execute(ExecutableRunnab
le.java:55)
at
org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:116)
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(CharTerminatedInputStre
am.java:98)
at
org.apache.james.util.watchdog.BytesReadResetInputStream.read(BytesReadReset
InputStream.java:96)
at
org.apache.james.util.DotStuffingInputStream.read(DotStuffingInputStream.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>(MimeMessageInputSt
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.java:15
1)
at
org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedReader.jav
a:111)
at
org.apache.james.smtpserver.SMTPHandler.readCommandLine(SMTPHandler.java:749
)
at
org.apache.james.smtpserver.SMTPHandler.handleConnection(SMTPHandler.java:37
0)
at
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner.run
(ServerConnection.java:422)
at
org.apache.excalibur.thread.impl.ExecutableRunnable.execute(ExecutableRunnab
le.java:55)
at
org.apache.excalibur.thread.impl.WorkerThread.run(WorkerThread.java:116)
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.java:15
1)
at
org.apache.james.util.CRLFTerminatedReader.readLine(CRLFTerminatedReader.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$ClientConnectionRunner for
Socket[addr=host109-57.pool8710.interbusiness.it/87.10.57.109,port=1652,loca
lport=25] on Thread[default Worker #11,5,default]
27/08/06 04:19:45 INFO connections:
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner for
Socket[addr=lns-bzn-28-82-250-146-127.adsl.proxad.net/82.250.146.127,port=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$ClientConnectionRunner for
Socket[addr=renoirpc58.sdnet.com.br/200.195.3.58,port=3661,localport=25] on
Thread[default Worker #14,5,default]
27/08/06 04:19:45 INFO connections:
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner 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$ClientConnectionRunner for
Socket[addr=85-250-132-198.bb.netvision.net.il/85.250.132.198,port=4595,loca
lport=25] on Thread[default Worker #7,5,default]
27/08/06 04:19:45 INFO connections:
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner 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$ClientConnectionRunner 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$ClientConnectionRunner 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$ClientConnectionRunner for
Socket[addr=sal69-1-82-231-94-116.fbx.proxad.net/82.231.94.116,port=1961,loc
alport=25] on Thread[default Worker #16,5,default]
27/08/06 04:19:45 INFO connections:
org.apache.james.util.connection.ServerConnection$ClientConnectionRunner 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$ClientConnectionRunner 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$ClientConnectionRunner 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$ClientConnectionRunner 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$ClientConnectionRunner for
Socket[addr=pool-71-101-142-254.tampfl.dsl-w.verizon.net/71.101.142.254,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$ClientConnectionRunner 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$ClientConnectionRunner for
Socket[addr=AAmiens-157-1-123-218.w86-215.abo.wanadoo.fr/86.215.182.218,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$ClientConnectionRunner 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$ClientConnectionRunner 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]
....