[
https://issues.apache.org/jira/browse/QPID-8071?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16320083#comment-16320083
]
Alojzij Blatnik edited comment on QPID-8071 at 1/10/18 1:35 PM:
----------------------------------------------------------------
{code:java}
Hi.
Thank you for quick response. I didn't know for the latter client. I'm using
qpid c++ broker version 1.36.0.
Now I changed from
<dependency>
<groupId>org.apache.qpid</groupId>
<artifactId>qpid-client</artifactId>
<version>6.1.5</version>
</dependency>
to
<dependency>
<groupId>org.apache.qpid</groupId>
<artifactId>qpid-jms-client</artifactId>
<version>0.28.0</version>
</dependency>
and now the error is the following:
1114 [main] ERROR org.apache.qpid.jms.JmsConnection - Failed to connect to
remote at:
amqps://qpid-broker:5671/?transport.keyAlias=alias&transport.keyStoreLocation=%2Ftestjkss%2Fqpidks.jks&transport.trustStoreLocation=%2Ftestjkss%2Fqpidcatruststore.jks&transport.keyStorePassword=xxx&transport.trustStorePassword=xxx
...
Caused by: javax.jms.JMSException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at org.apache.qpid.jms.JmsConnection.connect(JmsConnection.java:167)
at
org.apache.qpid.jms.JmsConnectionFactory.createConnection(JmsConnectionFactory.java:203)
at
org.apache.qpid.jms.JmsConnectionFactory.createConnection(JmsConnectionFactory.java:190)
......
... 9 more
Caused by: java.io.IOException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.jms.util.IOExceptionSupport.create(IOExceptionSupport.java:45)
at
org.apache.qpid.jms.provider.ProviderFuture.failOnError(ProviderFuture.java:114)
at
org.apache.qpid.jms.provider.ProviderFuture.sync(ProviderFuture.java:91)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.connect(AmqpProvider.java:222)
at org.apache.qpid.jms.JmsConnection.connect(JmsConnection.java:155)
... 12 more
Caused by: org.apache.qpid.proton.engine.TransportException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.proton.engine.TransportResultFactory$TransportResultImpl.checkIsOk(TransportResultFactory.java:116)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:784)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.qpid.proton.engine.TransportException: AMQP SASL header
mismatch value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.proton.engine.impl.SaslFrameParser.input(SaslFrameParser.java:157)
at
org.apache.qpid.proton.engine.impl.SaslImpl$SaslTransportWrapper.reallyProcessInput(SaslImpl.java:636)
at
org.apache.qpid.proton.engine.impl.SaslImpl$SaslTransportWrapper.process(SaslImpl.java:607)
at
org.apache.qpid.proton.engine.impl.HandshakeSniffingTransportWrapper.process(HandshakeSniffingTransportWrapper.java:101)
at
org.apache.qpid.proton.engine.impl.TransportImpl.process(TransportImpl.java:1495)
at
org.apache.qpid.proton.engine.impl.TransportImpl.processInput(TransportImpl.java:1453)
... 8 more
Caused by: javax.jms.JMSException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:608)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:592)
at
org.apache.qpid.jms.JmsConnection.createJmsConnection(JmsConnection.java:507)
at org.apache.qpid.jms.JmsConnection.start(JmsConnection.java:342)
at
si.halcom.transport.session.QpidTransporter.connectToQpid(QpidTransporter.java:168)
... 9 more
Caused by: java.io.IOException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.checkConnected(NettyTcpTransport.java:377)
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.allocateSendBuffer(NettyTcpTransport.java:256)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:995)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:986)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:793)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
If I add amqp.saslLayer=false (just for test) I get the following stack trace:
Caused by: javax.jms.JMSException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:608)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:592)
at
org.apache.qpid.jms.JmsConnection.createJmsConnection(JmsConnection.java:507)
at org.apache.qpid.jms.JmsConnection.start(JmsConnection.java:342)
........
... 9 more
Caused by: java.io.IOException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.checkConnected(NettyTcpTransport.java:377)
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.allocateSendBuffer(NettyTcpTransport.java:256)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:995)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:986)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:793)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Thanks
{code}
was (Author: predkambrij):
Hi.
Thank you for quick response. I didn't know for the latter client. I'm using
qpid c++ broker version 1.36.0.
Now I changed from
<dependency>
<groupId>org.apache.qpid</groupId>
<artifactId>qpid-client</artifactId>
<version>6.1.5</version>
</dependency>
to
<dependency>
<groupId>org.apache.qpid</groupId>
<artifactId>qpid-jms-client</artifactId>
<version>0.28.0</version>
</dependency>
and now the error is the following:
1114 [main] ERROR org.apache.qpid.jms.JmsConnection - Failed to connect to
remote at:
amqps://qpid-broker:5671/?transport.keyAlias=alias&transport.keyStoreLocation=%2Ftestjkss%2Fqpidks.jks&transport.trustStoreLocation=%2Ftestjkss%2Fqpidcatruststore.jks&transport.keyStorePassword=xxx&transport.trustStorePassword=xxx
...
Caused by: javax.jms.JMSException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at org.apache.qpid.jms.JmsConnection.connect(JmsConnection.java:167)
at
org.apache.qpid.jms.JmsConnectionFactory.createConnection(JmsConnectionFactory.java:203)
at
org.apache.qpid.jms.JmsConnectionFactory.createConnection(JmsConnectionFactory.java:190)
......
... 9 more
Caused by: java.io.IOException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.jms.util.IOExceptionSupport.create(IOExceptionSupport.java:45)
at
org.apache.qpid.jms.provider.ProviderFuture.failOnError(ProviderFuture.java:114)
at
org.apache.qpid.jms.provider.ProviderFuture.sync(ProviderFuture.java:91)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.connect(AmqpProvider.java:222)
at org.apache.qpid.jms.JmsConnection.connect(JmsConnection.java:155)
... 12 more
Caused by: org.apache.qpid.proton.engine.TransportException:
org.apache.qpid.proton.engine.TransportException: AMQP SASL header mismatch
value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.proton.engine.TransportResultFactory$TransportResultImpl.checkIsOk(TransportResultFactory.java:116)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:784)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.qpid.proton.engine.TransportException: AMQP SASL header
mismatch value 1, expecting 3. In state: HEADER4
at
org.apache.qpid.proton.engine.impl.SaslFrameParser.input(SaslFrameParser.java:157)
at
org.apache.qpid.proton.engine.impl.SaslImpl$SaslTransportWrapper.reallyProcessInput(SaslImpl.java:636)
at
org.apache.qpid.proton.engine.impl.SaslImpl$SaslTransportWrapper.process(SaslImpl.java:607)
at
org.apache.qpid.proton.engine.impl.HandshakeSniffingTransportWrapper.process(HandshakeSniffingTransportWrapper.java:101)
at
org.apache.qpid.proton.engine.impl.TransportImpl.process(TransportImpl.java:1495)
at
org.apache.qpid.proton.engine.impl.TransportImpl.processInput(TransportImpl.java:1453)
... 8 more
Caused by: javax.jms.JMSException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:608)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:592)
at
org.apache.qpid.jms.JmsConnection.createJmsConnection(JmsConnection.java:507)
at org.apache.qpid.jms.JmsConnection.start(JmsConnection.java:342)
at
si.halcom.transport.session.QpidTransporter.connectToQpid(QpidTransporter.java:168)
... 9 more
Caused by: java.io.IOException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.checkConnected(NettyTcpTransport.java:377)
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.allocateSendBuffer(NettyTcpTransport.java:256)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:995)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:986)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:793)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
If I add amqp.saslLayer=false (just for test) I get the following stack trace:
Caused by: javax.jms.JMSException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:86)
at
org.apache.qpid.jms.exceptions.JmsExceptionSupport.create(JmsExceptionSupport.java:108)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:608)
at
org.apache.qpid.jms.JmsConnection.createResource(JmsConnection.java:592)
at
org.apache.qpid.jms.JmsConnection.createJmsConnection(JmsConnection.java:507)
at org.apache.qpid.jms.JmsConnection.start(JmsConnection.java:342)
........
... 9 more
Caused by: java.io.IOException: Cannot send to a non-connected transport.
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.checkConnected(NettyTcpTransport.java:377)
at
org.apache.qpid.jms.transports.netty.NettyTcpTransport.allocateSendBuffer(NettyTcpTransport.java:256)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:995)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider.pumpToProtonTransport(AmqpProvider.java:986)
at
org.apache.qpid.jms.provider.amqp.AmqpProvider$17.run(AmqpProvider.java:793)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Thanks
> Java Qpid library does not report that session was closed
> ---------------------------------------------------------
>
> Key: QPID-8071
> URL: https://issues.apache.org/jira/browse/QPID-8071
> Project: Qpid
> Issue Type: Bug
> Components: JMS AMQP 0-x
> Affects Versions: qpid-java-6.1.5
> Environment: slow network towards Qpid broker
> for instance set this to the machine that is making connection towards qpid
> broker
> sudo tc qdisc add dev enp0s8 root handle 1: tbf rate 16kbit latency 25ms
> burst 2k
> reset the throttle with this
> sudo tc qdisc del dev enp0s8 root
> add those flags to shorten qpid.io_network_transport_timeout from 1min to 5
> seconds
> -Dqpid.sync_op_timeout=509000 -Dqpid.io_network_transport_timeout=5000
> try to send 512kB long message. If qpid.io_network_transport_timeout is
> raised to 3 minutes it should take about 300 seconds to send the message
> (with 16kbit link) (the default 60s threshold is exceeded).
> Reporter: Alojzij Blatnik
>
> when qpid.io_network_transport_timeout is exceeded, qpid-java library does
> not throw exception, but the exception is just logged, hence the code, that
> is calling qpid-java library doesn't know that qpid message wasn't sent until
> it becomes obvious that connection is closed.
> monkey patch:
> {{diff --git a/common/src/main/java/org/apache/qpid/transport/Session.java
> b/common/src/main/java/org/apache/qpid/transport/Session.java
> index 9568873..b1afd8d 100644
> --- a/common/src/main/java/org/apache/qpid/transport/Session.java
> +++ b/common/src/main/java/org/apache/qpid/transport/Session.java
> @@ -759,16 +759,7 @@ public class Session extends SessionInvoker
> }
> catch (SenderException e)
> {
> - if (!closing)
> - {
> - // if we are not closing then this will happen
> - // again on resume
> - LOGGER.error("error sending command", e);
> - }
> - else
> - {
> - e.rethrow();
> - }
> + e.rethrow();
> }
> if (autoSync)
> {
> }}
> Some logs:
> {{
> 12585 [main] ERROR org.apache.qpid.transport.network.io.IoSender - write
> timed out for socket /172.21.0.2:50618-qpid-broker/192.168.80.1:5671: head
> -2147216975, tail -2147348047
> 12586 [main] ERROR org.apache.qpid.transport.Session - error sending command
> org.apache.qpid.transport.SenderException: write timed out for socket
> /172.21.0.2:50618-qpid-broker/192.168.80.1:5671: head -2147216975, tail
> -2147348047
> at org.apache.qpid.transport.network.io.IoSender.send(IoSender.java:172)
> at
> org.apache.qpid.transport.network.security.ssl.SSLSender.doSend(SSLSender.java:215)
> at
> org.apache.qpid.transport.network.security.ssl.SSLSender.flush(SSLSender.java:149)
> at
> org.apache.qpid.transport.network.Disassembler.flush(Disassembler.java:88)
> at org.apache.qpid.transport.Connection.flush(Connection.java:428)
> at org.apache.qpid.transport.Session.send(Session.java:592)
> at org.apache.qpid.transport.Session.invoke(Session.java:758)
> at org.apache.qpid.transport.Session.invoke(Session.java:613)
> at
> org.apache.qpid.transport.SessionInvoker.messageTransfer(SessionInvoker.java:93)
> at
> org.apache.qpid.client.BasicMessageProducer_0_10.sendMessage(BasicMessageProducer_0_10.java:371)
> at
> org.apache.qpid.client.BasicMessageProducer.sendImpl(BasicMessageProducer.java:549)
> at
> org.apache.qpid.client.BasicMessageProducer.send(BasicMessageProducer.java:333)
> at tld.domain.OurLibrary$SendLogic.sendLogic(OurLibrary.java:430)
> at tld.domain.OurLibrary$SendLogic.run(OurLibrary.java:391)
> at tld.domain.OurLibrary.reconnectCommonLogic(OurLibrary.java:497)
> at tld.domain.OurLibrary.reconnectLogic(OurLibrary.java:483)
> at tld.domain.OurLibrary.send(OurLibrary.java:373)
> at tld.domain.Main.send(Main.java:476)
> at tld.domain.MainAttict.tesSendMsg(MainAttict.java:285)
> at tld.domain.MainAttict.main(MainAttict.java:368)
> 12589 [main] INFO tld.domain.MainAttict - message sent
> 12590 [IoRcvr-/172.21.0.2:50618-qpid-broker/192.168.80.1:5671] ERROR
> org.apache.qpid.client.AMQConnectionDelegate_0_10 - connection exception:
> conn:70d0de44
> org.apache.qpid.transport.ConnectionException: connection aborted
> at org.apache.qpid.transport.Connection.closed(Connection.java:574)
> at
> org.apache.qpid.transport.network.Assembler.closed(Assembler.java:115)
> at
> org.apache.qpid.transport.network.InputHandler.closed(InputHandler.java:221)
> at
> org.apache.qpid.transport.network.security.ssl.SSLReceiver.closed(SSLReceiver.java:69)
> at
> org.apache.qpid.transport.network.io.IoReceiver.run(IoReceiver.java:225)
> at java.lang.Thread.run(Thread.java:745)
> 12590 [main] INFO tld.domain.OurLibrary - lock for qpid connection status is
> not defined, hence it won't be created. Status: connection lost
> }}
--
This message was sent by Atlassian JIRA
(v6.4.14#64029)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@qpid.apache.org
For additional commands, e-mail: dev-h...@qpid.apache.org
