FreeSWITCH Pastebin

Voicemail working by any numbers with * prefix

From Prashant Kumar, 2 Months ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/fc31f559
Embed
Download Paste or View Raw
  1. +OK log level  [7]
  2. freeswitch@ip-172-31-42-21>
  3. freeswitch@ip-172-31-42-21>
  4. 2024-02-23 11:23:51.306483 98.67% [NOTICE] switch_channel.c:1142 New Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [ccb94ef2-5384-438d-946a-e4e9ef78959d]
  5. 2024-02-23 11:23:51.306483 98.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_NEW (Cur 1 Tot 9259)
  6. 2024-02-23 11:23:51.306483 98.67% [INFO] sofia.c:10459 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 receiving invite from 223.228.239.129:32416 version: 1.10.10-release  64bit call-id: OWE2OGFiMzMwZGM2YmZiODVhNWQ0M2IzMGYwOTIwOGY.
  7. 2024-02-23 11:23:51.306483 98.67% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 223.228.239.129:0.
  8. 2024-02-23 11:23:51.306483 98.67% [DEBUG] sofia.c:2419 detaching session ccb94ef2-5384-438d-946a-e4e9ef78959d
  9. 2024-02-23 11:23:51.306483 98.67% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [*101@pk1betav602.astppbilling.org] from ip 223.228.239.129
  10. 2024-02-23 11:23:51.306483 98.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State NEW
  11. 2024-02-23 11:23:51.726491 98.70% [DEBUG] sofia.c:2532 Re-attaching to session ccb94ef2-5384-438d-946a-e4e9ef78959d
  12. 2024-02-23 11:23:51.726491 98.70% [INFO] sofia.c:10459 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 receiving invite from 223.228.239.129:32416 version: 1.10.10-release  64bit call-id: OWE2OGFiMzMwZGM2YmZiODVhNWQ0M2IzMGYwOTIwOGY.
  13. 2024-02-23 11:23:51.726491 98.70% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 223.228.239.129:0.
  14. 2024-02-23 11:23:51.746466 98.70% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
  15. 2024-02-23 11:23:51.746466 98.70% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [received][100]
  16. 2024-02-23 11:23:51.746466 98.70% [DEBUG] sofia.c:7503 Remote SDP:
  17. v=0
  18. o=Z 0 0 IN IP4 223.228.239.129
  19. s=Z
  20. c=IN IP4 223.228.239.129
  21. t=0 0
  22. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  23. a=rtpmap:110 speex/8000
  24. a=rtpmap:98 iLBC/8000
  25. a=fmtp:98 mode=20
  26. a=rtpmap:101 telephone-event/8000
  27. a=fmtp:101 0-15
  28.  
  29. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  30. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  31. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  32. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  33. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  34. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  35. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  36. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  37. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  38. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  39. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  40. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  41. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
  42. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/9669963218@pk1betav602.astppbilling.org:5078 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  43. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_codec.c:111 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Original read codec set to PCMA:8
  44. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
  45. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_media.c:5856 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 recv payload to 101
  46. 2024-02-23 11:23:51.746466 98.70% [DEBUG] sofia.c:7927 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_NEW -> CS_INIT
  47. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_INIT (Cur 1 Tot 9259)
  48. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State INIT
  49. 2024-02-23 11:23:51.746466 98.70% [DEBUG] mod_sofia.c:97 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA INIT
  50. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:40 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard INIT
  51. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_INIT -> CS_ROUTING
  52. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State INIT going to sleep
  53. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_ROUTING (Cur 1 Tot 9259)
  54. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_channel.c:2399 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change DOWN -> RINGING
  55. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State ROUTING
  56. 2024-02-23 11:23:51.746466 98.70% [DEBUG] mod_sofia.c:158 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA ROUTING
  57. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_core_state_machine.c:230 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard ROUTING
  58. 2024-02-23 11:23:51.746466 98.70% [INFO] mod_dialplan_xml.c:639 Processing 9669963218 <9669963218>->*101 in context default
  59. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  60. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  61. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  62. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  63. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  64. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Hunt Destination Number: *101
  65. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
  66. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
  67. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Feature Code Transfer Blind Value :*2
  68. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : *101
  69. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  70. 2024-02-23 11:23:51.746466 98.70% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] VOICEMAIL :
  71. 2024-02-23 11:23:51.746466 98.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  72. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  73. <document type="freeswitch/xml">
  74. <section name="dialplan" description="ASTPP Dialplan">
  75. <context name="default">
  76. <extension name="\*101">
  77. <condition field="destination_number" expression="\*101">
  78. <action application="answer"/>
  79. <action application="voicemail" data="check default pk1betav602.astppbilling.org 9669963218"/>
  80. </condition>
  81. </extension>
  82. </context>
  83. </section>
  84. </document>
  85. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 parsing [default->\*101] continue=false
  86. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Regex (PASS) [\*101] destination_number(*101) =~ /\*101/ break=on-false
  87. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Action answer()
  88. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Action voicemail(check default pk1betav602.astppbilling.org 9669963218)
  89. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_ROUTING -> CS_EXECUTE
  90. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State ROUTING going to sleep
  91. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_EXECUTE (Cur 1 Tot 9259)
  92. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State EXECUTE
  93. 2024-02-23 11:23:51.766467 98.70% [DEBUG] mod_sofia.c:213 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA EXECUTE
  94. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_state_machine.c:323 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard EXECUTE
  95. EXECUTE [depth=0] sofia/default/9669963218@pk1betav602.astppbilling.org:5078 answer()
  96. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/9669963218@pk1betav602.astppbilling.org:5078] 172.31.42.21 port 27426 -> 223.228.239.129 port 8000 codec: 8 ms: 20
  97. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
  98. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_media.c:8972 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101
  99. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_media.c:8979 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf receive payload to 101
  100. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_core_media.c:9002 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set rtp dtmf delay to 40
  101. 2024-02-23 11:23:51.766467 98.70% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/9669963218@pk1betav602.astppbilling.org:5078:
  102. v=0
  103. o=FreeSWITCH 1708660005 1708660006 IN IP4 35.154.93.145
  104. s=FreeSWITCH
  105. c=IN IP4 35.154.93.145
  106. t=0 0
  107. m=audio 27426 RTP/AVP 8 101
  108. a=rtpmap:8 PCMA/8000
  109. a=rtpmap:101 telephone-event/8000
  110. a=fmtp:101 0-15
  111. a=ptime:20
  112. a=sendrecv
  113.  
  114. 2024-02-23 11:23:51.766467 98.70% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [completed][200]
  115. 2024-02-23 11:23:51.766467 98.70% [NOTICE] mod_dptools.c:1406 Channel [sofia/default/9669963218@pk1betav602.astppbilling.org:5078] has been answered
  116. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_channel.c:3912 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change RINGING -> ACTIVE
  117. EXECUTE [depth=0] sofia/default/9669963218@pk1betav602.astppbilling.org:5078 voicemail(check default pk1betav602.astppbilling.org 9669963218)
  118. 2024-02-23 11:23:51.766467 98.70% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  119. 2024-02-23 11:23:52.006465 98.70% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  120. 2024-02-23 11:23:52.006465 98.70% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-enter_pass.wav] (en:en)
  121. 2024-02-23 11:23:52.006465 98.70% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  122. 2024-02-23 11:23:52.006465 98.70% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  123. 2024-02-23 11:23:52.046465 98.70% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
  124. 2024-02-23 11:23:52.066466 98.70% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [ready][200]
  125. 2024-02-23 11:23:53.946486 98.43% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-enter_pass.wav
  126. 2024-02-23 11:23:54.046487 98.43% [DEBUG] switch_ivr_play_say.c:250 Handle say:[#] (en:en)
  127. span style="color: goldenrod; font-weight: bold;"> 2024-02-23 11:23:54.046487 98.43% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  128. 2024-02-23 11:23:54.706492 98.40% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://ascii/35.wav
  129. 2024-02-23 11:24:04.946468 95.57% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  130. 2024-02-23 11:24:04.946468 95.57% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-enter_pass.wav] (en:en)
  131. 2024-02-23 11:24:04.946468 95.57% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  132. 2024-02-23 11:24:04.946468 95.57% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  133. 2024-02-23 11:24:06.886491 95.17% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-enter_pass.wav
  134. 2024-02-23 11:24:06.986491 95.17% [DEBUG] switch_ivr_play_say.c:250 Handle say:[#] (en:en)
  135. span style="color: goldenrod; font-weight: bold;"> 2024-02-23 11:24:06.986491 95.17% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  136. 2024-02-23 11:24:07.646474 95.07% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://ascii/35.wav
  137. 2024-02-23 11:24:12.086485 95.00% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 7:960
  138. 2024-02-23 11:24:12.086485 95.00% [INFO] switch_channel.c:528 RECV DTMF 7:960
  139. 2024-02-23 11:24:12.706485 94.93% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 9:960
  140. 2024-02-23 11:24:12.706485 94.93% [INFO] switch_channel.c:528 RECV DTMF 9:960
  141. 2024-02-23 11:24:13.826465 94.87% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960
  142. 2024-02-23 11:24:13.826465 94.87% [INFO] switch_channel.c:528 RECV DTMF 5:960
  143. 2024-02-23 11:24:14.306484 94.87% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 9:960
  144. 2024-02-23 11:24:14.306484 94.87% [INFO] switch_channel.c:528 RECV DTMF 9:960
  145. 2024-02-23 11:24:14.526483 94.83% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960
  146. 2024-02-23 11:24:14.526483 94.83% [INFO] switch_channel.c:528 RECV DTMF 0:960
  147. 2024-02-23 11:24:15.246485 94.83% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF #:960
  148. span style="color: green; font-weight: bold;"> 2024-02-23 11:24:15.246485 94.83% [INFO] switch_channel.c:528 RECV DTMF #:960
  149. span style="color: goldenrod; font-weight: bold;"> 2024-02-23 11:24:15.366463 94.83% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  150. 2024-02-23 11:24:15.366463 94.83% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-you_have.wav] (en:en)
  151. 2024-02-23 11:24:15.366463 94.83% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  152. 2024-02-23 11:24:15.366463 94.83% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  153. 2024-02-23 11:24:15.926485 94.77% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-you_have.wav
  154. 2024-02-23 11:24:16.026553 94.77% [DEBUG] switch_ivr_play_say.c:250 Handle say:[1] (en:en)
  155. 2024-02-23 11:24:16.026553 94.77% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  156. 2024-02-23 11:24:16.726463 94.73% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/1.wav
  157. 2024-02-23 11:24:16.826490 94.73% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-urgent-new.wav] (en:en)
  158. 2024-02-23 11:24:16.826490 94.73% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  159. 2024-02-23 11:24:16.826490 94.73% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  160. 2024-02-23 11:24:17.606485 94.60% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-urgent-new.wav
  161. 2024-02-23 11:24:17.726487 94.60% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-message.wav] (en:en)
  162. 2024-02-23 11:24:17.726487 94.60% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  163. 2024-02-23 11:24:17.726487 94.60% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  164. 2024-02-23 11:24:18.306488 94.60% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-message.wav
  165. 2024-02-23 11:24:18.526493 94.37% [DEBUG] mod_voicemail.c:1604 Sending display update [1119705264|1119705264] to sofia/default/9669963218@pk1betav602.astppbilling.org:5078
  166. 2024-02-23 11:24:18.526493 94.37% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  167. 2024-02-23 11:24:18.526493 94.37% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-new.wav] (en:en)
  168. 2024-02-23 11:24:18.526493 94.37% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  169. 2024-02-23 11:24:18.526493 94.37% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  170. 2024-02-23 11:24:18.866491 94.37% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-new.wav
  171. 2024-02-23 11:24:18.986491 94.37% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-message_number.wav] (en:en)
  172. 2024-02-23 11:24:18.986491 94.37% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  173. 2024-02-23 11:24:18.986491 94.37% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  174. 2024-02-23 11:24:19.806485 94.13% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-message_number.wav
  175. 2024-02-23 11:24:19.906492 94.13% [DEBUG] switch_ivr_play_say.c:250 Handle say:[1] (en:en)
  176. 2024-02-23 11:24:19.906492 94.13% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  177. 2024-02-23 11:24:20.606484 94.10% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/1.wav
  178. 2024-02-23 11:24:20.706487 94.10% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  179. 2024-02-23 11:24:20.706487 94.10% [DEBUG] switch_ivr_play_say.c:250 Handle say:[1708686202] (en:en)
  180. 2024-02-23 11:24:20.706487 94.10% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  181. 2024-02-23 11:24:23.486488 94.20% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://time/today.wav!time/at.wav!digits/11.wav!time/oh.wav!digits/3.wav!time/a-m.wav
  182. 2024-02-23 11:24:23.606477 94.20% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  183. 2024-02-23 11:24:34.346465 96.60% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/storage/voicemail/default/pk1betav602.astppbilling.org/9669963218/msg_985d99dc-6b5f-4fcd-9675-5ba86461e187.wav
  184. 2024-02-23 11:24:34.346465 96.60% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  185. 2024-02-23 11:24:34.346465 96.60% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-listen_to_recording.wav] (en:en)
  186. 2024-02-23 11:24:34.346465 96.60% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  187. 2024-02-23 11:24:34.346465 96.60% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  188. 2024-02-23 11:24:35.566485 97.00% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
  189. 2024-02-23 11:24:35.686463 97.00% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  190. 2024-02-23 11:24:35.686463 97.00% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  191. 2024-02-23 11:24:35.686463 97.00% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  192. 2024-02-23 11:24:36.086463 97.00% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  193. 2024-02-23 11:24:36.186505 97.00% [DEBUG] switch_ivr_play_say.c:250 Handle say:[0] (en:en)
  194. 2024-02-23 11:24:36.186505 97.00% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  195. 2024-02-23 11:24:36.966511 97.00% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/0.wav
  196. 2024-02-23 11:24:37.086484 97.00% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-save_recording.wav] (en:en)
  197. 2024-02-23 11:24:37.086484 97.00% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  198. 2024-02-23 11:24:37.086484 97.00% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  199. 2024-02-23 11:24:38.226489 97.03% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-save_recording.wav
  200. 2024-02-23 11:24:38.326490 97.03% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  201. 2024-02-23 11:24:38.326490 97.03% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  202. 2024-02-23 11:24:38.326490 97.03% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  203. 2024-02-23 11:24:38.726502 97.07% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  204. 2024-02-23 11:24:38.846485 97.07% [DEBUG] switch_ivr_play_say.c:250 Handle say:[2] (en:en)
  205. 2024-02-23 11:24:38.846485 97.07% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  206. 2024-02-23 11:24:39.486463 96.97% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/2.wav
  207. 2024-02-23 11:24:39.586491 96.97% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-delete_recording.wav] (en:en)
  208. 2024-02-23 11:24:39.586491 96.97% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  209. 2024-02-23 11:24:39.586491 96.97% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  210. 2024-02-23 11:24:40.706485 96.93% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-delete_recording.wav
  211. 2024-02-23 11:24:40.806493 96.93% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  212. 2024-02-23 11:24:40.806493 96.93% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  213. 2024-02-23 11:24:40.806493 96.93% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  214. 2024-02-23 11:24:41.206512 96.93% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  215. 2024-02-23 11:24:41.326463 96.93% [DEBUG] switch_ivr_play_say.c:250 Handle say:[7] (en:en)
  216. 2024-02-23 11:24:41.326463 96.93% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  217. 2024-02-23 11:24:42.126474 96.83% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/7.wav
  218. 2024-02-23 11:24:42.226520 96.83% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-forward_to_email.wav] (en:en)
  219. 2024-02-23 11:24:42.226520 96.83% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  220. 2024-02-23 11:24:42.226520 96.83% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  221. 2024-02-23 11:24:44.006493 96.77% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-forward_to_email.wav
  222. 2024-02-23 11:24:44.126484 96.77% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  223. 2024-02-23 11:24:44.126484 96.77% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  224. 2024-02-23 11:24:44.126484 96.77% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  225. 2024-02-23 11:24:44.526483 96.80% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  226. 2024-02-23 11:24:44.646600 96.80% [DEBUG] switch_ivr_play_say.c:250 Handle say:[4] (en:en)
  227. 2024-02-23 11:24:44.646600 96.80% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  228. 2024-02-23 11:24:45.346465 96.80% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/4.wav
  229. 2024-02-23 11:24:45.446487 96.77% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-return_call.wav] (en:en)
  230. 2024-02-23 11:24:45.446487 96.77% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  231. 2024-02-23 11:24:45.446487 96.77% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  232. 2024-02-23 11:24:46.606464 96.80% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-return_call.wav
  233. 2024-02-23 11:24:46.706470 96.80% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  234. 2024-02-23 11:24:46.706470 96.80% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  235. 2024-02-23 11:24:46.706470 96.80% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  236. 2024-02-23 11:24:47.106488 96.80% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  237. 2024-02-23 11:24:47.226473 96.80% [DEBUG] switch_ivr_play_say.c:250 Handle say:[5] (en:en)
  238. 2024-02-23 11:24:47.226473 96.80% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  239. 2024-02-23 11:24:48.006472 96.83% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/5.wav
  240. 2024-02-23 11:24:48.106506 96.83% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-to_forward.wav] (en:en)
  241. 2024-02-23 11:24:48.106506 96.83% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  242. 2024-02-23 11:24:48.106506 96.83% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  243. 2024-02-23 11:24:49.366510 96.87% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-to_forward.wav
  244. 2024-02-23 11:24:49.486489 97.10% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-press.wav] (en:en)
  245. 2024-02-23 11:24:49.486489 97.10% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  246. 2024-02-23 11:24:49.486489 97.10% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  247. 2024-02-23 11:24:49.886504 97.10% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
  248. 2024-02-23 11:24:50.006463 97.10% [DEBUG] switch_ivr_play_say.c:250 Handle say:[8] (en:en)
  249. 2024-02-23 11:24:50.006463 97.10% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  250. 2024-02-23 11:24:50.606463 97.07% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/8.wav
  251. 2024-02-23 11:24:50.706464 97.07% [NOTICE] sofia.c:1065 Hangup sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [CS_EXECUTE] [NORMAL_CLEARING]
  252. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_voicemail.c:1925 Update MWI: Processing for 9669963218@pk1betav602.astppbilling.org in inbox
  253. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_voicemail.c:1950 Update MWI: Messages Waiting yes
  254. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_voicemail.c:1951 Update MWI: Update Reason PURGE
  255. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_voicemail.c:1952 Update MWI: Message Account 9669963218@pk1betav602.astppbilling.org
  256. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_voicemail.c:1953 Update MWI: Voice Message 1/0
  257. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_session.c:2979 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  258. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State EXECUTE going to sleep
  259. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_HANGUP (Cur 1 Tot 9259)
  260. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change ACTIVE -> HANGUP
  261. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State HANGUP
  262. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_sofia.c:469 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 hanging up, cause: NORMAL_CLEARING
  263. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard HANGUP, cause: NORMAL_CLEARING
  264. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State HANGUP going to sleep
  265. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_HANGUP -> CS_REPORTING
  266. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_REPORTING (Cur 1 Tot 9259)
  267. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State REPORTING
  268. 2024-02-23 11:24:50.706464 97.07% [INFO] mod_json_cdr.c:272 Process [ccb94ef2-5384-438d-946a-e4e9ef78959d.cdr.json]
  269. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard REPORTING, cause: NORMAL_CLEARING
  270. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State REPORTING going to sleep
  271. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_REPORTING -> CS_DESTROY
  272. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_session.c:1744 Session 9259 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Locked, Waiting on external entities
  273. 2024-02-23 11:24:50.706464 97.07% [NOTICE] switch_core_session.c:1762 Session 9259 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Ended
  274. 2024-02-23 11:24:50.706464 97.07% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [CS_DESTROY]
  275. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_DESTROY (Cur 0 Tot 9259)
  276. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State DESTROY
  277. 2024-02-23 11:24:50.706464 97.07% [DEBUG] mod_sofia.c:380 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA DESTROY
  278. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard DESTROY
  279. 2024-02-23 11:24:50.706464 97.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State DESTROY going to sleep
  280. 2024-02-23 11:25:13.586475 95.13% [NOTICE] switch_channel.c:1142 New Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [53f7c52b-ff08-43b4-94cf-93053b25cf8a]
  281. 2024-02-23 11:25:13.586475 95.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_NEW (Cur 1 Tot 9260)
  282. 2024-02-23 11:25:13.586475 95.13% [INFO] sofia.c:10459 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 receiving invite from 223.228.239.129:32416 version: 1.10.10-release  64bit call-id: ZjkxZjU3Mjk0OTE1NjM4MjMwMGQ2ZTk5MDMwN2JjZjk.
  283. 2024-02-23 11:25:13.586475 95.13% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 223.228.239.129:0.
  284. 2024-02-23 11:25:13.586475 95.13% [DEBUG] sofia.c:2419 detaching session 53f7c52b-ff08-43b4-94cf-93053b25cf8a
  285. 2024-02-23 11:25:13.586475 95.13% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [*737473@pk1betav602.astppbilling.org] from ip 223.228.239.129
  286. 2024-02-23 11:25:13.586475 95.13% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State NEW
  287. 2024-02-23 11:25:13.926485 95.13% [DEBUG] sofia.c:2532 Re-attaching to session 53f7c52b-ff08-43b4-94cf-93053b25cf8a
  288. 2024-02-23 11:25:13.946481 95.13% [INFO] sofia.c:10459 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 receiving invite from 223.228.239.129:32416 version: 1.10.10-release  64bit call-id: ZjkxZjU3Mjk0OTE1NjM4MjMwMGQ2ZTk5MDMwN2JjZjk.
  289. 2024-02-23 11:25:13.946481 95.13% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 223.228.239.129:0.
  290. 2024-02-23 11:25:13.946481 95.13% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
  291. 2024-02-23 11:25:13.946481 95.13% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [received][100]
  292. 2024-02-23 11:25:13.946481 95.13% [DEBUG] sofia.c:7503 Remote SDP:
  293. v=0
  294. o=Z 0 0 IN IP4 223.228.239.129
  295. s=Z
  296. c=IN IP4 223.228.239.129
  297. t=0 0
  298. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  299. a=rtpmap:110 speex/8000
  300. a=rtpmap:98 iLBC/8000
  301. a=fmtp:98 mode=20
  302. a=rtpmap:101 telephone-event/8000
  303. a=fmtp:101 0-15
  304.  
  305. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  306. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  307. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  308. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  309. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  310. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  311. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  312. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  313. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  314. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  315. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  316. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  317. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
  318. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/9669963218@pk1betav602.astppbilling.org:5078 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  319. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_codec.c:111 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Original read codec set to PCMA:8
  320. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
  321. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_media.c:5856 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 recv payload to 101
  322. 2024-02-23 11:25:13.946481 95.13% [DEBUG] sofia.c:7927 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_NEW -> CS_INIT
  323. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_INIT (Cur 1 Tot 9260)
  324. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State INIT
  325. 2024-02-23 11:25:13.946481 95.13% [DEBUG] mod_sofia.c:97 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA INIT
  326. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:40 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard INIT
  327. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_INIT -> CS_ROUTING
  328. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State INIT going to sleep
  329. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_ROUTING (Cur 1 Tot 9260)
  330. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_channel.c:2399 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change DOWN -> RINGING
  331. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State ROUTING
  332. 2024-02-23 11:25:13.946481 95.13% [DEBUG] mod_sofia.c:158 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA ROUTING
  333. 2024-02-23 11:25:13.946481 95.13% [DEBUG] switch_core_state_machine.c:230 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard ROUTING
  334. 2024-02-23 11:25:13.946481 95.13% [INFO] mod_dialplan_xml.c:639 Processing 9669963218 <9669963218>->*737473 in context default
  335. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  336. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  337. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  338. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  339. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  340. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Hunt Destination Number: *737473
  341. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
  342. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
  343. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Feature Code Transfer Blind Value :*2
  344. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : *737473
  345. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  346. 2024-02-23 11:25:13.966461 95.13% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] VOICEMAIL :
  347. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  348. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  349. <document type="freeswitch/xml">
  350. <section name="dialplan" description="ASTPP Dialplan">
  351. <context name="default">
  352. <extension name="\*737473">
  353. <condition field="destination_number" expression="\*737473">
  354. <action application="answer"/>
  355. <action application="voicemail" data="check default pk1betav602.astppbilling.org 9669963218"/>
  356. </condition>
  357. </extension>
  358. </context>
  359. </section>
  360. </document>
  361. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 parsing [default->\*737473] continue=false
  362. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Regex (PASS) [\*737473] destination_number(*737473) =~ /\*737473/ break=on-false
  363. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Action answer()
  364. Dialplan: sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Action voicemail(check default pk1betav602.astppbilling.org 9669963218)
  365. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_ROUTING -> CS_EXECUTE
  366. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State ROUTING going to sleep
  367. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_EXECUTE (Cur 1 Tot 9260)
  368. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State EXECUTE
  369. 2024-02-23 11:25:13.966461 95.13% [DEBUG] mod_sofia.c:213 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA EXECUTE
  370. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_state_machine.c:323 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard EXECUTE
  371. EXECUTE [depth=0] sofia/default/9669963218@pk1betav602.astppbilling.org:5078 answer()
  372. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/9669963218@pk1betav602.astppbilling.org:5078] 172.31.42.21 port 22354 -> 223.228.239.129 port 8000 codec: 8 ms: 20
  373. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
  374. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_media.c:8972 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101
  375. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_media.c:8979 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set 2833 dtmf receive payload to 101
  376. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_core_media.c:9002 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Set rtp dtmf delay to 40
  377. 2024-02-23 11:25:13.966461 95.13% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/9669963218@pk1betav602.astppbilling.org:5078:
  378. v=0
  379. o=FreeSWITCH 1708665159 1708665160 IN IP4 35.154.93.145
  380. s=FreeSWITCH
  381. c=IN IP4 35.154.93.145
  382. t=0 0
  383. m=audio 22354 RTP/AVP 8 101
  384. a=rtpmap:8 PCMA/8000
  385. a=rtpmap:101 telephone-event/8000
  386. a=fmtp:101 0-15
  387. a=ptime:20
  388. a=sendrecv
  389.  
  390. 2024-02-23 11:25:13.966461 95.13% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [completed][200]
  391. 2024-02-23 11:25:13.966461 95.13% [NOTICE] mod_dptools.c:1406 Channel [sofia/default/9669963218@pk1betav602.astppbilling.org:5078] has been answered
  392. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_channel.c:3912 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change RINGING -> ACTIVE
  393. EXECUTE [depth=0] sofia/default/9669963218@pk1betav602.astppbilling.org:5078 voicemail(check default pk1betav602.astppbilling.org 9669963218)
  394. 2024-02-23 11:25:13.966461 95.13% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  395. 2024-02-23 11:25:14.166462 95.13% [DEBUG] sofia.c:7493 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 entering state [ready][200]
  396. 2024-02-23 11:25:14.186465 95.13% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
  397. 2024-02-23 11:25:14.186465 95.13% [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  398. 2024-02-23 11:25:14.206466 95.13% [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-enter_pass.wav] (en:en)
  399. 2024-02-23 11:25:14.206466 95.13% [DEBUG] switch_ivr_play_say.c:262 Setting playback volume to 0
  400. 2024-02-23 11:25:14.206466 95.13% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  401. 2024-02-23 11:25:16.126544 95.07% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-enter_pass.wav
  402. 2024-02-23 11:25:16.246488 95.07% [DEBUG] switch_ivr_play_say.c:250 Handle say:[#] (en:en)
  403. span style="color: goldenrod; font-weight: bold;"> 2024-02-23 11:25:16.246488 95.07% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms
  404. 2024-02-23 11:25:16.906465 95.00% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://ascii/35.wav
  405. 2024-02-23 11:25:17.666468 95.07% [NOTICE] sofia.c:1065 Hangup sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [CS_EXECUTE] [NORMAL_CLEARING]
  406. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_session.c:2979 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  407. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State EXECUTE going to sleep
  408. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_HANGUP (Cur 1 Tot 9260)
  409. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Callstate Change ACTIVE -> HANGUP
  410. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State HANGUP
  411. 2024-02-23 11:25:17.666468 95.07% [DEBUG] mod_sofia.c:469 Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 hanging up, cause: NORMAL_CLEARING
  412. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard HANGUP, cause: NORMAL_CLEARING
  413. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State HANGUP going to sleep
  414. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_HANGUP -> CS_REPORTING
  415. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_REPORTING (Cur 1 Tot 9260)
  416. 2024-02-23 11:25:17.666468 95.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State REPORTING
  417. 2024-02-23 11:25:17.666468 95.07% [INFO] mod_json_cdr.c:272 Process [53f7c52b-ff08-43b4-94cf-93053b25cf8a.cdr.json]
  418. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard REPORTING, cause: NORMAL_CLEARING
  419. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State REPORTING going to sleep
  420. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State Change CS_REPORTING -> CS_DESTROY
  421. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_session.c:1744 Session 9260 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Locked, Waiting on external entities
  422. 2024-02-23 11:25:17.686467 95.07% [NOTICE] switch_core_session.c:1762 Session 9260 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Ended
  423. 2024-02-23 11:25:17.686467 95.07% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/9669963218@pk1betav602.astppbilling.org:5078 [CS_DESTROY]
  424. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) Running State Change CS_DESTROY (Cur 0 Tot 9260)
  425. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State DESTROY
  426. 2024-02-23 11:25:17.686467 95.07% [DEBUG] mod_sofia.c:380 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 SOFIA DESTROY
  427. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/9669963218@pk1betav602.astppbilling.org:5078 Standard DESTROY
  428. 2024-02-23 11:25:17.686467 95.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9669963218@pk1betav602.astppbilling.org:5078) State DESTROY going to sleep
  429. freeswitch@ip-172-31-42-21>
  430. freeswitch@ip-172-31-42-21>
  431. freeswitch@ip-172-31-42-21> /exit
  432.  
  433. You have new mail in /var/mail/root
  434. root@ip-172-31-42-21:/opt#
  435. root@ip-172-31-42-21:/opt#

Reply to "Voicemail working by any numbers with * prefix "