From Jen, 4 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2018-08-16 15:18:49.953089 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/7002128858@10.10.10.20 [640d9131-f2fa-4bee-82d1-d7435082f38d]
  2. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_NEW (Cur 1 Tot 47)
  3. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:9873 sofia/internal/7002128858@10.10.10.20 receiving invite from 10.10.10.34:5060 version: 1.6.20 -37-987c9b9 64bit
  4. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:9989 IP 10.10.10.34 Approved by acl "caztel[]". Access Granted.
  5. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:7084 Channel sofia/internal/7002128858@10.10.10.20 entering state [received][100]
  6. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:7094 Remote SDP:
  7. v=0
  8. o=CiscoSystemsSIP-GW-UserAgent 3034 1151 IN IP4 10.10.10.20
  9. s=SIP Call
  10. c=IN IP4 10.10.10.20
  11. t=0 0
  12. m=audio 19048 RTP/AVP 0
  13. c=IN IP4 10.10.10.20
  14. a=rtpmap:0 PCMU/8000
  15. a=ptime:20
  16.  
  17. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:7486 (sofia/internal/7002128858@10.10.10.20) State Change CS_NEW -> CS_INIT
  18. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/7002128858@10.10.10.20) State NEW
  19. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_INIT (Cur 1 Tot 47)
  20. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/7002128858@10.10.10.20) State INIT
  21. 2018-08-16 15:18:49.953089 [DEBUG] mod_sofia.c:90 sofia/internal/7002128858@10.10.10.20 SOFIA INIT
  22. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:40 sofia/internal/7002128858@10.10.10.20 Standard INIT
  23. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/7002128858@10.10.10.20) State Change CS_INIT -> CS_ROUTING
  24. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/7002128858@10.10.10.20) State INIT going to sleep
  25. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_ROUTING (Cur 1 Tot 47)
  26. 2018-08-16 15:18:49.953089 [DEBUG] switch_channel.c:2249 (sofia/internal/7002128858@10.10.10.20) Callstate Change DOWN -> RINGING
  27. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/7002128858@10.10.10.20) State ROUTING
  28. 2018-08-16 15:18:49.953089 [DEBUG] mod_sofia.c:143 sofia/internal/7002128858@10.10.10.20 SOFIA ROUTING
  29. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:236 sofia/internal/7002128858@10.10.10.20 Standard ROUTING
  30. 2018-08-16 15:18:49.953089 [INFO] mod_dialplan_xml.c:637 Processing 7002128858 <7002128858>->7002320710 in context public
  31. Dialplan: sofia/internal/7002128858@10.10.10.20 parsing [public->unloop] continue=false
  32. Dialplan: sofia/internal/7002128858@10.10.10.20 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  33. Dialplan: sofia/internal/7002128858@10.10.10.20 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  34. Dialplan: sofia/internal/7002128858@10.10.10.20 parsing [public->outside_call] continue=true
  35. Dialplan: sofia/internal/7002128858@10.10.10.20 Absolute Condition [outside_call]
  36. Dialplan: sofia/internal/7002128858@10.10.10.20 Action set(outside_call=true)
  37. Dialplan: sofia/internal/7002128858@10.10.10.20 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  38. Dialplan: sofia/internal/7002128858@10.10.10.20 parsing [public->call_debug] continue=true
  39. Dialplan: sofia/internal/7002128858@10.10.10.20 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  40. Dialplan: sofia/internal/7002128858@10.10.10.20 parsing [public->ivr_710] continue=false
  41. Dialplan: sofia/internal/7002128858@10.10.10.20 Regex (PASS) [ivr_710] destination_number(7002320710) =~ /^(7002320710)$/ break=on-false
  42. Dialplan: sofia/internal/7002128858@10.10.10.20 Action lua(ivr_710.lua)
  43. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/7002128858@10.10.10.20) State Change CS_ROUTING -> CS_EXECUTE
  44. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/7002128858@10.10.10.20) State ROUTING going to sleep
  45. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_EXECUTE (Cur 1 Tot 47)
  46. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/7002128858@10.10.10.20) State EXECUTE
  47. 2018-08-16 15:18:49.953089 [DEBUG] mod_sofia.c:198 sofia/internal/7002128858@10.10.10.20 SOFIA EXECUTE
  48. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_state_machine.c:328 sofia/internal/7002128858@10.10.10.20 Standard EXECUTE
  49. EXECUTE sofia/internal/7002128858@10.10.10.20 set(outside_call=true)
  50. 2018-08-16 15:18:49.953089 [DEBUG] mod_dptools.c:1548 SET sofia/internal/7002128858@10.10.10.20 [outside_call]=[true]
  51. EXECUTE sofia/internal/7002128858@10.10.10.20 export(RFC2822_DATE=Thu, 16 Aug 2018 15:18:49 -0400)
  52. 2018-08-16 15:18:49.953089 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 16 Aug 2018 15:18:49 -0400]
  53. EXECUTE sofia/internal/7002128858@10.10.10.20 lua(ivr_710.lua)
  54. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:3916 Activate Buggy RFC2833 Mode!
  55. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  56. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  57. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  58. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  59. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  60. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/7002128858@10.10.10.20 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  61. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_codec.c:111 sofia/internal/7002128858@10.10.10.20 Original read codec set to PCMU:0
  62. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4738 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  63. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:4767 sofia/internal/7002128858@10.10.10.20 Set 2833 dtmf send payload to 101 recv payload to 101
  64. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/7002128858@10.10.10.20] 192.168.4.107 port 32538 -> 10.10.10.20 port 19048 codec: 0 ms: 20
  65. 2018-08-16 15:18:49.953089 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  66. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:7180 sofia/internal/7002128858@10.10.10.20 Set 2833 dtmf send payload to 101
  67. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:7187 sofia/internal/7002128858@10.10.10.20 Set 2833 dtmf receive payload to 101
  68. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:7210 sofia/internal/7002128858@10.10.10.20 Set rtp dtmf delay to 40
  69. 2018-08-16 15:18:49.953089 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/7002128858@10.10.10.20!
  70. 2018-08-16 15:18:49.953089 [DEBUG] switch_channel.c:3474 (sofia/internal/7002128858@10.10.10.20) Callstate Change RINGING -> EARLY
  71. 2018-08-16 15:18:49.953089 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/7002128858@10.10.10.20.
  72. 2018-08-16 15:18:49.953089 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/7002128858@10.10.10.20:
  73. v=0
  74. o=FreeSWITCH 1534414591 1534414592 IN IP4 192.168.4.107
  75. s=FreeSWITCH
  76. c=IN IP4 192.168.4.107
  77. t=0 0
  78. m=audio 32538 RTP/AVP 0 101
  79. a=rtpmap:0 PCMU/8000
  80. a=rtpmap:101 telephone-event/8000
  81. a=fmtp:101 0-16
  82. a=ptime:20
  83. a=sendrecv
  84.  
  85. 2018-08-16 15:18:49.953089 [NOTICE] switch_cpp.cpp:685 Channel [sofia/internal/7002128858@10.10.10.20] has been answered
  86. 2018-08-16 15:18:49.953089 [DEBUG] switch_channel.c:3773 (sofia/internal/7002128858@10.10.10.20) Callstate Change EARLY -> ACTIVE
  87. 2018-08-16 15:18:49.953089 [DEBUG] sofia.c:7084 Channel sofia/internal/7002128858@10.10.10.20 entering state [completed][200]
  88. 2018-08-16 15:18:50.232967 [DEBUG] switch_ivr_play_say.c:3025 OPEN TTS watson_ws
  89. 2018-08-16 15:18:50.232967 [DEBUG] switch_ivr_play_say.c:3035 Raw Codec Activated
  90. 2018-08-16 15:18:50.232967 [INFO] watson_tts_ws.c:259 feed tts welcome to caztel 10 10 710. This is jenny speaking. How many I help you?
  91. 2018-08-16 15:18:50.932967 [DEBUG] watson_tts_ws.c:281 read: {
  92.    "binary_streams": [
  93.       {
  94.          "content_type": "audio/l16;channels=1;rate=8000"
  95.       }
  96.    ]
  97. }
  98. 2018-08-16 15:18:50.932967 [DEBUG] switch_ivr_play_say.c:2729 Speaking text: welcome to caztel 10 10 710. This is jenny speaking. How many I help you?
  99. 2018-08-16 15:18:51.692967 [NOTICE] watson_tts_ws.c:130 watson tts read thread start
  100. 2018-08-16 15:18:51.692967 [DEBUG] sofia.c:7084 Channel sofia/internal/7002128858@10.10.10.20 entering state [ready][200]
  101. 2018-08-16 15:18:51.692967 [NOTICE] watson_tts_ws.c:165 watson read thread done
  102. 2018-08-16 15:18:51.712967 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  103. 2018-08-16 15:18:58.012967 [DEBUG] switch_ivr_play_say.c:2907 done speaking text
  104. EXECUTE sofia/internal/7002128858@10.10.10.20 detect_speech(watson default default default)
  105. 2018-08-16 15:18:58.272967 [INFO] watson_asr_ws.c:274 codec = L16, rate = 8000, dest = default
  106. 2018-08-16 15:18:58.272967 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/internal/7002128858@10.10.10.20
  107. 2018-08-16 15:18:58.272967 [DEBUG] watson_asr_ws.c:322 Connecting to url wss://stream.watsonplatform.net/speech-to-text/api/v1/recognize?watson-token=NDOYeMzORpta%2BN6MCcDn1JnpFeEQz%2B5NlxorLnim24Yqu2yFffg5KgYB0uZ4Gs316GeM7cXUttb1f6ZtCOJtSmmCnLSNF1%2BF7XuH%2BWyrtG%2F1cjHmtFLXTUKFMC%2B45tQ0SOPBsG37fiILzL1mz4OAFxfPp9CvxZP5DBIa79Rz9BptsJ4D0Ya90M4QvVqIOtzyHzXywAP51sIbWp54Bedq0UBSQKZp1lJLi3%2Bylvk99nLcY9b6PBM6M66W9hb%2F5NuBqpM%2BRropr0fJrcZpTo7AQ1FfIT6Ks7z%2FNR1DQLKDd%2Ff4LjB8vOW08kiTm5a%2B5Rxx7AkxVp%2FXwOM2Ei%2FqI2QyOQAcbyoeOGPtIt2pwa1ecMDi1RrI%2Fgr7JyP5ZRiPPxycnLCGiIoPT3aL%2FtdZTuQajieI4czuUanZbFK%2FEwIyE9lTEFm4Yge2OGjVy5b9402ZmgPaxxslbkKltMxEu5eM3MLIOuWPuxQy8dE5mo5HD8We3CgAwmpwJcN6ptzRoSVTH6EBV5UGjXcEWy4ivxAiNEDBrkFErMh92R%2BiBaCSU%2F%2FOzNTrDtdgnQuCZOdm%2FMm8JgDOTgHWHh%2FySawq8Rp9y16fwJTxk7KliCDYhEt9REE0Mwvt%2FLfMiEONNGi5AJgUZ9BpJ0s01BoRVEH4F27y1XVQd%2BM4PSvpRhvnVdORjQcv61KFuThD7gCTdQXUvi%2F%2Fhkq1ii9nGa7A8cgVjzJhk7Rl9OOW1mIlWd713hUiEFMG7cIwB9LNQmJqZqB5IxHiXmxKd1F%2Fm1y6kkcpS98iNZz3OIlk4NHHqHi4CHOvGKVIX%2FD1cN0MIs5SVa5zkz8%2BJQXjS6Cg6tBP7emRr47Cx52bBl9lvynDZGT16nd0VCJobJc83%2Fl7NVt%2FRpb8INp9ia%2FAQ8UfXiTLPshT9F2OoqBBjodpoMwgYACWRDkp%2Bf8GQ8yOpMpbTSYLKy8orMO362XLlf8R2kxMk5VBJHwcROUdQdK%2FWNUjDwgr5BjtjPo%3D&model=en-US_NarrowbandModel
  108. 2018-08-16 15:18:58.593036 [INFO] watson_asr_ws.c:355 read: 27 {
  109.    "state": "listening"
  110. }
  111. 2018-08-16 15:18:58.593036 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  112. 2018-08-16 15:18:58.593036 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
  113. 2018-08-16 15:19:02.452967 [INFO] watson_asr_ws.c:462 Silence detected, sending stop.
  114. 2018-08-16 15:19:03.052968 [DEBUG] watson_asr_ws.c:175 read {
  115.    "results": [],
  116.    "result_index": 0
  117. }
  118. 2018-08-16 15:19:03.052968 [DEBUG] watson_asr_ws.c:569 Recognized: {
  119.    "results": [],
  120.    "result_index": 0
  121. }, Confidence: 0.000000, Confidence-Threshold: 0
  122. EXECUTE sofia/internal/7002128858@10.10.10.20 detect_speech(resume)
  123. 2018-08-16 15:19:03.072967 [DEBUG] watson_asr_ws.c:538 Manually Resuming
  124. 2018-08-16 15:19:09.572967 [INFO] watson_asr_ws.c:462 Silence detected, sending stop.
  125. 2018-08-16 15:19:09.572967 [DEBUG] watson_asr_ws.c:175 read {
  126.    "state": "listening"
  127. }
  128. 2018-08-16 15:19:10.152967 [DEBUG] watson_asr_ws.c:175 read {
  129.    "results": [],
  130.    "result_index": 0
  131. }
  132. 2018-08-16 15:19:10.152967 [DEBUG] watson_asr_ws.c:569 Recognized: {
  133.    "results": [],
  134.    "result_index": 0
  135. }, Confidence: 0.000000, Confidence-Threshold: 0
  136. EXECUTE sofia/internal/7002128858@10.10.10.20 detect_speech(resume)
  137. 2018-08-16 15:19:10.152967 [DEBUG] watson_asr_ws.c:538 Manually Resuming
  138. 2018-08-16 15:19:15.972968 [NOTICE] sofia.c:1012 Hangup sofia/internal/7002128858@10.10.10.20 [CS_EXECUTE] [NORMAL_CLEARING]
  139. 2018-08-16 15:19:15.972968 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://90000000
  140. 2018-08-16 15:19:15.972968 [DEBUG] switch_cpp.cpp:1112 sofia/internal/7002128858@10.10.10.20 destroy/unlink session from object
  141. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_session.c:2815 sofia/internal/7002128858@10.10.10.20 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  142. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/7002128858@10.10.10.20) State EXECUTE going to sleep
  143. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_HANGUP (Cur 1 Tot 47)
  144. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/internal/7002128858@10.10.10.20
  145. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/7002128858@10.10.10.20) Callstate Change ACTIVE -> HANGUP
  146. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/7002128858@10.10.10.20) State HANGUP
  147. 2018-08-16 15:19:15.972968 [DEBUG] mod_sofia.c:438 Channel sofia/internal/7002128858@10.10.10.20 hanging up, cause: NORMAL_CLEARING
  148. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:60 sofia/internal/7002128858@10.10.10.20 Standard HANGUP, cause: NORMAL_CLEARING
  149. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/7002128858@10.10.10.20) State HANGUP going to sleep
  150. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/7002128858@10.10.10.20) State Change CS_HANGUP -> CS_REPORTING
  151. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_REPORTING (Cur 1 Tot 47)
  152. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/7002128858@10.10.10.20) State REPORTING
  153. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:174 sofia/internal/7002128858@10.10.10.20 Standard REPORTING, cause: NORMAL_CLEARING
  154. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/7002128858@10.10.10.20) State REPORTING going to sleep
  155. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/7002128858@10.10.10.20) State Change CS_REPORTING -> CS_DESTROY
  156. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_session.c:1665 Session 47 (sofia/internal/7002128858@10.10.10.20) Locked, Waiting on external entities
  157. 2018-08-16 15:19:15.972968 [NOTICE] switch_core_session.c:1683 Session 47 (sofia/internal/7002128858@10.10.10.20) Ended
  158. 2018-08-16 15:19:15.972968 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/7002128858@10.10.10.20 [CS_DESTROY]
  159. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/7002128858@10.10.10.20) Running State Change CS_DESTROY (Cur 0 Tot 47)
  160. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/7002128858@10.10.10.20) State DESTROY
  161. 2018-08-16 15:19:15.972968 [DEBUG] mod_sofia.c:343 sofia/internal/7002128858@10.10.10.20 SOFIA DESTROY
  162. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:181 sofia/internal/7002128858@10.10.10.20 Standard DESTROY
  163. 2018-08-16 15:19:15.972968 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/7002128858@10.10.10.20) State DESTROY going to sleep
  164. freeswitch@froakie>

Replies to Untitled rss

Title Name Language When
Re: Untitled Jen freeswitch 4 Years ago.