2019-07-11 10:41:10:706791 [INFO] Receive SIP Event [nua_i_invite] Status 100 Trying [SIP-Agent-1]
2019-07-11 10:41:10:706842 [INFO] Receive SIP Event [nua_i_state] Status 100 Trying [SIP-Agent-1]
2019-07-11 10:41:10:706848 [NOTICE] SIP Call State [received]
2019-07-11 10:41:10:706880 [INFO] Create Session 0x7f3bf4004728 [uni2]
2019-07-11 10:41:10:706905 [INFO] Remote SDP 0x7f3bf4004728
v=0
o=FreeSWITCH 7957069661587620113 7436270796488759771 IN IP4 172.17.1.116
s=-
c=IN IP4 172.17.1.116
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
a=cmid:1
m=audio 4002 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=sendonly
a=mid:1
2019-07-11 10:41:10:706939 [NOTICE] Add Session
2019-07-11 10:41:10:706943 [INFO] Receive Offer 0x7f3bf4004728 [c:1 a:1 v:0]
2019-07-11 10:41:10:707015 [INFO] Add Pending Control Channel 579805baa38511e9@speechrecog [1]
2019-07-11 10:41:10:716519 [INFO] Enable RTP Session 172.17.2.196:5002
2019-07-11 10:41:10:716594 [INFO] Open RTP Receiver 172.17.2.196:5002 <- 172.17.1.116:4002 playout [50 ms] bounds [0 - 600 ms] adaptive [1] skew detection [1]
2019-07-11 10:41:10:716604 [INFO] Media Path 0x7f3bf4004728 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2019-07-11 10:41:10:716652 [INFO] Send Answer 0x7f3bf4004728 [c:1 a:1 v:0] Status OK
2019-07-11 10:41:10:716663 [INFO] Local SDP 0x7f3bf4004728
v=0
o=UniMRCPServer 0 0 IN IP4 172.17.2.196
s=-
c=IN IP4 172.17.2.196
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:579805baa38511e9@speechrecog
a=cmid:1
m=audio 5002 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=recvonly
a=mid:1
2019-07-11 10:41:10:717118 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2019-07-11 10:41:10:717159 [NOTICE] SIP Call State 0x7f3bf4004728 [completed]
2019-07-11 10:41:10:718486 [NOTICE] Accepted TCP/MRCPv2 Connection 172.17.2.196:1544 <-> 172.17.1.116:52216
2019-07-11 10:41:10:718523 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2019-07-11 10:41:10:718529 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2019-07-11 10:41:10:718533 [NOTICE] SIP Call State 0x7f3bf4004728 [ready]
2019-07-11 10:41:10:718535 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
2019-07-11 10:41:10:726777 [INFO] Receive MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [468 bytes]
MRCP/2.0 468 DEFINE-GRAMMAR 1
Channel-Identifier: 579805baa38511e9@speechrecog
Content-Type: application/srgs+xml
Content-Id: 5ce63f65-0f98-42da-93d8-f26bdbc88476
Content-Length: 278
<?xml version="1.0"?>
one
two
three
2019-07-11 10:41:10:726822 [INFO] Assign Control Channel 579805baa38511e9@speechrecog to Connection 172.17.2.196:1544 <-> 172.17.1.116:52216 [0] -> [1]
2019-07-11 10:41:10:726844 [INFO] Process DEFINE-GRAMMAR Request 579805baa38511e9@speechrecog [1]
2019-07-11 10:41:10:726869 [INFO] Process DEFINE-GRAMMAR Response 579805baa38511e9@speechrecog [1]
2019-07-11 10:41:10:726890 [INFO] Send MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [112 bytes]
MRCP/2.0 112 1 200 COMPLETE
Channel-Identifier: 579805baa38511e9@speechrecog
Completion-Cause: 000 success
2019-07-11 10:41:10:727815 [INFO] Receive MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [273 bytes]
MRCP/2.0 273 RECOGNIZE 2
Channel-Identifier: 579805baa38511e9@speechrecog
Content-Type: text/uri-list
Cancel-If-Queue: false
Recognition-Timeout: 10000
Start-Input-Timers: true
No-Input-Timeout: 5000
Content-Length: 44
session:5ce63f65-0f98-42da-93d8-f26bdbc88476
2019-07-11 10:41:10:727865 [INFO] Process RECOGNIZE Request 579805baa38511e9@speechrecog [2]
2019-07-11 10:41:10:727876 [INFO] Open Utterance Output File [/usr/local/unimrcp/var/utter-8kHz-579805baa38511e9.pcm] for Writing
2019-07-11 10:41:10:728055 [INFO] Process RECOGNIZE Response 579805baa38511e9@speechrecog [2]
2019-07-11 10:41:10:728077 [INFO] State Transition IDLE -> RECOGNIZING 579805baa38511e9@speechrecog
2019-07-11 10:41:10:728134 [INFO] Send MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [83 bytes]
MRCP/2.0 83 2 200 IN-PROGRESS
Channel-Identifier: 579805baa38511e9@speechrecog
2019-07-11 10:41:11:537114 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:547093 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:577014 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:586826 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:596742 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:606087 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:616821 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:626158 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:636391 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:646711 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:656691 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:666200 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:676635 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:686583 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:696703 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:706603 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:716682 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:726704 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:736657 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:746644 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:756449 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:766582 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:776901 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:786758 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:796875 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:806867 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:816656 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:826178 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:836737 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:846277 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:956524 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:966486 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:976403 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:11:986385 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:11:996284 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:12:006528 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:12:016476 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:12:026282 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:12:036295 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:12:046218 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:12:416443 [INFO] Activity Detector state changed [1]
2019-07-11 10:41:12:426244 [INFO] Activity Detector state changed [0]
2019-07-11 10:41:12:754256 [INFO] Receive MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [72 bytes]
MRCP/2.0 72 STOP 3
Channel-Identifier: 579805baa38511e9@speechrecog
2019-07-11 10:41:12:754472 [INFO] Process STOP Request 579805baa38511e9@speechrecog [3]
2019-07-11 10:41:12:756876 [INFO] Process STOP Response 579805baa38511e9@speechrecog [3]
2019-07-11 10:41:12:756977 [INFO] State Transition RECOGNIZING -> IDLE 579805baa38511e9@speechrecog
2019-07-11 10:41:12:757051 [INFO] Send MRCPv2 Data 172.17.2.196:1544 <-> 172.17.1.116:52216 [108 bytes]
MRCP/2.0 108 3 200 COMPLETE
Channel-Identifier: 579805baa38511e9@speechrecog
Active-Request-Id-List: 2
2019-07-11 10:41:12:761118 [INFO] TCP/MRCPv2 Peer Disconnected 172.17.2.196:1544 <-> 172.17.1.116:52216
2019-07-11 10:41:12:761411 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated [SIP-Agent-1]
2019-07-11 10:41:12:761443 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated [SIP-Agent-1]
2019-07-11 10:41:12:761795 [NOTICE] SIP Call State 0x7f3bf4004728 [terminated]
2019-07-11 10:41:12:761838 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated [SIP-Agent-1]
2019-07-11 10:41:12:761908 [INFO] Deactivate Session 0x7f3bf4004728
2019-07-11 10:41:12:761933 [INFO] Terminate Session 0x7f3bf4004728
2019-07-11 10:41:12:762049 [INFO] Remove Control Channel 579805baa38511e9@speechrecog [0]
2019-07-11 10:41:12:766471 [INFO] Close RTP Receiver 172.17.2.196:5002 <- 172.17.1.116:4002 [r:101 l:0 j:96 p:50 d:0 i:0]
2019-07-11 10:41:12:766549 [INFO] Remove RTP Session 172.17.2.196:5002
2019-07-11 10:41:12:766649 [NOTICE] Destroy TCP/MRCPv2 Connection 172.17.2.196:1544 <-> 172.17.1.116:52216
2019-07-11 10:41:12:766694 [NOTICE] Remove Session
2019-07-11 10:41:12:766724 [INFO] Session Terminated 0x7f3bf4004728
2019-07-11 10:41:12:766785 [NOTICE] Destroy Session