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 Markus Moldaschl <ma...@willhaben.at> on 2013/06/06 16:20:39 UTC
Performance problem with TcpTransport
Hi,
I'm currently stuck with a strange performance problem with my James
3.0-beta3 when I'm running our integration tests.
As you can see from the extract of the james-server.log, James takes
about 15 seconds from the time where the SMTP connection is closed to
the time where the email is sent to the remote delivery gateway.
According to the log-File the TcpTransport seems to be the problem.
Log-Output with DEBUG enabled:
INFO 2013-06-06 15:53:14,663 | pool-14-thread-12 |
smtpserver | ID=21754426 Connection closed for
127.0.0.1 (127.0.0.1)
DEBUG 2013-06-06 15:53:14,664 | dequeuer-2 |
TransactionContext | Begin:TX:ID:TP-59968-1370526743782-3:1:1
DEBUG 2013-06-06 15:53:14,670 | dequeuer-2 |
CachingConnectionFactory | Creating cached JMS Session for mode 0:
ActiveMQSession {id=ID:TP-59968-1370526743782-3:1:14,started=true}
DEBUG 2013-06-06 15:53:14,670 | spooler-1 |
mailspooler | ==== Begin processing mail
Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4====
... lines ommitted
DEBUG 2013-06-06 15:53:14,710 | spooler-1 |
MulticastProcessor | Done sequential processing 1 exchanges
DEBUG 2013-06-06 15:53:16,959 | dmlc-2 |
TcpTransport | Stopping transport tcp://localhost:61616
DEBUG 2013-06-06 15:53:22,960 | dmlc-2 |
TcpTransport | Stopping transport tcp://localhost:61616
DEBUG 2013-06-06 15:53:23,101 | Remote delivery thread (3) |
ActiveMQSession | ID:TP-59968-1370526743782-3:1:4
Transaction Commit :null
DEBUG 2013-06-06 15:53:23,102 | Remote delivery thread (3) |
ActiveMQMessageConsumer | remove:
ID:TP-59968-1370526743782-3:1:4:4, lastDeliveredSequenceId:0
... lines ommitted
DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
ActiveMQSession | ID:TP-59968-1370526743782-3:1:14
Transaction Commit :null
DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
ActiveMQMessageConsumer | remove:
ID:TP-59968-1370526743782-3:1:14:1, lastDeliveredSequenceId:0
DEBUG 2013-06-06 15:53:24,672 | VMTransport |
AbstractRegion | james removing consumer:
ID:TP-59968-1370526743782-3:1:14:1 for destination: queue://spool
DEBUG 2013-06-06 15:53:24,672 | VMTransport | Queue
| remove sub: QueueSubscription:
consumer=ID:TP-59968-1370526743782-3:1:14:1, destinations=1,
dispatched=0, delivered=0, pending=0, lastDeliveredSeqId: 0, dequeues:
0, dispatched: 1, inflight: 1
DEBUG 2013-06-06 15:53:24,673 | VMTransport |
AbstractRegion | james adding consumer:
ID:TP-59968-1370526743782-3:1:14:2 for destination: queue://spool
DEBUG 2013-06-06 15:53:28,961 | dmlc-2 |
TcpTransport | Stopping transport tcp://localhost:61616
DEBUG 2013-06-06 15:53:29,776 | spooler-1 |
BeanProcessor | Setting bean invocation result on the
OUT message: [org.apache.james.core.MailImpl@17c84ad]
DEBUG 2013-06-06 15:53:29,777 | spooler-1 |
ChoiceProcessor | #0 -
org.apache.james.mailetcontainer.impl.camel.MatcherMatch@4af1f4 matches:
false for: Exchange[Message: org.apache.james.core.MailImpl@17c84ad]
... lines ommitted
INFO 2013-06-06 15:53:30,268 | Remote delivery thread (3) |
mailetcontext | Attempting delivery of
Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457 to
host 127.0.0.1 at 127.0.0.1 from
bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for addresses
[B@127.0.0.1]
INFO 2013-06-06 15:53:30,300 | Remote delivery thread (3) |
mailetcontext | Mail
(Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457)
sent successfully to 127.0.0.1 at 127.0.0.1 from
bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for [B@127.0.0.1]
The problem occurs on my Windows 7 machine as well as on our Jenkins
which runs on Linux.
Does anybody know of this issue or does anybody have a hint for me?
Thanks
Markus
RE: Performance problem with TcpTransport
Posted by "Patwardhan, Kaustubh" <ka...@capgemini.com>.
Hi,
Same issue we also faced and we have removed the spamcheck functionality from config for timebeing.
Please let us also know if there is any replacement for dnsbl.njabl.org.
_______________________________________________________________________
Kaustubh Patwardhan
Senior Consultant | TS-NAR ITICS - IM
Capgemini India | Airoli
Main: +91 22 6755 7000 ext.-2212345 | Cell: +91 9920467097
Voip: 91-312-799-7777 dial "#" extn 2212345
www.capgemini.com
E-mail : kaustubh.patwardhan@capgemini.com
Fourth floor, Building no.8, Serene Estates,
Mind Space SEZ Campus, Thane-Belapur Road,
Airoli, Navi Mumbai - 400708
People matter, results count.
_______________________________________________________________________
-----Original Message-----
From: Markus Moldaschl [mailto:markus.moldaschl@willhaben.at]
Sent: Monday, June 10, 2013 8:18 PM
To: James Users List
Subject: AW: Performance problem with TcpTransport
Hi,
I've further tracked down the issue and found out that our nameserver runs in a timeout when James sends a request for dnsbl.njabl.org whereas other nameservers like the public google nameserver quickly refuses the request.
Looking at http://www.njabl.org/, one can see that the service is not available anymore. So I guess I can remove the
<mailet match="InSpammerBlacklist=dnsbl.njabl.org" class="ToProcessor">
<processor>spam</processor>
<notice>550 Requested action not taken: rejected - see http://njabl.org/</notice> </mailet>
from our mailetconfig.xml.
Will there be any replacement? Unfortunately I did not find more than https://issues.apache.org/jira/browse/JAMES-1380 on that topic.
Thx
Markus
-----Ursprüngliche Nachricht-----
Von: Markus Moldaschl [mailto:markus.moldaschl@willhaben.at]
Gesendet: Freitag, 07. Juni 2013 09:48
An: James Users List
Betreff: AW: Performance problem with TcpTransport
Thx Eric! You are right, we have an issue in our network infrastructure ... and I thought it was me ;-).
Markus
-----Ursprüngliche Nachricht-----
Von: Eric Charles [mailto:eric@apache.org]
Gesendet: Donnerstag, 06. Juni 2013 18:36
An: James Users List
Betreff: Re: Performance problem with TcpTransport
DNS resolution slow (falling back on the second dns sever)?
On 06/06/2013 15:20, Markus Moldaschl wrote:
> Hi,
>
>
>
> I'm currently stuck with a strange performance problem with my James
> 3.0-beta3 when I'm running our integration tests.
>
>
>
> As you can see from the extract of the james-server.log, James takes
> about 15 seconds from the time where the SMTP connection is closed to
> the time where the email is sent to the remote delivery gateway.
> According to the log-File the TcpTransport seems to be the problem.
>
>
>
> Log-Output with DEBUG enabled:
>
>
>
> INFO 2013-06-06 15:53:14,663 | pool-14-thread-12 |
> smtpserver | ID=21754426 Connection closed for
> 127.0.0.1 (127.0.0.1)
>
> DEBUG 2013-06-06 15:53:14,664 | dequeuer-2 |
> TransactionContext | Begin:TX:ID:TP-59968-1370526743782-3:1:1
>
> DEBUG 2013-06-06 15:53:14,670 | dequeuer-2 |
> CachingConnectionFactory | Creating cached JMS Session for mode 0:
> ActiveMQSession {id=ID:TP-59968-1370526743782-3:1:14,started=true}
>
> DEBUG 2013-06-06 15:53:14,670 | spooler-1 |
> mailspooler | ==== Begin processing mail
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4====
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:14,710 | spooler-1 |
> MulticastProcessor | Done sequential processing 1 exchanges
>
> DEBUG 2013-06-06 15:53:16,959 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:22,960 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:23,101 | Remote delivery thread (3) |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:4
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:23,102 | Remote delivery thread (3) |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:4:4, lastDeliveredSequenceId:0
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:14
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:14:1, lastDeliveredSequenceId:0
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport |
> AbstractRegion | james removing consumer:
> ID:TP-59968-1370526743782-3:1:14:1 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport | Queue
> | remove sub: QueueSubscription:
> consumer=ID:TP-59968-1370526743782-3:1:14:1, destinations=1,
> dispatched=0, delivered=0, pending=0, lastDeliveredSeqId: 0, dequeues:
> 0, dispatched: 1, inflight: 1
>
> DEBUG 2013-06-06 15:53:24,673 | VMTransport |
> AbstractRegion | james adding consumer:
> ID:TP-59968-1370526743782-3:1:14:2 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:28,961 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:29,776 | spooler-1 |
> BeanProcessor | Setting bean invocation result on the
> OUT message: [org.apache.james.core.MailImpl@17c84ad]
>
> DEBUG 2013-06-06 15:53:29,777 | spooler-1 |
> ChoiceProcessor | #0 -
> org.apache.james.mailetcontainer.impl.camel.MatcherMatch@4af1f4 matches:
> false for: Exchange[Message: org.apache.james.core.MailImpl@17c84ad]
>
>
>
> ... lines ommitted
>
>
>
> INFO 2013-06-06 15:53:30,268 | Remote delivery thread (3) |
> mailetcontext | Attempting delivery of
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> to host 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for addresses
> [B@127.0.0.1]
>
> INFO 2013-06-06 15:53:30,300 | Remote delivery thread (3) |
> mailetcontext | Mail
> (Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> ) sent successfully to 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for [B@127.0.0.1]
>
>
>
> The problem occurs on my Windows 7 machine as well as on our Jenkins
> which runs on Linux.
>
>
>
> Does anybody know of this issue or does anybody have a hint for me?
>
>
>
> Thanks
>
> Markus
>
>
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
This message contains information that may be privileged or confidential and is the property of the Capgemini Group. It is intended only for the person to whom it is addressed. If you are not the intended recipient, you are not authorized to read, print, retain, copy, disseminate, distribute, or use this message or any part thereof. If you receive this message in error, please notify the sender immediately and delete all copies of this message.
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
AW: Performance problem with TcpTransport
Posted by Markus Moldaschl <ma...@willhaben.at>.
Hi,
I've further tracked down the issue and found out that our nameserver runs in a timeout when James sends a request for dnsbl.njabl.org whereas other nameservers like the public google nameserver quickly refuses the request.
Looking at http://www.njabl.org/, one can see that the service is not available anymore. So I guess I can remove the
<mailet match="InSpammerBlacklist=dnsbl.njabl.org" class="ToProcessor">
<processor>spam</processor>
<notice>550 Requested action not taken: rejected - see http://njabl.org/</notice>
</mailet>
from our mailetconfig.xml.
Will there be any replacement? Unfortunately I did not find more than https://issues.apache.org/jira/browse/JAMES-1380 on that topic.
Thx
Markus
-----Ursprüngliche Nachricht-----
Von: Markus Moldaschl [mailto:markus.moldaschl@willhaben.at]
Gesendet: Freitag, 07. Juni 2013 09:48
An: James Users List
Betreff: AW: Performance problem with TcpTransport
Thx Eric! You are right, we have an issue in our network infrastructure ... and I thought it was me ;-).
Markus
-----Ursprüngliche Nachricht-----
Von: Eric Charles [mailto:eric@apache.org]
Gesendet: Donnerstag, 06. Juni 2013 18:36
An: James Users List
Betreff: Re: Performance problem with TcpTransport
DNS resolution slow (falling back on the second dns sever)?
On 06/06/2013 15:20, Markus Moldaschl wrote:
> Hi,
>
>
>
> I'm currently stuck with a strange performance problem with my James
> 3.0-beta3 when I'm running our integration tests.
>
>
>
> As you can see from the extract of the james-server.log, James takes
> about 15 seconds from the time where the SMTP connection is closed to
> the time where the email is sent to the remote delivery gateway.
> According to the log-File the TcpTransport seems to be the problem.
>
>
>
> Log-Output with DEBUG enabled:
>
>
>
> INFO 2013-06-06 15:53:14,663 | pool-14-thread-12 |
> smtpserver | ID=21754426 Connection closed for
> 127.0.0.1 (127.0.0.1)
>
> DEBUG 2013-06-06 15:53:14,664 | dequeuer-2 |
> TransactionContext | Begin:TX:ID:TP-59968-1370526743782-3:1:1
>
> DEBUG 2013-06-06 15:53:14,670 | dequeuer-2 |
> CachingConnectionFactory | Creating cached JMS Session for mode 0:
> ActiveMQSession {id=ID:TP-59968-1370526743782-3:1:14,started=true}
>
> DEBUG 2013-06-06 15:53:14,670 | spooler-1 |
> mailspooler | ==== Begin processing mail
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4====
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:14,710 | spooler-1 |
> MulticastProcessor | Done sequential processing 1 exchanges
>
> DEBUG 2013-06-06 15:53:16,959 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:22,960 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:23,101 | Remote delivery thread (3) |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:4
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:23,102 | Remote delivery thread (3) |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:4:4, lastDeliveredSequenceId:0
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:14
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:14:1, lastDeliveredSequenceId:0
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport |
> AbstractRegion | james removing consumer:
> ID:TP-59968-1370526743782-3:1:14:1 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport | Queue
> | remove sub: QueueSubscription:
> consumer=ID:TP-59968-1370526743782-3:1:14:1, destinations=1,
> dispatched=0, delivered=0, pending=0, lastDeliveredSeqId: 0, dequeues:
> 0, dispatched: 1, inflight: 1
>
> DEBUG 2013-06-06 15:53:24,673 | VMTransport |
> AbstractRegion | james adding consumer:
> ID:TP-59968-1370526743782-3:1:14:2 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:28,961 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:29,776 | spooler-1 |
> BeanProcessor | Setting bean invocation result on the
> OUT message: [org.apache.james.core.MailImpl@17c84ad]
>
> DEBUG 2013-06-06 15:53:29,777 | spooler-1 |
> ChoiceProcessor | #0 -
> org.apache.james.mailetcontainer.impl.camel.MatcherMatch@4af1f4 matches:
> false for: Exchange[Message: org.apache.james.core.MailImpl@17c84ad]
>
>
>
> ... lines ommitted
>
>
>
> INFO 2013-06-06 15:53:30,268 | Remote delivery thread (3) |
> mailetcontext | Attempting delivery of
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> to host 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for addresses
> [B@127.0.0.1]
>
> INFO 2013-06-06 15:53:30,300 | Remote delivery thread (3) |
> mailetcontext | Mail
> (Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> ) sent successfully to 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for [B@127.0.0.1]
>
>
>
> The problem occurs on my Windows 7 machine as well as on our Jenkins
> which runs on Linux.
>
>
>
> Does anybody know of this issue or does anybody have a hint for me?
>
>
>
> Thanks
>
> Markus
>
>
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
AW: Performance problem with TcpTransport
Posted by Markus Moldaschl <ma...@willhaben.at>.
Thx Eric! You are right, we have an issue in our network infrastructure ... and I thought it was me ;-).
Markus
-----Ursprüngliche Nachricht-----
Von: Eric Charles [mailto:eric@apache.org]
Gesendet: Donnerstag, 06. Juni 2013 18:36
An: James Users List
Betreff: Re: Performance problem with TcpTransport
DNS resolution slow (falling back on the second dns sever)?
On 06/06/2013 15:20, Markus Moldaschl wrote:
> Hi,
>
>
>
> I'm currently stuck with a strange performance problem with my James
> 3.0-beta3 when I'm running our integration tests.
>
>
>
> As you can see from the extract of the james-server.log, James takes
> about 15 seconds from the time where the SMTP connection is closed to
> the time where the email is sent to the remote delivery gateway.
> According to the log-File the TcpTransport seems to be the problem.
>
>
>
> Log-Output with DEBUG enabled:
>
>
>
> INFO 2013-06-06 15:53:14,663 | pool-14-thread-12 |
> smtpserver | ID=21754426 Connection closed for
> 127.0.0.1 (127.0.0.1)
>
> DEBUG 2013-06-06 15:53:14,664 | dequeuer-2 |
> TransactionContext | Begin:TX:ID:TP-59968-1370526743782-3:1:1
>
> DEBUG 2013-06-06 15:53:14,670 | dequeuer-2 |
> CachingConnectionFactory | Creating cached JMS Session for mode 0:
> ActiveMQSession {id=ID:TP-59968-1370526743782-3:1:14,started=true}
>
> DEBUG 2013-06-06 15:53:14,670 | spooler-1 |
> mailspooler | ==== Begin processing mail
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4====
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:14,710 | spooler-1 |
> MulticastProcessor | Done sequential processing 1 exchanges
>
> DEBUG 2013-06-06 15:53:16,959 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:22,960 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:23,101 | Remote delivery thread (3) |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:4
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:23,102 | Remote delivery thread (3) |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:4:4, lastDeliveredSequenceId:0
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:14
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:14:1, lastDeliveredSequenceId:0
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport |
> AbstractRegion | james removing consumer:
> ID:TP-59968-1370526743782-3:1:14:1 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport | Queue
> | remove sub: QueueSubscription:
> consumer=ID:TP-59968-1370526743782-3:1:14:1, destinations=1,
> dispatched=0, delivered=0, pending=0, lastDeliveredSeqId: 0, dequeues:
> 0, dispatched: 1, inflight: 1
>
> DEBUG 2013-06-06 15:53:24,673 | VMTransport |
> AbstractRegion | james adding consumer:
> ID:TP-59968-1370526743782-3:1:14:2 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:28,961 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:29,776 | spooler-1 |
> BeanProcessor | Setting bean invocation result on the
> OUT message: [org.apache.james.core.MailImpl@17c84ad]
>
> DEBUG 2013-06-06 15:53:29,777 | spooler-1 |
> ChoiceProcessor | #0 -
> org.apache.james.mailetcontainer.impl.camel.MatcherMatch@4af1f4 matches:
> false for: Exchange[Message: org.apache.james.core.MailImpl@17c84ad]
>
>
>
> ... lines ommitted
>
>
>
> INFO 2013-06-06 15:53:30,268 | Remote delivery thread (3) |
> mailetcontext | Attempting delivery of
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> to host 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for addresses
> [B@127.0.0.1]
>
> INFO 2013-06-06 15:53:30,300 | Remote delivery thread (3) |
> mailetcontext | Mail
> (Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457
> ) sent successfully to 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for [B@127.0.0.1]
>
>
>
> The problem occurs on my Windows 7 machine as well as on our Jenkins
> which runs on Linux.
>
>
>
> Does anybody know of this issue or does anybody have a hint for me?
>
>
>
> Thanks
>
> Markus
>
>
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
Re: Performance problem with TcpTransport
Posted by Eric Charles <er...@apache.org>.
DNS resolution slow (falling back on the second dns sever)?
On 06/06/2013 15:20, Markus Moldaschl wrote:
> Hi,
>
>
>
> I'm currently stuck with a strange performance problem with my James
> 3.0-beta3 when I'm running our integration tests.
>
>
>
> As you can see from the extract of the james-server.log, James takes
> about 15 seconds from the time where the SMTP connection is closed to
> the time where the email is sent to the remote delivery gateway.
> According to the log-File the TcpTransport seems to be the problem.
>
>
>
> Log-Output with DEBUG enabled:
>
>
>
> INFO 2013-06-06 15:53:14,663 | pool-14-thread-12 |
> smtpserver | ID=21754426 Connection closed for
> 127.0.0.1 (127.0.0.1)
>
> DEBUG 2013-06-06 15:53:14,664 | dequeuer-2 |
> TransactionContext | Begin:TX:ID:TP-59968-1370526743782-3:1:1
>
> DEBUG 2013-06-06 15:53:14,670 | dequeuer-2 |
> CachingConnectionFactory | Creating cached JMS Session for mode 0:
> ActiveMQSession {id=ID:TP-59968-1370526743782-3:1:14,started=true}
>
> DEBUG 2013-06-06 15:53:14,670 | spooler-1 |
> mailspooler | ==== Begin processing mail
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4====
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:14,710 | spooler-1 |
> MulticastProcessor | Done sequential processing 1 exchanges
>
> DEBUG 2013-06-06 15:53:16,959 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:22,960 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:23,101 | Remote delivery thread (3) |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:4
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:23,102 | Remote delivery thread (3) |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:4:4, lastDeliveredSequenceId:0
>
>
>
> ... lines ommitted
>
>
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQSession | ID:TP-59968-1370526743782-3:1:14
> Transaction Commit :null
>
> DEBUG 2013-06-06 15:53:24,672 | dequeuer-2 |
> ActiveMQMessageConsumer | remove:
> ID:TP-59968-1370526743782-3:1:14:1, lastDeliveredSequenceId:0
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport |
> AbstractRegion | james removing consumer:
> ID:TP-59968-1370526743782-3:1:14:1 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:24,672 | VMTransport | Queue
> | remove sub: QueueSubscription:
> consumer=ID:TP-59968-1370526743782-3:1:14:1, destinations=1,
> dispatched=0, delivered=0, pending=0, lastDeliveredSeqId: 0, dequeues:
> 0, dispatched: 1, inflight: 1
>
> DEBUG 2013-06-06 15:53:24,673 | VMTransport |
> AbstractRegion | james adding consumer:
> ID:TP-59968-1370526743782-3:1:14:2 for destination: queue://spool
>
> DEBUG 2013-06-06 15:53:28,961 | dmlc-2 |
> TcpTransport | Stopping transport tcp://localhost:61616
>
> DEBUG 2013-06-06 15:53:29,776 | spooler-1 |
> BeanProcessor | Setting bean invocation result on the
> OUT message: [org.apache.james.core.MailImpl@17c84ad]
>
> DEBUG 2013-06-06 15:53:29,777 | spooler-1 |
> ChoiceProcessor | #0 -
> org.apache.james.mailetcontainer.impl.camel.MatcherMatch@4af1f4 matches:
> false for: Exchange[Message: org.apache.james.core.MailImpl@17c84ad]
>
>
>
> ... lines ommitted
>
>
>
> INFO 2013-06-06 15:53:30,268 | Remote delivery thread (3) |
> mailetcontext | Attempting delivery of
> Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457 to
> host 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for addresses
> [B@127.0.0.1]
>
> INFO 2013-06-06 15:53:30,300 | Remote delivery thread (3) |
> mailetcontext | Mail
> (Mail1370526794622-977f4719-b1e2-4d48-8d5e-8d2dda4df9a4-!814652-!34457)
> sent successfully to 127.0.0.1 at 127.0.0.1 from
> bounce+bissc6cwb1q5swcn-B=127.0.0.1@localhost for [B@127.0.0.1]
>
>
>
> The problem occurs on my Windows 7 machine as well as on our Jenkins
> which runs on Linux.
>
>
>
> Does anybody know of this issue or does anybody have a hint for me?
>
>
>
> Thanks
>
> Markus
>
>
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org