[
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
