Failed to Create Session TTS-2 / TTS-) Channel error

[Eric Pino]

Hi Arsen,


I'm having an issue with unimcrp on asterisk and based on asterisk loga, the unimrcp  failed
to create session.  Is this an asterisk version issue and o you have idea on how to resolve the issue?

Please see logs below as reference.

Thank You
Eric



Asterisk Version: 11.6
OS: Centos 6.4
UniMRCP Server:  1.1.0
 


Dialplan
exten => 100,1,Answer()
exten => 100,2,MRCPSynth(Hello world)



*** Asterisk Log ***

--- SIP read from UDP:192.169.188.26:11460 --->
ACK sip:1...@192.169.188.41:5060 SIP/2.0
Via: SIP/2.0/UDP 192.169.188.26:11460;branch=z9hG4bK-d8754z-396e747163f4d92f-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:xl...@192.169.188.26:11460;transport=udp>
To: <sip:1...@192.169.188.41>;tag=as65e17744
From: <sip:xl...@192.169.188.41>;tag=a8ced13f
Call-ID: Y2Q2NmUzZWQzMmU0NDBkNmQxOTg2MjgxNmZiMmIyY2E
CSeq: 2 ACK
User-Agent: X-Lite release 4.5.2 stamp 70143
Content-Length: 0

<------------->
--- (10 headers 0 lines) ---
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_mrcpsynth.c:576 app_synth_exec: MRCPSynth() prompt: Hello world
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_unimrcp.c:122 unimrcp_log: Create MRCP Handle 0xd4017358 [mrcpv2-profile]
[Feb 28 17:53:00] WARNING[2868]: app_unimrcp.c:119 unimrcp_log: Failed to Create Session TTS-2 <new> [2]
[Feb 28 17:53:00] ERROR[2868]: app_mrcpsynth.c:218 speech_on_channel_add: (TTS-2) Channel error!
[Feb 28 17:53:00] WARNING[2868]: app_unimrcp.c:119 unimrcp_log: Failed to Create Session TTS-2 <new> [1]
[Feb 28 17:53:00] NOTICE[2868]: app_unimrcp.c:122 unimrcp_log: Destroy MRCP Handle TTS-2
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_mrcpsynth.c:525 mrcpsynth_exit: MRCPSynth() exiting status: ERROR on SIP/xlite-00000002
Scheduling destruction of SIP dialog 'Y2Q2NmUzZWQzMmU0NDBkNmQxOTg2MjgxNmZiMmIyY2E' in 32000 ms (Method: ACK)
set_destination: Parsing <sip:xl...@192.169.188.26:11460;transport=udp> for address/port to send to
set_destination: set destination to 192.169.188.26:11460
Reliably Transmitting (no NAT) to 192.169.188.26:11460:
BYE sip:xl...@192.169.188.26:11460;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.169.188.41:5060;branch=z9hG4bK0bbef145;rport
Max-Forwards: 70
From: <sip:1...@192.169.188.41>;tag=as65e17744
To: <sip:xl...@192.169.188.41>;tag=a8ced13f
Call-ID: Y2Q2NmUzZWQzMmU0NDBkNmQxOTg2MjgxNmZiMmIyY2E
CSeq: 102 BYE
User-Agent: Asterisk PBX 11.6.0
Proxy-Authorization: Digest username="xlite", realm="asterisk", algorithm=MD5, uri="sip:192.169.188.41", nonce="3a948404", response="86c492571246b67800970c2218c25f42"
X-Asterisk-HangupCause: Unknown
X-Asterisk-HangupCauseCode: 0
Content-Length: 0


*** unimrcp server log **

2014-02-28 17:42:33:207012 [NOTICE] Run as Daemon
2014-02-28 17:42:33:208283 [NOTICE] UniMRCP Server [1.1.0]
2014-02-28 17:42:33:208494 [INFO]   APR [1.4.6]
2014-02-28 17:42:33:208659 [NOTICE] Create MRCP Server
2014-02-28 17:42:33:209001 [NOTICE] Open Config File [/usr/local/unimrcp/conf/unimrcpserver.xml]
2014-02-28 17:42:33:209846 [INFO]   Set Property ip:192.169.188.41
2014-02-28 17:42:33:209941 [INFO]   Register Codec [PCMU]
2014-02-28 17:42:33:209995 [INFO]   Register Codec [PCMA]
2014-02-28 17:42:33:210068 [INFO]   Register Codec [L16]
2014-02-28 17:42:33:210173 [NOTICE] Register Resource [speechsynth]
2014-02-28 17:42:33:210232 [NOTICE] Register Resource [speechrecog]
2014-02-28 17:42:33:210279 [NOTICE] Register Resource [recorder]
2014-02-28 17:42:33:210319 [INFO]   Register Resource Factory
2014-02-28 17:42:33:210398 [NOTICE] Create SofiaSIP Agent [SIP-Agent-1] [1.12.11-204-g6ff5f3f] sip:192.169.188.41:8060;transport=udp,tcp
2014-02-28 17:42:33:210436 [INFO]   Register Signaling Agent [SIP-Agent-1]
2014-02-28 17:42:33:210490 [NOTICE] Create MRCPv2 Agent [MRCPv2-Agent-1] 192.169.188.41:1544 [100]
2014-02-28 17:42:33:210983 [INFO]   Register Connection Agent [MRCPv2-Agent-1]
2014-02-28 17:42:33:211093 [NOTICE] Create Media Engine [Media-Engine-1]
2014-02-28 17:42:33:211153 [INFO]   Register Media Engine [Media-Engine-1]
2014-02-28 17:42:33:211202 [NOTICE] Create RTP Termination Factory 192.169.188.41:[5000,6000]
2014-02-28 17:42:33:211238 [INFO]   Register RTP Termination Factory [RTP-Factory-1]
2014-02-28 17:42:33:211288 [INFO]   Load Plugin [Flite-1] [/usr/local/unimrcp/plugin/mrcpflite.so]
2014-02-28 17:42:33:225453 [INFO]   Register MRCP Engine [Flite-1]
2014-02-28 17:42:33:225571 [INFO]   Load Plugin [Demo-Synth-1] [/usr/local/unimrcp/plugin/demosynth.so]
2014-02-28 17:42:33:225921 [INFO]   Register MRCP Engine [Demo-Synth-1]
2014-02-28 17:42:33:225990 [INFO]   Load Plugin [Demo-Recog-1] [/usr/local/unimrcp/plugin/demorecog.so]
2014-02-28 17:42:33:226319 [INFO]   Register MRCP Engine [Demo-Recog-1]
2014-02-28 17:42:33:226380 [INFO]   Load Plugin [Recorder-1] [/usr/local/unimrcp/plugin/mrcprecorder.so]
2014-02-28 17:42:33:226622 [INFO]   Register MRCP Engine [Recorder-1]
2014-02-28 17:42:33:226708 [INFO]   Register RTP Settings [RTP-Settings-1]
2014-02-28 17:42:33:226761 [NOTICE] Create MRCPv2 Profile [uni2]
2014-02-28 17:42:33:226813 [INFO]   Assign MRCP Engine [speechsynth] [Demo-Synth-1]
2014-02-28 17:42:33:226848 [INFO]   Assign MRCP Engine [speechrecog] [Demo-Recog-1]
2014-02-28 17:42:33:226882 [INFO]   Assign MRCP Engine [recorder] [Recorder-1]
2014-02-28 17:42:33:226915 [INFO]   Register Profile [uni2]
2014-02-28 17:42:33:226959 [INFO]   Start Task [MRCP Server]
2014-02-28 17:42:33:227270 [INFO]   Open Engine [Recorder-1]
2014-02-28 17:42:33:227428 [INFO]   Open Engine [Demo-Synth-1]
2014-02-28 17:42:33:227470 [INFO]   Start Task [Demo Synth Engine]
2014-02-28 17:42:33:227608 [INFO]   Open Engine [Demo-Recog-1]
2014-02-28 17:42:33:227668 [INFO]   Start Task [Demo Recog Engine]
2014-02-28 17:42:33:227835 [INFO]   Open Engine [Flite-1]
2014-02-28 17:42:33:228771 [INFO]   Start Task [SIP-Agent-1]
2014-02-28 17:42:33:230734 [INFO]   Start Task [MRCPv2-Agent-1]
2014-02-28 17:42:33:231111 [INFO]   Start Task [Media-Engine-1]
2014-02-28 17:42:33:236485 [NOTICE] MRCP Server Started
2014-02-28 17:42:33:236784 [INFO]   Receive SIP Event [nua_r_set_params] Status 200 OK
Waiting for data... (interrupt to abort)

[Arsen Chaloyan]

Hi Eric,

This must be a typical configuration issue. I don't see what is wrong exactly as the log level is set to NOTICE on Asterisk. Set log-level = DEBUG in mrcp.conf, and make a new test call. Anyway, since the client and server are on the same host, there are not too many things to configure, just make sure you use on the client the same SIP or RTSP port that the MRCP server is listening on.

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
To unsubscribe from this group and stop receiving emails from it, send an email to unimrcp+u...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.

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

[Gbolahan Oshonubi]

Hello,

I have the same issue with the following errors:

SynthAndRecog

  == Using SIP RTP CoS mark 5

    -- Executing [25@default:1] Answer("SIP/203-0000001a", "") in new stack

    -- Executing [25@default:2] MRCPRecog("SIP/203-0000001a", "builtin:grammar/grammar,p=default&t=5000&b=1&ct=0.7&spl=en-US&f=hello-world") in new stack

[Oct 30 13:53:11] NOTICE[32301]: app_mrcprecog.c:1067 app_recog_exec: MRCPRecog() grammar: builtin:grammar/grammar

[Oct 30 13:53:11] NOTICE[32301]: app_mrcprecog.c:1087 app_recog_exec: MRCPRecog() options: p=default&t=5000&b=1&ct=0.7&spl=en-US&f=hello-world

[Oct 30 13:53:11] NOTICE[32301]: src/mrcp_application.c:96 : Create MRCP Handle 0x9d04990 [asr-mrcp2]

[Oct 30 13:53:11] NOTICE[32301]: src/mrcp_client_session.c:133 : Create Channel ASR-26 <new>

[Oct 30 13:53:11] NOTICE[7313]: src/mrcp_client_session.c:387 : Receive App Request ASR-26 <new> [2]

[Oct 30 13:53:11] WARNING[7313]: src/mrcp_client_session.c:1269 : Failed to Create Session ASR-26 <new> [2]

[Oct 30 13:53:11] NOTICE[7313]: src/mrcp_client_session.c:533 : Raise App Response ASR-26 <new> [2] FAILURE [1]

[Oct 30 13:53:11] ERROR[7313]: app_mrcprecog.c:286 speech_on_channel_add: (ASR-26) Channel error status=1, response code=0!

[Oct 30 13:53:11] NOTICE[7313]: src/mrcp_client_session.c:387 : Receive App Request ASR-26 <new> [1]

[Oct 30 13:53:11] NOTICE[7313]: src/mrcp_client_session.c:455 : Raise App Response ASR-26 <new> [1] SUCCESS [0]

[Oct 30 13:53:11] NOTICE[7313]: src/mrcp_application.c:182 : Destroy MRCP Handle ASR-26

[Oct 30 13:53:11] NOTICE[32301]: app_mrcprecog.c:1027 mrcprecog_exit: MRCPRecog() exiting status: ERROR on SIP/203-0000001a

  == Spawn extension (default, 25, 2) exited non-zero on 'SIP/203-0000001a'

    -- Executing [h@default:1] AGI("SIP/203-0000001a", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack

    -- <SIP/203-0000001a>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0

MRCPSynth

    -- Executing [33@default:1] Answer("SIP/203-0000001b", "") in new stack

    -- Executing [33@default:2] MRCPSynth("SIP/203-0000001b", "Hello world!,p=default") in new stack

[Oct 30 13:54:44] NOTICE[32554]: app_mrcpsynth.c:515 app_synth_exec: MRCPSynth() prompt: Hello world!

[Oct 30 13:54:44] NOTICE[32554]: app_mrcpsynth.c:533 app_synth_exec: MRCPSynth() options: p=default

[Oct 30 13:54:44] NOTICE[32554]: src/mrcp_application.c:96 : Create MRCP Handle 0x9d04990 [asr-mrcp2]

[Oct 30 13:54:44] NOTICE[32554]: src/mrcp_client_session.c:133 : Create Channel TTS-27 <new>

[Oct 30 13:54:44] NOTICE[7313]: src/mrcp_client_session.c:387 : Receive App Request TTS-27 <new> [2]

[Oct 30 13:54:44] WARNING[7313]: src/mrcp_client_session.c:1269 : Failed to Create Session TTS-27 <new> [2]

[Oct 30 13:54:44] NOTICE[7313]: src/mrcp_client_session.c:533 : Raise App Response TTS-27 <new> [2] FAILURE [1]

[Oct 30 13:54:44] ERROR[7313]: app_mrcpsynth.c:217 speech_on_channel_add: (TTS-27) Channel error status=1, response code=0!

[Oct 30 13:54:44] NOTICE[7313]: src/mrcp_client_session.c:387 : Receive App Request TTS-27 <new> [1]

[Oct 30 13:54:44] NOTICE[7313]: src/mrcp_client_session.c:455 : Raise App Response TTS-27 <new> [1] SUCCESS [0]

[Oct 30 13:54:44] NOTICE[7313]: src/mrcp_application.c:182 : Destroy MRCP Handle TTS-27

[Oct 30 13:54:44] NOTICE[32554]: app_mrcpsynth.c:472 mrcpsynth_exit: MRCPSynth() exiting status: ERROR on SIP/203-0000001b

  == Spawn extension (default, 33, 2) exited non-zero on 'SIP/203-0000001b'

    -- Executing [h@default:1] AGI("SIP/203-0000001b", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack

    -- <SIP/203-0000001b>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0

Please kindly advice on how to get out of this error. I have spent enough time on resolving this without success.

On Tuesday, March 4, 2014 at 5:16:42 AM UTC+1, Arsen Chaloyan wrote:

Hi Eric,

This must be a typical configuration issue. I don't see what is wrong exactly as the log level is set to NOTICE on Asterisk. Set log-level = DEBUG in mrcp.conf, and make a new test call. Anyway, since the client and server are on the same host, there are not too many things to configure, just make sure you use on the client the same SIP or RTSP port that the MRCP server is listening on.

On Fri, Feb 28, 2014 at 2:07 AM, Eric Pino <er...@glyphgames.com> wrote:

Hi Arsen,


I'm having an issue with unimcrp on asterisk and based on asterisk loga, the unimrcp  failed
to create session.  Is this an asterisk version issue and o you have idea on how to resolve the issue?

Please see logs below as reference.

Thank You
Eric



Asterisk Version: 11.6
OS: Centos 6.4
UniMRCP Server:  1.1.0
 


Dialplan
exten => 100,1,Answer()
exten => 100,2,MRCPSynth(Hello world)



*** Asterisk Log ***

--- SIP read from UDP:192.169.188.26:11460 --->
ACK sip:1...@192.169.188.41:5060 SIP/2.0
Via: SIP/2.0/UDP 192.169.188.26:11460;branch=z9hG4bK-d8754z-396e747163f4d92f-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:xl...@192.169.188.26:11460;transport=udp>

To: <sip...@192.169.188.41>;tag=as65e17744
From: <sip:...@192.169.188.41>;tag=a8ced13f

Call-ID: Y2Q2NmUzZWQzMmU0NDBkNmQxOTg2MjgxNmZiMmIyY2E
CSeq: 2 ACK
User-Agent: X-Lite release 4.5.2 stamp 70143
Content-Length: 0

<------------->
--- (10 headers 0 lines) ---
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_mrcpsynth.c:576 app_synth_exec: MRCPSynth() prompt: Hello world
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_unimrcp.c:122 unimrcp_log: Create MRCP Handle 0xd4017358 [mrcpv2-profile]
[Feb 28 17:53:00] WARNING[2868]: app_unimrcp.c:119 unimrcp_log: Failed to Create Session TTS-2 <new> [2]
[Feb 28 17:53:00] ERROR[2868]: app_mrcpsynth.c:218 speech_on_channel_add: (TTS-2) Channel error!
[Feb 28 17:53:00] WARNING[2868]: app_unimrcp.c:119 unimrcp_log: Failed to Create Session TTS-2 <new> [1]
[Feb 28 17:53:00] NOTICE[2868]: app_unimrcp.c:122 unimrcp_log: Destroy MRCP Handle TTS-2
[Feb 28 17:53:00] NOTICE[2898][C-00000002]: app_mrcpsynth.c:525 mrcpsynth_exit: MRCPSynth() exiting status: ERROR on SIP/xlite-00000002
Scheduling destruction of SIP dialog 'Y2Q2NmUzZWQzMmU0NDBkNmQxOTg2MjgxNmZiMmIyY2E' in 32000 ms (Method: ACK)
set_destination: Parsing <sip:xl...@192.169.188.26:11460;transport=udp> for address/port to send to
set_destination: set destination to 192.169.188.26:11460
Reliably Transmitting (no NAT) to 192.169.188.26:11460:
BYE sip:xl...@192.169.188.26:11460;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.169.188.41:5060;branch=z9hG4bK0bbef145;rport
Max-Forwards: 70

From: <sip...@192.169.188.41>;tag=as65e17744
To: <sip:...@192.169.188.41>;tag=a8ced13f