Completion-Cause: 002 no-input-timeout
631 views
Skip to first unread message
Felipe Lohnefink
Oct 1, 2018, 5:20:29 PM10/1/18
to UniMRCP
No matter what I do it always returns a "Completion-Cause: 002 no-input-timeout". Look:
======================================================
2018-09-28 16:22:41.651451 [DEBUG] mod_unimrcp.c:1584 (TTS-20) READY ==> CLOSED
2018-09-28 16:22:41.651451 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-09-28 16:22:41.651451 [DEBUG] mod_unimrcp.c:858 (TTS-20) audio queue destroyed
2018-09-28 16:22:41.651451 [INFO] switch_ivr_async.c:4464 (sofia/internal/10...@192.168.1.201) WAITING FOR RESULT
2018-09-28 16:22:43.311425 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-09-28 16:22:43.311425 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:57588 <-> 192.168.1.202:1544 [138 bytes]
MRCP/2.0 138 RECOGNITION-COMPLETE 1 COMPLETE
Channel-Identifier: 83db5a23a40041a2@speechrecog
Completion-Cause: 002 no-input-timeout
2018-09-28 16:22:43.311425 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x1e38e60;2;3]
2018-09-28 16:22:43.311425 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-09-28 16:22:43.311425 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x1e38e60;2;3]
2018-09-28 16:22:43.311425 [INFO] mrcp_client_session.c:516 (ASR-19) Raise App MRCP Event ASR-19 <83db5a23a40041a2>
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:3675 (ASR-19) RECOGNITION COMPLETE, Completion-Cause: 002
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:3693 (ASR-19) No result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2733 (ASR-19) ASR adding result headers
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2628 (ASR-19) result:
Completion-Cause: 002
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:1584 (ASR-19) PROCESSING ==> READY
2018-09-28 16:22:43.311425 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2545 (ASR-19) SUCCESS, have result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2545 (ASR-19) SUCCESS, have result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2786 (ASR-19) result:
Completion-Cause: 002
2018-09-28 16:22:43.351313 [INFO] switch_ivr_async.c:4365 (sofia/internal/10...@192.168.1.201) DETECTED SPEECH
EXECUTE sofia/internal/10...@192.168.1.201 log(INFO YOU SHALL NOT PASS Completion-Cause: 002)
2018-09-28 16:22:43.351313 [INFO] mod_dptools.c:1792 YOU SHALL NOT PASS Completion-Cause: 002
======================================================
*****************************************
Any ideas on why this might be happening?
*****************************************
I also have these output lines on my UniMRCP Log:
======================================================
Remote SDP 0x7fed58001b98 <new>
v=0
o=FreeSWITCH 4927429811839124068 3027661601211682187 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
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=sendonly
a=mid:1
2018-10-01 17:38:06:521699 [INFO] Receive SIP Event [nua_i_invite] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:521742 [INFO] Receive SIP Event [nua_i_state] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:521747 [NOTICE] SIP Call State [received]
2018-10-01 17:38:06:521769 [INFO] Create Session 0x7fed58001b98 <new> [uni2]
2018-10-01 17:38:06:521776 [INFO] Remote SDP 0x7fed58001b98 <new>
v=0
o=FreeSWITCH 4927429811839124068 3027661601211682187 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
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=sendonly
a=mid:1
2018-10-01 17:38:06:522457 [NOTICE] Add Session <13331e247bec46aa>
2018-10-01 17:38:06:522466 [INFO] Receive Offer 0x7fed58001b98 <13331e247bec46aa> [c:1 a:1 v:0]
2018-10-01 17:38:06:522733 [INFO] Add Pending Control Channel <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:523267 [INFO] Enable RTP Session 192.168.1.202:5000
2018-10-01 17:38:06:523291 [INFO] Open RTP Receiver 192.168.1.202:5000 <- 127.0.0.1:4000 playout [50 ms] bounds [0 - 600 ms] adaptive [1] skew detection [1]
2018-10-01 17:38:06:523296 [INFO] Media Path 0x7fed58001b98 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2018-10-01 17:38:06:523552 [INFO] Open <13331e247bec46aa@gsr>
2018-10-01 17:38:06:524629 [NOTICE] GSR Usage: 1/1/2
2018-10-01 17:38:06:524916 [INFO] Send Answer 0x7fed58001b98 <13331e247bec46aa> [c:1 a:1 v:0] Status OK
2018-10-01 17:38:06:524949 [INFO] Local SDP 0x7fed58001b98 <13331e247bec46aa>
v=0
o=UniMRCPServer 0 0 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:13331e247bec46aa@speechrecog
a=cmid:1
m=audio 5000 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=recvonly
a=mid:1
2018-10-01 17:38:06:526107 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526132 [NOTICE] SIP Call State 0x7fed58001b98 [completed]
2018-10-01 17:38:06:526459 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526474 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526479 [NOTICE] SIP Call State 0x7fed58001b98 [ready]
2018-10-01 17:38:06:526482 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
2018-10-01 17:38:06:527395 [NOTICE] Accepted TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:06:531075 [INFO] Receive MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [202 bytes]
MRCP/2.0 202 RECOGNIZE 1
Channel-Identifier: 13331e247bec46aa@speechrecog
Content-Type: text/uri-list
Cancel-If-Queue: false
Start-Input-Timers: true
Content-Length: 25
builtin:speech/transcribe
2018-10-01 17:38:06:531404 [INFO] Assign Control Channel <13331e247bec46aa@speechrecog> to Connection 192.168.1.202:1544 <-> 192.168.1.201:52590 [0] -> [1]
2018-10-01 17:38:06:531487 [INFO] Process RECOGNIZE Request <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:531551 [INFO] Init Speech Detector: frame-size=160, max-frame-count=360, output-frame-count=20, vad-mode=2, noinput-timeout=5000 ms, input-timeout=10000 ms, start-timeout=300 ms, complete-timeout=1000 ms, incomplete-timeout=3000 ms, leading-silence=300 ms, trailing-silence=300 ms, interim-results=1, start-of-input=external <13331e247bec46aa>
2018-10-01 17:38:06:531621 [INFO] Start No-Input Timer [5000 ms] <13331e247bec46aa>
2018-10-01 17:38:06:531641 [INFO] Create gRPC Stream <13331e247bec46aa@gsr>
2018-10-01 17:38:06:533662 [INFO] Process RECOGNIZE Response <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:533682 [INFO] State Transition IDLE -> RECOGNIZING <13331e247bec46aa@speechrecog>
2018-10-01 17:38:06:533884 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: 13331e247bec46aa@speechrecog
2018-10-01 17:38:06:540758 [INFO] Receive SIP Event [nua_i_invite] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:540789 [INFO] Receive SIP Event [nua_i_state] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:540794 [NOTICE] SIP Call State [received]
2018-10-01 17:38:06:540809 [INFO] Create Session 0x7fed58003eb8 <new> [uni2]
2018-10-01 17:38:06:540815 [INFO] Remote SDP 0x7fed58003eb8 <new>
v=0
o=FreeSWITCH 2180192109642619661 3444725127593390253 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:existing
a=resource:speechsynth
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=recvonly
a=mid:1
2018-10-01 17:38:06:540870 [NOTICE] Add Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:06:540875 [INFO] Receive Offer 0x7fed58003eb8 <dbbf5ad2b31c46a5> [c:1 a:1 v:0]
2018-10-01 17:38:06:541217 [INFO] Add Pending Control Channel <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:06:542712 [INFO] Enable RTP Session 192.168.1.202:5002
2018-10-01 17:38:06:542746 [INFO] Open RTP Transmitter 192.168.1.202:5002 -> 127.0.0.1:4002
2018-10-01 17:38:06:542752 [INFO] Media Path 0x7fed58003eb8 Source->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Encoder->[PCMU/8000/1]->Sink
2018-10-01 17:38:06:542797 [INFO] Open <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:06:542863 [NOTICE] GSS Usage: 1/1/2
2018-10-01 17:38:06:542906 [INFO] Send Answer 0x7fed58003eb8 <dbbf5ad2b31c46a5> [c:1 a:1 v:0] Status OK
2018-10-01 17:38:06:542918 [INFO] Local SDP 0x7fed58003eb8 <dbbf5ad2b31c46a5>
v=0
o=UniMRCPServer 0 0 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:dbbf5ad2b31c46a5@speechsynth
a=cmid:1
m=audio 5002 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendonly
a=mid:1
2018-10-01 17:38:06:543290 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:543309 [NOTICE] SIP Call State 0x7fed58003eb8 [completed]
2018-10-01 17:38:06:544020 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:544057 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:544064 [NOTICE] SIP Call State 0x7fed58003eb8 [ready]
2018-10-01 17:38:06:544066 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
2018-10-01 17:38:06:544804 [NOTICE] Accepted TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:06:550409 [INFO] Receive MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [169 bytes]
MRCP/2.0 169 SPEAK 1
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
Content-Type: text/plain
Voice-Name: en-US-Wavenet-A
Content-Length: 20
Please say something
2018-10-01 17:38:06:550449 [INFO] Assign Control Channel <dbbf5ad2b31c46a5@speechsynth> to Connection 192.168.1.202:1544 <-> 192.168.1.201:52592 [0] -> [1]
2018-10-01 17:38:06:550469 [INFO] Process SPEAK Request <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:06:550508 [INFO] Start Async Synth: encoding=1, sampling-rate=8000, language=en-US <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:08:466489 [INFO] Process SPEAK Response <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:08:466570 [NOTICE] State Transition IDLE -> SPEAKING <dbbf5ad2b31c46a5@speechsynth>
2018-10-01 17:38:08:466744 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
2018-10-01 17:38:09:852933 [NOTICE] Speech Complete <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:09:853289 [INFO] Process SPEAK-COMPLETE Event <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:09:853335 [NOTICE] State Transition SPEAKING -> IDLE <dbbf5ad2b31c46a5@speechsynth>
2018-10-01 17:38:09:854153 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [122 bytes]
MRCP/2.0 122 SPEAK-COMPLETE 1 COMPLETE
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
Completion-Cause: 000 normal
2018-10-01 17:38:09:887904 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:887977 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:888078 [NOTICE] SIP Call State 0x7fed58003eb8 [terminated]
2018-10-01 17:38:09:888133 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:888255 [INFO] Deactivate Session 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:888278 [INFO] Terminate Session 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:888360 [INFO] Close <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:09:888388 [NOTICE] GSS Usage: 0/1/2
2018-10-01 17:38:09:888436 [INFO] TCP/MRCPv2 Peer Disconnected 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:09:889394 [INFO] Remove Control Channel <dbbf5ad2b31c46a5@speechsynth> [0]
2018-10-01 17:38:09:892633 [INFO] Close RTP Transmitter 192.168.1.202:5002 -> 127.0.0.1:4002 [s:69 o:11040]
2018-10-01 17:38:09:892733 [INFO] Remove RTP Session 192.168.1.202:5002
2018-10-01 17:38:09:892867 [NOTICE] Destroy TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:09:900071 [NOTICE] Remove Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:900146 [INFO] Session Terminated 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:900210 [NOTICE] Destroy Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:11:522994 [INFO] Input Complete [success] size=0 bytes, dur=0 ms <13331e247bec46aa@gsr>
2018-10-01 17:38:11:523837 [INFO] Process RECOGNITION-COMPLETE Event <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:11:523879 [INFO] State Transition RECOGNIZING -> RECOGNIZED <13331e247bec46aa@speechrecog>
2018-10-01 17:38:11:523966 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [138 bytes]
MRCP/2.0 138 RECOGNITION-COMPLETE 1 COMPLETE
Channel-Identifier: 13331e247bec46aa@speechrecog
Completion-Cause: 002 no-input-timeout
2018-10-01 17:38:11:547184 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547227 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547234 [NOTICE] SIP Call State 0x7fed58001b98 [terminated]
2018-10-01 17:38:11:547247 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547258 [INFO] Deactivate Session 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:547263 [INFO] Terminate Session 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:547345 [INFO] TCP/MRCPv2 Peer Disconnected 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:11:547445 [INFO] Remove Control Channel <13331e247bec46aa@speechrecog> [0]
2018-10-01 17:38:11:547510 [INFO] Close <13331e247bec46aa@gsr>
2018-10-01 17:38:11:547604 [NOTICE] GSR Usage: 0/1/2
2018-10-01 17:38:11:552532 [INFO] Close RTP Receiver 192.168.1.202:5000 <- 127.0.0.1:4000 [r:0 l:0 j:0 p:50 d:0 i:0]
2018-10-01 17:38:11:552576 [INFO] Remove RTP Session 192.168.1.202:5000
2018-10-01 17:38:11:552619 [NOTICE] Destroy TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:11:553871 [NOTICE] Remove Session <13331e247bec46aa>
2018-10-01 17:38:11:553894 [INFO] Session Terminated 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:553918 [NOTICE] Destroy Session <13331e247bec46aa>
======================================================
======================================================
2018-09-28 16:22:41.651451 [DEBUG] mod_unimrcp.c:1584 (TTS-20) READY ==> CLOSED
2018-09-28 16:22:41.651451 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-09-28 16:22:41.651451 [DEBUG] mod_unimrcp.c:858 (TTS-20) audio queue destroyed
2018-09-28 16:22:41.651451 [INFO] switch_ivr_async.c:4464 (sofia/internal/10...@192.168.1.201) WAITING FOR RESULT
2018-09-28 16:22:43.311425 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-09-28 16:22:43.311425 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:57588 <-> 192.168.1.202:1544 [138 bytes]
MRCP/2.0 138 RECOGNITION-COMPLETE 1 COMPLETE
Channel-Identifier: 83db5a23a40041a2@speechrecog
Completion-Cause: 002 no-input-timeout
2018-09-28 16:22:43.311425 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x1e38e60;2;3]
2018-09-28 16:22:43.311425 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-09-28 16:22:43.311425 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x1e38e60;2;3]
2018-09-28 16:22:43.311425 [INFO] mrcp_client_session.c:516 (ASR-19) Raise App MRCP Event ASR-19 <83db5a23a40041a2>
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:3675 (ASR-19) RECOGNITION COMPLETE, Completion-Cause: 002
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:3693 (ASR-19) No result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2733 (ASR-19) ASR adding result headers
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2628 (ASR-19) result:
Completion-Cause: 002
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:1584 (ASR-19) PROCESSING ==> READY
2018-09-28 16:22:43.311425 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2545 (ASR-19) SUCCESS, have result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2545 (ASR-19) SUCCESS, have result
2018-09-28 16:22:43.311425 [DEBUG] mod_unimrcp.c:2786 (ASR-19) result:
Completion-Cause: 002
2018-09-28 16:22:43.351313 [INFO] switch_ivr_async.c:4365 (sofia/internal/10...@192.168.1.201) DETECTED SPEECH
EXECUTE sofia/internal/10...@192.168.1.201 log(INFO YOU SHALL NOT PASS Completion-Cause: 002)
2018-09-28 16:22:43.351313 [INFO] mod_dptools.c:1792 YOU SHALL NOT PASS Completion-Cause: 002
======================================================
*****************************************
Any ideas on why this might be happening?
*****************************************
I also have these output lines on my UniMRCP Log:
======================================================
Remote SDP 0x7fed58001b98 <new>
v=0
o=FreeSWITCH 4927429811839124068 3027661601211682187 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
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=sendonly
a=mid:1
2018-10-01 17:38:06:521699 [INFO] Receive SIP Event [nua_i_invite] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:521742 [INFO] Receive SIP Event [nua_i_state] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:521747 [NOTICE] SIP Call State [received]
2018-10-01 17:38:06:521769 [INFO] Create Session 0x7fed58001b98 <new> [uni2]
2018-10-01 17:38:06:521776 [INFO] Remote SDP 0x7fed58001b98 <new>
v=0
o=FreeSWITCH 4927429811839124068 3027661601211682187 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
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=sendonly
a=mid:1
2018-10-01 17:38:06:522457 [NOTICE] Add Session <13331e247bec46aa>
2018-10-01 17:38:06:522466 [INFO] Receive Offer 0x7fed58001b98 <13331e247bec46aa> [c:1 a:1 v:0]
2018-10-01 17:38:06:522733 [INFO] Add Pending Control Channel <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:523267 [INFO] Enable RTP Session 192.168.1.202:5000
2018-10-01 17:38:06:523291 [INFO] Open RTP Receiver 192.168.1.202:5000 <- 127.0.0.1:4000 playout [50 ms] bounds [0 - 600 ms] adaptive [1] skew detection [1]
2018-10-01 17:38:06:523296 [INFO] Media Path 0x7fed58001b98 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2018-10-01 17:38:06:523552 [INFO] Open <13331e247bec46aa@gsr>
2018-10-01 17:38:06:524629 [NOTICE] GSR Usage: 1/1/2
2018-10-01 17:38:06:524916 [INFO] Send Answer 0x7fed58001b98 <13331e247bec46aa> [c:1 a:1 v:0] Status OK
2018-10-01 17:38:06:524949 [INFO] Local SDP 0x7fed58001b98 <13331e247bec46aa>
v=0
o=UniMRCPServer 0 0 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:13331e247bec46aa@speechrecog
a=cmid:1
m=audio 5000 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=recvonly
a=mid:1
2018-10-01 17:38:06:526107 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526132 [NOTICE] SIP Call State 0x7fed58001b98 [completed]
2018-10-01 17:38:06:526459 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526474 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:526479 [NOTICE] SIP Call State 0x7fed58001b98 [ready]
2018-10-01 17:38:06:526482 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
2018-10-01 17:38:06:527395 [NOTICE] Accepted TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:06:531075 [INFO] Receive MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [202 bytes]
MRCP/2.0 202 RECOGNIZE 1
Channel-Identifier: 13331e247bec46aa@speechrecog
Content-Type: text/uri-list
Cancel-If-Queue: false
Start-Input-Timers: true
Content-Length: 25
builtin:speech/transcribe
2018-10-01 17:38:06:531404 [INFO] Assign Control Channel <13331e247bec46aa@speechrecog> to Connection 192.168.1.202:1544 <-> 192.168.1.201:52590 [0] -> [1]
2018-10-01 17:38:06:531487 [INFO] Process RECOGNIZE Request <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:531551 [INFO] Init Speech Detector: frame-size=160, max-frame-count=360, output-frame-count=20, vad-mode=2, noinput-timeout=5000 ms, input-timeout=10000 ms, start-timeout=300 ms, complete-timeout=1000 ms, incomplete-timeout=3000 ms, leading-silence=300 ms, trailing-silence=300 ms, interim-results=1, start-of-input=external <13331e247bec46aa>
2018-10-01 17:38:06:531621 [INFO] Start No-Input Timer [5000 ms] <13331e247bec46aa>
2018-10-01 17:38:06:531641 [INFO] Create gRPC Stream <13331e247bec46aa@gsr>
2018-10-01 17:38:06:533662 [INFO] Process RECOGNIZE Response <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:06:533682 [INFO] State Transition IDLE -> RECOGNIZING <13331e247bec46aa@speechrecog>
2018-10-01 17:38:06:533884 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: 13331e247bec46aa@speechrecog
2018-10-01 17:38:06:540758 [INFO] Receive SIP Event [nua_i_invite] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:540789 [INFO] Receive SIP Event [nua_i_state] Status 100 Trying [SIP-Agent-1]
2018-10-01 17:38:06:540794 [NOTICE] SIP Call State [received]
2018-10-01 17:38:06:540809 [INFO] Create Session 0x7fed58003eb8 <new> [uni2]
2018-10-01 17:38:06:540815 [INFO] Remote SDP 0x7fed58003eb8 <new>
v=0
o=FreeSWITCH 2180192109642619661 3444725127593390253 IN IP4 192.168.1.201
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:existing
a=resource:speechsynth
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=recvonly
a=mid:1
2018-10-01 17:38:06:540870 [NOTICE] Add Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:06:540875 [INFO] Receive Offer 0x7fed58003eb8 <dbbf5ad2b31c46a5> [c:1 a:1 v:0]
2018-10-01 17:38:06:541217 [INFO] Add Pending Control Channel <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:06:542712 [INFO] Enable RTP Session 192.168.1.202:5002
2018-10-01 17:38:06:542746 [INFO] Open RTP Transmitter 192.168.1.202:5002 -> 127.0.0.1:4002
2018-10-01 17:38:06:542752 [INFO] Media Path 0x7fed58003eb8 Source->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Encoder->[PCMU/8000/1]->Sink
2018-10-01 17:38:06:542797 [INFO] Open <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:06:542863 [NOTICE] GSS Usage: 1/1/2
2018-10-01 17:38:06:542906 [INFO] Send Answer 0x7fed58003eb8 <dbbf5ad2b31c46a5> [c:1 a:1 v:0] Status OK
2018-10-01 17:38:06:542918 [INFO] Local SDP 0x7fed58003eb8 <dbbf5ad2b31c46a5>
v=0
o=UniMRCPServer 0 0 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:dbbf5ad2b31c46a5@speechsynth
a=cmid:1
m=audio 5002 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendonly
a=mid:1
2018-10-01 17:38:06:543290 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:543309 [NOTICE] SIP Call State 0x7fed58003eb8 [completed]
2018-10-01 17:38:06:544020 [INFO] Receive SIP Event [nua_i_ack] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:544057 [INFO] Receive SIP Event [nua_i_state] Status 200 OK [SIP-Agent-1]
2018-10-01 17:38:06:544064 [NOTICE] SIP Call State 0x7fed58003eb8 [ready]
2018-10-01 17:38:06:544066 [INFO] Receive SIP Event [nua_i_active] Status 200 Call active [SIP-Agent-1]
2018-10-01 17:38:06:544804 [NOTICE] Accepted TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:06:550409 [INFO] Receive MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [169 bytes]
MRCP/2.0 169 SPEAK 1
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
Content-Type: text/plain
Voice-Name: en-US-Wavenet-A
Content-Length: 20
Please say something
2018-10-01 17:38:06:550449 [INFO] Assign Control Channel <dbbf5ad2b31c46a5@speechsynth> to Connection 192.168.1.202:1544 <-> 192.168.1.201:52592 [0] -> [1]
2018-10-01 17:38:06:550469 [INFO] Process SPEAK Request <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:06:550508 [INFO] Start Async Synth: encoding=1, sampling-rate=8000, language=en-US <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:08:466489 [INFO] Process SPEAK Response <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:08:466570 [NOTICE] State Transition IDLE -> SPEAKING <dbbf5ad2b31c46a5@speechsynth>
2018-10-01 17:38:08:466744 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
2018-10-01 17:38:09:852933 [NOTICE] Speech Complete <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:09:853289 [INFO] Process SPEAK-COMPLETE Event <dbbf5ad2b31c46a5@speechsynth> [1]
2018-10-01 17:38:09:853335 [NOTICE] State Transition SPEAKING -> IDLE <dbbf5ad2b31c46a5@speechsynth>
2018-10-01 17:38:09:854153 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52592 [122 bytes]
MRCP/2.0 122 SPEAK-COMPLETE 1 COMPLETE
Channel-Identifier: dbbf5ad2b31c46a5@speechsynth
Completion-Cause: 000 normal
2018-10-01 17:38:09:887904 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:887977 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:888078 [NOTICE] SIP Call State 0x7fed58003eb8 [terminated]
2018-10-01 17:38:09:888133 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:09:888255 [INFO] Deactivate Session 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:888278 [INFO] Terminate Session 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:888360 [INFO] Close <dbbf5ad2b31c46a5@gss>
2018-10-01 17:38:09:888388 [NOTICE] GSS Usage: 0/1/2
2018-10-01 17:38:09:888436 [INFO] TCP/MRCPv2 Peer Disconnected 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:09:889394 [INFO] Remove Control Channel <dbbf5ad2b31c46a5@speechsynth> [0]
2018-10-01 17:38:09:892633 [INFO] Close RTP Transmitter 192.168.1.202:5002 -> 127.0.0.1:4002 [s:69 o:11040]
2018-10-01 17:38:09:892733 [INFO] Remove RTP Session 192.168.1.202:5002
2018-10-01 17:38:09:892867 [NOTICE] Destroy TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52592
2018-10-01 17:38:09:900071 [NOTICE] Remove Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:900146 [INFO] Session Terminated 0x7fed58003eb8 <dbbf5ad2b31c46a5>
2018-10-01 17:38:09:900210 [NOTICE] Destroy Session <dbbf5ad2b31c46a5>
2018-10-01 17:38:11:522994 [INFO] Input Complete [success] size=0 bytes, dur=0 ms <13331e247bec46aa@gsr>
2018-10-01 17:38:11:523837 [INFO] Process RECOGNITION-COMPLETE Event <13331e247bec46aa@speechrecog> [1]
2018-10-01 17:38:11:523879 [INFO] State Transition RECOGNIZING -> RECOGNIZED <13331e247bec46aa@speechrecog>
2018-10-01 17:38:11:523966 [INFO] Send MRCPv2 Data 192.168.1.202:1544 <-> 192.168.1.201:52590 [138 bytes]
MRCP/2.0 138 RECOGNITION-COMPLETE 1 COMPLETE
Channel-Identifier: 13331e247bec46aa@speechrecog
Completion-Cause: 002 no-input-timeout
2018-10-01 17:38:11:547184 [INFO] Receive SIP Event [nua_i_bye] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547227 [INFO] Receive SIP Event [nua_i_state] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547234 [NOTICE] SIP Call State 0x7fed58001b98 [terminated]
2018-10-01 17:38:11:547247 [INFO] Receive SIP Event [nua_i_terminated] Status 200 Session Terminated [SIP-Agent-1]
2018-10-01 17:38:11:547258 [INFO] Deactivate Session 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:547263 [INFO] Terminate Session 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:547345 [INFO] TCP/MRCPv2 Peer Disconnected 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:11:547445 [INFO] Remove Control Channel <13331e247bec46aa@speechrecog> [0]
2018-10-01 17:38:11:547510 [INFO] Close <13331e247bec46aa@gsr>
2018-10-01 17:38:11:547604 [NOTICE] GSR Usage: 0/1/2
2018-10-01 17:38:11:552532 [INFO] Close RTP Receiver 192.168.1.202:5000 <- 127.0.0.1:4000 [r:0 l:0 j:0 p:50 d:0 i:0]
2018-10-01 17:38:11:552576 [INFO] Remove RTP Session 192.168.1.202:5000
2018-10-01 17:38:11:552619 [NOTICE] Destroy TCP/MRCPv2 Connection 192.168.1.202:1544 <-> 192.168.1.201:52590
2018-10-01 17:38:11:553871 [NOTICE] Remove Session <13331e247bec46aa>
2018-10-01 17:38:11:553894 [INFO] Session Terminated 0x7fed58001b98 <13331e247bec46aa>
2018-10-01 17:38:11:553918 [NOTICE] Destroy Session <13331e247bec46aa>
======================================================
Felipe Lohnefink
Oct 2, 2018, 10:02:55 AM10/2/18
to UniMRCP
Here the entire log of the this specific call... I think it will help in discovering something...
======================================================
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.775626 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/10...@192.168.1.201 [98ce0982-c5c1-11e8-900b-0dc117fcb7dd]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.775626 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_NEW (Cur 1 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.775626 [DEBUG] sofia.c:10080 sofia/internal/10...@192.168.1.201 receiving invite from 192.168.1.100:44312 version: 1.9.0 git 9844c06 2018-09-12 21:21:46Z 64bit
2018-10-01 18:33:11.775626 [DEBUG] sofia.c:10251 IP 192.168.1.100 Rejected by acl "domains". Falling back to Digest auth.
2018-10-01 18:33:11.775626 [DEBUG] sofia.c:2413 detaching session 98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.775626 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/10...@192.168.1.201) State NEW
2018-10-01 18:33:11.855600 [DEBUG] sofia.c:2522 Re-attaching to session 98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] sofia.c:10080 sofia/internal/10...@192.168.1.201 receiving invite from 192.168.1.100:44312 version: 1.9.0 git 9844c06 2018-09-12 21:21:46Z 64bit
2018-10-01 18:33:11.855600 [DEBUG] sofia.c:10251 IP 192.168.1.100 Rejected by acl "domains". Falling back to Digest auth.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] sofia.c:7291 Channel sofia/internal/10...@192.168.1.201 entering state [received][100]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] sofia.c:7301 Remote SDP:
98ce0982-c5c1-11e8-900b-0dc117fcb7dd v=0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd o=Zoiper 641210328 0 IN IP4 192.168.1.100
98ce0982-c5c1-11e8-900b-0dc117fcb7dd s=Zoiper
98ce0982-c5c1-11e8-900b-0dc117fcb7dd c=IN IP4 192.168.1.100
98ce0982-c5c1-11e8-900b-0dc117fcb7dd t=0 0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd m=audio 46472 RTP/AVP 3 101 0 8
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:3 GSM/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:101 telephone-event/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=fmtp:101 0-16
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:0 PCMU/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:8 PCMA/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] sofia.c:7695 (sofia/internal/10...@192.168.1.201) State Change CS_NEW -> CS_INIT
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_INIT (Cur 1 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/10...@192.168.1.201) State INIT
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] mod_sofia.c:93 sofia/internal/10...@192.168.1.201 SOFIA INIT
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:40 sofia/internal/10...@192.168.1.201 Standard INIT
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/10...@192.168.1.201) State Change CS_INIT -> CS_ROUTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/10...@192.168.1.201) State INIT going to sleep
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_ROUTING (Cur 1 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_channel.c:2249 (sofia/internal/10...@192.168.1.201) Callstate Change DOWN -> RINGING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/10...@192.168.1.201) State ROUTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] mod_sofia.c:154 sofia/internal/10...@192.168.1.201 SOFIA ROUTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:236 sofia/internal/10...@192.168.1.201 Standard ROUTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->9914 in context default
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->unloop] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->tod_example] continue=true
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Date/Time Match (PASS) [tod_example] break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action set(open=true)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->holiday_example] continue=true
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Date/TimeMatch (FAIL) [holiday_example] break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->global-intercept] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [global-intercept] destination_number(9914) =~ /^886$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->group-intercept] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [group-intercept] destination_number(9914) =~ /^\*8$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->intercept-ext] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [intercept-ext] destination_number(9914) =~ /^\*\*(\d+)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->redial] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [redial] destination_number(9914) =~ /^(redial|870)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->global] continue=true
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [global] ${default_password}(1234) =~ /^1234$/ break=never
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action log(CRIT Open /usr/local/freeswitch/conf/vars.xml and change the default_password.)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action log(CRIT Once changed type reloadxml at the console.)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action sleep(10000)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [global] ${switch_r_sdp}(v=0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd o=Zoiper 641210328 0 IN IP4 192.168.1.100
98ce0982-c5c1-11e8-900b-0dc117fcb7dd s=Zoiper
98ce0982-c5c1-11e8-900b-0dc117fcb7dd c=IN IP4 192.168.1.100
98ce0982-c5c1-11e8-900b-0dc117fcb7dd t=0 0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd m=audio 46472 RTP/AVP 3 101 0 8
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:3 GSM/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:101 telephone-event/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=fmtp:101 0-16
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:0 PCMU/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:8 PCMA/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Absolute Condition [global]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action hash(insert/${domain_name}-last_dial/global/${uuid})
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->snom-demo-2] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [snom-demo-2] destination_number(9914) =~ /^9001$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->snom-demo-1] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [snom-demo-1] destination_number(9914) =~ /^9000$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->eavesdrop] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [eavesdrop] destination_number(9914) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->eavesdrop] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [eavesdrop] destination_number(9914) =~ /^779$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->call_return] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [call_return] destination_number(9914) =~ /^\*69$|^869$|^lcr$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->del-group] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [del-group] destination_number(9914) =~ /^80(\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->add-group] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [add-group] destination_number(9914) =~ /^81(\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->call-group-simo] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [call-group-simo] destination_number(9914) =~ /^82(\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->call-group-order] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [call-group-order] destination_number(9914) =~ /^83(\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->extension-intercom] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [extension-intercom] destination_number(9914) =~ /^8(10[01][0-9])$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Local_Extension] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Local_Extension] destination_number(9914) =~ /^(10[01][0-9])$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Local_Extension_Skinny] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Local_Extension_Skinny] destination_number(9914) =~ /^(11[01][0-9])$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->group_dial_sales] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [group_dial_sales] destination_number(9914) =~ /^2000$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->group_dial_support] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [group_dial_support] destination_number(9914) =~ /^2001$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->group_dial_billing] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [group_dial_billing] destination_number(9914) =~ /^2002$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->operator] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [operator] destination_number(9914) =~ /^(operator|0)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->vmain] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [vmain] destination_number(9914) =~ /^vmain$|^4000$|^\*98$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->sip_uri] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [sip_uri] destination_number(9914) =~ /^sip:(.*)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->nb_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [nb_conferences] destination_number(9914) =~ /^(30\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->wb_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [wb_conferences] destination_number(9914) =~ /^(31\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->uwb_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [uwb_conferences] destination_number(9914) =~ /^(32\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->cdquality_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [cdquality_conferences] destination_number(9914) =~ /^(33\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->cdquality_stereo_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [cdquality_stereo_conferences] destination_number(9914) =~ /^(35\d{2}).*?-screen$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->conference-canvases] continue=true
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [conference-canvases] destination_number(9914) =~ /(35\d{2})-canvas-(\d+)/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->conf mod] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [conf mod] destination_number(9914) =~ /^6070-moderator$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->cdquality_conferences] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [cdquality_conferences] destination_number(9914) =~ /^(35\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->freeswitch_public_conf_via_sip] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(9914) =~ /^9(888|8888|1616|3232)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->mad_boss_intercom] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [mad_boss_intercom] destination_number(9914) =~ /^0911$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->mad_boss_intercom] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [mad_boss_intercom] destination_number(9914) =~ /^0912$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->mad_boss] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [mad_boss] destination_number(9914) =~ /^0913$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ivr_demo] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ivr_demo] destination_number(9914) =~ /^5000$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->dynamic_conference] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [dynamic_conference] destination_number(9914) =~ /^5001$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->rtp_multicast_page] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [rtp_multicast_page] destination_number(9914) =~ /^pagegroup$|^7243$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->park] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [park] destination_number(9914) =~ /^5900$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->unpark] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [unpark] destination_number(9914) =~ /^5901$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->valet_park] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [valet_park] destination_number(9914) =~ /^(6000)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->valet_park] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [valet_park] destination_number(9914) =~ /^((?!6000)60\d{2})$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->park] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [park] destination_number(9914) =~ /park\+(\d+)/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->unpark] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [unpark] destination_number(9914) =~ /^parking$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->park] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [park] destination_number(9914) =~ /callpark/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->unpark] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [unpark] destination_number(9914) =~ /pickup/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->wait] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [wait] destination_number(9914) =~ /^wait$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->fax_receive] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [fax_receive] destination_number(9914) =~ /^9178$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->fax_transmit] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [fax_transmit] destination_number(9914) =~ /^9179$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ringback_180] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ringback_180] destination_number(9914) =~ /^9180$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ringback_183_uk_ring] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ringback_183_uk_ring] destination_number(9914) =~ /^9181$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ringback_183_music_ring] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ringback_183_music_ring] destination_number(9914) =~ /^9182$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ringback_post_answer_uk_ring] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(9914) =~ /^9183$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ringback_post_answer_music] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ringback_post_answer_music] destination_number(9914) =~ /^9184$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->ClueCon] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [ClueCon] destination_number(9914) =~ /^9191$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->show_info] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [show_info] destination_number(9914) =~ /^9192$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->video_record] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [video_record] destination_number(9914) =~ /^9193$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->video_playback] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [video_playback] destination_number(9914) =~ /^9194$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->delay_echo] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [delay_echo] destination_number(9914) =~ /^9195$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->echo] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [echo] destination_number(9914) =~ /^9196$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->milliwatt] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [milliwatt] destination_number(9914) =~ /^9197$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->tone_stream] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [tone_stream] destination_number(9914) =~ /^9198$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->zrtp_enrollement] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [zrtp_enrollement] destination_number(9914) =~ /^9787$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->hold_music] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [hold_music] destination_number(9914) =~ /^9664$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->laugh break] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [laugh break] destination_number(9914) =~ /^9386$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->101] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [101] destination_number(9914) =~ /^101$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->pizza_demo] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [pizza_demo] destination_number(9914) =~ /^(pizza|74992)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Talking Clock Time] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Talking Clock Time] destination_number(9914) =~ /^9170$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Talking Clock Date] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Talking Clock Date] destination_number(9914) =~ /^9171$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Talking Clock Date and Time] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Talking Clock Date and Time] destination_number(9914) =~ /^9172$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Simple Lua Test] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Simple Lua Test] destination_number(9914) =~ /^(9910)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Read Back Entered Digits] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Read Back Entered Digits] destination_number(9914) =~ /^(9911)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Read Back Entered Digits #2] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Read Back Entered Digits #2] destination_number(9914) =~ /^(9912)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->Record Sound Files Utility] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (FAIL) [Record Sound Files Utility] destination_number(9914) =~ /^(9913)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 parsing [default->MRCP Test] continue=false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Regex (PASS) [MRCP Test] destination_number(9914) =~ /^(9914)$/ break=on-false
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action set(tts_engine=unimrcp)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action set(tts_voice=en-US-Wavenet-A)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action play_and_detect_speech(say: Please say something detect:unimrcp {start-input-timers=true}builtin:speech/transcribe)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Dialplan: sofia/internal/10...@192.168.1.201 Action log(INFO YOU SHALL NOT PASS ${detect_speech_result})
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/10...@192.168.1.201) State Change CS_ROUTING -> CS_EXECUTE
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/10...@192.168.1.201) State ROUTING going to sleep
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_EXECUTE (Cur 1 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/10...@192.168.1.201) State EXECUTE
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] mod_sofia.c:209 sofia/internal/10...@192.168.1.201 SOFIA EXECUTE
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] switch_core_state_machine.c:328 sofia/internal/10...@192.168.1.201 Standard EXECUTE
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 set(open=true)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [DEBUG] mod_dptools.c:1598 SET sofia/internal/10...@192.168.1.201 [open]=[true]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [CRIT] mod_dptools.c:1792 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 log(CRIT Open /usr/local/freeswitch/conf/vars.xml and change the default_password.)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [CRIT] mod_dptools.c:1792 Open /usr/local/freeswitch/conf/vars.xml and change the default_password.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 log(CRIT Once changed type reloadxml at the console.)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [CRIT] mod_dptools.c:1792 Once changed type reloadxml at the console.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:11.855600 [CRIT] mod_dptools.c:1792 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 sleep(10000)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 hash(insert/192.168.1.201-spymap/1001/98ce0982-c5c1-11e8-900b-0dc117fcb7dd)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 hash(insert/192.168.1.201-last_dial/1001/9914)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 hash(insert/192.168.1.201-last_dial/global/98ce0982-c5c1-11e8-900b-0dc117fcb7dd)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 export(RFC2822_DATE=Mon, 01 Oct 2018 18:33:21 -0300)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 01 Oct 2018 18:33:21 -0300]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 set(tts_engine=unimrcp)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] mod_dptools.c:1598 SET sofia/internal/10...@192.168.1.201 [tts_engine]=[unimrcp]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 set(tts_voice=en-US-Wavenet-A)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] mod_dptools.c:1598 SET sofia/internal/10...@192.168.1.201 [tts_voice]=[en-US-Wavenet-A]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_session.c:2709 Application play_and_detect_speech Requires media! pre_answering channel sofia/internal/10...@192.168.1.201
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [INFO] switch_core_session.c:2711 Sending early media
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/10...@192.168.1.201 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_codec.c:111 sofia/internal/10...@192.168.1.201 Original read codec set to PCMU:0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:5795 sofia/internal/10...@192.168.1.201 Set 2833 dtmf send payload to 101 recv payload to 101
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:8511 AUDIO RTP [sofia/internal/10...@192.168.1.201] 192.168.1.201 port 32496 -> 192.168.1.100 port 46472 codec: 0 ms: 20
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_rtp.c:4299 Starting timer [soft] 160 bytes per 20ms
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:8815 sofia/internal/10...@192.168.1.201 Set 2833 dtmf send payload to 101
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:8822 sofia/internal/10...@192.168.1.201 Set 2833 dtmf receive payload to 101
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:8845 sofia/internal/10...@192.168.1.201 Set rtp dtmf delay to 40
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/10...@192.168.1.201!
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_channel.c:3482 (sofia/internal/10...@192.168.1.201) Callstate Change RINGING -> EARLY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] switch_core_media.c:8494 Audio params are unchanged for sofia/internal/10...@192.168.1.201.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] mod_sofia.c:2514 Ring SDP:
98ce0982-c5c1-11e8-900b-0dc117fcb7dd v=0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd o=FreeSWITCH 1538397105 1538397106 IN IP4 192.168.1.201
98ce0982-c5c1-11e8-900b-0dc117fcb7dd s=FreeSWITCH
98ce0982-c5c1-11e8-900b-0dc117fcb7dd c=IN IP4 192.168.1.201
98ce0982-c5c1-11e8-900b-0dc117fcb7dd t=0 0
98ce0982-c5c1-11e8-900b-0dc117fcb7dd m=audio 32496 RTP/AVP 0 101
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:0 PCMU/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=rtpmap:101 telephone-event/8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=fmtp:101 0-16
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=ptime:20
98ce0982-c5c1-11e8-900b-0dc117fcb7dd a=sendrecv
98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] sofia.c:7291 Channel sofia/internal/10...@192.168.1.201 entering state [early][183]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 play_and_detect_speech(say: Please say something detect:unimrcp {start-input-timers=true}builtin:speech/transcribe)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [INFO] mod_unimrcp.c:3134 asr_handle: name = unimrcp, codec = (null), rate = 8000, grammar = (null), param = (null)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [INFO] mod_unimrcp.c:3136 codec = L16, rate = 8000, dest = (null)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:21.955647 [DEBUG] mod_unimrcp.c:690 (ASR-2) audio queue created
2018-10-01 18:33:21.955647 [NOTICE] mrcp_application.c:96 (ASR-2) Create MRCP Handle 0x7f7af00fbc30 [gandalf]
2018-10-01 18:33:21.955647 [INFO] mrcp_client_session.c:133 (ASR-2) Create Channel ASR-2 <new>
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0027370;4;0]
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0027370;4;0]
2018-10-01 18:33:21.955647 [INFO] mrcp_client_session.c:387 (ASR-2) Receive App Request ASR-2 <new> [2]
2018-10-01 18:33:21.955647 [INFO] mrcp_client.c:700 (ASR-2) Add MRCP Handle ASR-2 <new>
2018-10-01 18:33:21.955647 [DEBUG] mrcp_client_session.c:1283 (ASR-2) Dispatch App Request ASR-2 <new> [2]
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1e19720;1;0]
2018-10-01 18:33:21.955647 [NOTICE] mrcp_client_session.c:719 (ASR-2) Add Control Channel ASR-2 <new@speechrecog>
2018-10-01 18:33:21.955647 [DEBUG] mrcp_client_session.c:745 (ASR-2) Add Media Termination ASR-2 <new@media-tm>
2018-10-01 18:33:21.955647 [DEBUG] mrcp_client_session.c:777 (ASR-2) Add Media Termination ASR-2 <new@rtp-tm>
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1ef1610;1;0]
2018-10-01 18:33:21.955647 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:21.955647 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1e19720;1;0]
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;0]
2018-10-01 18:33:21.955647 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:21.955647 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;0]
2018-10-01 18:33:21.955647 [DEBUG] mrcp_client_session.c:294 (ASR-2) Control Channel Added ASR-2 <new@speechrecog>
2018-10-01 18:33:21.955647 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.001748 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1ef1610;1;0]
2018-10-01 18:33:22.001748 [DEBUG] mpf_context.c:182 () Add Media Context ASR-2
2018-10-01 18:33:22.001748 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af001d010;3;0]
2018-10-01 18:33:22.001748 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af001d010;3;0]
2018-10-01 18:33:22.001748 [DEBUG] mrcp_client_session.c:944 (ASR-2) Media Termination Added ASR-2 <new@media-tm>
2018-10-01 18:33:22.001748 [DEBUG] mrcp_client_session.c:944 (ASR-2) Media Termination Added ASR-2 <new@rtp-tm>
2018-10-01 18:33:22.001748 [INFO] mrcp_client_session.c:411 (ASR-2) Send Offer ASR-2 <new> [c:1 a:1 v:0] to 192.168.1.202:8060
2018-10-01 18:33:22.001748 [INFO] mrcp_sofiasip_client_agent.c:354 (ASR-2) Local SDP ASR-2 <new>
v=0
o=FreeSWITCH 0 0 IN IP4 127.0.0.1
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:new
a=resource:speechrecog
a=cmid:1
m=audio 4004 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
2018-10-01 18:33:22.001748 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.001748 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 0 INVITE sent [gandalf]
2018-10-01 18:33:22.001748 [NOTICE] mrcp_sofiasip_client_agent.c:547 (ASR-2) SIP Call State ASR-2 [calling]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_r_invite] Status 200 OK [gandalf]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 200 OK [gandalf]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_sofiasip_client_agent.c:547 (ASR-2) SIP Call State ASR-2 [ready]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:433 (ASR-2) Remote SDP ASR-2 <new>
v=0
o=UniMRCPServer 1397046326234004133 7120249517562176668 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:1c311e1fe8aa4cb8@speechrecog
a=cmid:1
m=audio 5008 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=recvonly
a=mid:1
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ae403be40;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_active] Status 200 Call active [gandalf]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ae403be40;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:151 (ASR-2) Receive Answer ASR-2 <new> [c:1 a:1 v:0] Status 200
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:1141 (ASR-2) Modify Control Channel ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1e19720;1;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:1180 (ASR-2) Modify Media Termination ASR-2 <1c311e1fe8aa4cb8@rtp-tm>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1e29b00;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1e19720;1;0]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_client_connection.c:338 () Established TCP/MRCPv2 Connection 192.168.1.201:52936 <-> 192.168.1.202:1544
2018-10-01 18:33:22.015817 [INFO] mrcp_client_connection.c:435 (ASR-2) Add Control Channel <1c311e1fe8aa4cb8@speechrecog> 192.168.1.201:52936 <-> 192.168.1.202:1544 [1]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;1]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;1]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:311 (ASR-2) Control Channel Modified ASR-2 <1c311e1fe8aa4cb8@speechrecog>
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1e29b00;1;0]
2018-10-01 18:33:22.015817 [INFO] mpf_rtp_stream.c:331 () Enable RTP Session 127.0.0.1:4004
2018-10-01 18:33:22.015817 [DEBUG] mpf_bridge.c:129 () Create Linear Audio Bridge ASR-2
2018-10-01 18:33:22.015817 [INFO] mpf_rtp_stream.c:921 () Open RTP Transmitter 127.0.0.1:4004 -> 192.168.1.202:5008
2018-10-01 18:33:22.015817 [INFO] mpf_bridge.c:93 () Media Path ASR-2 Source->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Encoder->[PCMU/8000/1]->Sink
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af001d010;3;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af001d010;3;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:985 (ASR-2) Media Termination Modified ASR-2 <1c311e1fe8aa4cb8@rtp-tm>
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:455 (ASR-2) Raise App Response ASR-2 <1c311e1fe8aa4cb8> [2] SUCCESS [0]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1900 (ASR-2) RECOGNIZER channel is ready, codec = LPCM, sample rate = 8000
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1584 (ASR-2) CLOSED ==> READY
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1062 (ASR-2) channel is ready
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] switch_core_media_bug.c:962 Attaching BUG to sofia/internal/10...@192.168.1.201
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1465 (ASR-2) param = start-input-timers, val = true
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:3201 (ASR-2) grammar = builtin:speech/transcribe, name =
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:3218 (ASR-2) Grammar is URI
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:3290 (ASR-2) grammar is text/uri-list
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:2361 (ASR-2) Loading grammar 9ee92fae-c5c1-11e8-901b-0dc117fcb7dd, data = builtin:speech/transcribe
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:2526 (ASR-2) Disabling all grammars
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:2485 (ASR-2) Enabling grammar 9ee92fae-c5c1-11e8-901b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:2848 (ASR-2) "start-input-timers": "true"
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af00392d0;4;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af00392d0;4;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:392 (ASR-2) Receive App MRCP Request ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:622 (ASR-2) Send MRCP Request ASR-2 <1c311e1fe8aa4cb8@speechrecog> [1]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_connection.c:530 (ASR-2) Send MRCPv2 Data 192.168.1.201:52936 <-> 192.168.1.202:1544 [202 bytes]
MRCP/2.0 202 RECOGNIZE 1
Channel-Identifier: 1c311e1fe8aa4cb8@speechrecog
Content-Type: text/uri-list
Cancel-If-Queue: false
Start-Input-Timers: true
Content-Length: 25
builtin:speech/transcribe
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:246 () Wait for Messages [MRCPv2ConnectionAgent] timeout [3000]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:52936 <-> 192.168.1.202:1544 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: 1c311e1fe8aa4cb8@speechrecog
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:500 (ASR-2) Raise App MRCP Response ASR-2 <1c311e1fe8aa4cb8>
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:3610 (ASR-2) RECOGNIZE IN PROGRESS
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1584 (ASR-2) READY ==> PROCESSING
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [INFO] mod_unimrcp.c:1631 speech_handle: name = unimrcp, rate = 8000, speed = 0, samples = 160, voice = , engine = unimrcp, param = (null)
2018-10-01 18:33:22.015817 [INFO] mod_unimrcp.c:1634 voice = en-US-Wavenet-A, rate = 8000
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:690 (TTS-3) audio queue created
2018-10-01 18:33:22.015817 [NOTICE] mrcp_application.c:96 (TTS-3) Create MRCP Handle 0x7f7af01305b0 [gandalf]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:133 (TTS-3) Create Channel TTS-3 <new>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:387 (TTS-3) Receive App Request TTS-3 <new> [2]
2018-10-01 18:33:22.015817 [INFO] mrcp_client.c:700 (TTS-3) Add MRCP Handle TTS-3 <new>
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:1283 (TTS-3) Dispatch App Request TTS-3 <new> [2]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_client_session.c:719 (TTS-3) Add Control Channel TTS-3 <new@speechsynth>
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:745 (TTS-3) Add Media Termination TTS-3 <new@media-tm>
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:777 (TTS-3) Add Media Termination TTS-3 <new@rtp-tm>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1e29e10;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:294 (TTS-3) Control Channel Added TTS-3 <new@speechsynth>
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1e29e10;1;0]
2018-10-01 18:33:22.015817 [DEBUG] mpf_context.c:182 () Add Media Context TTS-3
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:944 (TTS-3) Media Termination Added TTS-3 <new@media-tm>
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:944 (TTS-3) Media Termination Added TTS-3 <new@rtp-tm>
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:411 (TTS-3) Send Offer TTS-3 <new> [c:1 a:1 v:0] to 192.168.1.202:8060
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:354 (TTS-3) Local SDP TTS-3 <new>
v=0
o=FreeSWITCH 0 0 IN IP4 127.0.0.1
s=-
c=IN IP4 127.0.0.1
t=0 0
m=application 9 TCP/MRCPv2 1
a=setup:active
a=connection:existing
a=resource:speechsynth
a=cmid:1
m=audio 4006 RTP/AVP 0 8 96
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:96 L16/8000
a=recvonly
a=mid:1
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 0 INVITE sent [gandalf]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_sofiasip_client_agent.c:547 (TTS-3) SIP Call State TTS-3 [calling]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_r_invite] Status 200 OK [gandalf]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 200 OK [gandalf]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_sofiasip_client_agent.c:547 (TTS-3) SIP Call State TTS-3 [ready]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:433 (TTS-3) Remote SDP TTS-3 <new>
v=0
o=UniMRCPServer 2275848863285399921 2737025834093878145 IN IP4 192.168.1.202
s=-
c=IN IP4 192.168.1.202
t=0 0
m=application 1544 TCP/MRCPv2 1
a=setup:passive
a=connection:new
a=channel:fd4cf168fd2c4b6b@speechsynth
a=cmid:1
m=audio 5010 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=sendonly
a=mid:1
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ae403be40;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_active] Status 200 Call active [gandalf]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ae403be40;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:151 (TTS-3) Receive Answer TTS-3 <new> [c:1 a:1 v:0] Status 200
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:1141 (TTS-3) Modify Control Channel TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:1180 (TTS-3) Modify Media Termination TTS-3 <fd4cf168fd2c4b6b@rtp-tm>
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1e0e500;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1f70c90;1;0]
2018-10-01 18:33:22.015817 [NOTICE] mrcp_client_connection.c:338 () Established TCP/MRCPv2 Connection 192.168.1.201:52938 <-> 192.168.1.202:1544
2018-10-01 18:33:22.015817 [INFO] mrcp_client_connection.c:435 (TTS-3) Add Control Channel <fd4cf168fd2c4b6b@speechsynth> 192.168.1.201:52938 <-> 192.168.1.202:1544 [1]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;1]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;1]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:311 (TTS-3) Control Channel Modified TTS-3 <fd4cf168fd2c4b6b@speechsynth>
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1e0e500;1;0]
2018-10-01 18:33:22.015817 [INFO] mpf_rtp_stream.c:331 () Enable RTP Session 127.0.0.1:4006
2018-10-01 18:33:22.015817 [DEBUG] mpf_bridge.c:129 () Create Linear Audio Bridge TTS-3
2018-10-01 18:33:22.015817 [INFO] mpf_rtp_stream.c:509 () Open RTP Receiver 127.0.0.1:4006 <- 192.168.1.202:5010 playout [0 ms] bounds [0 - 600 ms] adaptive [0] skew detection [1]
2018-10-01 18:33:22.015817 [INFO] mpf_bridge.c:93 () Media Path TTS-3 Source->[PCMU/8000/1]->Decoder->[LPCM/8000/1]->Bridge->[LPCM/8000/1]->Sink
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:22.015817 [DEBUG] mrcp_client_session.c:985 (TTS-3) Media Termination Modified TTS-3 <fd4cf168fd2c4b6b@rtp-tm>
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:455 (TTS-3) Raise App Response TTS-3 <fd4cf168fd2c4b6b> [2] SUCCESS [0]
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1900 (TTS-3) SYNTHESIZER channel is ready, codec = LPCM, sample rate = 8000
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1584 (TTS-3) CLOSED ==> READY
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1062 (TTS-3) channel is ready
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1465 (TTS-3) param = Voice-Name, val = en-US-Wavenet-A
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] switch_ivr_play_say.c:3009 OPEN TTS unimrcp
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:22.015817 [DEBUG] switch_ivr_play_say.c:3019 Raw Codec Activated
2018-10-01 18:33:22.015817 [DEBUG] mod_unimrcp.c:1200 (TTS-3) Voice-Name: en-US-Wavenet-A
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:392 (TTS-3) Receive App MRCP Request TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:22.015817 [INFO] mrcp_client_session.c:622 (TTS-3) Send MRCP Request TTS-3 <fd4cf168fd2c4b6b@speechsynth> [1]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1e22cd0;1;0]
2018-10-01 18:33:22.015817 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:22.015817 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1e22cd0;1;0]
2018-10-01 18:33:22.015817 [INFO] mrcp_client_connection.c:530 (TTS-3) Send MRCPv2 Data 192.168.1.201:52938 <-> 192.168.1.202:1544 [169 bytes]
MRCP/2.0 169 SPEAK 1
Channel-Identifier: fd4cf168fd2c4b6b@speechsynth
Content-Type: text/plain
Voice-Name: en-US-Wavenet-A
Content-Length: 20
Please say something
2018-10-01 18:33:22.015817 [DEBUG] apt_poller_task.c:246 () Wait for Messages [MRCPv2ConnectionAgent] timeout [3000]
2018-10-01 18:33:23.795515 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-10-01 18:33:23.795515 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:52938 <-> 192.168.1.202:1544 [83 bytes]
MRCP/2.0 83 1 200 IN-PROGRESS
Channel-Identifier: fd4cf168fd2c4b6b@speechsynth
2018-10-01 18:33:23.795515 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:23.795515 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:23.795515 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:23.795515 [INFO] mrcp_client_session.c:500 (TTS-3) Raise App MRCP Response TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:23.795515 [DEBUG] mod_unimrcp.c:1973 (TTS-3) REQUEST IN PROGRESS
2018-10-01 18:33:23.795515 [DEBUG] mod_unimrcp.c:1584 (TTS-3) READY ==> PROCESSING
2018-10-01 18:33:23.795515 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:23.795515 [DEBUG] switch_ivr_play_say.c:2713 Speaking text: Please say something
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:23.815601 [DEBUG] switch_rtp.c:7549 Correct audio ip/port confirmed.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:23.815601 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
2018-10-01 18:33:25.195521 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-10-01 18:33:25.195521 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:52938 <-> 192.168.1.202:1544 [122 bytes]
MRCP/2.0 122 SPEAK-COMPLETE 1 COMPLETE
Channel-Identifier: fd4cf168fd2c4b6b@speechsynth
Completion-Cause: 000 normal
2018-10-01 18:33:25.195521 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:25.195521 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:25.195521 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:25.195521 [INFO] mrcp_client_session.c:516 (TTS-3) Raise App MRCP Event TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:25.195521 [DEBUG] mod_unimrcp.c:2003 (TTS-3) SPEAK-COMPLETE
2018-10-01 18:33:25.195521 [DEBUG] mod_unimrcp.c:1584 (TTS-3) PROCESSING ==> DONE
2018-10-01 18:33:25.195521 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:25.216893 [DEBUG] mod_unimrcp.c:1584 (TTS-3) DONE ==> READY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:25.216893 [DEBUG] switch_ivr_play_say.c:2891 done speaking text
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:25.216893 [DEBUG] mod_unimrcp.c:930 (TTS-3) Waiting for MRCP session to terminate
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0077100;4;0]
2018-10-01 18:33:25.216893 [INFO] mrcp_client_session.c:387 (TTS-3) Receive App Request TTS-3 <fd4cf168fd2c4b6b> [1]
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:1283 (TTS-3) Dispatch App Request TTS-3 <fd4cf168fd2c4b6b> [1]
2018-10-01 18:33:25.216893 [INFO] mrcp_client_session.c:833 (TTS-3) Terminate Session TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:854 (TTS-3) Remove Control Channel TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1f56b10;1;0]
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:864 (TTS-3) Subtract Media Termination TTS-3 <fd4cf168fd2c4b6b@media-tm>
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:885 (TTS-3) Subtract Media Termination TTS-3 <fd4cf168fd2c4b6b@rtp-tm>
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1e741b0;1;0]
2018-10-01 18:33:25.216893 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:25.216893 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1f56b10;1;0]
2018-10-01 18:33:25.216893 [INFO] mrcp_client_connection.c:459 (TTS-3) Remove Control Channel <fd4cf168fd2c4b6b@speechsynth> [0]
2018-10-01 18:33:25.216893 [INFO] mrcp_client_connection.c:386 () Close TCP/MRCPv2 Connection 192.168.1.201:52938 <-> 192.168.1.202:1544
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;2]
2018-10-01 18:33:25.216893 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;2]
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:331 (TTS-3) Control Channel Removed TTS-3 <fd4cf168fd2c4b6b@speechsynth>
2018-10-01 18:33:25.216893 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:25.216893 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_r_bye] Status 200 OK [gandalf]
2018-10-01 18:33:25.216893 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 200 to BYE [gandalf]
2018-10-01 18:33:25.216893 [NOTICE] mrcp_sofiasip_client_agent.c:547 (TTS-3) SIP Call State TTS-3 [terminated]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ae403be40;1;1]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ae403be40;1;1]
2018-10-01 18:33:25.216893 [INFO] mrcp_client_session.c:209 (TTS-3) Session Terminated TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:25.216893 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1e741b0;1;0]
2018-10-01 18:33:25.216893 [DEBUG] mpf_bridge.c:100 () Destroy Audio Bridge TTS-3
2018-10-01 18:33:25.216893 [INFO] mpf_rtp_stream.c:541 () Close RTP Receiver 127.0.0.1:4006 <- 192.168.1.202:5010 [r:0 l:0 j:0 p:0 d:0 i:0]
2018-10-01 18:33:25.216893 [DEBUG] mpf_context.c:238 () Remove Media Context TTS-3
2018-10-01 18:33:25.216893 [INFO] mpf_rtp_stream.c:418 () Remove RTP Session 127.0.0.1:4006
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:25.216893 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0027290;3;0]
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:1014 (TTS-3) Media Termination Subtracted TTS-3 <fd4cf168fd2c4b6b@media-tm>
2018-10-01 18:33:25.216893 [DEBUG] mrcp_client_session.c:1014 (TTS-3) Media Termination Subtracted TTS-3 <fd4cf168fd2c4b6b@rtp-tm>
2018-10-01 18:33:25.216893 [NOTICE] mrcp_client_connection.c:235 () Destroy TCP/MRCPv2 Connection 192.168.1.201:52938 <-> 192.168.1.202:1544
2018-10-01 18:33:25.216893 [INFO] mrcp_client.c:710 (TTS-3) Remove MRCP Handle TTS-3 <fd4cf168fd2c4b6b>
2018-10-01 18:33:25.216893 [INFO] mrcp_client_session.c:455 (TTS-3) Raise App Response TTS-3 <fd4cf168fd2c4b6b> [1] SUCCESS [0]
2018-10-01 18:33:25.216893 [DEBUG] mod_unimrcp.c:1835 (TTS-3) Destroying MRCP session
2018-10-01 18:33:25.216893 [NOTICE] mrcp_application.c:182 (TTS-3) Destroy MRCP Handle TTS-3
2018-10-01 18:33:25.216893 [DEBUG] mod_unimrcp.c:1584 (TTS-3) READY ==> CLOSED
2018-10-01 18:33:25.216893 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:25.216893 [DEBUG] mod_unimrcp.c:858 (TTS-3) audio queue destroyed
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:25.216893 [INFO] switch_ivr_async.c:4464 (sofia/internal/10...@192.168.1.201) WAITING FOR RESULT
2018-10-01 18:33:27.015685 [DEBUG] apt_poller_task.c:269 () Process Signalled Descriptor [MRCPv2ConnectionAgent]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_connection.c:635 () Receive MRCPv2 Data 192.168.1.201:52936 <-> 192.168.1.202:1544 [138 bytes]
MRCP/2.0 138 RECOGNITION-COMPLETE 1 COMPLETE
Channel-Identifier: 1c311e1fe8aa4cb8@speechrecog
Completion-Cause: 002 no-input-timeout
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:27.015685 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;3]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_session.c:516 (ASR-2) Raise App MRCP Event ASR-2 <1c311e1fe8aa4cb8>
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:3675 (ASR-2) RECOGNITION COMPLETE, Completion-Cause: 002
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:3693 (ASR-2) No result
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:2733 (ASR-2) ASR adding result headers
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:2628 (ASR-2) result:
98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Completion-Cause: 002
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:1584 (ASR-2) PROCESSING ==> READY
2018-10-01 18:33:27.015685 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:2545 (ASR-2) SUCCESS, have result
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:2545 (ASR-2) SUCCESS, have result
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:2786 (ASR-2) result:
98ce0982-c5c1-11e8-900b-0dc117fcb7dd
98ce0982-c5c1-11e8-900b-0dc117fcb7dd Completion-Cause: 002
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [INFO] switch_ivr_async.c:4365 (sofia/internal/10...@192.168.1.201) DETECTED SPEECH
98ce0982-c5c1-11e8-900b-0dc117fcb7dd EXECUTE sofia/internal/10...@192.168.1.201 log(INFO YOU SHALL NOT PASS Completion-Cause: 002)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [INFO] mod_dptools.c:1792 YOU SHALL NOT PASS Completion-Cause: 002
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [NOTICE] switch_core_state_machine.c:385 sofia/internal/10...@192.168.1.201 has executed the last dialplan instruction, hanging up.
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/10...@192.168.1.201 [CS_EXECUTE] [NORMAL_CLEARING]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/10...@192.168.1.201) State EXECUTE going to sleep
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_HANGUP (Cur 1 Tot 2)
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af00276e0;4;0]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:930 (ASR-2) Waiting for MRCP session to terminate
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af00276e0;4;0]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_session.c:387 (ASR-2) Receive App Request ASR-2 <1c311e1fe8aa4cb8> [1]
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:1283 (ASR-2) Dispatch App Request ASR-2 <1c311e1fe8aa4cb8> [1]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_session.c:833 (ASR-2) Terminate Session ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:854 (ASR-2) Remove Control Channel ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCPv2ConnectionAgent] [0x1ced100;1;0]
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:864 (ASR-2) Subtract Media Termination ASR-2 <1c311e1fe8aa4cb8@media-tm>
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:885 (ASR-2) Subtract Media Termination ASR-2 <1c311e1fe8aa4cb8@rtp-tm>
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MediaEngine] [0x1e29970;1;0]
2018-10-01 18:33:27.015685 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:27.015685 [DEBUG] apt_poller_task.c:261 () Process Poller Wakeup [MRCPv2ConnectionAgent]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCPv2ConnectionAgent] [0x1ced100;1;0]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_connection.c:459 (ASR-2) Remove Control Channel <1c311e1fe8aa4cb8@speechrecog> [0]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_connection.c:386 () Close TCP/MRCPv2 Connection 192.168.1.201:52936 <-> 192.168.1.202:1544
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ad803f280;2;2]
2018-10-01 18:33:27.015685 [DEBUG] apt_poller_task.c:251 () Wait for Messages [MRCPv2ConnectionAgent]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ad803f280;2;2]
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:331 (ASR-2) Control Channel Removed ASR-2 <1c311e1fe8aa4cb8@speechrecog>
2018-10-01 18:33:27.015685 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:27.015685 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_r_bye] Status 200 OK [gandalf]
2018-10-01 18:33:27.015685 [INFO] mrcp_sofiasip_client_agent.c:609 () Receive SIP Event [nua_i_state] Status 200 to BYE [gandalf]
2018-10-01 18:33:27.015685 [NOTICE] mrcp_sofiasip_client_agent.c:547 (ASR-2) SIP Call State ASR-2 [terminated]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7ae403be40;1;1]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7ae403be40;1;1]
2018-10-01 18:33:27.015685 [INFO] mrcp_client_session.c:209 (ASR-2) Session Terminated ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:27.015685 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MediaEngine] [0x1e29970;1;0]
2018-10-01 18:33:27.015685 [DEBUG] mpf_bridge.c:100 () Destroy Audio Bridge ASR-2
2018-10-01 18:33:27.015685 [INFO] mpf_rtp_stream.c:935 () Close RTP Transmitter 127.0.0.1:4004 -> 192.168.1.202:5008 [s:0 o:0]
2018-10-01 18:33:27.015685 [DEBUG] mpf_context.c:238 () Remove Media Context ASR-2
2018-10-01 18:33:27.015685 [INFO] mpf_rtp_stream.c:418 () Remove RTP Session 127.0.0.1:4004
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:265 () Signal Message to [MRCP Client] [0x7f7af0039870;3;0]
2018-10-01 18:33:27.015685 [DEBUG] apt_task.c:337 () Process Message [MRCP Client] [0x7f7af0039870;3;0]
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:1014 (ASR-2) Media Termination Subtracted ASR-2 <1c311e1fe8aa4cb8@media-tm>
2018-10-01 18:33:27.015685 [DEBUG] mrcp_client_session.c:1014 (ASR-2) Media Termination Subtracted ASR-2 <1c311e1fe8aa4cb8@rtp-tm>
2018-10-01 18:33:27.015685 [NOTICE] mrcp_client_connection.c:235 () Destroy TCP/MRCPv2 Connection 192.168.1.201:52936 <-> 192.168.1.202:1544
2018-10-01 18:33:27.015685 [INFO] mrcp_client.c:710 (ASR-2) Remove MRCP Handle ASR-2 <1c311e1fe8aa4cb8>
2018-10-01 18:33:27.015685 [INFO] mrcp_client_session.c:455 (ASR-2) Raise App Response ASR-2 <1c311e1fe8aa4cb8> [1] SUCCESS [0]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:1835 (ASR-2) Destroying MRCP session
2018-10-01 18:33:27.015685 [NOTICE] mrcp_application.c:182 (ASR-2) Destroy MRCP Handle ASR-2
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:1584 (ASR-2) READY ==> CLOSED
2018-10-01 18:33:27.015685 [DEBUG] apt_consumer_task.c:141 () Wait for Messages [MRCP Client]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_unimrcp.c:858 (ASR-2) audio queue destroyed
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] switch_core_media_bug.c:1268 Removing BUG from sofia/internal/10...@192.168.1.201
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/10...@192.168.1.201) Callstate Change EARLY -> HANGUP
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/10...@192.168.1.201) State HANGUP
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_sofia.c:449 Channel sofia/internal/10...@192.168.1.201 hanging up, cause: NORMAL_CLEARING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.015685 [DEBUG] mod_sofia.c:588 Responding to INVITE with: 480
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:60 sofia/internal/10...@192.168.1.201 Standard HANGUP, cause: NORMAL_CLEARING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/10...@192.168.1.201) State HANGUP going to sleep
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/10...@192.168.1.201) State Change CS_HANGUP -> CS_REPORTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/10...@192.168.1.201) Running State Change CS_REPORTING (Cur 1 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/10...@192.168.1.201) State REPORTING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:174 sofia/internal/10...@192.168.1.201 Standard REPORTING, cause: NORMAL_CLEARING
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/10...@192.168.1.201) State REPORTING going to sleep
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/10...@192.168.1.201) State Change CS_REPORTING -> CS_DESTROY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_session.c:1714 Session 2 (sofia/internal/10...@192.168.1.201) Locked, Waiting on external entities
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [NOTICE] switch_core_session.c:1732 Session 2 (sofia/internal/10...@192.168.1.201) Ended
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [NOTICE] switch_core_session.c:1736 Close Channel sofia/internal/10...@192.168.1.201 [CS_DESTROY]
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/10...@192.168.1.201) Running State Change CS_DESTROY (Cur 0 Tot 2)
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/10...@192.168.1.201) State DESTROY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] mod_sofia.c:354 sofia/internal/10...@192.168.1.201 SOFIA DESTROY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:181 sofia/internal/10...@192.168.1.201 Standard DESTROY
98ce0982-c5c1-11e8-900b-0dc117fcb7dd 2018-10-01 18:33:27.057418 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/10...@192.168.1.201) State DESTROY going to sleep
Arsen Chaloyan
Oct 2, 2018, 3:54:43 PM10/2/18
to UniMRCP
Hi Felipe,
As indicated by the following log statements, FreeSWITCH sent 0 packets and 0 octets to UniMRCP server, that is why you got no-input
2018-10-01 18:33:22.015817 [INFO] mpf_rtp_stream.c:921 () Open RTP Transmitter 127.0.0.1:4004 -> 192.168.1.202:5008
2018-10-01 18:33:27.015685 [INFO] mpf_rtp_stream.c:935 () Close RTP Transmitter 127.0.0.1:4004 -> 192.168.1.202:5008 [s:0 o:0]
Using 127.0.0.1 is not going to work. This is a configuration issue. All you need to do is to replace the IP addresses provided in the corresponding guide below
Set client-ip and also rtp-ip to the IP address of FS.
--
You received this message because you are subscribed to the Google Groups "UniMRCP" group.
To unsubscribe from this group and stop receiving emails from it, send an email to unimrcp+u...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
--
Reply all
Reply to author
Forward
0 new messages