andrenatal / unimrcp

Automatically exported from code.google.com/p/unimrcp
Apache License 2.0
0 stars 0 forks source link

Asterisk+Unimrcp+pocketsphinx #129

Closed GoogleCodeExporter closed 9 years ago

GoogleCodeExporter commented 9 years ago
What steps will reproduce the problem?
1. This dialplan: 
exten => 4321,1,Answer()
exten => 4321,2,SpeechCreate()
exten => 4321,3,SpeechLoadGrammar(digit,/usr/local/unimrcp/data/grammar.jsgf)
exten => 4321,4,SpeechActivateGrammar(digits)
exten => 4321,5,SpeechBackground(beep,20)
exten => 4321,6,GotoIf($["${SPEECH(results)}" = "0"]?7:9)
exten => 4321,7,Playback(vm-nonumber)
exten => 4321,8,Goto(5)
exten => 4321,9,Verbose(1,The recognized input is ${SPEECH_TEXT(0)})
exten => 4321,10,Verbose(1,The score is ${SPEECH_SCORE(0)})
exten => 4321,11,Verbose(1,The matched grammar is ${SPEECH_GRAMMAR(0)})
exten => 4321,12,SpeechDeactivateGrammar(digit)
exten => 4321,13,SpeechUnloadGrammar(digit)
exten => 4321,14,SpeechDestroy()
exten => 4321,15,Hangup()

Note: ran "dos2unix grammar.jsgf"
2. Make a call and it doesen't pass from loading grammar (Asterisk full log of 
the call):
[Sep 23 11:48:24] VERBOSE[3254] netsock.c:   == Using SIP RTP TOS bits 184
[Sep 23 11:48:24] VERBOSE[3254] netsock.c:   == Using SIP RTP CoS mark 5
[Sep 23 11:48:24] VERBOSE[3299] pbx.c:     -- Executing [4321@from-internal:1] 
Answer("SIP/4001-00000001", "") in new stack
[Sep 23 11:48:25] VERBOSE[3299] pbx.c:     -- Executing [4321@from-internal:2] 
SpeechCreate("SIP/4001-00000001", "") in new stack
[Sep 23 11:48:25] NOTICE[3299] app_unimrcp.c: Create MRCP Handle 0x8edf048 
[uni2]
[Sep 23 11:48:25] DEBUG[3299] app_unimrcp.c: Create Channel 0x8edf048 <new>
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Send session request type:2
[Sep 23 11:48:25] DEBUG[3299] app_unimrcp.c: Signal Message to [MRCP Client] 
[4;0]
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Wait for session response
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [4;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Receive App Request 0x8edf048 
<new> [2]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Add MRCP Handle 0x8edf048 <new>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Dispatch App Request 0x8edf048 
<new> [2]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] NOTICE[3272] app_unimrcp.c: Add Control Channel 0x8edf048 
<new@speechrecog>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Add Media Termination 0x8edf048 
<new@media-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Add Media Termination 0x8edf048 
<new@rtp-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [Media-Engine-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Poller Wakeup 
[MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Message [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Signal Message to [MRCP Client] 
[2;0]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [2;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Control Channel Added 0x8edf048 
<new@speechrecog>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Process Message [Media-Engine-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Add Media Context 0x8edf048
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [3;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Media Termination Added 0x8edf048 
<new@media-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Media Termination Added 0x8edf048 
<new@rtp-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Send Offer 0x8edf048 <new> [c:1 
a:1 v:0] to 127.0.0.1:8060
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Local SDP 0x8edf048 <new>
v=0^M
c=IN IP4 127.0.0.1^M
t=0 0^M
m=application 9 TCP/MRCPv2 1^M
a=setup:active^M
a=connection:new^M
a=resource:speechrecog^M
a=cmid:1^M
m=audio 4002 RTP/AVP 0 8 96 101^M
a=rtpmap:0 PCMU/8000^M
a=rtpmap:8 PCMA/8000^M
a=rtpmap:96 L16/8000^M
a=rtpmap:101 telephone-event/8000^M
a=fmtp:101 0-15^M
a=sendonly^M
a=ptime:20^M
a=mid:1^M

[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_i_state] 
Status 0 INVITE sent
[Sep 23 11:48:25] NOTICE[3273] app_unimrcp.c: SIP Call State 0x8edf048 [calling]
[Sep 23 11:48:25] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_r_invite] 
Status 200 OK
[Sep 23 11:48:25] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_i_state] 
Status 200 OK
[Sep 23 11:48:25] NOTICE[3273] app_unimrcp.c: SIP Call State 0x8edf048 [ready]
[Sep 23 11:48:25] DEBUG[3273] app_unimrcp.c: Remote SDP 0x8edf048 <new>
v=0^M
o=UniMRCPServer 3069930143120673766 390112542560944466 IN IP4 10.4.10.171^M
s=-^M
c=IN IP4 127.0.0.1^M
t=0 0^M
m=application 1544 TCP/MRCPv2 1^M
a=setup:passive^M
a=connection:new^M
a=channel:caa5f04ad1bf405b@speechrecog^M
a=cmid:1^M
m=audio 5002 RTP/AVP 0 101^M
a=rtpmap:0 PCMU/8000^M
a=rtpmap:101 telephone-event/8000^M
a=fmtp:101 0-15^M
a=recvonly^M
a=ptime:20^M
a=mid:1^M

[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Receive Answer 0x8edf048 <new> 
[c:1 a:1 v:0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Modify Control Channel 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Modify Media Termination 0x8edf048 
<caa5f04ad1bf405b@rtp-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [Media-Engine-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Poller Wakeup 
[MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Message [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] NOTICE[3275] app_unimrcp.c: Established TCP/MRCPv2 Connection 
127.0.0.1:53792 <-> 127.0.0.1:154
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Add Control Channel 
<caa5f04ad1bf405b@speechrecog> 127.0.0.1:53792 <-> 127.0.0.1:1544 [1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Signal Message to [MRCP Client] 
[2;1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [2;1]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Control Channel Modified 0x8edf048 
<caa5f04ad1bf405b@speechrecog>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_i_active] 
Status 200 Call active
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Process Message [Media-Engine-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Enable RTP Session 127.0.0.1:4002
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Create Linear Audio Bridge 
0x8edf048
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Open RTP Transmitter 
127.0.0.1:4002 -> 127.0.0.1:5002
[Sep 23 11:48:25] DEBUG[3276] app_unimrcp.c: Media Path 0x8edf048 
Source->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Encoder->[PCMU/8000/1]->Sink
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [3;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Media Termination Modified 
0x8edf048 <caa5f04ad1bf405b@rtp-tm>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Raise App Response 0x8edf048 
<caa5f04ad1bf405b> [2] SUCCESS [0]
[Sep 23 11:48:25] DEBUG[3272] res_speech_unimrcp.c: Received message from 
client stack
[Sep 23 11:48:25] DEBUG[3272] res_speech_unimrcp.c: On channel add
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Waked up, status code: 0
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Create media buffer 
frame_size:160
[Sep 23 11:48:25] NOTICE[3299] res_speech_unimrcp.c: Created speech instance 
'caa5f04ad1bf405b'
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Set properties 
'caa5f04ad1bf405b'
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Send MRCP request
[Sep 23 11:48:25] DEBUG[3299] app_unimrcp.c: Signal Message to [MRCP Client] 
[4;0]
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Wait for MRCP response
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [4;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Receive App MRCP Request 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Send MRCP Request 0x8edf048 
<caa5f04ad1bf405b@speechrecog> [1]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Poller Wakeup 
[MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Message [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Send MRCPv2 Stream 127.0.0.1:53792 
<-> 127.0.0.1:1544 [132 bytes]
MRCP/2.0 132 SET-PARAMS 1^M
Channel-Identifier: caa5f04ad1bf405b@speechrecog^M
Recognition-Timeout: 20000^M
No-Input-Timeout: 15000^M
^M

[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Signalled Descriptor 
[MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Receive MRCPv2 Stream 
127.0.0.1:53792 <-> 127.0.0.1:1544 [80 bytes]
MRCP/2.0 80 1 200 COMPLETE^M
Channel-Identifier: caa5f04ad1bf405b@speechrecog^M
^M

[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Signal Message to [MRCP Client] 
[2;3]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [2;3]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Raise App MRCP Response 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:25] DEBUG[3272] res_speech_unimrcp.c: Received message from 
client stack
[Sep 23 11:48:25] DEBUG[3272] res_speech_unimrcp.c: On message receive
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Waked up
[Sep 23 11:48:25] VERBOSE[3299] pbx.c:     -- Executing [4321@from-internal:3] 
SpeechLoadGrammar("SIP/4001-00000001", 
"digit,/usr/local/unimrcp/data/grammar.jsgf") in new stack
[Sep 23 11:48:25] NOTICE[3299] res_speech_unimrcp.c: Load grammar name:digit 
type:application/x-jsgf path:/usr/local/unimrcp/data/grammar.jsgf 
'caa5f04ad1bf405b'
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Send MRCP request
[Sep 23 11:48:25] DEBUG[3299] app_unimrcp.c: Signal Message to [MRCP Client] 
[4;0]
[Sep 23 11:48:25] DEBUG[3299] res_speech_unimrcp.c: Wait for MRCP response
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [4;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Receive App MRCP Request 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Send MRCP Request 0x8edf048 
<caa5f04ad1bf405b@speechrecog> [2]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Signal Message to [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Poller Wakeup 
[MRCPv2-Agent-1]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Process Message [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Send MRCPv2 Stream 127.0.0.1:53792 
<-> 127.0.0.1:1544 [216 bytes]
MRCP/2.0 216 DEFINE-GRAMMAR 2^M
Channel-Identifier: caa5f04ad1bf405b@speechrecog^M
Content-Type: application/x-jsgf^M
Content-Id: digit^M
Content-Length: 60^M
^M
#JSGF V1.0;
grammar digits;
public <numbers> = (one | two);

[Sep 23 11:48:25] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Process Signalled Descriptor 
[MRCPv2-Agent-1]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Receive MRCPv2 Stream 
127.0.0.1:53792 <-> 127.0.0.1:1544 [112 bytes]
MRCP/2.0 112 2 407 COMPLETE^M
Channel-Identifier: caa5f04ad1bf405b@speechrecog^M
Completion-Cause: 000 success^M
^M

[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Signal Message to [MRCP Client] 
[2;3]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [2;3]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Raise App MRCP Response 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:34] DEBUG[3272] res_speech_unimrcp.c: Received message from 
client stack
[Sep 23 11:48:34] DEBUG[3272] res_speech_unimrcp.c: On message receive
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:34] DEBUG[3299] res_speech_unimrcp.c: Waked up
[Sep 23 11:48:34] WARNING[3299] res_speech_unimrcp.c: Received failure response
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:   == Spawn extension (from-internal, 
4321, 3) exited non-zero on 'SIP/4001-00000001'
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [h@from-internal:1] 
Macro("SIP/4001-00000001", "hangupcall") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:1] 
GotoIf("SIP/4001-00000001", "1?noautomon") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Goto (macro-hangupcall,s,3)
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:3] 
NoOp("SIP/4001-00000001", "TOUCH_MONITOR_OUTPUT=") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:4] 
GotoIf("SIP/4001-00000001", "1?noautomon2") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Goto (macro-hangupcall,s,6)
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:6] 
NoOp("SIP/4001-00000001", "MONITOR_FILENAME=") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:7] 
GotoIf("SIP/4001-00000001", "1?skiprg") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Goto (macro-hangupcall,s,10)
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:10] 
GotoIf("SIP/4001-00000001", "1?skipblkvm") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Goto (macro-hangupcall,s,13)
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:13] 
GotoIf("SIP/4001-00000001", "1?theend") in new stack
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Goto (macro-hangupcall,s,15)
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:     -- Executing [s@macro-hangupcall:15] 
Hangup("SIP/4001-00000001", "") in new stack
[Sep 23 11:48:34] VERBOSE[3299] app_macro.c:   == Spawn extension 
(macro-hangupcall, s, 15) exited non-zero on 'SIP/4001-00000001' in macro 
'hangupcall'
[Sep 23 11:48:34] VERBOSE[3299] pbx.c:   == Spawn extension (from-internal, h, 
1) exited non-zero on 'SIP/4001-00000001'
[Sep 23 11:48:34] NOTICE[3299] res_speech_unimrcp.c: Destroy speech instance 
'caa5f04ad1bf405b'
[Sep 23 11:48:34] DEBUG[3299] res_speech_unimrcp.c: Send session request type:1
[Sep 23 11:48:34] DEBUG[3299] app_unimrcp.c: Signal Message to [MRCP Client] 
[4;0]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [4;0]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Receive App Request 0x8edf048 
<caa5f04ad1bf405b> [1]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Dispatch App Request 0x8edf048 
<caa5f04ad1bf405b> [1]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Terminate Session 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Remove Control Channel 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Signal Message to [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Subtract Media Termination 
0x8edf048 <caa5f04ad1bf405b@media-tm>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Subtract Media Termination 
0x8edf048 <caa5f04ad1bf405b@rtp-tm>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Signal Message to [Media-Engine-1] 
[1;0]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Process Poller Wakeup 
[MRCPv2-Agent-1]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Process Message [MRCPv2-Agent-1] 
[1;0]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Remove Control Channel 
<caa5f04ad1bf405b@speechrecog> [0]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Close TCP/MRCPv2 Connection 
127.0.0.1:53792 <-> 127.0.0.1:1544
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Signal Message to [MRCP Client] 
[2;2]
[Sep 23 11:48:34] DEBUG[3275] app_unimrcp.c: Wait for Messages [MRCPv2-Agent-1]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [2;2]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Control Channel Removed 0x8edf048 
<caa5f04ad1bf405b@speechrecog>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:34] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_r_bye] 
Status 200 OK
[Sep 23 11:48:34] DEBUG[3273] app_unimrcp.c: Receive SIP Event [nua_i_state] 
Status 200 to BYE
[Sep 23 11:48:34] NOTICE[3273] app_unimrcp.c: SIP Call State 0x8edf048 
[terminated]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [1;1]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Session Terminated 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:34] DEBUG[3299] res_speech_unimrcp.c: Wait for session response
[Sep 23 11:48:34] DEBUG[3276] app_unimrcp.c: Process Message [Media-Engine-1] 
[1;0]
[Sep 23 11:48:34] DEBUG[3276] app_unimrcp.c: Destroy Audio Bridge 0x8edf048
[Sep 23 11:48:34] DEBUG[3276] app_unimrcp.c: Close RTP Transmitter 
127.0.0.1:4002 -> 127.0.0.1:5002 [s:0 o:0]
[Sep 23 11:48:34] DEBUG[3276] app_unimrcp.c: Remove Media Context 0x8edf048
[Sep 23 11:48:34] DEBUG[3276] app_unimrcp.c: Remove RTP Session 127.0.0.1:4002
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Process Message [MRCP Client] [3;0]
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Media Termination Subtracted 
0x8edf048 <caa5f04ad1bf405b@media-tm>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Media Termination Subtracted 
0x8edf048 <caa5f04ad1bf405b@rtp-tm>
[Sep 23 11:48:34] NOTICE[3272] app_unimrcp.c: Destroy TCP/MRCPv2 Connection 
127.0.0.1:53792 <-> 127.0.0.1:1544
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Remove MRCP Handle 0x8edf048 
<caa5f04ad1bf405b>
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Raise App Response 0x8edf048 
<caa5f04ad1bf405b> [1] SUCCESS [0]
[Sep 23 11:48:34] DEBUG[3272] res_speech_unimrcp.c: Received message from 
client stack
[Sep 23 11:48:34] DEBUG[3272] res_speech_unimrcp.c: On session terminate
[Sep 23 11:48:34] DEBUG[3272] app_unimrcp.c: Wait for Messages [MRCP Client]
[Sep 23 11:48:34] DEBUG[3299] res_speech_unimrcp.c: Waked up, status code: 0
[Sep 23 11:48:34] NOTICE[3299] app_unimrcp.c: Destroy MRCP Handle 0x8edf048
3. This is unimrcpserver log:
2011-09-23 10:41:16:386816 [INFO]   Init Decoder <8b3621dd70ec47d7@pocketsphinx>
INFO: cmd_ln.c(691): Parsing command line:
\
        -alpha 0.97 \
        -dither yes \
        -doublebw no \
        -nfilt 31 \
        -ncep 13 \
        -lowerf 200 \
        -upperf 3500 \
        -nfft 256 \
        -wlen 0.0256 \
        -transform legacy \
        -feat 1s_c_d_dd \
        -agc none \
        -cmn current \
        -varnorm no

Current configuration:
[NAME]          [DEFLT]         [VALUE]
-agc            none            none
-agcthresh      2.0             2.000000e+00
-alpha          0.97            9.700000e-01
-ceplen         13              13
-cmn            current         current
-cmninit        8.0             8.0
-dither         no              yes
-doublebw       no              no
-feat           1s_c_d_dd       1s_c_d_dd
-frate          100             50
-input_endian   little          little
-lda
-ldadim         0               0
-lifter         0               0
-logspec        no              no
-lowerf         133.33334       2.000000e+02
-ncep           13              13
-nfft           512             256
-nfilt          40              31
-remove_dc      no              no
-round_filters  yes             yes
-samprate       16000           8.000000e+03
-seed           -1              -1
-smoothspec     no              no
-svspec
-transform      legacy          legacy
-unit_area      yes             yes
-upperf         6855.4976       3.500000e+03
-varnorm        no              no
-verbose        no              no
-warp_params
-warp_type      inverse_linear  inverse_linear
-wlen           0.025625        2.560000e-02

INFO: acmod.c(242): Parsed model-specific feature parameters from 
../data/communicator/feat.params
INFO: fe_interface.c(289): You are using the internal mechanism to generate the 
seed.
INFO: feat.c(684): Initializing feature stream to type: '1s_c_d_dd', ceplen=13, 
CMN='current', VARNORM='no', AGC='none'
INFO: cmn.c(142): mean[0]= 12.00, mean[1..12]= 0.0
INFO: mdef.c(520): Reading model definition: ../data/communicator/mdef
INFO: bin_mdef.c(173): Allocating 104160 * 8 bytes (813 KiB) for CD tree
INFO: tmat.c(205): Reading HMM transition probability matrices: 
../data/communicator/transition_matrices
INFO: acmod.c(117): Attempting to use SCHMM computation module
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/means
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/variances
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(354): 44589 variance values floored
INFO: acmod.c(119): Attempting to use PTHMM computation module
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/means
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/variances
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(354): 44589 variance values floored
INFO: ptm_mgau.c(800): Number of codebooks exceeds 256: 4153
INFO: acmod.c(121): Falling back to general multi-stream GMM computation
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/means
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: 
../data/communicator/variances
INFO: ms_gauden.c(292): 4153 codebook, 1 feature, size:
INFO: ms_gauden.c(294):  64x39
INFO: ms_gauden.c(354): 44589 variance values floored
INFO: ms_senone.c(160): Reading senone mixture weights: 
../data/communicator/mixture_weights
INFO: ms_senone.c(211): Truncating senone logs3(pdf) values by 10 bits
INFO: ms_senone.c(218): Not transposing mixture weights in memory
INFO: ms_senone.c(277): Read mixture weights for 4153 senones: 1 features x 64 
codewords
INFO: ms_senone.c(331): Mapping senones to individual codebooks
INFO: ms_mgau.c(122): The value of topn: 4
INFO: dict.c(306): Allocating 133345 * 20 bytes (2604 KiB) for word entries
INFO: dict.c(321): Reading main dictionary: ../data/default.dic
INFO: dict.c(212): Allocated 975 KiB for strings, 1606 KiB for phones
INFO: dict.c(324): 129247 words read
INFO: dict.c(330): Reading filler dictionary: ../data/communicator/noisedict
INFO: dict.c(212): Allocated 0 KiB for strings, 0 KiB for phones
INFO: dict.c(333): 3 words read
INFO: dict2pid.c(396): Building PID tables for dictionary
INFO: dict2pid.c(404): Allocating 51^3 * 2 bytes (259 KiB) for word-initial 
triphones
INFO: dict2pid.c(131): Allocated 31416 bytes (30 KiB) for word-final triphones
INFO: dict2pid.c(195): Allocated 31416 bytes (30 KiB) for single-phone word 
triphones
INFO: fsg_search.c(145): FSG(beam: -1080, pbeam: -1080, wbeam: -634; wip: -26, 
pip: 0)
INFO: jsgf.c(546): Defined rule: <digits.g00000>
INFO: jsgf.c(546): Defined rule: PUBLIC <digits.numbers>
INFO: fsg_model.c(213): Computing transitive closure for null transitions
INFO: fsg_model.c(264): 2 null transitions added
ERROR: "fsg_search.c", line 332: The word 'two' is missing in the dictionary
2011-09-23 10:41:25:119856 [WARN]   Failed to Init Decoder 
<8b3621dd70ec47d7@pocketsphinx>
2011-09-23 10:41:25:120288 [INFO]   Process DEFINE-GRAMMAR Response 
<8b3621dd70ec47d7@speechrecog> [2]
2011-09-23 10:41:25:120469 [INFO]   Send MRCPv2 Stream 127.0.0.1:1544 <-> 
127.0.0.1:51440 [112 bytes]
MRCP/2.0 112 2 407 COMPLETE
Channel-Identifier: 8b3621dd70ec47d7@speechrecog
Completion-Cause: 000 success

2011-09-23 10:41:25:156725 [INFO]   TCP/MRCPv2 Peer Disconnected 127.0.0.1:1544 
<-> 127.0.0.1:51440
2011-09-23 10:41:25:157822 [INFO]   Receive SIP Event [nua_i_bye] Status 200 
Session Terminated
2011-09-23 10:41:25:157860 [INFO]   Receive SIP Event [nua_i_state] Status 200 
Session Terminated
2011-09-23 10:41:25:157874 [NOTICE] SIP Call State 0xa6960c8 [terminated]
2011-09-23 10:41:25:157898 [INFO]   Receive SIP Event [nua_i_terminated] Status 
200 Session Terminated
2011-09-23 10:41:25:158209 [INFO]   Deactivate Session 0xa6960c8 
<8b3621dd70ec47d7>
2011-09-23 10:41:25:158238 [INFO]   Terminate Session 0xa6960c8 
<8b3621dd70ec47d7>
2011-09-23 10:41:25:158269 [INFO]   Close Channel 
<8b3621dd70ec47d7@pocketsphinx>
2011-09-23 10:41:25:158284 [NOTICE] Remove Session <8b3621dd70ec47d7>
2011-09-23 10:41:25:158648 [INFO]   Remove Control Channel 
<8b3621dd70ec47d7@speechrecog> [0]
2011-09-23 10:41:25:158706 [INFO]   Close RTP Receiver 127.0.0.1:5004 <- 
127.0.0.1:4006 [r:0 l:0 j:0 d:0 i:0]
2011-09-23 10:41:25:158720 [INFO]   Remove RTP Session 127.0.0.1:5004
2011-09-23 10:41:25:159025 [NOTICE] Destroy TCP/MRCPv2 Connection 
127.0.0.1:1544 <-> 127.0.0.1:51440
2011-09-23 10:41:25:159052 [INFO]   Session Terminated 0xa6960c8 
<8b3621dd70ec47d7>
2011-09-23 10:41:25:159095 [NOTICE] Destroy Session <8b3621dd70ec47d7>
exit
2011-09-23 10:51:16:254075 [INFO]   Terminate Task [MRCP Server]
2011-09-23 10:51:16:254212 [INFO]   Close Engine [Recorder-1]
2011-09-23 10:51:16:254230 [INFO]   Close Engine [Demo-Synth-1]
2011-09-23 10:51:16:254240 [INFO]   Terminate Task [Demo Synth Engine]
2011-09-23 10:51:16:254416 [INFO]   Close Engine [PocketSphinx-1]
2011-09-23 10:51:16:254434 [INFO]   Terminate Task [SIP-Agent-1]
2011-09-23 10:51:16:254754 [INFO]   Receive SIP Event [nua_r_shutdown] Status 
200 Shutdown successful
2011-09-23 10:51:16:255067 [INFO]   Terminate Task [RTSP-Agent-1]
2011-09-23 10:51:16:255262 [INFO]   Terminate Task [MRCPv2-Agent-1]
2011-09-23 10:51:16:255445 [INFO]   Terminate Task [Media-Engine-1]
2011-09-23 10:51:16:259495 [NOTICE] MRCP Server Terminated
2011-09-23 10:51:16:259652 [NOTICE] Server Uptime [2036 sec]
2011-09-23 10:51:16:259670 [INFO]   Destroy MRCP Engines
2011-09-23 10:51:16:259685 [INFO]   Destroy Task [Demo Synth Engine]
2011-09-23 10:51:16:259706 [INFO]   Unload Plugins
2011-09-23 10:51:16:259863 [INFO]   Destroy Task [SIP-Agent-1]
2011-09-23 10:51:16:259876 [INFO]   Destroy Task [RTSP-Agent-1]
2011-09-23 10:51:16:259941 [INFO]   Destroy Task [MRCPv2-Agent-1]
2011-09-23 10:51:16:259972 [INFO]   Destroy Task [Media-Engine-1]
2011-09-23 10:51:16:259986 [INFO]   Destroy Task [MRCP Server]

PS: i get this when asterisk starting: 
[Sep 23 12:32:19] WARNING[2813] xmldoc.c: Couldn't find application MRCPRecog 
in XML documentation
[Sep 23 12:32:19] WARNING[2813] xmldoc.c: Couldn't find application MRCPRecog 
in XML documentation
[Sep 23 12:32:19] VERBOSE[2813] pbx.c:   == Registered application 'MRCPRecog'
 and also, Asterisk core dumped me almost every time i restarted it.

What version of the product are you using? On what operating system?
CentOS release 5.6 (Final)
Asterisk 1.6.2.13
Unimrcp 1.0.0
pocketsphinx-0.7
sphinxbase-0.7
unimrcp-deps-1.1.0
Installed connector inluded in uni-ast-package-0.3.2
Please provide any additional information below.
I'm from Venezuela, this is for my college thesis and i really need to get this 
working, really need yor help. Thank's

Original issue reported on code.google.com by fernando...@gmail.com on 23 Sep 2011 at 5:14

GoogleCodeExporter commented 9 years ago
you have the same errors that i had with the sphinx

ERROR: "fsg_search.c", line 332: The word 'two' is missing in the dictionary
2011-09-23 10:41:25:119856 [WARN]   Failed to Init Decoder 
<8b3621dd70ec47d7@pocketsphinx>

take a look at the POSTS "Asterisk 1.4 UniMRCP and PocketSphinx" and "Asterisk 
1.6 UniMRCP and PocketSphinx"

In my case was a problem with the grammar, it was corrupt, because I opened it 
in windows, after making the grammar un Linux with a pico, I made a "stow now" 
of Asterisk, and it worked!!!

suerte

Original comment by assantas...@gmail.com on 5 Oct 2011 at 9:22

GoogleCodeExporter commented 9 years ago
I trust the problem was in the end-of-line characters.

Original comment by achalo...@gmail.com on 7 Mar 2013 at 2:24