I managed to install Asterisk with Unimrcp and Pocketsphinx , everything seems to be working alright. I made a simple dialplan where i call the asterisk server and it uses voice recognition to transcribe 1 2 or 3 on the screen based on what i am saying. The problem is that everytime i try to use it I get the message : "Recognition completed abnormally cause: 003 reason: none " . Any idea what the problem might be ? I can't seem to find the solution to this problem , any help would be greatly appreciated.
Here is the dialplan i am using :
"
exten => 631,1,Answer()
same => n,SpeechCreate()
same => n,SpeechLoadGrammar(digits,/usr/local/unimrcp/data/grammar.jsgf)
same => n,SpeechActivateGrammar(digits)
same => n,SpeechStart()
same => n,SpeechBackground(hello-world,5)
same => n,NoOp(The result of speech recognition: ${SPEECH_TEXT(0)})
same => n,SpeechDeactivateGrammar(digits)
same => n,SpeechUnloadGrammar(digits)
same => n,SpeechDestroy()
same => n,Hangup()
"
This is the output the Asterisk CLI gives :
"
Accepting AUTHENTICATED call from 141.85.94.112:
> requested format = gsm,
> requested prefs = (),
> actual format = ulaw,
> host prefs = (),
> priority = mine
-- Executing [631@demo:1] Answer("IAX2/test_phone_1563145293-8665", "") in new stack
-- Executing [631@demo:2] SpeechCreate("IAX2/test_phone_1563145293-8665", "") in new stack [Aug 9 13:28:42] NOTICE[4385][C-00000005]: app_unimrcp.c:122 unimrcp_log: Create MRCP Handle 0xb7324ec0 [uni2] [Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:233 uni_recog_create_internal: (RSU-5) Create speech resource [Aug 9 13:28:42] NOTICE[4004]: app_unimrcp.c:122 unimrcp_log: Add Control Channel RSU-5 new@speechrecog [Aug 9 13:28:42] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [calling] [Aug 9 13:28:42] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [ready] [Aug 9 13:28:42] NOTICE[4007]: app_unimrcp.c:122 unimrcp_log: Established TCP/MRCPv2 Connection 141.85.94.106:46311 <-> 141.85.94.106:1544
-- Executing [631@demo:3] SpeechLoadGrammar("IAX2/test_phone_1563145293-8665", "digits,/usr/local/unimrcp/data/grammar.jsgf") in new stack [Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:451 uni_recog_load_grammar: (RSU-5) Load grammar name: digits type: application/x-jsgf path: /usr/local/unimrcp/data/grammar.jsgf
-- Executing [631@demo:4] SpeechActivateGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack [Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:525 uni_recog_activate_grammar: (RSU-5) Activate grammar name: digits
-- Executing [631@demo:5] SpeechStart("IAX2/test_phone_1563145293-8665", "") in new stack [Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:585 uni_recog_start: (RSU-5) Start recognition
-- Executing [631@demo:6] SpeechBackground("IAX2/test_phone_1563145293-8665", "hello-world,5") in new stack [Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:803 uni_recog_get: (RSU-5) Get result completion cause: 003 reason: none [Aug 9 13:29:03] WARNING[4385][C-00000005]: res_speech_unimrcp.c:809 uni_recog_get: (RSU-5) Recognition completed abnormally cause: 003 reason: none
-- Executing [631@demo:7] NoOp("IAX2/test_phone_1563145293-8665", "The result of speech recognition: ") in new stack
-- Executing [631@demo:8] SpeechDeactivateGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack [Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:540 uni_recog_deactivate_grammar: (RSU-5) Deactivate grammar name: digits
-- Executing [631@demo:9] SpeechUnloadGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack [Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:486 uni_recog_unload_grammar: (RSU-5) Unload grammar name: digits
-- Executing [631@demo:10] SpeechDestroy("IAX2/test_phone_1563145293-8665", "") in new stack [Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:288 uni_recog_destroy: (RSU-5) Destroy speech resource [Aug 9 13:29:03] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [terminated] [Aug 9 13:29:03] NOTICE[4004]: app_unimrcp.c:122 unimrcp_log: Destroy TCP/MRCPv2 Connection 141.85.94.106:46311 <-> 141.85.94.106:1544 [Aug 9 13:29:03] NOTICE[4385][C-00000005]: app_unimrcp.c:122 unimrcp_log: Destroy MRCP Handle RSU-5
-- Executing [631@demo:11] Hangup("IAX2/test_phone_1563145293-8665", "") in new stack
== Spawn extension (demo, 631, 11) exited non-zero on 'IAX2/test_phone_1563145293-8665'
-- Hungup 'IAX2/test_phone_1563145293-8665'
"
This issue is not related to pocketsphinx. The cause 3 means that the time limit for recognition has exceeded. It seems you have too long prompt, that's why it tries to recognize 10 seconds of speech.
Increase hotword limit or fix this issue in Unimrcp in other way.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
Hello!
I managed to install Asterisk with Unimrcp and Pocketsphinx , everything seems to be working alright. I made a simple dialplan where i call the asterisk server and it uses voice recognition to transcribe 1 2 or 3 on the screen based on what i am saying. The problem is that everytime i try to use it I get the message : "Recognition completed abnormally cause: 003 reason: none " . Any idea what the problem might be ? I can't seem to find the solution to this problem , any help would be greatly appreciated.
Here is the dialplan i am using :
"
exten => 631,1,Answer()
same => n,SpeechCreate()
same => n,SpeechLoadGrammar(digits,/usr/local/unimrcp/data/grammar.jsgf)
same => n,SpeechActivateGrammar(digits)
same => n,SpeechStart()
same => n,SpeechBackground(hello-world,5)
same => n,NoOp(The result of speech recognition: ${SPEECH_TEXT(0)})
same => n,SpeechDeactivateGrammar(digits)
same => n,SpeechUnloadGrammar(digits)
same => n,SpeechDestroy()
same => n,Hangup()
"
This is the output the Asterisk CLI gives :
"
> requested format = gsm,
> requested prefs = (),
> actual format = ulaw,
> host prefs = (),
> priority = mine
-- Executing [631@demo:1] Answer("IAX2/test_phone_1563145293-8665", "") in new stack
-- Executing [631@demo:2] SpeechCreate("IAX2/test_phone_1563145293-8665", "") in new stack
[Aug 9 13:28:42] NOTICE[4385][C-00000005]: app_unimrcp.c:122 unimrcp_log: Create MRCP Handle 0xb7324ec0 [uni2]
[Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:233 uni_recog_create_internal: (RSU-5) Create speech resource
[Aug 9 13:28:42] NOTICE[4004]: app_unimrcp.c:122 unimrcp_log: Add Control Channel RSU-5 new@speechrecog
[Aug 9 13:28:42] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [calling]
[Aug 9 13:28:42] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [ready]
[Aug 9 13:28:42] NOTICE[4007]: app_unimrcp.c:122 unimrcp_log: Established TCP/MRCPv2 Connection 141.85.94.106:46311 <-> 141.85.94.106:1544
-- Executing [631@demo:3] SpeechLoadGrammar("IAX2/test_phone_1563145293-8665", "digits,/usr/local/unimrcp/data/grammar.jsgf") in new stack
[Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:451 uni_recog_load_grammar: (RSU-5) Load grammar name: digits type: application/x-jsgf path: /usr/local/unimrcp/data/grammar.jsgf
-- Executing [631@demo:4] SpeechActivateGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack
[Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:525 uni_recog_activate_grammar: (RSU-5) Activate grammar name: digits
-- Executing [631@demo:5] SpeechStart("IAX2/test_phone_1563145293-8665", "") in new stack
[Aug 9 13:28:42] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:585 uni_recog_start: (RSU-5) Start recognition
-- Executing [631@demo:6] SpeechBackground("IAX2/test_phone_1563145293-8665", "hello-world,5") in new stack
[Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:803 uni_recog_get: (RSU-5) Get result completion cause: 003 reason: none
[Aug 9 13:29:03] WARNING[4385][C-00000005]: res_speech_unimrcp.c:809 uni_recog_get: (RSU-5) Recognition completed abnormally cause: 003 reason: none
-- Executing [631@demo:7] NoOp("IAX2/test_phone_1563145293-8665", "The result of speech recognition: ") in new stack
-- Executing [631@demo:8] SpeechDeactivateGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack
[Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:540 uni_recog_deactivate_grammar: (RSU-5) Deactivate grammar name: digits
-- Executing [631@demo:9] SpeechUnloadGrammar("IAX2/test_phone_1563145293-8665", "digits") in new stack
[Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:486 uni_recog_unload_grammar: (RSU-5) Unload grammar name: digits
-- Executing [631@demo:10] SpeechDestroy("IAX2/test_phone_1563145293-8665", "") in new stack
[Aug 9 13:29:03] NOTICE[4385][C-00000005]: res_speech_unimrcp.c:288 uni_recog_destroy: (RSU-5) Destroy speech resource
[Aug 9 13:29:03] NOTICE[4005]: app_unimrcp.c:122 unimrcp_log: SIP Call State RSU-5 [terminated]
[Aug 9 13:29:03] NOTICE[4004]: app_unimrcp.c:122 unimrcp_log: Destroy TCP/MRCPv2 Connection 141.85.94.106:46311 <-> 141.85.94.106:1544
[Aug 9 13:29:03] NOTICE[4385][C-00000005]: app_unimrcp.c:122 unimrcp_log: Destroy MRCP Handle RSU-5
-- Executing [631@demo:11] Hangup("IAX2/test_phone_1563145293-8665", "") in new stack
== Spawn extension (demo, 631, 11) exited non-zero on 'IAX2/test_phone_1563145293-8665'
-- Hungup 'IAX2/test_phone_1563145293-8665'
"
And this is the output the UniMRCP server gives :
"2013-08-09 13:28:24:841182 [NOTICE] UniMRCP Server [1.1.0]
2013-08-09 13:28:24:841489 [INFO] APR [1.4.6]
2013-08-09 13:28:24:841517 [NOTICE] Create MRCP Server
2013-08-09 13:28:24:841590 [NOTICE] Open Config File [../conf/unimrcpserver.xml]
2013-08-09 13:28:24:842318 [INFO] Set Property ip:141.85.94.106
2013-08-09 13:28:24:842347 [INFO] Register Codec [PCMU]
2013-08-09 13:28:24:842438 [INFO] Register Codec [PCMA]
2013-08-09 13:28:24:842449 [INFO] Register Codec [L16]
2013-08-09 13:28:24:842493 [NOTICE] Register Resource [speechsynth]
2013-08-09 13:28:24:842511 [NOTICE] Register Resource [speechrecog]
2013-08-09 13:28:24:842525 [NOTICE] Register Resource [recorder]
2013-08-09 13:28:24:842538 [NOTICE] Register Resource [speakverify]
2013-08-09 13:28:24:842549 [INFO] Register Resource Factory
2013-08-09 13:28:24:842587 [NOTICE] Create SofiaSIP Agent [SIP-Agent-1] [1.12.11-204-g6ff5f3f] sip:141.85.94.106:8060;transport=udp,tcp
2013-08-09 13:28:24:842600 [INFO] Register Signaling Agent [SIP-Agent-1]
2013-08-09 13:28:24:842639 [NOTICE] Create RTSP Server [RTSP-Agent-1] 141.85.94.106:1554 [100]
2013-08-09 13:28:24:843860 [INFO] Register Signaling Agent [RTSP-Agent-1]
2013-08-09 13:28:24:843899 [NOTICE] Create MRCPv2 Agent [MRCPv2-Agent-1] 141.85.94.106:1544 [100]
2013-08-09 13:28:24:844051 [INFO] Register Connection Agent [MRCPv2-Agent-1]
2013-08-09 13:28:24:844073 [NOTICE] Create Media Engine [Media-Engine-1]
2013-08-09 13:28:24:844099 [INFO] Register Media Engine [Media-Engine-1]
2013-08-09 13:28:24:844118 [NOTICE] Create RTP Termination Factory 141.85.94.106:[5000,6000]
2013-08-09 13:28:24:844129 [INFO] Register RTP Termination Factory [RTP-Factory-1]
2013-08-09 13:28:24:844149 [INFO] Load Plugin [PocketSphinx-1] [../plugin/mrcppocketsphinx.so]
2013-08-09 13:28:24:898470 [INFO] Create PocketSphinx Engine
2013-08-09 13:28:24:898508 [INFO] Register MRCP Engine [PocketSphinx-1]
2013-08-09 13:28:24:898533 [INFO] Load Plugin [Demo-Synth-1] [../plugin/demosynth.so]
2013-08-09 13:28:24:898729 [INFO] Register MRCP Engine [Demo-Synth-1]
2013-08-09 13:28:24:898747 [INFO] Load Plugin [Demo-Recog-1] [../plugin/demorecog.so]
2013-08-09 13:28:24:898903 [INFO] Register MRCP Engine [Demo-Recog-1]
2013-08-09 13:28:24:898920 [INFO] Load Plugin [Demo-Verifier-1] [../plugin/demoverifier.so]
2013-08-09 13:28:24:899073 [INFO] Register MRCP Engine [Demo-Verifier-1]
2013-08-09 13:28:24:899089 [INFO] Load Plugin [Recorder-1] [../plugin/mrcprecorder.so]
2013-08-09 13:28:24:899259 [INFO] Register MRCP Engine [Recorder-1]
2013-08-09 13:28:24:899309 [INFO] Register RTP Settings [RTP-Settings-1]
2013-08-09 13:28:24:899334 [NOTICE] Create MRCPv2 Profile [uni2]
2013-08-09 13:28:24:899446 [INFO] Assign MRCP Engine [speechsynth] [Demo-Synth-1]
2013-08-09 13:28:24:899462 [INFO] Assign MRCP Engine [speechrecog] [PocketSphinx-1]
2013-08-09 13:28:24:899472 [INFO] Assign MRCP Engine [recorder] [Recorder-1]
2013-08-09 13:28:24:899482 [INFO] Assign MRCP Engine [speakverify] [Demo-Verifier-1]
2013-08-09 13:28:24:899491 [INFO] Register Profile [uni2]
2013-08-09 13:28:24:899505 [NOTICE] Create MRCPv1 Profile [uni1]
2013-08-09 13:28:24:899516 [INFO] Assign MRCP Engine [speechsynth] [Demo-Synth-1]
2013-08-09 13:28:24:899525 [INFO] Assign MRCP Engine [speechrecog] [PocketSphinx-1]
2013-08-09 13:28:24:899534 [INFO] Assign MRCP Engine [recorder] [Recorder-1]
2013-08-09 13:28:24:899543 [INFO] Assign MRCP Engine [speakverify] [Demo-Verifier-1]
2013-08-09 13:28:24:899552 [INFO] Register Profile [uni1]
2013-08-09 13:28:24:899566 [INFO] Start Task [MRCP Server]
2013-08-09 13:28:42:082158 [NOTICE] Add Session <766cb1a200de11e3>
2013-08-09 13:28:42:082180 [INFO] Receive Offer 0xb2400d58 <766cb1a200de11e3> [c:1 a:1 v:0]
2013-08-09 13:28:42:082326 [INFO] Add Pending Control Channel 766cb1a200de11e3@speechrecog [1]
2013-08-09 13:28:42:083112 [INFO] Enable RTP Session 141.85.94.106:5000
2013-08-09 13:28:42:083172 [INFO] Open RTP Receiver 141.85.94.106:5000 <- 141.85.94.106:4010 playout [50 ms] bounds [0 - 600 ms] adaptive [1] skew detection [1]
2013-08-09 13:28:42:083194 [INFO] Media Path 0xb2400d58 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2013-08-09 13:28:42:083245 [INFO] Open Channel 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:42:083600 [INFO] Run Recognition Thread 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:42:083648 [INFO] Send Answer 0xb2400d58 <766cb1a200de11e3> [c:1 a:1 v:0] Status OK
2013-08-09 13:28:42:083704 [INFO] Local SDP 0xb2400d58 <766cb1a200de11e3>
v=0
o=UniMRCPServer 0 0 IN IP4 141.85.94.106
s=-
c=IN IP4 141.85.94.106
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:766cb1a200de11e3@speechrecog
a=cmid:1
m=audio 5000 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=recvonly
a=ptime:20
a=mid:1
2013-08-09 13:28:42:085165 [INFO] Receive SIP Event [nua_i_state] Status 200 OK
2013-08-09 13:28:42:085189 [NOTICE] SIP Call State 0xb2400d58 [completed]
2013-08-09 13:28:42:085804 [NOTICE] Accepted TCP/MRCPv2 Connection 141.85.94.106:1544 <-> 141.85.94.106:46311
2013-08-09 13:28:42:086256 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK
2013-08-09 13:28:42:086282 [INFO] Receive SIP Event [nua_i_state] Status 200 OK
2013-08-09 13:28:42:086293 [NOTICE] SIP Call State 0xb2400d58 [ready]
2013-08-09 13:28:42:086304 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active
2013-08-09 13:28:42:090143 [INFO] Receive MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [132 bytes]
MRCP/2.0 132 SET-PARAMS 1
Channel-Identifier: 766cb1a200de11e3@speechrecog
Recognition-Timeout: 20000
No-Input-Timeout: 15000
2013-08-09 13:28:42:090255 [INFO] Attach Control Channel 766cb1a200de11e3@speechrecog to Connection 141.85.94.106:1544 <-> 141.85.94.106:46311 [1]
2013-08-09 13:28:42:090292 [INFO] Process SET-PARAMS Request 766cb1a200de11e3@speechrecog [1]
2013-08-09 13:28:42:091841 [INFO] Dispatch Request SET-PARAMS 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:42:091876 [INFO] Process SET-PARAMS Response 766cb1a200de11e3@speechrecog [1]
2013-08-09 13:28:42:091935 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [80 bytes]
MRCP/2.0 80 1 200 COMPLETE
Channel-Identifier: 766cb1a200de11e3@speechrecog
2013-08-09 13:28:42:092447 [INFO] Receive MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [225 bytes]
MRCP/2.0 225 DEFINE-GRAMMAR 2
Channel-Identifier: 766cb1a200de11e3@speechrecog
Content-Type: application/x-jsgf
Content-Id: digits
Content-Length: 68
JSGF V1.0;
grammar digits;
public <numbers> = (one | two | three);</numbers>
2013-08-09 13:28:42:092522 [INFO] Process DEFINE-GRAMMAR Request 766cb1a200de11e3@speechrecog [2]
2013-08-09 13:28:42:092553 [INFO] Dispatch Request DEFINE-GRAMMAR 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:42:092579 [INFO] Create Grammar File [../data/766cb1a200de11e3-digits.gram] 766cb1a200de11e3@pocketsphinx
INFO: cmd_ln.c(691): Parsing command line:
\
-samprate 8000 \
-hmm /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator \
-jsgf ../data/766cb1a200de11e3-digits.gram \
-dict /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/lm/en_US/cmu07a.dic \
-frate 50 \
-silprob 0.005
Current configuration:
[NAME] [DEFLT] [VALUE]
-agc none none
-agcthresh 2.0 2.000000e+00
-alpha 0.97 9.700000e-01
-ascale 20.0 2.000000e+01
-aw 1 1
-backtrace no no
-beam 1e-48 1.000000e-48
-bestpath yes yes
-bestpathlw 9.5 9.500000e+00
-bghist no no
-ceplen 13 13
-cmn current current
-cmninit 8.0 8.0
-compallsen no no
-debug 0
-dict /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/lm/en_US/cmu07a.dic
-dictcase no no
-dither no no
-doublebw no no
-ds 1 1
-fdict
-feat 1s_c_d_dd 1s_c_d_dd
-featparams
-fillprob 1e-8 1.000000e-08
-frate 100 50
-fsg
-fsgusealtpron yes yes
-fsgusefiller yes yes
-fwdflat yes yes
-fwdflatbeam 1e-64 1.000000e-64
-fwdflatefwid 4 4
-fwdflatlw 8.5 8.500000e+00
-fwdflatsfwin 25 25
-fwdflatwbeam 7e-29 7.000000e-29
-fwdtree yes yes
-hmm /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator
-input_endian little little
-jsgf ../data/766cb1a200de11e3-digits.gram
-kdmaxbbi -1 -1
-kdmaxdepth 0 0
-kdtree
-latsize 5000 5000
-lda
-ldadim 0 0
-lextreedump 0 0
-lifter 0 0
-lm
-lmctl
-lmname default default
-logbase 1.0001 1.000100e+00
-logfn
-logspec no no
-lowerf 133.33334 1.333333e+02
-lpbeam 1e-40 1.000000e-40
-lponlybeam 7e-29 7.000000e-29
-lw 6.5 6.500000e+00
-maxhmmpf -1 -1
-maxnewoov 20 20
-maxwpf -1 -1
-mdef
-mean
-mfclogdir
-min_endfr 0 0
-mixw
-mixwfloor 0.0000001 1.000000e-07
-mllr
-mmap yes yes
-ncep 13 13
-nfft 512 512
-nfilt 40 40
-nwpen 1.0 1.000000e+00
-pbeam 1e-48 1.000000e-48
-pip 1.0 1.000000e+00
-pl_beam 1e-10 1.000000e-10
-pl_pbeam 1e-5 1.000000e-05
-pl_window 0 0
-rawlogdir
-remove_dc no no
-round_filters yes yes
-samprate 16000 8.000000e+03
-seed -1 -1
-sendump
-senlogdir
-senmgau
-silprob 0.005 5.000000e-03
-smoothspec no no
-svspec
-tmat
-tmatfloor 0.0001 1.000000e-04
-topn 4 4
-topn_beam 0 0
-toprule
-transform legacy legacy
-unit_area yes yes
-upperf 6855.4976 6.855498e+03
-usewdphones no no
-uw 1.0 1.000000e+00
-var
-varfloor 0.0001 1.000000e-04
-varnorm no no
-verbose no no
-warp_params
-warp_type inverse_linear inverse_linear
-wbeam 7e-29 7.000000e-29
-wip 0.65 6.500000e-01
-wlen 0.025625 2.562500e-02
2013-08-09 13:28:42:098479 [INFO] Init Decoder 766cb1a200de11e3@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 s2_4x \
-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 s2_4x
-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(246): Parsed model-specific feature parameters from /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/feat.params
INFO: fe_interface.c(299): You are using the internal mechanism to generate the seed.
INFO: feat.c(713): Initializing feature stream to type: 's2_4x', ceplen=13, CMN='current', VARNORM='no', AGC='none'
INFO: cmn.c(142): mean[0]= 12.00, mean[1..12]= 0.0
INFO: mdef.c(517): Reading model definition: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/mdef
INFO: bin_mdef.c(179): Allocating 104160 * 8 bytes (813 KiB) for CD tree
INFO: tmat.c(205): Reading HMM transition probability matrices: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/transition_matrices
INFO: acmod.c(121): Attempting to use SCHMM computation module
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/means
INFO: ms_gauden.c(292): 1 codebook, 4 feature, size:
INFO: ms_gauden.c(294): 256x12
INFO: ms_gauden.c(294): 256x24
INFO: ms_gauden.c(294): 256x3
INFO: ms_gauden.c(294): 256x12
INFO: ms_gauden.c(198): Reading mixture gaussian parameter: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/variances
INFO: ms_gauden.c(292): 1 codebook, 4 feature, size:
INFO: ms_gauden.c(294): 256x12
INFO: ms_gauden.c(294): 256x24
INFO: ms_gauden.c(294): 256x3
INFO: ms_gauden.c(294): 256x12
INFO: ms_gauden.c(354): 59 variance values floored
INFO: s2_semi_mgau.c(903): Loading senones from dump file /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/sendump
INFO: s2_semi_mgau.c(927): BEGIN FILE FORMAT DESCRIPTION
INFO: s2_semi_mgau.c(990): Rows: 256, Columns: 6256
INFO: s2_semi_mgau.c(1022): Using memory-mapped I/O for senones
INFO: s2_semi_mgau.c(1296): Maximum top-N: 4 Top-N beams: 0 0 0 0
INFO: dict.c(317): Allocating 137549 * 20 bytes (2686 KiB) for word entries
INFO: dict.c(332): Reading main dictionary: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/lm/en_US/cmu07a.dic
INFO: dict.c(211): Allocated 1010 KiB for strings, 1664 KiB for phones
INFO: dict.c(335): 133436 words read
INFO: dict.c(341): Reading filler dictionary: /home/eko/asterisk.plus.uniMRCP/pocketsphinx-0.8/model/communicator/noisedict
INFO: dict.c(211): Allocated 0 KiB for strings, 0 KiB for phones
INFO: dict.c(344): 17 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(581): Defined rule: <digits.g00000>
INFO: jsgf.c(581): Defined rule: PUBLIC <digits.numbers>
INFO: fsg_model.c(215): Computing transitive closure for null transitions
INFO: fsg_model.c(270): 3 null transitions added
INFO: fsg_model.c(421): Adding silence transitions for <sil> to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++AE++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++AH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++BACKGROUND++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++BREATH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++COUGH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++EH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++ER++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++LAUGH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++MM++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++MUMBLE++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++NOISE++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++OH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++SMACK++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++UH++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++UH_NOISE++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++UM++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_model.c(421): Adding silence transitions for ++UM_NOISE++ to FSG
INFO: fsg_model.c(441): Added 7 silence word transitions
INFO: fsg_search.c(366): Added 1 alternate word transitions
INFO: fsg_lextree.c(108): Allocated 728 bytes (0 KiB) for left and right context phones
INFO: fsg_lextree.c(253): 192 HMM nodes in lextree (184 leaves)
INFO: fsg_lextree.c(255): Allocated 20736 bytes (20 KiB) for all lextree nodes
INFO: fsg_lextree.c(258): Allocated 19872 bytes (19 KiB) for lextree leafnodes
2013-08-09 13:28:42:759535 [INFO] Process DEFINE-GRAMMAR Response 766cb1a200de11e3@speechrecog [2]
2013-08-09 13:28:42:759577 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [112 bytes]
MRCP/2.0 112 2 200 COMPLETE
Channel-Identifier: 766cb1a200de11e3@speechrecog
Completion-Cause: 000 success</sil></digits.numbers></digits.g00000>
2013-08-09 13:28:42:759896 [INFO] Receive MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [191 bytes]
MRCP/2.0 191 RECOGNIZE 3
Channel-Identifier: 766cb1a200de11e3@speechrecog
Content-Type: text/uri-list
Cancel-If-Queue: false
Start-Input-Timers: true
Content-Length: 14
session:digits
2013-08-09 13:28:42:759931 [INFO] Process RECOGNIZE Request 766cb1a200de11e3@speechrecog [3]
2013-08-09 13:28:42:759945 [INFO] Dispatch Request RECOGNIZE 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:42:759973 [INFO] Process RECOGNIZE Response 766cb1a200de11e3@speechrecog [3]
2013-08-09 13:28:42:759977 [INFO] State Transition IDLE -> RECOGNIZING 766cb1a200de11e3@speechrecog
2013-08-09 13:28:42:759987 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [83 bytes]
MRCP/2.0 83 3 200 IN-PROGRESS
Channel-Identifier: 766cb1a200de11e3@speechrecog
2013-08-09 13:28:43:281501 [INFO] Detected Voice Activity 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:43:281571 [INFO] Process START-OF-INPUT Event 766cb1a200de11e3@speechrecog [3]
2013-08-09 13:28:43:281633 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [94 bytes]
MRCP/2.0 94 START-OF-INPUT 3 IN-PROGRESS
Channel-Identifier: 766cb1a200de11e3@speechrecog
2013-08-09 13:28:44:751486 [INFO] Get Recognition Partial Result [two] Score [-16044] 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:45:052467 [INFO] Get Recognition Partial Result [three] Score [-18702] 766cb1a200de11e3@pocketsphinx
2013-08-09 13:28:45:151494 [INFO] Get Recognition Partial Result [one] Score [-19543] 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:281381 [INFO] Recognition Timeout Elapsed 766cb1a200de11e3@pocketsphinx
INFO: fsg_search.c(1032): 1022 frames, 5434 HMMs (5/fr), 20638 senones (20/fr), 5319 history entries (5/fr)
INFO: fsg_search.c(1417): Start node two.0:5:19
INFO: fsg_search.c(1417): Start node <sil>.0:2:90
INFO: fsg_search.c(1417): Start node <sil>.0:2:90
INFO: fsg_search.c(1456): End node <sil>.418:434:1021 (-3490)
INFO: fsg_search.c(1456): End node <sil>.418:434:1021 (-3490)
INFO: fsg_search.c(1456): End node <sil>.418:434:1021 (-3490)
INFO: fsg_search.c(1680): lattice start node
.0 end node.1022INFO: ps_lattice.c(1365): Normalizer P(O) = alpha(:1022:1022) = -3976447
INFO: ps_lattice.c(1403): Joint P(O,S) = -3976447 P(S|O) = 0
2013-08-09 13:29:03:298084 [INFO] Get Recognition Final Result [one] Prob [0] Score [-77665] 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:298106 [INFO] Process RECOGNITION-COMPLETE Event 766cb1a200de11e3@speechrecog [3]
2013-08-09 13:29:03:298111 [INFO] State Transition RECOGNIZING -> RECOGNIZED 766cb1a200de11e3@speechrecog
2013-08-09 13:29:03:298142 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [389 bytes]
MRCP/2.0 389 RECOGNITION-COMPLETE 3 COMPLETE
Channel-Identifier: 766cb1a200de11e3@speechrecog
Completion-Cause: 003 hotword-maxtime
Content-Type: application/x-nlsml
Content-Length: 196</sil></sil></sil></sil></sil>
<rezult grammar="digits">
< interpretation grammar="digits" confidence="99" ></rezult>
<interpretation>
<result></result></interpretation>
2013-08-09 13:29:03:299635 [INFO] Receive MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [103 bytes]
MRCP/2.0 103 DEFINE-GRAMMAR 4
Channel-Identifier: 766cb1a200de11e3@speechrecog
Content-Id: digits
2013-08-09 13:29:03:299668 [INFO] Process DEFINE-GRAMMAR Request 766cb1a200de11e3@speechrecog [4]
2013-08-09 13:29:03:299673 [INFO] State Transition RECOGNIZED -> IDLE 766cb1a200de11e3@speechrecog
2013-08-09 13:29:03:299681 [INFO] Dispatch Request DEFINE-GRAMMAR 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:299688 [INFO] Remove Grammar File [../data/766cb1a200de11e3-digits.gram] 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:299745 [INFO] Process DEFINE-GRAMMAR Response 766cb1a200de11e3@speechrecog [4]
2013-08-09 13:29:03:299759 [INFO] Send MRCPv2 Stream 141.85.94.106:1544 <-> 141.85.94.106:46311 [112 bytes]
MRCP/2.0 112 4 200 COMPLETE
Channel-Identifier: 766cb1a200de11e3@speechrecog
Completion-Cause: 000 success
2013-08-09 13:29:03:300179 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated
2013-08-09 13:29:03:300188 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated
2013-08-09 13:29:03:300192 [NOTICE] SIP Call State 0xb2400d58 [terminated]
2013-08-09 13:29:03:300198 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated
2013-08-09 13:29:03:300205 [INFO] Deactivate Session 0xb2400d58 <766cb1a200de11e3>
2013-08-09 13:29:03:300208 [INFO] Terminate Session 0xb2400d58 <766cb1a200de11e3>
2013-08-09 13:29:03:300217 [INFO] Close Channel 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:300308 [INFO] Remove Control Channel 766cb1a200de11e3@speechrecog [0]
2013-08-09 13:29:03:300318 [INFO] TCP/MRCPv2 Peer Disconnected 141.85.94.106:1544 <-> 141.85.94.106:46311
2013-08-09 13:29:03:300365 [NOTICE] Destroy TCP/MRCPv2 Connection 141.85.94.106:1544 <-> 141.85.94.106:46311
2013-08-09 13:29:03:300572 [INFO] Free Decoder 766cb1a200de11e3@pocketsphinx
2013-08-09 13:29:03:372465 [NOTICE] Remove Session <766cb1a200de11e3>
2013-08-09 13:29:03:381117 [INFO] Close RTP Receiver 141.85.94.106:5000 <- 141.85.94.106:4010 [r:1021 l:0 j:46 p:50 d:0 i:0]
2013-08-09 13:29:03:381138 [INFO] Remove RTP Session 141.85.94.106:5000
2013-08-09 13:29:03:381178 [INFO] Session Terminated 0xb2400d58 <766cb1a200de11e3>
"
This issue is not related to pocketsphinx. The cause 3 means that the time limit for recognition has exceeded. It seems you have too long prompt, that's why it tries to recognize 10 seconds of speech.
Increase hotword limit or fix this issue in Unimrcp in other way.