activemq-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Frank Gynnild (Commented) (JIRA)" <jira+amq...@apache.org>
Subject [jira] [Commented] (AMQNET-350) Clients using the NMS library sometimes hijacks (binds) to the port that ActiveMQ is listening to
Date Wed, 16 Nov 2011 19:22:51 GMT

    [ https://issues.apache.org/jira/browse/AMQNET-350?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13151444#comment-13151444
] 

Frank Gynnild commented on AMQNET-350:
--------------------------------------

I reproduced it again, this time with debug tracing turned on. There is no signs of "Binding",
so that's probably a lead in the dark.
A common denominator has been that the address occupying it is an IPv6 address.
How's the free, local port determined? I just had a brief look at the code, it seems to use
the "-1" as input, 
I think it needs to be "0", but don't know if that causes the above problem or not.


---
2011-11-16 19:55:40,643 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 2560ms before attempting connection.
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 2560ms before attempting connection.
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:42,674 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 2560ms before attempting connection.
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:45,237 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 5120ms before attempting connection.
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 5120ms before attempting connection.
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:47,284 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 5120ms before attempting connection.
2011-11-16 19:55:52,409 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:52,409 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:52,424 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:52,424 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:52,440 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:55:52,440 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 10240ms before attempting connection.
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 10240ms before attempting connection.
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:55:54,456 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 10240ms before attempting connection.
2011-11-16 19:56:04,706 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:04,706 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:04,722 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:04,722 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:04,722 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:04,722 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:06,722 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:06,722 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 20480ms before attempting connection.
2011-11-16 19:56:06,722 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:06,722 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 20480ms before attempting connection.
2011-11-16 19:56:06,768 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:06,768 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 20480ms before attempting connection.
2011-11-16 19:56:27,206 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:27,206 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:27,206 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:27,206 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:27,253 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:27,253 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:29,237 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:29,237 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 30000ms before attempting connection.
2011-11-16 19:56:29,237 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:29,237 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 30000ms before attempting connection.
2011-11-16 19:56:29,284 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:56:29,284 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Waiting 30000ms before attempting connection.
2011-11-16 19:56:59,238 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:59,238 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:59,238 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:59,238 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:59,285 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Attempting sync connect to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:59,285 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Opening socket to: gelado on port: 61616
2011-11-16 19:56:59,285 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connected to gelado:61616 using InterNetworkV6 protocol.
2011-11-16 19:56:59,285 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Creating Inactivity Monitor: 4
2011-11-16 19:56:59,285 <32472> [15288] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - InactivityMonitor[4]: Read Check time interval: 300000
2011-11-16 19:56:59,285 <32472> [15288] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - InactivityMonitor[4]: Initial Delay time interval: 10000
2011-11-16 19:56:59,285 <32472> [ActiveMQ Failover Worker: 66629781] INFO  InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Restoring previous transport connection.
2011-11-16 19:56:59,285 <32472> [15288] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - InactivityMonitor[4]: Write Check time interval: 100000
2011-11-16 19:56:59,300 <32472> [ActiveMQ Failover Worker: 66629781] INFO  InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Sending queued commands...
2011-11-16 19:56:59,300 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Transport has resumed normal operation.
2011-11-16 19:56:59,300 <32472> [ActiveMQ Failover Worker: 66629781] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connection established
2011-11-16 19:56:59,300 <32472> [ActiveMQ Failover Worker: 66629781] INFO  InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Successfully reconnected to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000
2011-11-16 19:56:59,300 <32472> [15288] ERROR InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Unknown command: ConnectionControl[ commandId = 0, responseRequired = False, Close
= False, Exit = False, FaultTolerant = True, Resume = False, Suspend = False, ConnectedBrokers
= , ReconnectTo = , RebalanceConnection = False ]
2011-11-16 19:57:01,269 <32472> [ActiveMQ Failover Worker: 39864430] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:57:01,269 <32472> [ActiveMQ Failover Worker: 56931087] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - Connect fail to: tcp://gelado:61616/?nms.PrefetchPolicy.queuePrefetch=0&keepAlive=true&wireFormat.TightEncodingEnabled=false&wireFormat.CacheEnabled=false&wireFormat.MaxInactivityDuration=300000,
reason: Error connecting to gelado:61616.
2011-11-16 19:57:09,316 <32472> [22188] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - InactivityMonitor[4]: Message sent since last write check. Resetting flag.
2011-11-16 19:58:49,380 <32472> [26280] DEBUG InfoFinder.Framework.Diagnostics.NmsClientLibraryLogger
[(null)] - InactivityMonitor[4]: Message sent since last write check. Resetting flag.
---
                
> Clients using the NMS library sometimes hijacks (binds) to the port that ActiveMQ is
listening to
> -------------------------------------------------------------------------------------------------
>
>                 Key: AMQNET-350
>                 URL: https://issues.apache.org/jira/browse/AMQNET-350
>             Project: ActiveMQ .Net
>          Issue Type: Bug
>    Affects Versions: 1.5.0, 1.5.1, 1.5.2
>            Reporter: Frank Gynnild
>            Assignee: Timothy Bish
>
> If we stop ActiveMQ service first, we've sometimes seen that various clients (using the
NMS library) binds to the same port as ActiveMQ uses for listening (61616).
> This causes the ActiveMQ service to fail to start since its listening port is occupied.
> Doing a "netstat -a -b" we can see the problem:
> ---
> TCP [fe80::c591:4e66:8992:debb%11]:61616 Gelado:61616 ESTABLISHED
> TestClient.exe]
> ---
> When trying to start ActiveMQ we get this:
> ---
> 2011-11-16 10:27:38,223 | ERROR | Failed to start ActiveMQ JMS Message Broker. Reason:
java.io.IOException: Transport Connector could not be registered in JMX: Failed to bind to
server socket: tcp://0.0.0.0:61616?keepAlive=true&wireFormat.tightEncodingEnabled=false&wireFormat.cacheEnabled=false&wireFormat.maxInactivityDuration=300000
due to: java.net.SocketException: Unrecognized Windows Sockets error: 0: JVM_Bind | org.apache.activemq.broker.BrokerService
| main
> java.io.IOException: Transport Connector could not be registered in JMX: Failed to bind
to server socket: tcp://0.0.0.0:61616?keepAlive=true&wireFormat.tightEncodingEnabled=false&wireFormat.cacheEnabled=false&wireFormat.maxInactivityDuration=300000
due to: java.net.SocketException: Unrecognized Windows Sockets error: 0: JVM_Bind
> at org.apache.activemq.util.IOExceptionSupport.create(IOExceptionSupport.java:27)
> at org.apache.activemq.broker.BrokerService.registerConnectorMBean(BrokerService.java:1678)
> at org.apache.activemq.broker.BrokerService.startTransportConnector(BrokerService.java:2166)
> at org.apache.activemq.broker.BrokerService.startAllConnectors(BrokerService.java:2082)
> at org.apache.activemq.broker.BrokerService.start(BrokerService.java:516)
> ---
> When exiting the client process, the ActiveMQ service is able to start up again.
> I had a look at the source code, and I think the bug is inside the TcpTransportFactory.cs:
> {code}
> if(!String.IsNullOrEmpty(location.AbsolutePath) && !location.AbsolutePath.Equals("/"))
> 			{
> 				int index = location.AbsolutePath.IndexOf(':');
> 				try
> 				{
> 					localPort = Int16.Parse(location.AbsolutePath.Substring(index + 1));					
> 					localAddress = location.AbsolutePath.Substring(1, index - 1);
> 					Tracer.DebugFormat("Binding Socket to {0} on port: {1}", localAddress, localPort);
> 				}
> 				catch
> 				{
>             		Tracer.Warn("Invalid Port value on URI for local bind option, ignoring.");
> 				}
> 			}
> {code}

--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators: https://issues.apache.org/jira/secure/ContactAdministrators!default.jspa
For more information on JIRA, see: http://www.atlassian.com/software/jira

        

Mime
View raw message