Hope you are doing good...
We have UniMRCP setup with Amazon Lex in a Cisco VVB env. I have just completed the setup and made a test phone call. Overall, everything looks good and I could see the intents flowing to & fro between the UniMRCP & VVB.
However, in the UniMRCP logs, I see there are some warning messages coming for every call. Here is the message in red & I have highlighted in the logs as well..
Can I ignore these? Any special settings to be done? Please let me know if you need anything else.
2020-09-28 14:47:47:906105 [INFO] Remote SDP 0x7f5b00007b58 <new>
v=0
o=- 123 456 IN IP4 10.2.74.43
s=Cisco Virtualized Voice Browser 12.0.1.10000-17
c=IN IP4 10.2.74.43
t=0 0
m=audio 24610 RTP/AVP 0 96
a=rtpmap:0 pcmu/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-15
a=sendonly
a=mid:1
m=application 9 TCP/MRCPv2 19
a=setup:active
a=connection:new
a=fmtp:96 0-15
a=resource:speechrecog
a=cmid:1
2020-09-28 14:47:47:906227 [NOTICE] Add Session <3e476e00db484a2e>
2020-09-28 14:47:47:906246 [INFO] Receive Offer 0x7f5b00007b58 <3e476e00db484a2e> [c:1 a:1 v:0]
2020-09-28 14:47:47:906262 [INFO] Found MRCP Engine [Lex-1] for Resource [speechrecog] 0x7f5b00007b58 <3e476e00db484a2e>
2020-09-28 14:47:47:906360 [INFO] Add Pending Control Channel <3e476e00db484a2e@speechrecog> [1]
2020-09-28 14:47:47:908721 [INFO] Open RTP Receiver
10.2.74.97:5008 <-
10.2.74.43:24610 playout [50 ms] bounds [0 - 600 ms] adaptive [1] skew detection [1]
2020-09-28 14:47:47:908744 [INFO] Media Path 0x7f5b00007b58 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2020-09-28 14:47:47:908781 [INFO] Open <3e476e00db484a2e@lex>
2020-09-28 14:47:47:908794 [NOTICE] Lex Usage: 1/1/2
2020-09-28 14:47:47:908806 [INFO] Send Answer 0x7f5b00007b58 <3e476e00db484a2e> [c:1 a:1 v:0] Status OK
2020-09-28 14:47:47:908822 [INFO] Local SDP 0x7f5b00007b58 <3e476e00db484a2e>
v=0
o=UniMRCPServer 0 0 IN IP4 10.2.74.97
s=-
c=IN IP4 10.2.74.97
t=0 0
m=audio 5008 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=recvonly
a=mid:1
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:3e476e00db484a2e@speechrecog
a=cmid:1
2020-09-28 14:47:47:909137 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2020-09-28 14:47:47:909152 [NOTICE] SIP Call State 0x7f5b00007b58 [completed]
2020-09-28 14:47:47:910947 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2020-09-28 14:47:47:910970 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2020-09-28 14:47:47:910975 [NOTICE] SIP Call State 0x7f5b00007b58 [ready]
2020-09-28 14:47:47:910986 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
MRCP/2.0 557 DEFINE-GRAMMAR 100
Channel-Identifier: 3e476e00db484a2e@speechrecog
Fetch-Timeout:10000
Cache-Control:max-stale=0,max-age=300
Content-Type:application/srgs+xml
Speech-Language:en-US
Content-Id:li...@application.grammar
Content-Length: 201
<?xml version="1.0" encoding="UTF-8"?>
<grammar mode="voice" root="root" version="1.0" xml:lang="en-US">
<rule id="root" scope="public">
<one-of>
<item>
help
</item>
</one-of>
</rule>
</grammar>
2020-09-28 14:47:47:912193 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912202 [WARN] Failed to Parse MRCPv2 Data
2020-09-28 14:47:47:912206 [INFO] Process DEFINE-GRAMMAR Request <3e476e00db484a2e@speechrecog> [100]
2020-09-28 14:47:47:912207 [WARN] Cannot read the first field in start-line
MRCP/2.0 276 DEFINE-GRAMMAR 101
Channel-Identifier: 3e476e00db484a2e@speechrecog
Fetch-Timeout:10000
Cache-Control:max-stale=0,max-age=300
Content-Type:text/uri-list
Speech-Language:en-US
Content-Id:fie...@field.grammar
Content-Length: 25
builtin:speech/transcribe
2020-09-28 14:47:47:912277 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912282 [WARN] Failed to Parse MRCPv2 Data
2020-09-28 14:47:47:912285 [WARN] Cannot read the first field in start-line
MRCP/2.0 270 DEFINE-GRAMMAR 102
Channel-Identifier: 3e476e00db484a2e@speechrecog
Fetch-Timeout:10000
Cache-Control:max-stale=0,max-age=300
Content-Type:text/uri-list
Speech-Language:en-US
Content-Id:fie...@field.grammar
Content-Length: 19
builtin:dtmf/digits
2020-09-28 14:47:47:912308 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912312 [WARN] Failed to Parse MRCPv2 Data
2020-09-28 14:47:47:912315 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912341 [INFO] Process DEFINE-GRAMMAR Response <3e476e00db484a2e@speechrecog> [100]
2020-09-28 14:47:47:912362 [INFO] Process DEFINE-GRAMMAR Request <3e476e00db484a2e@speechrecog> [101]
MRCP/2.0 114 100 200 COMPLETE
Channel-Identifier: 3e476e00db484a2e@speechrecog
Completion-Cause: 000 success
2020-09-28 14:47:47:912381 [INFO] Process DEFINE-GRAMMAR Response <3e476e00db484a2e@speechrecog> [101]
2020-09-28 14:47:47:912416 [INFO] Process DEFINE-GRAMMAR Request <3e476e00db484a2e@speechrecog> [102]
MRCP/2.0 114 101 200 COMPLETE
Channel-Identifier: 3e476e00db484a2e@speechrecog
Completion-Cause: 000 success
2020-09-28 14:47:47:912435 [INFO] Process DEFINE-GRAMMAR Response <3e476e00db484a2e@speechrecog> [102]
MRCP/2.0 114 102 200 COMPLETE
Channel-Identifier: 3e476e00db484a2e@speechrecog
Completion-Cause: 000 success
MRCP/2.0 616 RECOGNIZE 103
Channel-Identifier: 3e476e00db484a2e@speechrecog
Cancel-If-Queue: false
N-Best-List-Length:1
Sensitivity-Level:0.5
Speech-Complete-Timeout:700
Speech-Incomplete-Timeout:1000
Confidence-Threshold:0.4
Speed-Vs-Accuracy:0.5
Recognition-Timeout:20000
No-Input-Timeout:5000
DTMF-Interdigit-Timeout:3000
DTMF-Term-Timeout:0
Fetch-Timeout:10000
Logging-Tag:32000000015
DTMF-Term-Char:#
Content-Type:text/uri-list
Speech-Language:en-US
Start-Input-Timers:false
Content-Length: 93
session:li...@application.grammar
session:fie...@field.grammar
session:fie...@field.grammar
2020-09-28 14:47:47:912861 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912864 [WARN] Failed to Parse MRCPv2 Data
2020-09-28 14:47:47:912867 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:47:912869 [INFO] Process RECOGNIZE Request <3e476e00db484a2e@speechrecog> [103]
2020-09-28 14:47:47:912883 [WARN] Speech Grammar Already Specified <3e476e00db484a2e@lex>
2020-09-28 14:47:47:912890 [INFO] Init Speech Detector: frame-size=160, max-frame-count=160, output-frame-count=20, vad-mode=2, noinput-timeout=5000 ms, input-timeout=20000 ms, start-timeout=300 ms, complete-timeout=700 ms, incomplete-timeout=1000 ms, leading-silence=300 ms, trailing-silence=300 ms, interim-results=0, start-of-input=internal <3e476e00db484a2e>
2020-09-28 14:47:47:912898 [INFO] Init DTMF Detector: interdigit-timeout=3000 ms, term-timeout=0 ms, term-char=#, length=0, min-length=0, max-length=0 <3e476e00db484a2e>
2020-09-28 14:47:47:912907 [INFO] Process RECOGNIZE Response <3e476e00db484a2e@speechrecog> [103]
2020-09-28 14:47:47:912911 [INFO] State Transition IDLE -> RECOGNIZING <3e476e00db484a2e@speechrecog>
MRCP/2.0 85 103 200 IN-PROGRESS
Channel-Identifier: 3e476e00db484a2e@speechrecog
2020-09-28 14:47:48:886270 [INFO] Receive SIP Event [nua_i_options] Status 100 Trying [SIP-Agent-1]
2020-09-28 14:47:48:886307 [INFO] Resource Discovery SDP
[v=0
o=UniMRCPServer 0 0 IN IP4 10.2.74.97
s=-
c=IN IP4 10.2.74.97
t=0 0
m=application 0 TCP/MRCPv2 1
a=resource:speechsynth
a=resource:speechrecog
m=audio 0 RTP/AVP 0 8 96 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=rtpmap:101 telephone-event/8000
]
2020-09-28 14:47:49:228670 [INFO] Speech Detector State Transition NO-INPUT -> IN-PROGRESS [1320 ms] <3e476e00db484a2e>
2020-09-28 14:47:49:228719 [INFO] Start Input Timer [20000 ms] <3e476e00db484a2e>
2020-09-28 14:47:49:228782 [INFO] Process START-OF-INPUT Event <3e476e00db484a2e@speechrecog> [103]
MRCP/2.0 117 START-OF-INPUT 103 IN-PROGRESS
Channel-Identifier: 3e476e00db484a2e@speechrecog
Input-Type: speech
2020-09-28 14:47:50:694413 [INFO] Receive SIP Event [nua_i_options] Status 100 Trying [SIP-Agent-1]
2020-09-28 14:47:50:694458 [INFO] Resource Discovery SDP
[v=0
o=UniMRCPServer 0 0 IN IP4 10.2.74.97
s=-
c=IN IP4 10.2.74.97
t=0 0
m=application 0 TCP/MRCPv2 1
a=resource:speechsynth
a=resource:speechrecog
m=audio 0 RTP/AVP 0 8 96 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=rtpmap:101 telephone-event/8000
]
2020-09-28 14:47:53:028695 [INFO] Speech Detector State Transition IN-PROGRESS -> COMPLETE [3800 ms] <3e476e00db484a2e>
2020-09-28 14:47:53:028743 [INFO] Detector Stats: leading-silence=300 ms, input=3400 ms, trailing-silence=700 ms <3e476e00db484a2e>
2020-09-28 14:47:53:028769 [INFO] Input Complete [success] size=70400 bytes, dur=4400 ms <3e476e00db484a2e@lex>
2020-09-28 14:47:53:028812 [INFO] PostContentAsync: bot-name [BookTripTest] alias [BookTripTest] user-id [3e476e00db484a2e] content-type [audio/lpcm; sample-rate=8000; sample-size-bits=16; channel-count=1; is-big-endian=false] accept [text/plain; charset=utf-8] request-attribs [{}] credentials-profile [] <3e476e00db484a2e@lex>
2020-09-28 14:47:54:478838 [INFO] Process PostContent Results: transcript [book a hotel] intent [BookHotel] message [What city will you be staying in?] content-type [text/plain; charset=utf-8] content [0] <3e476e00db484a2e@lex>
2020-09-28 14:47:54:478986 [INFO] Process RECOGNITION-COMPLETE Event <3e476e00db484a2e@speechrecog> [103]
2020-09-28 14:47:54:478998 [INFO] State Transition RECOGNIZING -> RECOGNIZED <3e476e00db484a2e@speechrecog>
MRCP/2.0 711 RECOGNITION-COMPLETE 103 COMPLETE
Channel-Identifier: 3e476e00db484a2e@speechrecog
Completion-Cause: 000 success
Content-Type: application/x-nlsml
Content-Length: 524
<?xml version="1.0"?><result><interpretation grammar="session:li...@application.grammar" confidence="1"><instance><intent>BookHotel</intent><slots><CheckInDate></CheckInDate><Location></Location><Nights></Nights><RoomType></RoomType></slots><messageformat>PlainText</messageformat><message>What city will you be staying in?</message><dialogstate>ElicitSlot</dialogstate><slottoelicit>Location</slottoelicit><sessionattributes></sessionattributes></instance><input mode="speech">book a hotel</input></interpretation></result>
MRCP/2.0 557 DEFINE-GRAMMAR 104
Channel-Identifier: 3e476e00db484a2e@speechrecog
Fetch-Timeout:10000
Cache-Control:max-stale=0,max-age=300
Content-Type:application/srgs+xml
Speech-Language:en-US
Content-Id:li...@application.grammar
Content-Length: 201
<?xml version="1.0" encoding="UTF-8"?>
<grammar mode="voice" root="root" version="1.0" xml:lang="en-US">
<rule id="root" scope="public">
<one-of>
<item>
help
</item>
</one-of>
</rule>
</grammar>
2020-09-28 14:47:54:485730 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:54:485736 [WARN] Failed to Parse MRCPv2 Data
2020-09-28 14:47:54:485739 [WARN] Cannot read the first field in start-line
2020-09-28 14:47:54:485753 [INFO] Process DEFINE-GRAMMAR Request <3e476e00db484a2e@speechrecog> [104]
2020-09-28 14:47:54:485767 [INFO] State Transition RECOGNIZED -> IDLE <3e476e00db484a2e@speechrecog>
2020-09-28 14:47:54:485863 [INFO] Process DEFINE-GRAMMAR Response <3e476e00db484a2e@speechrecog> [104]
MRCP/2.0 114 104 200 COMPLETE
Channel-Identifier: 3e476e00db484a2e@speechrecog
Completion-Cause: 000 success