Author Message
yann
Joined: Feb 8, 2017
Messages: 2
Offline
Hi,

I am getting errors while tying to register terminal in the server mode:

This is the request:

<?xml version="1.0" encoding="utf-8"?>
<RegisterTerminalRequest xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns="http://www.avaya.com/csta">
<device typeOfNumber="other" mediaClass="notKnown">40204:S8800:10.10.1.24:0</device>
<loginInfo>
<forceLogin>true</forceLogin>
<sharedControl>false</sharedControl>
<password>1234</password>
<mediaMode>SERVER</mediaMode>
<dependencyMode>DEPENDENT</dependencyMode>
</loginInfo>
</RegisterTerminalRequest>

This is the response:

-----------------------
Incoming XML 115 40204:S8800:10.10.1.24:0
<?xml version="1.0" encoding="UTF-8"?>
<CSTAErrorCode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3">
<unspecified />
</CSTAErrorCode>

This is the snapshot from dmcc-trace.log.0 file:

FINE: invokeID= 115 Routing request=session[session 5E29DB60C3B6BCDD764B64DF45E2E762-0] com.avaya.csta.binding.RegisterTerminalRequest@11a0a79
2017-02-08 16.33.07,812 :T-111: com.avaya.cs.callinfo.CallInfoImplA get_dapi_version
INFO: dapi version for :S8800: =6
2017-02-08 16.33.07,817 :T-111: com.avaya.mvcs.station.h323.ras.DefaultTerminalDevice <init>
INFO: [S8800:10.10.1.24:40204:0] Resetting the re-discovery counter to 0
2017-02-08 16.33.07,818 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl acquireAesLicense
FINE: [S8800:10.10.1.24:40204:0] : Attempting to acquire AES license for cName=null
2017-02-08 16.33.07,843 :T-111: sun.net.www.protocol.http.HttpURLConnection writeRequests
FINE: sun.net.www.MessageHeader@1d4b9579 pairs: {POST /WebLM/LicenseServer HTTP/1.1: null}{Content-Type: application/x-www-form-urlencoded}{Cache-Control: no-cache}{Pragma: no-cache}{User-Agent: Java/1.8.0_20-AV}{Host: 10.10.1.61:52233}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Content-Length: 87}
2017-02-08 16.33.07,844 :T-111: sun.net.www.protocol.http.HttpURLConnection getInputStream0
FINE: sun.net.www.MessageHeader@38ab805 pairs: {null: HTTP/1.1 200 OK}{Server: Apache-Coyote/1.1}{Content-Type: text/plain;charset=ISO-8859-1}{Content-Length: 152}{Date: Wed, 08 Feb 2017 23:32:34 GMT}
2017-02-08 16.33.07,861 :T-111: sun.net.www.protocol.http.HttpURLConnection writeRequests
FINE: sun.net.www.MessageHeader@67a5609 pairs: {POST /WebLM/LicenseServer HTTP/1.1: null}{Content-Type: application/x-www-form-urlencoded}{Cache-Control: no-cache}{Pragma: no-cache}{User-Agent: Java/1.8.0_20-AV}{Host: 10.10.1.61:52233}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Content-Length: 160}
2017-02-08 16.33.07,864 :T-111: sun.net.www.protocol.http.HttpURLConnection getInputStream0
FINE: sun.net.www.MessageHeader@1487d475 pairs: {null: HTTP/1.1 200 OK}{Server: Apache-Coyote/1.1}{Content-Type: text/plain;charset=ISO-8859-1}{Content-Length: 53}{Date: Wed, 08 Feb 2017 23:32:34 GMT}
2017-02-08 16.33.07,864 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl acquireAesLicense
FINE: [S8800:10.10.1.24:40204:0] : Acquired an AES license - cName=null, feature=VALUE_AES_DMCC_DMC
2017-02-08 16.33.07,864 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl acquireAesLicense
FINE: [S8800:10.10.1.24:40204:0] : Setting response to indicate an unprivileged AES license
2017-02-08 16.33.07,864 :T-111: com.avaya.cmapi.intsvc.TerminalSession registerDevice
FINE: [S8800:10.10.1.24:40204:0] : registering in server media mode - aesLicense=true
2017-02-08 16.33.07,864 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 privateRegisterDevice
FINE: [S8800:10.10.1.24:40204:0] codecList is null, setting to default codecs
2017-02-08 16.33.07,864 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 privateRegisterDevice
FINE: [S8800:10.10.1.24:40204:0] encryptionList is null, setting to default encryption
2017-02-08 16.33.07,865 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 privateRegisterDevice
FINE: [S8800:10.10.1.24:40204:0] state=REGISTERING
2017-02-08 16.33.08,405 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 privateRegisterDevice
FINE: [S8800:10.10.1.24:40204:0] state=IDLE
2017-02-08 16.33.08,406 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 tearDownDevice
FINE: [S8800:10.10.1.24:40204:0] Tearing down station
2017-02-08 16.33.08,406 :T-111: com.avaya.mvcs.terminal.h323.TerminalH323 tearDownDevice
FINE: [S8800:10.10.1.24:40204:0] state=IDLE prevState=IDLE
2017-02-08 16.33.08,406 :T-111: com.avaya.cmapi.intsvc.TerminalSession stopRecording
FINE: [S8800:10.10.1.24:40204:0] stopRecording request
2017-02-08 16.33.08,406 :T-111: com.avaya.mvcs.station.h323.ras.TerminalMulticaster removeTerminalListener
INFO: A null listener was removed.
2017-02-08 16.33.08,406 :T-111: com.avaya.mvcs.station.h323.ras.TerminalMulticaster removeTerminalListener
INFO: A null listener was removed.
2017-02-08 16.33.08,406 :T-111: com.avaya.cmapi.intsvc.ToneCollectionAdapter removeToneCollectorListener
FINE: [S8800:10.10.1.24:40204:0] 4: Removing listener
2017-02-08 16.33.08,406 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl freeAesLicense
FINE: [S8800:10.10.1.24:40204:0] : Attempting to release AES license
2017-02-08 16.33.08,418 :T-111: sun.net.www.protocol.http.HttpURLConnection writeRequests
FINE: sun.net.www.MessageHeader@98de179 pairs: {POST /WebLM/LicenseServer HTTP/1.1: null}{Content-Type: application/x-www-form-urlencoded}{Cache-Control: no-cache}{Pragma: no-cache}{User-Agent: Java/1.8.0_20-AV}{Host: 10.10.1.61:52233}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Content-Length: 88}
2017-02-08 16.33.08,419 :T-111: sun.net.www.protocol.http.HttpURLConnection getInputStream0
FINE: sun.net.www.MessageHeader@8a2cc65 pairs: {null: HTTP/1.1 200 OK}{Server: Apache-Coyote/1.1}{Content-Type: text/plain;charset=ISO-8859-1}{Content-Length: 154}{Date: Wed, 08 Feb 2017 23:32:34 GMT}
2017-02-08 16.33.08,436 :T-111: sun.net.www.protocol.http.HttpURLConnection writeRequests
FINE: sun.net.www.MessageHeader@45d8549 pairs: {POST /WebLM/LicenseServer HTTP/1.1: null}{Content-Type: application/x-www-form-urlencoded}{Cache-Control: no-cache}{Pragma: no-cache}{User-Agent: Java/1.8.0_20-AV}{Host: 10.10.1.61:52233}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Content-Length: 156}
2017-02-08 16.33.08,440 :T-111: sun.net.www.protocol.http.HttpURLConnection getInputStream0
FINE: sun.net.www.MessageHeader@1e637865 pairs: {null: HTTP/1.1 200 OK}{Server: Apache-Coyote/1.1}{Content-Type: text/plain;charset=ISO-8859-1}{Content-Length: 45}{Date: Wed, 08 Feb 2017 23:32:34 GMT}
2017-02-08 16.33.08,441 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl freeAesLicense
FINE: [S8800:10.10.1.24:40204:0] : Released an AES license - cName=null, feature=VALUE_AES_DMCC_DMC
2017-02-08 16.33.08,441 :T-111: com.avaya.cmapi.intsvc.CstaTerminalImpl tearDownDevice
FINE: [S8800:10.10.1.24:40204:0] station is now clear
2017-02-08 16.33.08,441 :T-111: com.avaya.platform.broker.impl.AsyncServiceMethodImpl invoke
WARNING: Exception when calling method.invoke
java.lang.reflect.InvocationTargetException
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.avaya.platform.broker.impl.AsyncServiceMethodImpl.invoke(AsyncServiceMethodImpl.java:164)
at com.avaya.workflow.impl.RouterImpl.routeRequest(RouterImpl.java:97)
at com.avaya.mvcs.proxy.CstaRouter.routeRequest(CstaRouter.java:173)
at com.avaya.mvcs.proxy.CstaRouterService.routeRequest(CstaRouterService.java:141)
at com.avaya.mvcs.proxy.CstaRouterNode.processPacket(CstaRouterNode.java:365)
at com.avaya.mvcs.proxy.AbstractPipelineNode.process(AbstractPipelineNode.java:130)
at com.avaya.mvcs.proxy.Pipeline$PipelineSubscriber.inform(Pipeline.java:452)
at com.avaya.common.eventservice.UnfilteredSubscription.notify(UnfilteredSubscription.java:70)
at com.avaya.common.multicaster.Multicaster.notify(Multicaster.java:386)
at com.avaya.common.channel.Channel.publish(Channel.java:115)
at com.avaya.common.eventservice.EventService.publish(EventService.java:124)
at com.avaya.common.eventservice.EventServiceManager.publish(EventServiceManager.java:156)
at com.avaya.common.eventservice.Publisher.publish(Publisher.java:110)
at com.avaya.mvcs.proxy.CstaUnmarshallerNode$CstaUnmarshallerProcessorThread.run(CstaUnmarshallerNode.java:268)
at com.avaya.common.util.concurrent.impl.RunnableWrapper.run(RunnableWrapper.java:57)
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: java.lang.RuntimeException: com.avaya.api.portmanagement.NoMoreSwPortsException: There are no ports available for IP Address:/10.10.0.32
at com.avaya.mvcs.terminal.h323.TerminalH323.privateRegisterDevice(TerminalH323.java:408)
at com.avaya.mvcs.terminal.h323.TerminalH323.registerDevice(TerminalH323.java:288)
at com.avaya.cmapi.intsvc.TerminalSession.registerDevice(TerminalSession.java:189)
at com.avaya.cmapi.intsvc.CstaTerminalImpl.register(CstaTerminalImpl.java:447)
at com.avaya.cmapi.intsvc.CstaTerminalImpl.registerTerminal(CstaTerminalImpl.java:667)
at com.avaya.cmapi.extsvc.H323RegistrationServices.registerTerminal(H323RegistrationServices.java:100)
... 22 more
Caused by: com.avaya.api.portmanagement.NoMoreSwPortsException: There are no ports available for IP Address:/10.10.0.32
at com.avaya.mvcs.media.net.RtpPortBindUtil.bindConsecutiveFreePortsForRTP(RtpPortBindUtil.java:144)
at com.avaya.mvcs.media.net.RtpPortBindUtil.bindUDPPort(RtpPortBindUtil.java:108)
at com.avaya.common.util.portmanagement.PortContextManager.bindDatagramSocketsToPort(PortContextManager.java:238)
at com.avaya.common.nio.managed.defaultImpl.DefaultChannelProvider.bindUDPChannels(DefaultChannelProvider.java:500)
at com.avaya.mvcs.media.rtp.RtpSessionImpl.bindUDPChannels(RtpSessionImpl.java:153)
at com.avaya.mvcs.media.rtp.RtpSessionImpl.init(RtpSessionImpl.java:137)
at com.avaya.mvcs.media.rtp.RtpSessionImpl.init(RtpSessionImpl.java:118)
at com.avaya.mvcs.media.audio.AudioImpl.setupAudio(AudioImpl.java:255)
at com.avaya.mvcs.media.audio.AudioImpl.setupAudio(AudioImpl.java:235)
at com.avaya.mvcs.media.audio.AudioImpl.<init>(AudioImpl.java:122)
at com.avaya.mvcs.media.audio.AudioLookupFactory.createAudio(AudioLookupFactory.java:125)
at com.avaya.mvcs.media.MediaFactoryImpl.createAudio(MediaFactoryImpl.java:73)
at com.avaya.mvcs.terminal.h323.TerminalH323.privateRegisterDevice(TerminalH323.java:363)
... 27 more
2017-02-08 16.33.08,441 :T-111: com.avaya.mvcs.proxy.CstaRouterNode processPacket
FINE: invokeID= 115 Received com.avaya.platform.broker.impl.AsyncResponse@e68271 in response to session[session 5E29DB60C3B6BCDD764B64DF45E2E762-0] com.avaya.csta.binding.RegisterTerminalRequest@11a0a79

I am using Avaya remote development lab for the testing.

AES IP 10.10.1.32
CM IP 10.10.1.24
MartinFlynn
Joined: Nov 30, 2009
Messages: 1922
Online
The root cause of your problem seems to be:

Caused by: com.avaya.api.portmanagement.NoMoreSwPortsException: There are no ports available for IP Address:/10.10.0.32

I have not come across this before so it poses me a couple of questions:

1. What is 10.10.0.32? I would have thought this should be the IP address of the AE Services, but you say that is 10.10.1.32.

2. The exception complains that no ports are available. I cannot tell if it means that:
a. It cannot get a port on the Media Gateway/Server, or
b. all 65K ports on the AE Services are in use or
c. a subset of AE Services ports are reserved for RTP and these are all used up. If you have already registered a large number of terminals, this may be the case.

You could use 'netstat' or other tools to try to see what ports are being used and by what applications but, I think the simplest first step would be to reboot the AE Services.

Martin
Go to:   
Mobile view