..

HMP Linux 4.1 update 165 rejects incoming INVITE with 486 Busy Here

..

Developer Group

Developer Group
Connect with thousands of other developers to brainstorm ideas, share best practices and tips - or just chat about the latest emerging technologies making noise in the field. And of course, get the most up-to-date service and support news from Dialogic.
Dialogic PowerMedia HMP GlobalCall and R4 API

HMP Linux 4.1 update 165 rejects incoming INVITE with 486 Busy Here

  • Hi,

    I have a problem on my SIP server :
    Cent OS 6.6
    Linux HMP update 165
    120 SIP chanells

    I receive incoming SIP calls and it all works fine until one moment.
    Then on incoming INVITE HMP rejects call with 486 Busy Here and it doesn't inform my application about it.
    I Know that this happens when there are no available Resources , but all of my channels were avilable as i can se that last 120 were distributed among all of those 120 channels and did get GCEV_DROPCALL and RELEASE_CALLEX from HMP:

    Now i have no idea what is causing it.
    Aftere i restart DIALOGIC and my application it works than again for a while and again i get the same problem
    In dialogic rtf log i have no data about the call event thouh i added lot of debug in RTF config  file.

  • Do you know if there is some unexpected SIP messages which are being sent to the system that are not being handled during runtime?  Meaning likes of OPTIONS, or NOTIFY? Potentially some scenario is resulting in loss of internal call objects of legs, that is not allowing additional SIP calls once prior calls were answered. Hard to say without seeing the actual logs though.

    Also note, Su165 is quite old, and it may help to upgrade to later SU to see if behaviors changes.

    Regards,

    Jeff

  • Hi,

    I instaled update 236, now i have to wait to see if it will hapen again.  

  • Hi,

    I have strange error in my aplication . It Works fine  and after a while i i get these messages :

    ssp_x86Linux_boot: [ERROR RTP OTHER] rtpsocket.c 308:

    ssp_x86Linux_boot: rtpSocketGet: bind returned an error: errno=98 rc=-1 port=49204 mode=0 hostaddr=46a8180a

    ssp_x86Linux_boot: [ERROR RTP OTHER] rtpsocket.c 327:

    ssp_x86Linux_boot: rtpSocketGet: After 101 ms of retrying bind returned an error: -1#012 port=49204

    ssp_x86Linux_boot: [IPVSC ERROR RTP OTHER #27] rtpopen.c 206

    ssp_x86Linux_boot: Open: RTP read port[0] 49204 Error

    ssp_x86Linux_boot: [ERROR PIO STATE #27] piostream.c 407

    ssp_x86Linux_boot: rtpOpen(Read:0) returned an error

    ssp_x86Linux_boot: [ERROR PIO STATE #27] pioopen.c 310

    ssp_x86Linux_boot: pioStreamOpen Read returned an error

    ssp_x86Linux_boot:    4965728: prx.c.1850:start: PRX.27: pioOpen returned an error

    ssp_x86Linux_boot:    4965728: prx.c.857:PRX_SetCodec: PRX.27: start returned an error

    ssp_x86Linux_boot: [ERROR NTC RX #27] ses.c 1059

    ssp_x86Linux_boot: PRX_SetCodec returned an error rc=

    ssp_x86Linux_boot: [ERROR NTC RX #27] nti.c 5905

    ssp_x86Linux_boot: SES_SetRxCoderInfo returned an error

    ssp_x86Linux_boot: [ERROR NTC OTHER] nti.c 7468:

    ssp_x86Linux_boot: startAlgSession: System Busy. mediaInfoFlag 53

    ssp_x86Linux_boot: [ERROR NTC OTHER #27] nti.c 7525

    ssp_x86Linux_boot: startMediaSession(): StartAlgFailed Failed.

    The other eror is like this :

                                    rtpcreate.c.154:rtpCreateParmFn: RTP.41 no free handles

    ssp_x86Linux_boot: [ERROR PIO STATE #41] pioopen.c 233

    ssp_x86Linux_boot: pioStreamCreate returned an error

    ssp_x86Linux_boot:  788103178: prx.c.1850:start: PRX.41: pioOpen returned an error

    ssp_x86Linux_boot:  788103178: prx.c.857:PRX_SetCodec: PRX.41: start returned an error

    ssp_x86Linux_boot: [ERROR NTC RX #41] ses.c 1059

    ssp_x86Linux_boot: PRX_SetCodec returned an error rc=

    ssp_x86Linux_boot: [ERROR NTC RX #41] nti.c 5905

    ssp_x86Linux_boot: SES_SetRxCoderInfo returned an error

    ssp_x86Linux_boot: [ERROR NTC OTHER] nti.c 7468:

    ssp_x86Linux_boot: startAlgSession: System Busy. mediaInfoFlag 53

    ssp_x86Linux_boot: [ERROR NTC OTHER #41] nti.c 7525

    ssp_x86Linux_boot: startMediaSession(): StartAlgFailed Failed.

    ssp_x86Linux_boot:  788103240: rtpcreate.c.154:rtpCreateParmFn: RTP.69 no free handles

    ssp_x86Linux_boot: [ERROR PIO STATE #69] pioopen.c 233

    ssp_x86Linux_boot: pioStreamCreate returned an error

    ssp_x86Linux_boot:  788103240: prx.c.1850:start: PRX.69: pioOpen returned an error

    ssp_x86Linux_boot:  788103240: prx.c.857:PRX_SetCodec: PRX.69: start returned an error

    ssp_x86Linux_boot: [ERROR NTC RX #69] ses.c 1059

    ssp_x86Linux_boot: PRX_SetCodec returned an error rc=

    ssp_x86Linux_boot: [ERROR NTC RX #69] nti.c 5905

    ssp_x86Linux_boot: SES_SetRxCoderInfo returned an error

    ssp_x86Linux_boot: [ERROR NTC OTHER] nti.c 7468:

    ssp_x86Linux_boot: startAlgSession: System Busy. mediaInfoFlag 53

    ssp_x86Linux_boot: [ERROR NTC OTHER #69] nti.c 7525

    ssp_x86Linux_boot: startMediaSession(): StartAlgFailed Failed

    IP chanlles were all cleared after previous call and i got GCEV_RELEASECALLEX.

    Any Advice woulf be appreciated.

  • Hi,

    Also to update in this is in rtf log:

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_GetCallInfo(crn:0x800006bh, info_id:515)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           ::::> gc_GetCallInfo(crn:0x800006bh, info_id:515) - returns;0

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_GetCallInfo(crn:0x800006bh, info_id:263)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           ::::> gc_GetCallInfo(crn:0x800006bh, info_id:263) - returns;0

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_GetCallInfo(crn:0x800006bh, info_id:264)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           ::::> gc_GetCallInfo(crn:0x800006bh, info_id:264) - returns;0

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_SetUserInfo(target_type:12, target_id:443, duration:1)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           ::::> gc_SetUserInfo(target_type:12, target_id:443, duration:1) - returns:0

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_AcceptCall(crn:0x800006bh, rings:3, mode:async)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gcep                  iptB2T47  ----- GC event:0x804h(GCEV_ACCEPT) posted on linedev:443, crn:0x6bh

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                 iptB2T47  ::::> gc_AcceptCall(crn:0x800006bh, rings:3, mode:async) - returns: 0

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_UnListen(linedev:443, mode:async)

    11/22/2018 10:18:36.341  15613  3066820416 gc                      APPL         gclib                           <:::: gc_GetResourceH(linedev:443, resource_type:3)

    11/22/2018 10:18:36.342  15613  3066820416 gc                      APPL         gclib                 iptB2T47  ::::> gc_GetResourceH(linedev:443, resourceh:444, resource_type:3) - returns:0

    11/22/2018 10:18:36.342  15613  3066820416 gc                      APPL         gclib                 ipmB1C107 ::::> gc_UnListen(linedev:443, mode:async) - returns:0

    11/22/2018 10:18:36.342  15613  3066820416 gc                      APPL         gclib                           <:::: gc_AnswerCall(crn:0x800006bh, rings:0, mode:async)

    11/22/2018 10:18:36.342  15613  3066820416 gc                      APPL         gclib                 iptB2T47  ::::> gc_AnswerCall(crn:0x800006bh, rings:0, mode:async) - returns: 0

    11/22/2018 10:18:36.344  15613  2845830000 Dm3Odi.dll              Error        Qcd [0:1:1:5:107] CDm3StdComp::Dm3GetErrorResult() -> (Std_MsgError) Message[0x4000] Xid[0xdb] Src[0:1:1:5:107] Dest[128:0:0:0:5] ErrorCode[0x2]

    11/22/2018 10:18:36.344  15613  2845830000 libipm_ipvsc            ERR1         CIPVscChannel         ipmB1C107  ---  ::OnStartAlgorithmSession: ch=ipmB1C107 ErrorCode=0x2 -Component or Instance was busy executing a previous command when another command was received.PrevError=0x0

    11/22/2018 10:18:36.344  15613  2845830000 libipm_ipvsc            ERR1         CIPVscChannel         ipmB1C107  ---  ConvertDM3ResultToR4Error: RESULT_COMPONENT_ERROR                         error code: 0x2

    11/22/2018 10:18:36.344  15613  2845830000 libipm_ipvsc            ERR1         CIPVscChannel         ipmB1C107  ---  ConvertDM3ResultToR4Error: RESULT_COMPONENT_ERROR                         converted error code: 0x3

    11/22/2018 10:18:36.344  15613  2780343152 gc                      APPL         gcep                  iptB2T47  ----- GC event:0x801h(GCEV_TASKFAIL) posted on linedev:443, crn:0x6bh

    11/22/2018 10:18:36.344  15613  2780343152 gc_h3r                  ERR1         mediastate.cpp:1320   !   107 ! << MediaState::ipmEventHandler :IPMEV_ERROR received from media

    11/22/2018 10:18:36.344  15613  2780343152 gc_h3r                  ERR1         mediastate.cpp:1169   !   107 ! mediaPrintLog:st ST_TX_START_2FDX Printing event/transition log

    returns: 0

  • I suspect in this case that some other process on the Linux system is starting to make use of the RTP ports which are expected to be used by HMP. Thus there is some conflict with another process as we see rtp open socket failures in the messages files.

    You probably want to explore if another process running on the system is using ports in the HMP range (49152 - xxx) where xxx is the number of IP ports x2 in your license.

    Regards,

    Jeff M.

  • Hi,

    That is what i thought but after i restart my application and do dlstop dlstart it all works so that is not the issue.

    I get this error on every call on that channnel until i restart HMP.

    Aperentlly RTP socket is not closed eventhogh in app everything was fine in previous call on that channel.

    And another error which is suspisiious is

    rtpcreate.c.154:rtpCreateParmFn: RTP.41 no free handles .