Re: [UniMRCP] Receive SIP Event [nua_r_invite] Status 503 Service Unavailable

[Arsen Chaloyan]

Hi Ariel,

Is your MRCP server up and running? Please note that Asterisk
encapsulates the UniMRCP client stack to communicate with an MRCP
server. The server is not part of Asterisk, it has to be ran
separately.

In your case, Asterisk (MRCP client) sent a SIP/MRCPv2 INVITE to
127.0.0.1:8060 but didn't get a response.

On Mon, Sep 10, 2012 at 10:41 PM, Ariel Berg <arie...@gmail.com> wrote:
> Hey ,
>
> I'm trying to configure unimrcp on virtual box but i got lost in the
> configurations.
>
> i get a WARNING[4478]: res_speech_unimrcp.c:193 uni_recog_create: Failed to
> add channel
> and the dialplan dies at SpeechLoadGrammar (Spawn extension (default, 1211,
> 6) exited non-zero on 'SIP/1000-00000000')
>
> I am using ubuntu server 12.04
> unimrcp v2
> asterisk 1.8.11
> and pocketsphinix
>
> Connected to Asterisk 1.8.11-cert5 currently running on ubuntu (pid = 4428)
> Verbosity was 0 and is now 4
> == Using SIP RTP CoS mark 5
> -- Executing [1211@default:1] Ringing("SIP/1000-00000000", "") in new
> stack
> -- Executing [1211@default:2] Wait("SIP/1000-00000000", "2") in new
> stack
> -- Executing [1211@default:3] Answer("SIP/1000-00000000", "") in new
> stack
> -- Executing [1211@default:4] Wait("SIP/1000-00000000", "1") in new
> stack
> -- Executing [1211@default:5] SpeechCreate("SIP/1000-00000000", "") in
> new stack
> [Sep 11 08:33:25] NOTICE[4478]: app_unimrcp.c:4169 unimrcp_log: Create MRCP
> Handle 0x34007988 [uni2]
> [Sep 11 08:33:25] DEBUG[4478]: app_unimrcp.c:4172 unimrcp_log: Create
> Channel 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:907
> uni_recog_sm_request_send: Send session request type:2
> [Sep 11 08:33:25] DEBUG[4478]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCP Client] [4;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [4;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Receive App
> Request 0x7ff334007988 <new> [2]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Add MRCP
> Handle 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Dispatch App
> Request 0x7ff334007988 <new> [2]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCPv2-Agent-1] [1;0]
> [Sep 11 08:33:25] NOTICE[4457]: app_unimrcp.c:4169 unimrcp_log: Add Control
> Channel 0x7ff334007988 <new@speechrecog>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Add Media
> Termination 0x7ff334007988 <new@media-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Add Media
> Termination 0x7ff334007988 <new@rtp-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [Media-Engine-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Process
> Poller Wakeup [MRCPv2-Agent-1]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCPv2-Agent-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCP Client] [2;0]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCPv2-Agent-1]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [2;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Control
> Channel Added 0x7ff334007988 <new@speechrecog>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:933
> uni_recog_sm_request_send: Wait for session response
> [Sep 11 08:33:25] DEBUG[4461]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [Media-Engine-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4461]: app_unimrcp.c:4175 unimrcp_log: Add Media
> Context 0x7ff334007988
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [3;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Media
> Termination Added 0x7ff334007988 <new@media-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Media
> Termination Added 0x7ff334007988 <new@rtp-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Send Offer
> 0x7ff334007988 <new> [c:1 a:1 v:0] to 127.0.0.1:8060
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Local SDP
> 0x7ff334007988 <new>
> v=0
> o=UniMRCPClient 0 0 IN IP4 127.0.0.1
> s=-
> c=IN IP4 127.0.0.1
> t=0 0
> m=application 9 TCP/MRCPv2 1
> a=setup:active
> a=connection:new
> a=resource:speechrecog
> a=cmid:1
> m=audio 5000 RTP/AVP 0 8 96 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:96 L16/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendonly
> a=ptime:20
> a=mid:1
>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4458]: app_unimrcp.c:4172 unimrcp_log: Receive SIP
> Event [nua_i_state] Status 0 INVITE sent
> [Sep 11 08:33:25] NOTICE[4458]: app_unimrcp.c:4169 unimrcp_log: SIP Call
> State 0x7ff334007988 [calling]
> [Sep 11 08:33:25] DEBUG[4458]: app_unimrcp.c:4172 unimrcp_log: Receive SIP
> Event [nua_r_invite] Status 503 Service Unavailable
> [Sep 11 08:33:25] DEBUG[4458]: app_unimrcp.c:4172 unimrcp_log: Receive SIP
> Event [nua_i_state] Status 503 Service Unavailable
> [Sep 11 08:33:25] NOTICE[4458]: app_unimrcp.c:4169 unimrcp_log: SIP Call
> State 0x7ff334007988 [terminated]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [1;4]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Raise App
> Response 0x7ff334007988 <new> [2] FAILURE [2]
> [Sep 11 08:33:25] DEBUG[4457]: res_speech_unimrcp.c:766 uni_message_handler:
> Received message from client stack
> [Sep 11 08:33:25] DEBUG[4457]: res_speech_unimrcp.c:705 on_channel_add: On
> channel add
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:938
> uni_recog_sm_request_send: Waked up, status code: 2
> [Sep 11 08:33:25] WARNING[4478]: res_speech_unimrcp.c:193 uni_recog_create:
> Failed to add channel
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:907
> uni_recog_sm_request_send: Send session request type:1
> [Sep 11 08:33:25] DEBUG[4478]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCP Client] [4;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [4;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Receive App
> Request 0x7ff334007988 <new> [1]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Dispatch App
> Request 0x7ff334007988 <new> [1]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Terminate
> Session 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Remove
> Control Channel 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCPv2-Agent-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Subtract
> Media Termination 0x7ff334007988 <new@media-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Subtract
> Media Termination 0x7ff334007988 <new@rtp-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [Media-Engine-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [1;1]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Session
> Terminated 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Process
> Poller Wakeup [MRCPv2-Agent-1]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCPv2-Agent-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Signal
> Message to [MRCP Client] [2;2]
> [Sep 11 08:33:25] DEBUG[4460]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCPv2-Agent-1]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [2;2]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Control
> Channel Removed 0x7ff334007988 <new@speechrecog>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4461]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [Media-Engine-1] [1;0]
> [Sep 11 08:33:25] DEBUG[4461]: app_unimrcp.c:4175 unimrcp_log: Remove Media
> Context 0x7ff334007988
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Process
> Message [MRCP Client] [3;0]
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Media
> Termination Subtracted 0x7ff334007988 <new@media-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Media
> Termination Subtracted 0x7ff334007988 <new@rtp-tm>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Remove MRCP
> Handle 0x7ff334007988 <new>
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4172 unimrcp_log: Raise App
> Response 0x7ff334007988 <new> [1] SUCCESS [0]
> [Sep 11 08:33:25] DEBUG[4457]: res_speech_unimrcp.c:766 uni_message_handler:
> Received message from client stack
> [Sep 11 08:33:25] DEBUG[4457]: res_speech_unimrcp.c:696
> on_session_terminate: On session terminate
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:933
> uni_recog_sm_request_send: Wait for session response
> [Sep 11 08:33:25] DEBUG[4457]: app_unimrcp.c:4175 unimrcp_log: Wait for
> Messages [MRCP Client]
> [Sep 11 08:33:25] DEBUG[4478]: res_speech_unimrcp.c:938
> uni_recog_sm_request_send: Waked up, status code: 0
> [Sep 11 08:33:25] NOTICE[4478]: app_unimrcp.c:4169 unimrcp_log: Destroy MRCP
> Handle 0x7ff334007988
> -- Executing [1211@default:6] SpeechLoadGrammar("SIP/1000-00000000",
> "digit,/etc/asterisk/grammar.jsgf") in new stack
> == Spawn extension (default, 1211, 6) exited non-zero on
> 'SIP/1000-00000000'
> ubuntu*CLI> WARNING[4478]: res_speech_unimrcp.c:193 uni_recog_create: Failed
> to add channel
> No such command 'WARNING[4478]: res_speech_unimrcp.c:193 uni_recog_create:
> Failed to add channel' (type 'core show help WARNING[4478]:
> res_speech_unimrcp.c:193' for other possible commands)
>
> I have searched to forum but i couldn't find any solution...
>
> I'd appreciate any help
>
> Ariel
>
>
>
>
>
> --
> You received this message because you are subscribed to the Google Groups
> "UniMRCP" group.
> To view this discussion on the web visit
> https://groups.google.com/d/msg/unimrcp/-/cHBVLBbEv5MJ.
> To post to this group, send email to uni...@googlegroups.com.
> To unsubscribe from this group, send email to
> unimrcp+u...@googlegroups.com.
> For more options, visit this group at
> http://groups.google.com/group/unimrcp?hl=en.



--
Arsen Chaloyan
Author of UniMRCP
http://www.unimrcp.org