Problem while using diameter-sh-server-ra-DU-1.0.1.GA.jar

[Sachin Parnami]

Hi All,

i get mentioned error when ever any event is being fired on Diameter-server (using sh-server-ra)
scenario

Mobicents-Diameter-Client                Mobicents-Diameter-Server
              |                                                           |
               --------------------UDR------------------------------->

@Mobicents-Diameter-Server
i get

11:54:00,314 INFO  [netWork] Open incomming connection Socket[addr=/127.0.0.1,port=38202,localport=1812]
11:54:16,929 INFO  [DiameterStackMultiplexer] Diameter Stack Mux :: processRequest :: Command-Code [306]
11:54:16,929 INFO  [DiameterStackMultiplexer] Diameter Stack Mux :: findListener :: AVP AppId [AppId [Vendor-Id:10415; Auth-Application-Id:16777217; Acct-Application-Id:0]]
11:54:16,929 INFO  [DiameterStackMultiplexer] Diameter Stack Mux :: findListener :: Found Listener [org.mobicents.slee.resource.diameter.sh.server.DiameterShServerResourceAdaptor@1ba92bb]
11:54:16,981 INFO  [DiameterShServerResourceAdaptor] Activity started [Diameter Session ID[127.0.0.1;290;2184940829]]
11:54:17,023 ERROR [STDERR] java.lang.IllegalArgumentException: Invalid AuthSessionState value: 2
11:54:17,034 ERROR [STDERR]     at net.java.slee.resource.diameter.base.events.avp.AuthSessionStateType.fromInt(AuthSessionStateType.java:72)
11:54:17,085 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.ShServerActivityImpl.fetchSessionData(ShServerActivityImpl.java:437)
11:54:17,086 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.DiameterShServerResourceAdaptor.fireEvent(DiameterShServerResourceAdaptor.java:683)
11:54:17,086 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.handlers.ShServerSessionFactory.doMessage(ShServerSessionFactory.java:139)
11:54:17,086 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.handlers.ShServerSessionFactory.doUserDataRequestEvent(ShServerSessionFactory.java:186)
11:54:17,086 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl.handleEvent(ShServerSessionImpl.java:289)
11:54:17,086 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl.processRequest(ShServerSessionImpl.java:170)
11:54:17,086 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.DiameterShServerResourceAdaptor$ShServerProviderImpl.createActivity(DiameterShServerResourceAdaptor.java:848)
11:54:17,086 ERROR [STDERR]     at org.mobicents.slee.resource.diameter.sh.server.DiameterShServerResourceAdaptor.processRequest(DiameterShServerResourceAdaptor.java:582)
11:54:17,086 ERROR [STDERR]     at org.mobicents.diameter.stack.DiameterStackMultiplexer.processRequest(DiameterStackMultiplexer.java:185)
11:54:17,087 ERROR [STDERR]     at org.jdiameter.server.impl.PeerImpl$LocalActionConext.receiveMessage(PeerImpl.java:272)
11:54:17,087 ERROR [STDERR]     at org.jdiameter.server.impl.fsm.PeerFSMImpl$2.processEvent(PeerFSMImpl.java:149)
11:54:17,087 ERROR [STDERR]     at org.jdiameter.client.impl.fsm.PeerFSMImpl$1.run(PeerFSMImpl.java:129)
11:54:17,088 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
11:54:17,088 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
11:54:17,088 ERROR [STDERR]     at java.lang.Thread.run(Thread.java:595)
11:54:17,139 INFO  [DiameterExampleSbb] sbbCreate invoked.
11:54:17,139 INFO  [DiameterExampleSbb] sbbPostCreate invoked.
11:54:17,140 INFO  [DiameterExampleSbb] sbbPassivate invoked.
11:54:17,143 INFO  [DiameterExampleSbb] sbbActivate invoked.
11:54:17,146 INFO  [DiameterExampleSbb] sbbLoad invoked.
11:54:17,147 INFO  [DiameterExampleSbb] +++++++User-Data-Request activity[org.mobicents.slee.resource.diameter.sh.server.ShServerActivityImpl@f1257f] received.

+----------------------------------- HEADER ----------------------------------+
| Version.................1
| Message-Length..........0
| Command-Flags...........R[true] P[true] E[false] T[false]
| Command-Code............306
| Application-Id..........16777217
| Hop-By-Hop Identifier...37420126
| End-To-End Identifier...1991245830
+------------------------------------ AVPs -----------------------------------+
| AVP: Code[260] VendorID[0] Value[<Grouped>] Flags[M=true;E=false;V=false]
|   AVP: Code[266] VendorID[0] Value[10415] Flags[M=true;E=false;V=false]
|   AVP: Code[258] VendorID[0] Value[16777217] Flags[M=true;E=false;V=false]
| AVP: Code[263] VendorID[0] Value[127.0.0.1;290;2184940829] Flags[M=false;E=false;V=false]
| AVP: Code[264] VendorID[0] Value[127.0.0.1] Flags[M=true;E=false;V=false]
| AVP: Code[296] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[293] VendorID[0] Value[aaa://127.0.0.1:1812] Flags[M=true;E=false;V=false]
| AVP: Code[283] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[705] VendorID[10415] Value[0] Flags[M=true;E=false;V=true]
| AVP: Code[703] VendorID[10415] Value[11] Flags[M=true;E=false;V=true]
| AVP: Code[700] VendorID[10415] Value[<Grouped>] Flags[M=true;E=false;V=true]
|   AVP: Code[601] VendorID[10415] Value[sip:al...@open-ims.test] Flags[M=true;E=true;V=true]
| AVP: Code[277] VendorID[0] Value[2] Flags[M=true;E=false;V=false]
+-----------------------------------------------------------------------------+

11:54:17,148 INFO  [DiameterExampleSbb] sbbStore invoked.
11:54:17,148 INFO  [DiameterExampleSbb] sbbPassivate invoked.
11:54:17,150 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: eventProcessingSuccessful :: handle[Diameter Session ID[127.0.0.1;290;2184940829]], event[
+----------------------------------- HEADER ----------------------------------+
| Version.................1
| Message-Length..........0
| Command-Flags...........R[true] P[true] E[false] T[false]
| Command-Code............306
| Application-Id..........16777217
| Hop-By-Hop Identifier...37420126
| End-To-End Identifier...1991245830
+------------------------------------ AVPs -----------------------------------+
| AVP: Code[260] VendorID[0] Value[<Grouped>] Flags[M=true;E=false;V=false]
|   AVP: Code[266] VendorID[0] Value[10415] Flags[M=true;E=false;V=false]
|   AVP: Code[258] VendorID[0] Value[16777217] Flags[M=true;E=false;V=false]
| AVP: Code[263] VendorID[0] Value[127.0.0.1;290;2184940829] Flags[M=false;E=false;V=false]
| AVP: Code[264] VendorID[0] Value[127.0.0.1] Flags[M=true;E=false;V=false]
| AVP: Code[296] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[293] VendorID[0] Value[aaa://127.0.0.1:1812] Flags[M=true;E=false;V=false]
| AVP: Code[283] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[705] VendorID[10415] Value[0] Flags[M=true;E=false;V=true]
| AVP: Code[703] VendorID[10415] Value[11] Flags[M=true;E=false;V=true]
| AVP: Code[700] VendorID[10415] Value[<Grouped>] Flags[M=true;E=false;V=true]
|   AVP: Code[601] VendorID[10415] Value[sip:al...@open-ims.test] Flags[M=true;E=true;V=true]
| AVP: Code[277] VendorID[0] Value[2] Flags[M=true;E=false;V=false]
+-----------------------------------------------------------------------------+
], eventID[49], address[null], flags[0].
11:55:16,993 ERROR [STDERR] org.jdiameter.common.impl.validation.JAvpNotAllowedException: AVP: Destination-Host@10044 Name[Destination-Host] Code[293] Vendor[0] MLP[0] Allowed[false]  has wrong count in message - 1
11:55:16,994 ERROR [STDERR]     at org.jdiameter.common.impl.validation.MessageRepresentation.validate(MessageRepresentation.java:107)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.common.impl.validation.DiameterMessageValidator.validate(DiameterMessageValidator.java:184)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.genericSend(BaseSessionImpl.java:68)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.genericSend(BaseSessionImpl.java:55)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl$MyFuture.send(BaseSessionImpl.java:208)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.send(BaseSessionImpl.java:104)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl.handleEvent(ShServerSessionImpl.java:239)
11:55:16,994 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl$TxTimerTask.run(ShServerSessionImpl.java:580)
11:55:16,994 ERROR [STDERR]     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
11:55:16,994 ERROR [STDERR]     at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
11:55:16,994 ERROR [STDERR]     at java.util.concurrent.FutureTask.run(FutureTask.java:123)
11:55:16,994 ERROR [STDERR]     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
11:55:16,994 ERROR [STDERR]     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
11:55:16,995 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
11:55:16,995 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
11:55:16,995 ERROR [STDERR]     at java.lang.Thread.run(Thread.java:595)
11:55:16,995 ERROR [AppSessionImpl] Wrong messageT[TX_TIMER_EXPIRED] R[MessageImpl{commandCode=306, flags=192}] A[null]
11:55:17,002 INFO  [DiameterExampleSbb] sbbActivate invoked.
11:55:17,002 INFO  [DiameterExampleSbb] sbbLoad invoked.
11:55:17,002 INFO  [DiameterExampleSbb]  Activity Ended[org.mobicents.slee.resource.diameter.sh.server.ShServerActivityImpl@f1257f]
11:55:17,004 INFO  [DiameterExampleSbb] sbbStore invoked.
11:55:17,004 INFO  [DiameterExampleSbb] sbbRemove invoked.
11:55:17,007 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: activityEnded :: handle[Diameter Session ID[127.0.0.1;290;2184940829].
11:55:17,009 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: eventProcessingSuccessful :: handle[Diameter Session ID[127.0.0.1;290;2184940829]], event[org.mobicents.slee.runtime.ActivityEndEventImpl@cfc659], eventID[0], address[null], flags[0].


Second Error again for Validation but with sh-server :(


--
Regards,
Sachin Parnami

[Alexandre Mendonça]

Indeed, there's no Auth-Session-State with value 2, from RFC 3588:

8.11.  Auth-Session-State AVP



   The Auth-Session-State AVP (AVP Code 277) is of type Enumerated and
   specifies whether state is maintained for a particular session.  The
   client MAY include this AVP in requests as a hint to the server, but


   the value in the server's answer message is binding.  The following
   values are supported:

   STATE_MAINTAINED              0
      This value is used to specify that session state is being
      maintained, and the access device MUST issue a session termination


      message when service to the user is terminated.  This is the
      default value.

   NO_STATE_MAINTAINED           1
      This value is used to specify that no session termination messages
      will be sent by the access device upon expiration of the


      Authorization-Lifetime.

To be fixed.

Alexandre Mendonça
JBoss R&D

[Sachin Parnami]

I get other problems as well while firing events on Mobicents-Diameter-Server node (Sh-server)

SNR and PUR are not being processed where as UDR works fine :) 

DiameterShServerResourceAdaptor shows eventProcessingSuccessful as mentioned in below log

PS: these logs are followed by above mentioned error , but looks weired i dont get this error sending UDR(whereas destination host is also present in UDR as well)

16:23:52,473 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: eventProcessingSuccessful :: handle[Diameter Session ID[127.0.0.1;290;2805916184]], event[

+----------------------------------- HEADER ----------------------------------+
| Version.................1
| Message-Length..........0
| Command-Flags...........R[true] P[true] E[false] T[false]

| Command-Code............307
| Application-Id..........16777217
| Hop-By-Hop Identifier...658002420
| End-To-End Identifier...2031091760

+------------------------------------ AVPs -----------------------------------+
| AVP: Code[260] VendorID[0] Value[<Grouped>] Flags[M=true;E=false;V=false]
|   AVP: Code[266] VendorID[0] Value[10415] Flags[M=true;E=false;V=false]
|   AVP: Code[258] VendorID[0] Value[16777217] Flags[M=true;E=false;V=false]

| AVP: Code[263] VendorID[0] Value[127.0.0.1;290;2805916184] Flags[M=false;E=false;V=false]

| AVP: Code[264] VendorID[0] Value[127.0.0.1] Flags[M=true;E=false;V=false]
| AVP: Code[296] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[293] VendorID[0] Value[aaa://127.0.0.1:1812] Flags[M=true;E=false;V=false]
| AVP: Code[283] VendorID[0] Value[mobicents.org] Flags[M=true;E=false;V=false]
| AVP: Code[705] VendorID[10415] Value[0] Flags[M=true;E=false;V=true]
| AVP: Code[703] VendorID[10415] Value[11] Flags[M=true;E=false;V=true]
| AVP: Code[700] VendorID[10415] Value[<Grouped>] Flags[M=true;E=false;V=true]
|   AVP: Code[601] VendorID[10415] Value[sip:al...@open-ims.test] Flags[M=true;E=true;V=true]
| AVP: Code[277] VendorID[0] Value[2] Flags[M=true;E=false;V=false]

| AVP: Code[702] VendorID[10415] Value[sip:ali...@open-ims.test] Flags[M=true;E=false;V=true]
+-----------------------------------------------------------------------------+
], eventID[50], address[null], flags[0].
16:24:52,373 ERROR [STDERR] org.jdiameter.common.impl.validation.JAvpNotAllowedException: AVP: Destination-Host@10044 Name[Destination-Host] Code[293] Vendor[0] MLP[0] Allowed[false]  has wrong count in message - 1
16:24:52,373 ERROR [STDERR]     at org.jdiameter.common.impl.validation.MessageRepresentation.validate(MessageRepresentation.java:107)
16:24:52,373 ERROR [STDERR]     at org.jdiameter.common.impl.validation.DiameterMessageValidator.validate(DiameterMessageValidator.java:184)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.genericSend(BaseSessionImpl.java:68)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.genericSend(BaseSessionImpl.java:55)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl$MyFuture.send(BaseSessionImpl.java:208)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.client.impl.BaseSessionImpl.send(BaseSessionImpl.java:104)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl.handleEvent(ShServerSessionImpl.java:239)
16:24:52,374 ERROR [STDERR]     at org.jdiameter.server.impl.app.sh.ShServerSessionImpl$TxTimerTask.run(ShServerSessionImpl.java:580)
16:24:52,374 ERROR [STDERR]     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
16:24:52,374 ERROR [STDERR]     at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
16:24:52,374 ERROR [STDERR]     at java.util.concurrent.FutureTask.run(FutureTask.java:123)
16:24:52,375 ERROR [STDERR]     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
16:24:52,375 ERROR [STDERR]     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
16:24:52,375 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
16:24:52,375 ERROR [STDERR]     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
16:24:52,375 ERROR [STDERR]     at java.lang.Thread.run(Thread.java:595)
16:24:52,375 ERROR [AppSessionImpl] Wrong messageT[TX_TIMER_EXPIRED] R[MessageImpl{commandCode=307, flags=192}] A[null]
16:24:52,394 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: activityEnded :: handle[Diameter Session ID[127.0.0.1;290;2805916184].
16:24:52,397 INFO  [DiameterShServerResourceAdaptor] Diameter ShServer RA :: eventProcessingSuccessful :: handle[Diameter Session ID[127.0.0.1;290;2805916184]], event[org.mobicents.slee.runtime.ActivityEndEventImpl@179a4c9], eventID[0], address[null], flags[0].

2009/7/16 Alexandre Mendonça <brai...@gmail.com>

--
Regards,
Sachin Parnami