[UniMRCP] Asterisk FreePBX Configuration issue

1,042 views
Skip to first unread message

andy...@hotmail.com

unread,
Jun 8, 2010, 9:49:59 AM6/8/10
to UniMRCP
I have everything installed and have done the configuration items
mentioned.

If I make a SIP call using MRCPSynth the call goes through but there
is no audio.

The TTS engine is Nuance and nvncmdline works OK. I have used nss2
profile in res-speech-unimrcp.conf.

I have set all the IP's in mrcp.conf to my own IP address.

The only significant problem in the Asterisk log is :-

[2010-06-08 15:45:37] WARNING[3102] app_unimrcp.c: Number of control
channels [1] != Number of control media in answer [0]

Here is the complete Asterisk log :-

[2010-06-08 15:45:35] VERBOSE[3124] logger.c: -- Executing [1111@from-
internal:1] Answer("SIP/10002-09b2b078", "") in new stack
[2010-06-08 15:45:35] VERBOSE[3124] logger.c: -- Executing [1111@from-
internal:2] Wait("SIP/10002-09b2b078", "2") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [1111@from-
internal:3] MRCPSynth("SIP/10002-09b2b078", "Hello world!|
p=default&i=any&f=/tmp/synth.raw&l=en-GB&v=daniel&g=male") in new
stack
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|p=default|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|i=any|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|f=/tmp/
synth.raw|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|l=en-GB|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|v=daniel|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Option=|g=male|
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Profile to use:
default
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Text to synthesize
is: Hello world!
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Filename to save
to: /tmp/synth.raw
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Language to use: en-
GB
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Prosody volume use:
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Prosody rate use:
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Voice name to use:
daniel
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Voice gender to use:
male
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: DTMF enable: 1
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) audio queue
created
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Created speech
channel: Name=TTS-0, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: Create MRCP Handle
0x9b3d160 [speech-nuance5-mrcp2]
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Create Channel
0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Receive App Request
0x9b3d160 <new> [2]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Add MRCP Handle
0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Dispatch App Request
0x9b3d160 <new> [2]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-08 15:45:37] NOTICE[3102] app_unimrcp.c: Add Control Channel
0x9b3d160 <new@speechsynth>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Add Media Termination
0x9b3d160 <new@media-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Add Media Termination
0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Signal Message to
[MRCP Client] [2;0]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Add Media Context
0x9b3d160
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [2;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Control Channel Added
0x9b3d160 <new@speechsynth>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Media Termination
Added 0x9b3d160 <new@media-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Media Termination
Added 0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Send Offer 0x9b3d160
<new> [c:1 a:1 v:0] to 192.168.0.100:5060
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Local SDP 0x9b3d160
<new>
v=0
o=Asterisk 0 0 IN IP4 192.168.0.100
s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1
m=audio 4000 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

[2010-06-08 15:45:37] WARNING[3113] chan_sip.c: Unsupported SDP media
type in offer: application 9 TCP/MRCPv2 1
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 0 INVITE sent
[2010-06-08 15:45:37] NOTICE[3106] app_unimrcp.c: SIP Call State
0x9b3d160 [calling]
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Executing [s@from-sip-
external:1] GotoIf("SIP/5093-09b419d8", "1?checklang:noanonymous") in
new stack
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Goto (from-sip-
external,s,2)
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Executing [s@from-sip-
external:2] GotoIf("SIP/5093-09b419d8", "0?setlanguage:from-trunk||1")
in new stack
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Goto (from-trunk,s,1)
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Executing [s@from-
trunk:1] Answer("SIP/5093-09b419d8", "100") in new stack
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_r_invite] Status 200 OK
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 200 OK
[2010-06-08 15:45:37] NOTICE[3106] app_unimrcp.c: SIP Call State
0x9b3d160 [ready]
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Remote SDP 0x9b3d160
<new>
v=0
o=root 2784 2784 IN IP4 192.168.0.100
s=session
c=IN IP4 192.168.0.100
t=0 0
m=audio 14436 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=silenceSupp:off - - - -
a=ptime:20

[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_i_active] Status 200 Call active
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [1;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Receive Answer
0x9b3d160 <new> [c:0 a:1 v:0]
[2010-06-08 15:45:37] WARNING[3102] app_unimrcp.c: Number of control
channels [1] != Number of control media in answer [0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Modify Media
Termination 0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Enable RTP Session
192.168.0.100:4000
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Set Timer 0x9b3d730
[5000]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Set Timer 0x9b3d748
[1000]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Create Null Audio
Bridge 0x9b3d160
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Open RTP Receiver
192.168.0.100:4000 <- 192.168.0.100:14436 playout [50 ms]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Media Path 0x9b3d160
Source->[PCMU/8000/1]->Bridge->[PCMU/8000/1]->Sink
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Media Termination
Modified 0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Raise App Response
0x9b3d160 <new> [2] SUCCESS [0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: speech_on_channel_add
[2010-06-08 15:45:37] NOTICE[3102] app_unimrcp.c: (TTS-0) Unable to
create DTMF generator
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) SYNTHESIZER
channel is ready, codec = PCMU, sample rate = 8000
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) CLOSED ==>
READY
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) channel is
ready
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) param =
speech-language, val = en-GB
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) param = voice-
name, val = daniel
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) param = voice-
gender, val = male
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) param = kill-
on-barge-in, val = true
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) voice-name:
daniel
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) voice-gender:
male
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) kill-on-barge-
in: true
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) speech-
language: en-GB
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Receive App MRCP
Request 0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Raise App Failure
MRCP Response 0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Raise App MRCP
Response 0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) unexpected
SPEAK response, request_state = 0
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) READY ==>
ERROR
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Destroying speech
channel: Name=TTS-0, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) Waiting for
MRCP session to terminate
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Receive App Request
0x9b3d160 <new> [1]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Dispatch App Request
0x9b3d160 <new> [1]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Terminate Session
0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Remove Control
Channel 0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Signal Message to
[MRCP Client] [2;2]
[2010-06-08 15:45:37] DEBUG[3103] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Subtract Media
Termination 0x9b3d160 <new@media-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Subtract Media
Termination 0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_r_bye] Status 200 OK
[2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 200 to BYE
[2010-06-08 15:45:37] NOTICE[3106] app_unimrcp.c: SIP Call State
0x9b3d160 [terminated]
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: == Spawn extension (from-
trunk, s, 1) exited non-zero on 'SIP/5093-09b419d8'
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: -- Executing [h@from-
trunk:1] Hangup("SIP/5093-09b419d8", "") in new stack
[2010-06-08 15:45:37] VERBOSE[3125] logger.c: == Spawn extension (from-
trunk, h, 1) exited non-zero on 'SIP/5093-09b419d8'
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [2;2]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Control Channel
Removed 0x9b3d160 <new@speechsynth>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [1;1]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Session Terminated
0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Destroy Audio Bridge
0x9b3d160
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Close RTP Receiver
192.168.0.100:4000 <- 192.168.0.100:14436 [r:0 l:0 j:0 d:0 i:0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Remove Media Context
0x9b3d160
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Remove RTP Session
192.168.0.100:4000
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Kill Timer 0x9b3d730
[5000]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Kill Timer 0x9b3d748
[1000]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Generate RTCP SR
[ssrc:18913628 s:0 o:0 ts:0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Generate RTCP RR
[ssrc:0 last_seq:0 j:0 lost:0 frac:0]
[2010-06-08 15:45:37] DEBUG[3104] app_unimrcp.c: Send Compound RTCP
Packet [BYE] [100 bytes] 192.168.0.100:4001 -> 192.168.0.100:14437
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Media Termination
Subtracted 0x9b3d160 <new@media-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Media Termination
Subtracted 0x9b3d160 <new@rtp-tm>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Remove MRCP Handle
0x9b3d160 <new>
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Raise App Response
0x9b3d160 <new> [1] SUCCESS [0]
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c:
speech_on_session_terminate
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) Destroying
MRCP session
[2010-06-08 15:45:37] NOTICE[3102] app_unimrcp.c: Destroy MRCP Handle
0x9b3d160
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: (TTS-0) ERROR ==>
CLOSED
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: (TTS-0) audio queue
destroyed
[2010-06-08 15:45:37] NOTICE[3124] app_unimrcp.c: (TTS-0) Audio queue
destroyed
[2010-06-08 15:45:37] DEBUG[3124] app_unimrcp.c: Destroyed speech
channel complete
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [1111@from-
internal:4] Hangup("SIP/10002-09b2b078", "") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: == Spawn extension (from-
internal, 1111, 4) exited non-zero on 'SIP/10002-09b2b078'
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [h@from-
internal:1] Macro("SIP/10002-09b2b078", "hangupcall") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [s@macro-
hangupcall:1] GotoIf("SIP/10002-09b2b078", "1?skiprg") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Goto (macro-
hangupcall,s,4)
[2010-06-08 15:45:37] DEBUG[3124] app_macro.c: Executed application:
GotoIf
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [s@macro-
hangupcall:4] GotoIf("SIP/10002-09b2b078", "1?skipblkvm") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Goto (macro-
hangupcall,s,7)
[2010-06-08 15:45:37] DEBUG[3124] app_macro.c: Executed application:
GotoIf
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [s@macro-
hangupcall:7] GotoIf("SIP/10002-09b2b078", "1?theend") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Goto (macro-
hangupcall,s,9)
[2010-06-08 15:45:37] DEBUG[3124] app_macro.c: Executed application:
GotoIf
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: -- Executing [s@macro-
hangupcall:9] Hangup("SIP/10002-09b2b078", "") in new stack
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-09b2b078' in
macro 'hangupcall'
[2010-06-08 15:45:37] VERBOSE[3124] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-09b2b078'
[2010-06-08 15:45:37] DEBUG[3102] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-08 15:45:46] VERBOSE[3126] logger.c: == Parsing '/etc/
asterisk/manager.conf': [2010-06-08 15:45:46] VERBOSE[3126] logger.c:
Found

Arsen Chaloyan

unread,
Jun 8, 2010, 11:06:05 AM6/8/10
to uni...@googlegroups.com
On Tue, Jun 8, 2010 at 6:49 PM, andy...@hotmail.com <andy...@hotmail.com> wrote:
I have everything installed and have done the configuration items
mentioned.

OK

If I make a SIP call using MRCPSynth the call goes through but there
is no audio.

The TTS engine is Nuance and nvncmdline works OK. I have used nss2
profile in res-speech-unimrcp.conf.

Let me clarify a bit. res-speech-unimrcp.conf is used by res-speech-unimrcp module, which in its turn contains the implementation of Asterisk's Generic Speech Recognition API. While mrcp.conf is used by app-unimrcp, which provides dialplan applications such as MRCPSynth and MRCPRecog.

Having said, for MRCPSynth you should configure mrcp.conf. That should be enough.

I have set all the IP's in mrcp.conf to my own IP address.

It depends. If you run Nuance and Asterisk on the same host then, yes you should use the same own IP address everywhere.
 

The only significant problem in the Asterisk log is :-

[2010-06-08 15:45:37] WARNING[3102] app_unimrcp.c: Number of control
channels [1] != Number of control media in answer [0]

You mixed the SIP ports as I believe. See below.

This makes me think that Asterisk received the SIP/MRCPv2 response from Nuance. Or even worse, you sent the SIP/MRCPv2 offer to Asterisk instead of Nuance MRCP server.

If you provide the exact topology, including the IP addresses and the SIP ports of your Asterisk and Nuance, I should be able to help you out.
 

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
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
The author of UniMRCP
http://www.unimrcp.org

andy...@hotmail.com

unread,
Jun 8, 2010, 11:42:21 AM6/8/10
to UniMRCP
OK, here is mrcp.conf :-

[general]
default-asr-profile = speech-nuance5-mrcp2
default-tts-profile = speech-nuance5-mrcp2
; UniMRCP logging level to appear in Asterisk logs. Options are:
; EMERGENCY|ALERT|CRITICAL|ERROR|WARNING|NOTICE|INFO|DEBUG -->
log-level = DEBUG
max-connection-count = 100
offer-new-connection = 1
; rx-buffer-size = 1024
; tx-buffer-size = 1024
; request-timeout = 60

[speech-nuance5-mrcp1]
; +++ MRCP settings +++
version = 1
;
; +++ RTSP +++
; === RSTP settings ===
server-ip = 192.168.0.100
server-port = 4900
; force-destination = 1
resource-location = media
speechsynth = speechsynthesizer
speechrecog = speechrecognizer
;
; +++ RTP +++
; === RTP factory ===
; rtp-ip = 0.0.0.0
rtp-ip = 192.168.0.100
; rtp-ext-ip = auto
rtp-port-min = 4000
rtp-port-max = 5000
; === RTP settings ===
; --- Jitter buffer settings ---
playout-delay = 50
; min-playout-delay = 20
max-playout-delay = 200
; --- RTP settings ---
ptime = 20
codecs = PCMU PCMA L16/96/8000
; --- RTCP settings ---
rtcp = 1
rtcp-bye = 2
rtcp-tx-interval = 5000
rtcp-rx-resolution = 1000


[speech-nuance5-mrcp2]
; +++ MRCP settings +++
version = 2
;
; +++ SIP +++
; === SIP settings ===
server-ip = 192.168.0.100
server-port = 5060
; server-username = test
force-destination = 1
; === SIP agent ===
; client-ip = 0.0.0.0
client-ip = 192.168.0.100
; client-ext-ip = auto
client-port = 5093
sip-transport = udp
; ua-name = Asterisk
; sdp-origin = Asterisk
;
; +++ RTP +++
; === RTP factory ===
; rtp-ip = 0.0.0.0
rtp-ip = 192.168.0.100
; rtp-ext-ip = auto
rtp-port-min = 4000
rtp-port-max = 5000
; === RTP settings ===
; --- Jitter buffer settings ---
playout-delay = 50
; min-playout-delay = 20
max-playout-delay = 200
; --- RTP settings ---
ptime = 20
codecs = PCMU PCMA L16/96/8000
; --- RTCP settings ---
rtcp = 1
rtcp-bye = 2
rtcp-tx-interval = 5000
rtcp-rx-resolution = 1000

This is what I hope is the right bit of NSSserver.cfg :-

############################## SIP configuration
###############################
#
# Specifies the basic configuration concerning the SIP layer
#

# Interfaces for receive connections on sip protocol,
# if disabled then used interface 0.0.0.0
# The same as server.mrcp2.sip.transport.interface.0
VXIString 0.0.0.0
# Example of custom interface
server.mrcp2.sip.transport.interface.0 VXIString
192.168.0.100
#server.mrcp2.sip.transport.interface.1 VXIString
10.15.36.81
#server.mrcp2.sip.transport.interface.3 VXIString
127.0.0.1

server.mrcp2.sip.transport.tcp.port VXIInteger 5060
server.mrcp2.sip.transport.udp.port VXIInteger 5060
server.mrcp2.sip.transport.tls.port VXIInteger 5061
server.mrcp2.sip.transport.tls.keyDir VXIString $
(NSSSVRSDK)/certs
server.mrcp2.transport.tls.keyDomain VXIString localdomain
#server.mrcp2.sip.transport.tls.keyPassword VXIString
server.mrcp2.sip.maxCountOfSession VXIInteger 96
#default value for tcp and tls maxCountOfConnections 4095
#server.mrcp2.sip.transport.tcp.maxCountOfConnections VXIInteger 4095
#server.mrcp2.sip.transport.tls.maxCountOfConnections VXIInteger 4095
server.mrcp2.sip.sessionTimeout VXIInteger 60000
#resip loglevels:
#None = -1, Crit = 2, Err = 3, Warning = 4, Info = 6, Debug = 7, Stack
= 8, StdErr = 9, Bogus = 666
server.mrcp2.sip.logLevel VXIInteger 2

# If value is 1 server uses IP address in SIP header Contact, else -
host name.
# Default value is 0.
server.mrcp2.sip.contact.useHostIPAddress VXIInteger 0

# If set to 1, the INVITE response will include the proprietary SIP
headers
# P-Nuance-CallLog-FilePath
# P-Nuance-CallLog-URI
# which will indicate the local disk path and URI of the call log.
# Note that the call log is only moved to the returned location
# after the session has ended.
server.mrcp2.sip.returnCallLogLocators VXIInteger 0

# If set to 1, the BYE response will include the proprietary SIP
header
# P-Nuance-WCR-URIs
# which will indicate the URI of the WCR logs for that session.
# Note that the WCR log is only moved to the returned location
# once the session has ended.
server.mrcp2.sip.returnWCRURIs VXIInteger 0

#Redefinition HostName
#server.mrcp2.sip.contact.HostName VXIString

# Maximum size of message head in bytes
#server.mrcp2.sip.msgHeadMaxSize VXIInteger 8192


########################### MRCPv2 configuration
###############################
#
# Specifies the basic configuration concerning the MRCPv2 layer
#
server.mrcp2.transport.tcp.port VXIInteger 6075
server.mrcp2.transport.timeout VXIInteger 20
server.mrcp2.transport.tls.port VXIInteger 6076
server.mrcp2.transport.tls.keyDir VXIString $(NSSSVRSDK)/certs
#server.mrcp2.transport.tls.keyPassword VXIString

###
# The number of threads used to fetch audio and feed it to the
recognizer and recorder
# Typically there should be 1 audio thread for about 15 to 20
# worker threads
server.mrcp2.audioengine.audiothreadnumber VXIInteger 20


########################### RTP configuration
##################################
#
# Specifies the basic configuration concerning the RTP layer
#
server.rtp.port VXIInteger 7892
server.rtp.maxCountOfSession VXIInteger 600

###
# The amount of the RTP receiving buffer specified in miliseconds.
# The greater the buffer, the lesser probability that audio will be
lost
# under heavy loads. The greater the buffer, the greater memory
consumption
# of system memory by the server.
server.rtp.bufferSize VXIInteger 5000

###
# The max size of accepted RTP packets
server.rtp.maxPacketSize VXIInteger 1024

# The size of RTP socket buffer for receiving
server.rtp.recvSockBufferSize VXIInteger 8192
# The size of RTP socket buffer for sending
server.rtp.sendSockBufferSize VXIInteger 8192

# By default (1), DTMF is detected with its leading edge. For
trailing edge detection,
# set this value to 0.
#server.rtp.dtmfTriggerLeading VXIInteger 1

# Timeout for RTP DTMF end of event. In the case of trailing edge
detection is selected
# and there is no end of event packet in DTMF packets, this time out
value will be used
# to parse DTMF. This value is set in msec.
#server.rtp.dtmfTrailingEdgeTimeout VXIInteger 2000

###
# The number of transmissions for TSS event signaling RTP packets
server.rtp.tssEventSignaling.numTransmissions VXIInteger 3

###
# RTP payload type for TSS event signaling, should be from the dynamic
payload range 96-127
# and should not conflict with other dynamic payload types in a SPD
offer/answer dialog.
# The default value is -1, meaning that TSS event signalling is
disabled in the RTP stack and

# all plugins related from it.
server.rtp.payloadType.tssEventSignaling VXIInteger -1

###
# This should be set to 1 to destroy sessions for a client connection
# when socket connection with client has error
server.transport.closeonpurpose VXIInteger 0

# Vovida RTP Stack Log Level (logs to stderr/stdout)
# 1 = ALERT, 2 = CRITICAL, 3 = ERROR, 4 = WARNING, 5 = NOTICE, 6 =
INFO, 7 = DEBUG, 8 = STACK
server.rtp.logLevel VXIInteger 2

# By default (1) option rejects the audio from ports other then UDP
port/IP address
# pointed in SDP offer/answer during SIP/RTSP setup.
server.rtp.strictSdpMediaPortUse VXIInteger 1

# If enabled, NSS will send RTCP sender reports when applicable
server.rtp.sendRTCPSenderReports VXIInteger 0


Is that sufficient?

Thanks

Andy.



On Jun 8, 5:06 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> On Tue, Jun 8, 2010 at 6:49 PM, andyw...@hotmail.com
> <andyw...@hotmail.com>wrote:
> > * [2010-06-08 15:45:37] WARNING[3113] chan_sip.c: Unsupported SDP media
> > type in offer: application 9 TCP/MRCPv2 1*
>
> This makes me think that Asterisk received the SIP/MRCPv2 response from
> Nuance. Or even worse, you sent the SIP/MRCPv2 offer to Asterisk instead of
> Nuance MRCP server.
>
> If you provide the exact topology, including the IP addresses and the SIP
> ports of your Asterisk and Nuance, I should be able to help you out.
>
> > *
> > * [2010-06-08 15:45:37] DEBUG[3106] app_unimrcp.c: Receive SIP Event
> ...
>
> read more »

Arsen Chaloyan

unread,
Jun 8, 2010, 12:14:59 PM6/8/10
to uni...@googlegroups.com
Well, Andy. This is exactly what I suspected.

Your Asterisk and Nunace are listening on the same port of the same net interface (192.168.0.100:5060). This is not going to work.
If you need to run Nuance on the same host, Id' suggest to change its SIP port. See the suggested changes in mrcp.conf and nssserver.cfg outlined below...

server-port = 6060
server.mrcp2.sip.transport.tcp.port          VXIInteger    6060
server.mrcp2.sip.transport.udp.port          VXIInteger    6060
server.mrcp2.sip.transport.tls.port          VXIInteger    6061
 

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
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.

andy...@hotmail.com

unread,
Jun 9, 2010, 3:22:12 AM6/9/10
to UniMRCP
Hi Arsen

Did as you said, looks like something does not like being spoken to by
SIP....

[2010-06-09 09:14:09] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_r_invite] Status 503 Service Unavailable
[2010-06-09 09:14:09] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 503 Service Unavailable

See below!


[2010-06-09 09:14:06] VERBOSE[4227] logger.c: -- Executing [1111@from-
internal:1] Answer("SIP/10002-0881f558", "") in new stack
[2010-06-09 09:14:06] VERBOSE[4227] logger.c: -- Executing [1111@from-
internal:2] Wait("SIP/10002-0881f558", "2") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [1111@from-
internal:3] MRCPSynth("SIP/10002-0881f558", "Hello world!|
p=default&i=any&f=/tmp/synth.raw&l=en-GB&v=daniel&g=male") in new
stack
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|p=default|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|i=any|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|f=/tmp/
synth.raw|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|l=en-GB|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|v=daniel|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Option=|g=male|
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Profile to use:
default
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Text to synthesize
is: Hello world!
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Filename to save
to: /tmp/synth.raw
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Language to use: en-
GB
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Prosody volume use:
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Prosody rate use:
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Voice name to use:
daniel
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Voice gender to use:
male
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: DTMF enable: 1
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: (TTS-0) audio queue
created
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: Created speech
channel: Name=TTS-0, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: Create MRCP Handle
0x8834f68 [speech-nuance5-mrcp2]
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: Create Channel
0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Receive App Request
0x8834f68 <new> [2]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Add MRCP Handle
0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x8834f68 <new> [2]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 09:14:09] NOTICE[2803] app_unimrcp.c: Add Control Channel
0x8834f68 <new@speechsynth>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x8834f68 <new@media-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x8834f68 <new@rtp-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;0]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Control Channel Added
0x8834f68 <new@speechsynth>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 09:14:09] DEBUG[2805] app_unimrcp.c: Add Media Context
0x8834f68
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x8834f68 <new@media-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x8834f68 <new@rtp-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Send Offer 0x8834f68
<new> [c:1 a:1 v:0] to 192.168.0.100:6060
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Local SDP 0x8834f68
<new>
v=0
o=Asterisk 0 0 IN IP4 192.168.0.100
s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1

m=audio 4000 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

[2010-06-09 09:14:09] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 0 INVITE sent
[2010-06-09 09:14:09] NOTICE[2807] app_unimrcp.c: SIP Call State
0x8834f68 [calling]


[2010-06-09 09:14:09] NOTICE[2807] app_unimrcp.c: SIP Call State
0x8834f68 [terminated]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;4]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Raise App Response
0x8834f68 <new> [2] FAILURE [2]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: speech_on_channel_add
[2010-06-09 09:14:09] ERROR[2803] app_unimrcp.c: (TTS-0) SYNTHESIZER
channel error!
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Terminating MRCP
session
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: (TTS-0) CLOSED ==>
ERROR
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Receive App Request
0x8834f68 <new> [1]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x8834f68 <new> [1]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Terminate Session
0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Remove Control
Channel 0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x8834f68 <new@media-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x8834f68 <new@rtp-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;1]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Session Terminated
0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;2]
[2010-06-09 09:14:09] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;2]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Control Channel
Removed 0x8834f68 <new@speechsynth>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 09:14:09] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 09:14:09] DEBUG[2805] app_unimrcp.c: Remove Media Context
0x8834f68
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x8834f68 <new@media-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x8834f68 <new@rtp-tm>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Remove MRCP Handle
0x8834f68 <new>
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Raise App Response
0x8834f68 <new> [1] SUCCESS [0]
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c:
speech_on_session_terminate
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: (TTS-0) Destroying
MRCP session
[2010-06-09 09:14:09] NOTICE[2803] app_unimrcp.c: Destroy MRCP Handle
0x8834f68
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: (TTS-0) ERROR ==>
CLOSED
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: Destroying speech
channel: Name=TTS-0, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: (TTS-0) audio queue
destroyed
[2010-06-09 09:14:09] NOTICE[4227] app_unimrcp.c: (TTS-0) Audio queue
destroyed
[2010-06-09 09:14:09] DEBUG[4227] app_unimrcp.c: Destroyed speech
channel complete
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: == Spawn extension (from-
internal, 1111, 3) exited non-zero on 'SIP/10002-0881f558'
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [h@from-
internal:1] Macro("SIP/10002-0881f558", "hangupcall") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [s@macro-
hangupcall:1] GotoIf("SIP/10002-0881f558", "1?skiprg") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Goto (macro-
hangupcall,s,4)
[2010-06-09 09:14:09] DEBUG[4227] app_macro.c: Executed application:
GotoIf
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [s@macro-
hangupcall:4] GotoIf("SIP/10002-0881f558", "1?skipblkvm") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Goto (macro-
hangupcall,s,7)
[2010-06-09 09:14:09] DEBUG[4227] app_macro.c: Executed application:
GotoIf
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [s@macro-
hangupcall:7] GotoIf("SIP/10002-0881f558", "1?theend") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Goto (macro-
hangupcall,s,9)
[2010-06-09 09:14:09] DEBUG[4227] app_macro.c: Executed application:
GotoIf
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: -- Executing [s@macro-
hangupcall:9] Hangup("SIP/10002-0881f558", "") in new stack
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-0881f558' in
macro 'hangupcall'
[2010-06-09 09:14:09] VERBOSE[4227] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-0881f558'
[2010-06-09 09:14:09] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]


Andy.



On Jun 8, 6:14 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Well, Andy. This is exactly what I suspected.
>
> Your Asterisk and Nunace are listening on the same port of the same net
> interface (192.168.0.100:5060). This is not going to work.
> If you need to run Nuance on the same host, Id' suggest to change its SIP
> port. See the suggested changes in mrcp.conf and nssserver.cfg outlined
> below...
>
> On Tue, Jun 8, 2010 at 8:42 PM, andyw...@hotmail.com
> <andyw...@hotmail.com>wrote:
> > * server-port = 6060*
> > *server.mrcp2.sip.transport.**tcp.port          VXIInteger    6060
> > server.mrcp2.sip.transport.**udp.port          VXIInteger    6060
> > server.mrcp2.sip.transport.**tls.port          VXIInteger    6061*
> > *
> > * server.mrcp2.sip.transport.tls.keyDir        VXIString     $
> ...
>
> read more »

Arsen Chaloyan

unread,
Jun 9, 2010, 5:51:56 AM6/9/10
to uni...@googlegroups.com
Hi Andy,

It seems you have done with the configuration.


[2010-06-09 09:14:09] DEBUG[2807] app_unimrcp.c: Receive SIP Event [nua_r_invite] Status 503 Service Unavailable

The above means that Nuance responded with "503 Service Unavailable". Why? Most probably this is some sort of licensing issue. Make sure you have all the corresponding Nuance licenses installed. Check Nuance's logs for more info.

> ...
>
> read more »

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
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.

andy...@hotmail.com

unread,
Jun 9, 2010, 6:18:28 AM6/9/10
to UniMRCP
Hi Arsen

It appears that the Nuance Speech Server had not been started for some
reason.

Once I started it manually it all worked!

Thanks for all your help

Andy.

On Jun 9, 11:51 am, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Hi Andy,
>
> It seems you have done with the configuration.
>
> [2010-06-09 09:14:09] DEBUG[2807] app_unimrcp.c: Receive SIP Event
> [nua_r_invite] Status 503 Service Unavailable
>
> The above means that Nuance responded with "503 Service Unavailable". Why?
> Most probably this is some sort of licensing issue. Make sure you have all
> the corresponding Nuance licenses installed. Check Nuance's logs for more
> info.
>
> On Wed, Jun 9, 2010 at 12:22 PM, andyw...@hotmail.com
> <andyw...@hotmail.com>wrote:
> ...
>
> read more »

andy...@hotmail.com

unread,
Jun 9, 2010, 6:51:29 AM6/9/10
to UniMRCP
Ha, knew it was too good to be true!

Once two calls have been successfully made it all stops working.

If you stop and restart the Nuance Speech Server you get two more
calls...

Log below of one good, one bad call. Looks like a SIP problem.

Andy.

[2010-06-09 12:43:46] VERBOSE[4961] logger.c: -- Executing [1001@from-
internal:1] ReadFile("SIP/10002-08820f78", "test=/tmp/1001.txt") in
new stack
[2010-06-09 12:43:46] VERBOSE[4961] logger.c: -- Executing [1001@from-
internal:2] MRCPSynth("SIP/10002-08820f78", "the next train at
platform 6 is the 16:54 First Great Western Mayflower service to
Aberdare Diesel Sidings , calling at Aberdeen Kittybrewster , Aberdeen
Waterloo , Aberthaw , Accrington , Aldermaston , Alton Towers ,
Aspatria , Aylesham , Axminster , Avonmouth Dock Junction , Horbury
Junction , Hurstbourne , Kentish Town Junction , Kettering , and
Aberdare Diesel Sidings .|p=default&i=any&f=/tmp/synth.raw&l=en-
GB&v=daniel&g=male") in new stack
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|p=default|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|i=any|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|f=/tmp/
synth.raw|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|l=en-GB|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|v=daniel|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Option=|g=male|
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Profile to use:
default
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Text to synthesize
is: the next train at platform 6 is the 16:54 First Great Western
Mayflower service to Aberdare Diesel Sidings , calling at Aberdeen
Kittybrewster , Aberdeen Waterloo , Aberthaw , Accrington ,
Aldermaston , Alton Towers , Aspatria , Aylesham , Axminster ,
Avonmouth Dock Junction , Horbury Junction , Hurstbourne , Kentish
Town Junction , Kettering , and Aberdare Diesel Sidings .
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Filename to save
to: /tmp/synth.raw
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Language to use: en-
GB
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Prosody volume use:
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Prosody rate use:
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Voice name to use:
daniel
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Voice gender to use:
male
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: DTMF enable: 1
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) audio queue
created
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: Created speech
channel: Name=TTS-10, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 12:43:46] NOTICE[4961] app_unimrcp.c: Create MRCP Handle
0x886fcf8 [speech-nuance5-mrcp2]
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: Create Channel
0x886fcf8 <new>
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Receive App Request
0x886fcf8 <new> [2]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Add MRCP Handle
0x886fcf8 <new>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x886fcf8 <new> [2]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] NOTICE[2803] app_unimrcp.c: Add Control Channel
0x886fcf8 <new@speechsynth>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x886fcf8 <new@media-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;0]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Control Channel Added
0x886fcf8 <new@speechsynth>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Add Media Context
0x886fcf8
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x886fcf8 <new@media-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Send Offer 0x886fcf8
<new> [c:1 a:1 v:0] to 192.168.0.100:6060
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Local SDP 0x886fcf8
<new>
v=0
o=Asterisk 0 0 IN IP4 192.168.0.100
s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1
m=audio 4020 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 0 INVITE sent
[2010-06-09 12:43:46] NOTICE[2807] app_unimrcp.c: SIP Call State
0x886fcf8 [calling]
[2010-06-09 12:43:46] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_r_invite] Status 200 OK
[2010-06-09 12:43:46] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 200 OK
[2010-06-09 12:43:46] NOTICE[2807] app_unimrcp.c: SIP Call State
0x886fcf8 [ready]
[2010-06-09 12:43:46] DEBUG[2807] app_unimrcp.c: Remote SDP 0x886fcf8
<new>
v=0
o=- 1276080226 1276080226 IN IP4 192.168.0.100
s=MRCP session
c=IN IP4 192.168.0.100
t=0 0
m=application 6075 TCP/MRCPv2 1
a=channel:2@speechsynth
a=cmid:1
a=connection:new
a=setup:passive
m=audio 7894 RTP/AVP 0 8 96 100
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:96 l16/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=sendonly
a=mid:1

[2010-06-09 12:43:46] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_active] Status 200 Call active
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Receive Answer
0x886fcf8 <new> [c:1 a:1 v:0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Modify Control
Channel 0x886fcf8 <2>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Modify Media
Termination 0x886fcf8 <2@rtp-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] NOTICE[2804] app_unimrcp.c: Established TCP/
MRCPv2 Connection 192.168.0.100:41941 <-> 192.168.0.100:6075
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Add Control Channel
<2@speechsynth> 192.168.0.100:41941 <-> 192.168.0.100:6075 [1]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;1]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;1]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Control Channel
Modified 0x886fcf8 <2@speechsynth>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Enable RTP Session
192.168.0.100:4020
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Set Timer 0x88702c8
[5000]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Set Timer 0x88702e0
[1000]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Create Null Audio
Bridge 0x886fcf8
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Open RTP Receiver
192.168.0.100:4020 <- 192.168.0.100:7894 playout [50 ms]
[2010-06-09 12:43:46] DEBUG[2805] app_unimrcp.c: Media Path 0x886fcf8
Source->[PCMU/8000/1]->Bridge->[PCMU/8000/1]->Sink
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Media Termination
Modified 0x886fcf8 <2@rtp-tm>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Raise App Response
0x886fcf8 <2> [2] SUCCESS [0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: speech_on_channel_add
[2010-06-09 12:43:46] NOTICE[2803] app_unimrcp.c: (TTS-10) Unable to
create DTMF generator
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: (TTS-10) SYNTHESIZER
channel is ready, codec = PCMU, sample rate = 8000
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: (TTS-10) CLOSED ==>
READY
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) channel is
ready
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) param =
speech-language, val = en-GB
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) param =
voice-name, val = daniel
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) param =
voice-gender, val = male
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) param = kill-
on-barge-in, val = true
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) voice-name:
daniel
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) voice-
gender: male
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) kill-on-
barge-in: true
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: (TTS-10) speech-
language: en-GB
[2010-06-09 12:43:46] DEBUG[4961] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Receive App MRCP
Request 0x886fcf8 <2>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Send MRCP Request
0x886fcf8 <2@speechsynth> [1]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Send MRCPv2 Stream
192.168.0.100:41941 <-> 192.168.0.100:6075 [585 bytes]
MRCP/2.0 585 SPEAK 1
Channel-Identifier: 2@speechsynth
Content-Type: text/plain
Voice-Name: daniel
Voice-Gender: male
Kill-On-Barge-In: true
Speech-Language: en-GB
Content-Length: 391

the next train at platform 6 is the 16:54 First Great Western
Mayflower service to Aberdare Diesel Sidings , calling at Aberdeen
Kittybrewster , Aberdeen Waterloo , Aberthaw , Accrington ,
Aldermaston , Alton Towers , Aspatria , Aylesham , Axminster ,
Avonmouth Dock Junction , Horbury Junction , Hurstbourne , Kentish
Town Junction , Kettering , and Aberdare Diesel Sidings .
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Process Signalled
Descriptor [MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Receive MRCPv2 Stream
192.168.0.100:41941 <-> 192.168.0.100:6075 [106 bytes]
MRCP/2.0 106 1 200 IN-PROGRESS
Channel-Identifier: 2@speechsynth
Speech-Marker: timestamp=1522332304


[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;3]
[2010-06-09 12:43:46] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;3]
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Raise App MRCP
Response 0x886fcf8 <2>
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: (TTS-10) REQUEST IN
PROGRESS
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: (TTS-10) READY ==>
PROCESSING
[2010-06-09 12:43:46] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:47] DEBUG[2805] app_unimrcp.c: Timer Elapsed
0x88702e0 [1000]
[2010-06-09 12:43:47] DEBUG[2805] app_unimrcp.c: Set Timer 0x88702e0
[2000]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Timer Elapsed
0x88702e0 [2000]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Set Timer 0x88702e0
[3000]
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: Null frame ==
hangup() detected
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: (TTS-10) Stopping
SYNTHESIZER
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Receive App MRCP
Request 0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Send MRCP Request
0x886fcf8 <2@speechsynth> [2]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Send MRCPv2 Stream
192.168.0.100:41941 <-> 192.168.0.100:6075 [57 bytes]
MRCP/2.0 57 STOP 2
Channel-Identifier: 2@speechsynth


[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Process Signalled
Descriptor [MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Receive MRCPv2 Stream
192.168.0.100:41941 <-> 192.168.0.100:6075 [130 bytes]
MRCP/2.0 130 2 200 COMPLETE
Channel-Identifier: 2@speechsynth
Speech-Marker: timestamp=1522348624
Active-Request-Id-List: 1


[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;3]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;3]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Raise App MRCP
Response 0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: (TTS-10) COMPLETE
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: (TTS-10) PROCESSING
==> READY
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: (TTS-10) SYNTHESIZER
stopped
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: Destroying speech
channel: Name=TTS-10, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: (TTS-10) Waiting for
MRCP session to terminate
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Receive App Request
0x886fcf8 <2> [1]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x886fcf8 <2> [1]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Terminate Session
0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Remove Control
Channel 0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x886fcf8 <2@media-tm>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x886fcf8 <2@rtp-tm>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Remove Control
Channel <2@speechsynth> [0]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Close TCP/MRCPv2
Connection 192.168.0.100:41941 <-> 192.168.0.100:6075
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;2]
[2010-06-09 12:43:48] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;2]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Control Channel
Removed 0x886fcf8 <2@speechsynth>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_r_bye] Status 503 DNS Error
[2010-06-09 12:43:48] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 503 to BYE
[2010-06-09 12:43:48] NOTICE[2807] app_unimrcp.c: SIP Call State
0x886fcf8 [terminated]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;1]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Session Terminated
0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Destroy Audio Bridge
0x886fcf8
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Close RTP Receiver
192.168.0.100:4020 <- 192.168.0.100:7894 [r:102 l:0 j:79 d:0 i:0]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Remove Media Context
0x886fcf8
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Remove RTP Session
192.168.0.100:4020
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Kill Timer 0x88702c8
[5000]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Kill Timer 0x88702e0
[3000]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Generate RTCP RR
[ssrc:604407911 last_seq:7201 j:79 lost:0 frac:0]
[2010-06-09 12:43:48] DEBUG[2805] app_unimrcp.c: Send Compound RTCP
Packet [BYE] [80 bytes] 192.168.0.100:4021 -> 192.168.0.100:7895
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x886fcf8 <2@media-tm>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x886fcf8 <2@rtp-tm>
[2010-06-09 12:43:48] NOTICE[2803] app_unimrcp.c: Destroy TCP/MRCPv2
Connection 192.168.0.100:41941 <-> 192.168.0.100:6075
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Remove MRCP Handle
0x886fcf8 <2>
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Raise App Response
0x886fcf8 <2> [1] SUCCESS [0]
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c:
speech_on_session_terminate
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: (TTS-10) Destroying
MRCP session
[2010-06-09 12:43:48] NOTICE[2803] app_unimrcp.c: Destroy MRCP Handle
0x886fcf8
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: (TTS-10) READY ==>
CLOSED
[2010-06-09 12:43:48] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: (TTS-10) audio queue
destroyed
[2010-06-09 12:43:48] NOTICE[4961] app_unimrcp.c: (TTS-10) Audio queue
destroyed
[2010-06-09 12:43:48] DEBUG[4961] app_unimrcp.c: Destroyed speech
channel complete
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: == Spawn extension (from-
internal, 1001, 2) exited non-zero on 'SIP/10002-08820f78'
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Executing [h@from-
internal:1] Macro("SIP/10002-08820f78", "hangupcall") in new stack
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Executing [s@macro-
hangupcall:1] GotoIf("SIP/10002-08820f78", "1?skiprg") in new stack
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Goto (macro-
hangupcall,s,4)
[2010-06-09 12:43:48] DEBUG[4961] app_macro.c: Executed application:
GotoIf
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Executing [s@macro-
hangupcall:4] GotoIf("SIP/10002-08820f78", "1?skipblkvm") in new stack
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Goto (macro-
hangupcall,s,7)
[2010-06-09 12:43:48] DEBUG[4961] app_macro.c: Executed application:
GotoIf
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Executing [s@macro-
hangupcall:7] GotoIf("SIP/10002-08820f78", "1?theend") in new stack
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Goto (macro-
hangupcall,s,9)
[2010-06-09 12:43:48] DEBUG[4961] app_macro.c: Executed application:
GotoIf
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: -- Executing [s@macro-
hangupcall:9] Hangup("SIP/10002-08820f78", "") in new stack
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-08820f78' in
macro 'hangupcall'
[2010-06-09 12:43:48] VERBOSE[4961] logger.c: == Spawn extension
(macro-hangupcall, s, 9) exited non-zero on 'SIP/10002-08820f78'
[2010-06-09 12:43:50] VERBOSE[4962] logger.c: -- Executing [1111@from-
internal:1] Answer("SIP/10002-08849608", "") in new stack
[2010-06-09 12:43:50] VERBOSE[4962] logger.c: -- Executing [1111@from-
internal:2] MRCPSynth("SIP/10002-08849608", "Hello world!|
p=default&i=any&f=/tmp/synth.raw&l=en-GB&v=daniel&g=male") in new
stack
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|p=default|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|i=any|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|f=/tmp/
synth.raw|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|l=en-GB|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|v=daniel|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Option=|g=male|
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Profile to use:
default
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Text to synthesize
is: Hello world!
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Filename to save
to: /tmp/synth.raw
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Language to use: en-
GB
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Prosody volume use:
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Prosody rate use:
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Voice name to use:
daniel
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Voice gender to use:
male
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: DTMF enable: 1
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: (TTS-11) audio queue
created
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: Created speech
channel: Name=TTS-11, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: Create MRCP Handle
0x886fcf8 [speech-nuance5-mrcp2]
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: Create Channel
0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Receive App Request
0x886fcf8 <new> [2]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Add MRCP Handle
0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x886fcf8 <new> [2]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:50] NOTICE[2803] app_unimrcp.c: Add Control Channel
0x886fcf8 <new@speechsynth>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x886fcf8 <new@media-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Add Media Termination
0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 12:43:50] DEBUG[2805] app_unimrcp.c: Add Media Context
0x886fcf8
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;0]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x886fcf8 <new@media-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Media Termination
Added 0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Control Channel Added
0x886fcf8 <new@speechsynth>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Send Offer 0x886fcf8
<new> [c:1 a:1 v:0] to 192.168.0.100:6060
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Local SDP 0x886fcf8
<new>
v=0
o=Asterisk 0 0 IN IP4 192.168.0.100
s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1
m=audio 4022 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 0 INVITE sent
[2010-06-09 12:43:50] NOTICE[2807] app_unimrcp.c: SIP Call State
0x886fcf8 [calling]
[2010-06-09 12:43:50] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_r_invite] Status 503 Service Unavailable
[2010-06-09 12:43:50] DEBUG[2807] app_unimrcp.c: Receive SIP Event
[nua_i_state] Status 503 Service Unavailable
[2010-06-09 12:43:50] NOTICE[2807] app_unimrcp.c: SIP Call State
0x886fcf8 [terminated]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;4]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Raise App Response
0x886fcf8 <new> [2] FAILURE [2]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: speech_on_channel_add
[2010-06-09 12:43:50] ERROR[2803] app_unimrcp.c: (TTS-11) SYNTHESIZER
channel error!
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Terminating MRCP
session
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: (TTS-11) CLOSED ==>
ERROR
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCP Client] [4;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [4;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Receive App Request
0x886fcf8 <new> [1]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Dispatch App Request
0x886fcf8 <new> [1]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Terminate Session
0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Remove Control
Channel 0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Signal Message to
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x886fcf8 <new@media-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Subtract Media
Termination 0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Signal Message to
[MediaEngine] [1;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [1;1]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Session Terminated
0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Process Poller Wakeup
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Process Message
[MRCPv2ConnectionAgent] [1;0]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Signal Message to
[MRCP Client] [2;2]
[2010-06-09 12:43:50] DEBUG[2804] app_unimrcp.c: Wait for Messages
[MRCPv2ConnectionAgent]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [2;2]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Control Channel
Removed 0x886fcf8 <new@speechsynth>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[2805] app_unimrcp.c: Process Message
[MediaEngine] [1;0]
[2010-06-09 12:43:50] DEBUG[2805] app_unimrcp.c: Remove Media Context
0x886fcf8
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Process Message [MRCP
Client] [3;0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x886fcf8 <new@media-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Media Termination
Subtracted 0x886fcf8 <new@rtp-tm>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Remove MRCP Handle
0x886fcf8 <new>
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Raise App Response
0x886fcf8 <new> [1] SUCCESS [0]
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c:
speech_on_session_terminate
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: (TTS-11) Destroying
MRCP session
[2010-06-09 12:43:50] NOTICE[2803] app_unimrcp.c: Destroy MRCP Handle
0x886fcf8
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: (TTS-11) ERROR ==>
CLOSED
[2010-06-09 12:43:50] DEBUG[2803] app_unimrcp.c: Wait for Messages
[MRCP Client]
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: Destroying speech
channel: Name=TTS-11, Type=SYNTHESIZER, Codec=PCMU, Rate=8000
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: (TTS-11) audio queue
destroyed
[2010-06-09 12:43:50] NOTICE[4962] app_unimrcp.c: (TTS-11) Audio queue
destroyed
[2010-06-09 12:43:50] DEBUG[4962] app_unimrcp.c: Destroyed speech
channel complete
[2010-06-09 12:43:50] VERBOSE[4962] logger.c: == Spawn extension (from-
internal, 1111, 2) exited non-zero on 'SIP/10002-08849608'
[2010-06-09 12:43:50] VERBOSE[4962] logger.c: -- Executing [h@from-
internal:1] Macro("SIP/10002-08849608", "hangupcall") in new stack
[2010-06-09 12:43:50] VERBOSE[4962] logger.c: -- Executing [s@macro-
hangupcall:1] GotoIf("SIP/10002-08849608", "1?skiprg") in new stack


On Jun 9, 12:18 pm, "andyw...@hotmail.com" <andyw...@hotmail.com>
> ...
>
> read more »

Vlad Socaciu

unread,
Jun 9, 2010, 8:30:59 AM6/9/10
to uni...@googlegroups.com
503 may be a license issue on the server. Check your server licenses by running the FlexNet report. Note that the licenses may not be released for some reason by the Nuance server at the end of a SIP session; I saw that and could not explain why. It is important to stop Nuance Speech Server when working with the license configuration utility and restart it when you are done.

> ...
>
> read more »

andy...@hotmail.com

unread,
Jun 9, 2010, 10:49:12 AM6/9/10
to UniMRCP
The licencing is fine, it would not work at all without it!

I have 4 licences.

If I make 4 calls it works, the 5th one fails.

If I wait a minute or a bit less then you can do it all over again!

It looks like the Nuance Speech Server is either not giving the
licenses back, or something is not happening to release them.



On Jun 9, 2:30 pm, Vlad Socaciu <curat...@gmail.com> wrote:
> 503 may be a license issue on the server. Check your server licenses by
> running the FlexNet report. Note that the licenses may not be released for
> some reason by the Nuance server at the end of a SIP session; I saw that and
> could not explain why. It is important to stop Nuance Speech Server when
> working with the license configuration utility and restart it when you are
> done.
>
> On Wed, Jun 9, 2010 at 3:51 AM, andyw...@hotmail.com
> <andyw...@hotmail.com>wrote:
> ...
>
> read more »

Arsen Chaloyan

unread,
Jun 9, 2010, 11:26:22 AM6/9/10
to uni...@googlegroups.com
Your description looks quite realistic at this time.

I remember a similar issue
http://code.google.com/p/unimrcp/issues/detail?id=70

And I believe the problem is in the SIP dialog between UniMRCP client (Sofia-SIP) and Nuance. A network capture would be helpful. I've already suggested to look into Nuance's logs.

Finally, if you start to believe that UniMRCP is a working solution, it will help you identify this sort of configuration or setup related issues quickly."Working" means it should work 100 times out of 100 attempts.


> ...
>
> read more »

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
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.

andy...@hotmail.com

unread,
Jun 10, 2010, 3:46:18 AM6/10/10
to UniMRCP
OK, have up loaded Asterisk FreePBX Config Issue 1.pcap which shows
the SIP tranactions.

This is with...

server.mrcp2.sip.contact.useHostIPAddress VXIInteger 0

If you set it to 1 then you get 127.0.0.1 coming up in all funny
places!

Thanks

Andy.

On Jun 9, 5:26 pm, Arsen Chaloyan <achalo...@gmail.com> wrote:
> Your description looks quite realistic at this time.
>
> I remember a similar issuehttp://code.google.com/p/unimrcp/issues/detail?id=70
>
> And I believe the problem is in the SIP dialog between UniMRCP client
> (Sofia-SIP) and Nuance. A network capture would be helpful. I've already
> suggested to look into Nuance's logs.
>
> Finally, if you start to believe that UniMRCP is a working solution, it will
> help you identify this sort of configuration or setup related issues
> quickly."Working" means it should work 100 times out of 100 attempts.
>
> On Wed, Jun 9, 2010 at 7:49 PM, andyw...@hotmail.com
> <andyw...@hotmail.com>wrote:
> ...
>
> read more »

Arsen Chaloyan

unread,
Jun 10, 2010, 4:20:15 AM6/10/10
to uni...@googlegroups.com
Well, everything seems to be clear to me now.

- Working example

INVITE sip:192.168.0.100:6060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.100:5093;rport;branch=z9hG4bKUHr3c88ZDmvHH
Max-Forwards: 70
From: <sip:192.168.0.100:5093>;tag=K2H7Fv42XD0tQ
To: <sip:192.168.0.100:6060>
Call-ID: 536ef114-ef06-122d-bf97-000c76a2865b
CSeq: 131959487 INVITE
Contact: <sip:192.168.0.100:5093>
User-Agent: Asterisk
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE
Supported: timer, 100rel
Min-SE: 120
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 362

v=0
o=Asterisk 5357650498333614251 1130108425792615755 IN IP6 2002:5310:4d9a:b:20c:76ff:fea2:865b

s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1
m=audio 4026 RTP/AVP 0 8 96

a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.100:5093;rport=5093;branch=z9hG4bKUHr3c88ZDmvHH
Contact: <sip:mrcps...@192.168.0.100:6060;transport=UDP>
To: <sip:192.168.0.100:6060>;tag=73906013
From: <sip:192.168.0.100:5093>;tag=K2H7Fv42XD0tQ
Call-ID: 536ef114-ef06-122d-bf97-000c76a2865b
CSeq: 131959487 INVITE
Content-Type: application/sdp
Content-Length: 375

v=0
o=- 1276155647 1276155647 IN IP4 192.168.0.100

s=MRCP session
c=IN IP4 192.168.0.100
t=0 0
m=application 6075 TCP/MRCPv2 1
a=channel:1@speechsynth

a=cmid:1
a=connection:new
a=setup:passive
m=audio 7892 RTP/AVP 0 8 96 100
a=fmtp:100 0-15
a=mid:1

a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:96 l16/8000
a=rtpmap:100 telephone-event/8000
a=sendonly

ACK sip:mrcps...@192.168.0.100:6060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.100:5093;rport;branch=z9hG4bKvtHve3S3aXj4c
Max-Forwards: 70
From: <sip:192.168.0.100:5093>;tag=K2H7Fv42XD0tQ
To: <sip:192.168.0.100:6060>;tag=73906013
Call-ID: 536ef114-ef06-122d-bf97-000c76a2865b
CSeq: 131959487 ACK
Contact: <sip:192.168.0.100:5093>
Content-Length: 0


- Non-working example

INVITE sip:192.168.0.100:6060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.100:5093;rport;branch=z9hG4bKyc4DjSUa5eZ9K
Max-Forwards: 70
From: <sip:192.168.0.100:5093>;tag=mBB0HQN6tppDK
To: <sip:192.168.0.100:6060>
Call-ID: 558b45f8-ef06-122d-bf97-000c76a2865b
CSeq: 131959489 INVITE
Contact: <sip:192.168.0.100:5093>
User-Agent: Asterisk
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE
Supported: timer, 100rel
Min-SE: 120
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 362

v=0
o=Asterisk 3431727995680865177 6520114178085479169 IN IP6 2002:5310:4d9a:b:20c:76ff:fea2:865b

s=-
c=IN IP4 192.168.0.100
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechsynth
a=cmid:1
m=audio 4028 RTP/AVP 0 8 96

a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=ptime:20
a=mid:1

SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.100:5093;rport=5093;branch=z9hG4bKyc4DjSUa5eZ9K
Contact: <sip:mrcps...@pbx.local:6060;transport=UDP>
To: <sip:192.168.0.100:6060>;tag=af2daa09
From: <sip:192.168.0.100:5093>;tag=mBB0HQN6tppDK
Call-ID: 558b45f8-ef06-122d-bf97-000c76a2865b
CSeq: 131959489 INVITE
Content-Type: application/sdp
Content-Length: 375

v=0
o=- 1276155651 1276155651 IN IP4 192.168.0.100

s=MRCP session
c=IN IP4 192.168.0.100
t=0 0
m=application 6075 TCP/MRCPv2 1
a=channel:2@speechsynth
a=cmid:1
a=connection:new
a=setup:passive
m=audio 7894 RTP/AVP 0 8 96 100
a=fmtp:100 0-15
a=mid:1

a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:96 l16/8000
a=rtpmap:100 telephone-event/8000
a=sendonly


I believe Sofia-SIP couldn't resolve the hostname pbx.local:6060 to send the SIP ACK to. You should either configure your DNS server according or don't use host names at all. Taking into account you're running all on the same host, the later one looks much more reasonable to me.

I'd suggest to set
server.mrcp2.sip.contact.useHostIPAddress       VXIInteger    1

and use 127.0.0.1 everywhere, including mrcp.conf too. It should work.

> ...
>
> read more »

--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
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.

andy...@hotmail.com

unread,
Jun 10, 2010, 4:30:13 AM6/10/10
to UniMRCP
Hi Arsen

Yes, you were correct, it works fine now!

Now to document the install process :(

Thanks again...

Andy.
> *Contact: <sip:mrcpser...@192.168.0.100:6060;transport=UDP>
> *To: <sip:192.168.0.100:6060>;tag=73906013
> From: <sip:192.168.0.100:5093>;tag=K2H7Fv42XD0tQ
> Call-ID: 536ef114-ef06-122d-bf97-000c76a2865b
> CSeq: 131959487 INVITE
> Content-Type: application/sdp
> Content-Length: 375
>
> v=0
> o=- 1276155647 1276155647 IN IP4 192.168.0.100
> s=MRCP session
> c=IN IP4 192.168.0.100
> t=0 0
> m=application 6075 TCP/MRCPv2 1
> a=channel:1@speechsynth
> a=cmid:1
> a=connection:new
> a=setup:passive
> m=audio 7892 RTP/AVP 0 8 96 100
> a=fmtp:100 0-15
> a=mid:1
> a=rtpmap:0 pcmu/8000
> a=rtpmap:8 pcma/8000
> a=rtpmap:96 l16/8000
> a=rtpmap:100 telephone-event/8000
> a=sendonly
>
> ACK sip:mrcpser...@192.168.0.100:6060;transport=UDP SIP/2.0
> *Contact: <sip:mrcpser...@pbx.local:6060;transport=UDP>*
> To: <sip:192.168.0.100:6060>;tag=af2daa09
> From: <sip:192.168.0.100:5093>;tag=mBB0HQN6tppDK
> Call-ID: 558b45f8-ef06-122d-bf97-000c76a2865b
> CSeq: 131959489 INVITE
> Content-Type: application/sdp
> Content-Length: 375
>
> v=0
> o=- 1276155651 1276155651 IN IP4 192.168.0.100
> s=MRCP session
> c=IN IP4 192.168.0.100
> t=0 0
> m=application 6075 TCP/MRCPv2 1
> a=channel:2@speechsynth
> a=cmid:1
> a=connection:new
> a=setup:passive
> m=audio 7894 RTP/AVP 0 8 96 100
> a=fmtp:100 0-15
> a=mid:1
> a=rtpmap:0 pcmu/8000
> a=rtpmap:8 pcma/8000
> a=rtpmap:96 l16/8000
> a=rtpmap:100 telephone-event/8000
> a=sendonly
>
> I believe Sofia-SIP couldn't resolve the hostname *pbx.local:6060* to send
> the SIP ACK to. You should either configure your DNS server according or
> don't use host names at all. Taking into account you're running all on the
> same host, the later one looks much more reasonable to me.
>
> I'd suggest to set
> server.mrcp2.sip.contact.useHostIPAddress       VXIInteger    1
>
> and use 127.0.0.1 everywhere, including mrcp.conf too. It should work.
>
> On Thu, Jun 10, 2010 at 12:46 PM, andyw...@hotmail.com <andyw...@hotmail.com
> ...
>
> read more »
Reply all
Reply to author
Forward
0 new messages