FreeSWITCH Pastebin

Voice broadcast is not working,

From Prashant Kumar, 1 Year ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/4e9a60c4
Embed
Download Paste or View Raw
  1. Type /help <enter> to see a list of commands
  2.  
  3.  
  4.  
  5. +OK log level  [7]
  6. freeswitch@ip-172-31-40-49>
  7. freeswitch@ip-172-31-40-49>
  8. freeswitch@ip-172-31-40-49>
  9. freeswitch@ip-172-31-40-49>
  10. freeswitch@ip-172-31-40-49>
  11. freeswitch@ip-172-31-40-49>
  12. freeswitch@ip-172-31-40-49>
  13. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  14. 2022-09-22 12:18:03.069963 97.80% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@13.127.15.161:1 [2ce42887-20ab-48eb-8e56-8faac22ba62b]
  15. 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:5121 (sofia/default/1515121325@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  16. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 118)
  17. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@13.127.15.161:1) State INIT
  18. 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@13.127.15.161:1 SOFIA INIT
  19. 2022-09-22 12:18:03.069963 97.80% [INFO] sofia_glue.c:1651 sofia/default/1515121325@13.127.15.161:1 sending invite call-id: (null)
  20. 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia_glue.c:1654 sofia/default/1515121325@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  21. Local SDP:
  22. v=0
  23. o=FreeSWITCH 1663817287 1663817288 IN IP4 13.127.15.161
  24. s=FreeSWITCH
  25. c=IN IP4 13.127.15.161
  26. t=0 0
  27. m=audio 31796 RTP/AVP 8 0 101
  28. a=rtpmap:8 PCMA/8000
  29. a=rtpmap:0 PCMU/8000
  30. a=rtpmap:101 telephone-event/8000
  31. a=fmtp:101 0-15
  32. a=ptime:20
  33. a=sendrecv
  34.  
  35. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@13.127.15.161:1 Standard INIT
  36. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  37. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@13.127.15.161:1) State INIT going to sleep
  38. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 118)
  39. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@13.127.15.161:1) State ROUTING
  40. 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@13.127.15.161:1 SOFIA ROUTING
  41. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1515121325@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  42. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@13.127.15.161:1) State ROUTING going to sleep
  43. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 118)
  44. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@13.127.15.161:1) State CONSUME_MEDIA
  45. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  46. 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@13.127.15.161:1 entering state [calling][0]
  47. 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@13.127.15.161:1 entering state [terminated][503]
  48. 2022-09-22 12:18:03.069963 97.80% [NOTICE] sofia.c:8738 Hangup sofia/default/1515121325@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  49. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 118)
  50. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  51. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@13.127.15.161:1) State HANGUP
  52. 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  53. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  54. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@13.127.15.161:1) State HANGUP going to sleep
  55. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  56. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 118)
  57. 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@13.127.15.161:1) State REPORTING
  58. 2022-09-22 12:18:03.069963 97.80% [INFO] mod_json_cdr.c:271 Process [2ce42887-20ab-48eb-8e56-8faac22ba62b.cdr.json]
  59. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  60. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@13.127.15.161:1) State REPORTING going to sleep
  61. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  62. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_session.c:1753 Session 118 (sofia/default/1515121325@13.127.15.161:1) Locked, Waiting on external entities
  63. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  64. 2022-09-22 12:18:03.089923 97.80% [NOTICE] switch_core_session.c:1771 Session 118 (sofia/default/1515121325@13.127.15.161:1) Ended
  65. 2022-09-22 12:18:03.089923 97.80% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@13.127.15.161:1 [CS_DESTROY]
  66. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 118)
  67. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@13.127.15.161:1) State DESTROY
  68. 2022-09-22 12:18:03.089923 97.80% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@13.127.15.161:1 SOFIA DESTROY
  69. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@13.127.15.161:1 Standard DESTROY
  70. 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@13.127.15.161:1) State DESTROY going to sleep
  71. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  72. 2022-09-22 12:18:04.109928 97.73% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@13.127.15.161:1 [54f5988c-c359-4c9e-9997-71e7d7daf73e]
  73. 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:5121 (sofia/default/2536854752@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  74. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 119)
  75. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@13.127.15.161:1) State INIT
  76. 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@13.127.15.161:1 SOFIA INIT
  77. 2022-09-22 12:18:04.109928 97.73% [INFO] sofia_glue.c:1651 sofia/default/2536854752@13.127.15.161:1 sending invite call-id: (null)
  78. 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia_glue.c:1654 sofia/default/2536854752@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  79. Local SDP:
  80. v=0
  81. o=FreeSWITCH 1663823858 1663823859 IN IP4 13.127.15.161
  82. s=FreeSWITCH
  83. c=IN IP4 13.127.15.161
  84. t=0 0
  85. m=audio 25226 RTP/AVP 8 0 101
  86. a=rtpmap:8 PCMA/8000
  87. a=rtpmap:0 PCMU/8000
  88. a=rtpmap:101 telephone-event/8000
  89. a=fmtp:101 0-15
  90. a=ptime:20
  91. a=sendrecv
  92.  
  93. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@13.127.15.161:1 Standard INIT
  94. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  95. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@13.127.15.161:1) State INIT going to sleep
  96. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 119)
  97. 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@13.127.15.161:1 entering state [calling][0]
  98. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@13.127.15.161:1) State ROUTING
  99. 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@13.127.15.161:1 SOFIA ROUTING
  100. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_ivr_originate.c:67 (sofia/default/2536854752@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  101. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@13.127.15.161:1) State ROUTING going to sleep
  102. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 119)
  103. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@13.127.15.161:1) State CONSUME_MEDIA
  104. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  105. 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@13.127.15.161:1 entering state [terminated][503]
  106. 2022-09-22 12:18:04.109928 97.73% [NOTICE] sofia.c:8738 Hangup sofia/default/2536854752@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  107. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 119)
  108. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  109. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@13.127.15.161:1) State HANGUP
  110. 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  111. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  112. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@13.127.15.161:1) State HANGUP going to sleep
  113. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  114. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 119)
  115. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@13.127.15.161:1) State REPORTING
  116. 2022-09-22 12:18:04.109928 97.73% [INFO] mod_json_cdr.c:271 Process [54f5988c-c359-4c9e-9997-71e7d7daf73e.cdr.json]
  117. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  118. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@13.127.15.161:1) State REPORTING going to sleep
  119. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  120. 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_session.c:1753 Session 119 (sofia/default/2536854752@13.127.15.161:1) Locked, Waiting on external entities
  121. 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  122. 2022-09-22 12:18:04.129921 97.73% [NOTICE] switch_core_session.c:1771 Session 119 (sofia/default/2536854752@13.127.15.161:1) Ended
  123. 2022-09-22 12:18:04.129921 97.73% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@13.127.15.161:1 [CS_DESTROY]
  124. 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 119)
  125. 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@13.127.15.161:1) State DESTROY
  126. 2022-09-22 12:18:04.129921 97.73% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@13.127.15.161:1 SOFIA DESTROY
  127. 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@13.127.15.161:1 Standard DESTROY
  128. 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@13.127.15.161:1) State DESTROY going to sleep
  129. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  130. 2022-09-22 12:18:05.129932 97.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@13.127.15.161:1 [adb812e1-d181-41f2-8a9d-dedfd9d608cd]
  131. 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:5121 (sofia/default/1554856322@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  132. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 120)
  133. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@13.127.15.161:1) State INIT
  134. 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@13.127.15.161:1 SOFIA INIT
  135. 2022-09-22 12:18:05.129932 97.67% [INFO] sofia_glue.c:1651 sofia/default/1554856322@13.127.15.161:1 sending invite call-id: (null)
  136. 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia_glue.c:1654 sofia/default/1554856322@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  137. Local SDP:
  138. v=0
  139. o=FreeSWITCH 1663823961 1663823962 IN IP4 13.127.15.161
  140. s=FreeSWITCH
  141. c=IN IP4 13.127.15.161
  142. t=0 0
  143. m=audio 25124 RTP/AVP 8 0 101
  144. a=rtpmap:8 PCMA/8000
  145. a=rtpmap:0 PCMU/8000
  146. a=rtpmap:101 telephone-event/8000
  147. a=fmtp:101 0-15
  148. a=ptime:20
  149. a=sendrecv
  150.  
  151. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@13.127.15.161:1 Standard INIT
  152. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  153. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@13.127.15.161:1) State INIT going to sleep
  154. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 120)
  155. 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@13.127.15.161:1 entering state [calling][0]
  156. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@13.127.15.161:1) State ROUTING
  157. 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@13.127.15.161:1 SOFIA ROUTING
  158. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1554856322@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  159. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@13.127.15.161:1) State ROUTING going to sleep
  160. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 120)
  161. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@13.127.15.161:1) State CONSUME_MEDIA
  162. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  163. 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@13.127.15.161:1 entering state [terminated][503]
  164. 2022-09-22 12:18:05.129932 97.67% [NOTICE] sofia.c:8738 Hangup sofia/default/1554856322@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  165. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 120)
  166. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  167. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@13.127.15.161:1) State HANGUP
  168. 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  169. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  170. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@13.127.15.161:1) State HANGUP going to sleep
  171. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  172. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 120)
  173. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@13.127.15.161:1) State REPORTING
  174. 2022-09-22 12:18:05.129932 97.67% [INFO] mod_json_cdr.c:271 Process [adb812e1-d181-41f2-8a9d-dedfd9d608cd.cdr.json]
  175. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  176. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@13.127.15.161:1) State REPORTING going to sleep
  177. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  178. 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_session.c:1753 Session 120 (sofia/default/1554856322@13.127.15.161:1) Locked, Waiting on external entities
  179. 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  180. 2022-09-22 12:18:05.149948 97.67% [NOTICE] switch_core_session.c:1771 Session 120 (sofia/default/1554856322@13.127.15.161:1) Ended
  181. 2022-09-22 12:18:05.149948 97.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@13.127.15.161:1 [CS_DESTROY]
  182. 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 120)
  183. 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@13.127.15.161:1) State DESTROY
  184. 2022-09-22 12:18:05.149948 97.67% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@13.127.15.161:1 SOFIA DESTROY
  185. 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@13.127.15.161:1 Standard DESTROY
  186. 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@13.127.15.161:1) State DESTROY going to sleep
  187. 2022-09-22 12:18:06.149931 97.60% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  188. 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@13.127.15.161:1 [8d0bba73-f95b-481f-a9a7-83486c6740da]
  189. 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:5121 (sofia/default/1547964325@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  190. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 121)
  191. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@13.127.15.161:1) State INIT
  192. 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@13.127.15.161:1 SOFIA INIT
  193. 2022-09-22 12:18:06.169918 97.60% [INFO] sofia_glue.c:1651 sofia/default/1547964325@13.127.15.161:1 sending invite call-id: (null)
  194. 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia_glue.c:1654 sofia/default/1547964325@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  195. Local SDP:
  196. v=0
  197. o=FreeSWITCH 1663825622 1663825623 IN IP4 13.127.15.161
  198. s=FreeSWITCH
  199. c=IN IP4 13.127.15.161
  200. t=0 0
  201. m=audio 23464 RTP/AVP 8 0 101
  202. a=rtpmap:8 PCMA/8000
  203. a=rtpmap:0 PCMU/8000
  204. a=rtpmap:101 telephone-event/8000
  205. a=fmtp:101 0-15
  206. a=ptime:20
  207. a=sendrecv
  208.  
  209. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@13.127.15.161:1 Standard INIT
  210. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  211. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@13.127.15.161:1) State INIT going to sleep
  212. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 121)
  213. 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@13.127.15.161:1 entering state [calling][0]
  214. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@13.127.15.161:1) State ROUTING
  215. 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@13.127.15.161:1 SOFIA ROUTING
  216. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1547964325@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  217. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@13.127.15.161:1) State ROUTING going to sleep
  218. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 121)
  219. 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@13.127.15.161:1 entering state [terminated][503]
  220. 2022-09-22 12:18:06.169918 97.60% [NOTICE] sofia.c:8738 Hangup sofia/default/1547964325@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  221. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@13.127.15.161:1) State CONSUME_MEDIA
  222. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  223. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 121)
  224. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  225. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@13.127.15.161:1) State HANGUP
  226. 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  227. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  228. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@13.127.15.161:1) State HANGUP going to sleep
  229. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  230. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 121)
  231. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@13.127.15.161:1) State REPORTING
  232. 2022-09-22 12:18:06.169918 97.60% [INFO] mod_json_cdr.c:271 Process [8d0bba73-f95b-481f-a9a7-83486c6740da.cdr.json]
  233. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  234. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:168 sofia/default/1547964325@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  235. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@13.127.15.161:1) State REPORTING going to sleep
  236. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1547964325@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  237. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_session.c:1753 Session 121 (sofia/default/1547964325@13.127.15.161:1) Locked, Waiting on external entities
  238. 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_core_session.c:1771 Session 121 (sofia/default/1547964325@13.127.15.161:1) Ended
  239. 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1547964325@13.127.15.161:1 [CS_DESTROY]
  240. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 121)
  241. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@13.127.15.161:1) State DESTROY
  242. 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:379 sofia/default/1547964325@13.127.15.161:1 SOFIA DESTROY
  243. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:175 sofia/default/1547964325@13.127.15.161:1 Standard DESTROY
  244. 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@13.127.15.161:1) State DESTROY going to sleep
  245. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  246. 2022-09-22 12:18:07.169965 97.37% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1030303044@13.127.15.161:1 [250a01f7-1f9c-4bd5-b4df-a0c16f552a37]
  247. 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:5121 (sofia/default/1030303044@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  248. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 122)
  249. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@13.127.15.161:1) State INIT
  250. 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:97 sofia/default/1030303044@13.127.15.161:1 SOFIA INIT
  251. 2022-09-22 12:18:07.169965 97.37% [INFO] sofia_glue.c:1651 sofia/default/1030303044@13.127.15.161:1 sending invite call-id: (null)
  252. 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia_glue.c:1654 sofia/default/1030303044@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  253. Local SDP:
  254. v=0
  255. o=FreeSWITCH 1663822861 1663822862 IN IP4 13.127.15.161
  256. s=FreeSWITCH
  257. c=IN IP4 13.127.15.161
  258. t=0 0
  259. m=audio 26226 RTP/AVP 8 0 101
  260. a=rtpmap:8 PCMA/8000
  261. a=rtpmap:0 PCMU/8000
  262. a=rtpmap:101 telephone-event/8000
  263. a=fmtp:101 0-15
  264. a=ptime:20
  265. a=sendrecv
  266.  
  267. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:40 sofia/default/1030303044@13.127.15.161:1 Standard INIT
  268. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1030303044@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  269. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@13.127.15.161:1) State INIT going to sleep
  270. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 122)
  271. 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@13.127.15.161:1 entering state [calling][0]
  272. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@13.127.15.161:1) State ROUTING
  273. 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:158 sofia/default/1030303044@13.127.15.161:1 SOFIA ROUTING
  274. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1030303044@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  275. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@13.127.15.161:1) State ROUTING going to sleep
  276. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 122)
  277. 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@13.127.15.161:1 entering state [terminated][503]
  278. 2022-09-22 12:18:07.169965 97.37% [NOTICE] sofia.c:8738 Hangup sofia/default/1030303044@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  279. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@13.127.15.161:1) State CONSUME_MEDIA
  280. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  281. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 122)
  282. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1030303044@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  283. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@13.127.15.161:1) State HANGUP
  284. 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:468 Channel sofia/default/1030303044@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  285. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:59 sofia/default/1030303044@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  286. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@13.127.15.161:1) State HANGUP going to sleep
  287. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1030303044@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  288. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 122)
  289. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@13.127.15.161:1) State REPORTING
  290. 2022-09-22 12:18:07.169965 97.37% [INFO] mod_json_cdr.c:271 Process [250a01f7-1f9c-4bd5-b4df-a0c16f552a37.cdr.json]
  291. 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  292. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:168 sofia/default/1030303044@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  293. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@13.127.15.161:1) State REPORTING going to sleep
  294. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1030303044@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  295. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_session.c:1753 Session 122 (sofia/default/1030303044@13.127.15.161:1) Locked, Waiting on external entities
  296. 2022-09-22 12:18:07.189968 97.37% [NOTICE] switch_core_session.c:1771 Session 122 (sofia/default/1030303044@13.127.15.161:1) Ended
  297. 2022-09-22 12:18:07.189968 97.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1030303044@13.127.15.161:1 [CS_DESTROY]
  298. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 122)
  299. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@13.127.15.161:1) State DESTROY
  300. 2022-09-22 12:18:07.189968 97.37% [DEBUG] mod_sofia.c:379 sofia/default/1030303044@13.127.15.161:1 SOFIA DESTROY
  301. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:175 sofia/default/1030303044@13.127.15.161:1 Standard DESTROY
  302. 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@13.127.15.161:1) State DESTROY going to sleep
  303. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  304. 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1039999999@13.127.15.161:1 [77c0d747-501a-46f4-b906-334995bca531]
  305. 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:5121 (sofia/default/1039999999@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  306. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 123)
  307. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@13.127.15.161:1) State INIT
  308. 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:97 sofia/default/1039999999@13.127.15.161:1 SOFIA INIT
  309. 2022-09-22 12:18:08.189955 97.37% [INFO] sofia_glue.c:1651 sofia/default/1039999999@13.127.15.161:1 sending invite call-id: (null)
  310. 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia_glue.c:1654 sofia/default/1039999999@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  311. Local SDP:
  312. v=0
  313. o=FreeSWITCH 1663820050 1663820051 IN IP4 13.127.15.161
  314. s=FreeSWITCH
  315. c=IN IP4 13.127.15.161
  316. t=0 0
  317. m=audio 29038 RTP/AVP 8 0 101
  318. a=rtpmap:8 PCMA/8000
  319. a=rtpmap:0 PCMU/8000
  320. a=rtpmap:101 telephone-event/8000
  321. a=fmtp:101 0-15
  322. a=ptime:20
  323. a=sendrecv
  324.  
  325. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:40 sofia/default/1039999999@13.127.15.161:1 Standard INIT
  326. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1039999999@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  327. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@13.127.15.161:1) State INIT going to sleep
  328. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 123)
  329. 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@13.127.15.161:1 entering state [calling][0]
  330. 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@13.127.15.161:1 entering state [terminated][503]
  331. 2022-09-22 12:18:08.189955 97.37% [NOTICE] sofia.c:8738 Hangup sofia/default/1039999999@13.127.15.161:1 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  332. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@13.127.15.161:1) State ROUTING
  333. 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:158 sofia/default/1039999999@13.127.15.161:1 SOFIA ROUTING
  334. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@13.127.15.161:1) State ROUTING going to sleep
  335. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 123)
  336. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1039999999@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  337. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@13.127.15.161:1) State HANGUP
  338. 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:468 Channel sofia/default/1039999999@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  339. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:59 sofia/default/1039999999@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  340. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@13.127.15.161:1) State HANGUP going to sleep
  341. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1039999999@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  342. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 123)
  343. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@13.127.15.161:1) State REPORTING
  344. 2022-09-22 12:18:08.189955 97.37% [INFO] mod_json_cdr.c:271 Process [77c0d747-501a-46f4-b906-334995bca531.cdr.json]
  345. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  346. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:168 sofia/default/1039999999@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  347. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@13.127.15.161:1) State REPORTING going to sleep
  348. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1039999999@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  349. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_session.c:1753 Session 123 (sofia/default/1039999999@13.127.15.161:1) Locked, Waiting on external entities
  350. 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_core_session.c:1771 Session 123 (sofia/default/1039999999@13.127.15.161:1) Ended
  351. 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1039999999@13.127.15.161:1 [CS_DESTROY]
  352. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 123)
  353. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@13.127.15.161:1) State DESTROY
  354. 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:379 sofia/default/1039999999@13.127.15.161:1 SOFIA DESTROY
  355. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:175 sofia/default/1039999999@13.127.15.161:1 Standard DESTROY
  356. 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@13.127.15.161:1) State DESTROY going to sleep
  357. 2022-09-22 12:18:09.189958 97.27% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  358. 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1077777777@13.127.15.161:1 [54836d79-ee18-4cf0-9db0-4768936a4ee1]
  359. 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:5121 (sofia/default/1077777777@13.127.15.161:1) State Change CS_NEW -> CS_INIT
  360. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 124)
  361. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@13.127.15.161:1) State INIT
  362. 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:97 sofia/default/1077777777@13.127.15.161:1 SOFIA INIT
  363. 2022-09-22 12:18:09.209952 97.27% [INFO] sofia_glue.c:1651 sofia/default/1077777777@13.127.15.161:1 sending invite call-id: (null)
  364. 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia_glue.c:1654 sofia/default/1077777777@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  365. Local SDP:
  366. v=0
  367. o=FreeSWITCH 1663822035 1663822036 IN IP4 13.127.15.161
  368. s=FreeSWITCH
  369. c=IN IP4 13.127.15.161
  370. t=0 0
  371. m=audio 27054 RTP/AVP 8 0 101
  372. a=rtpmap:8 PCMA/8000
  373. a=rtpmap:0 PCMU/8000
  374. a=rtpmap:101 telephone-event/8000
  375. a=fmtp:101 0-15
  376. a=ptime:20
  377. a=sendrecv
  378.  
  379. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1077777777@13.127.15.161:1 Standard INIT
  380. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1077777777@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING
  381. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@13.127.15.161:1) State INIT going to sleep
  382. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 124)
  383. 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@13.127.15.161:1 entering state [calling][0]
  384. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@13.127.15.161:1) State ROUTING
  385. 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:158 sofia/default/1077777777@13.127.15.161:1 SOFIA ROUTING
  386. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1077777777@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  387. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@13.127.15.161:1) State ROUTING going to sleep
  388. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 124)
  389. 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@13.127.15.161:1 entering state [terminated][503]
  390. 2022-09-22 12:18:09.209952 97.27% [NOTICE] sofia.c:8738 Hangup sofia/default/1077777777@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  391. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@13.127.15.161:1) State CONSUME_MEDIA
  392. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@13.127.15.161:1) State CONSUME_MEDIA going to sleep
  393. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 124)
  394. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1077777777@13.127.15.161:1) Callstate Change DOWN -> HANGUP
  395. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@13.127.15.161:1) State HANGUP
  396. 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1077777777@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  397. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1077777777@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  398. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@13.127.15.161:1) State HANGUP going to sleep
  399. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1077777777@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING
  400. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 124)
  401. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@13.127.15.161:1) State REPORTING
  402. 2022-09-22 12:18:09.209952 97.27% [INFO] mod_json_cdr.c:271 Process [54836d79-ee18-4cf0-9db0-4768936a4ee1.cdr.json]
  403. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  404. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1077777777@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  405. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@13.127.15.161:1) State REPORTING going to sleep
  406. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1077777777@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY
  407. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_session.c:1753 Session 124 (sofia/default/1077777777@13.127.15.161:1) Locked, Waiting on external entities
  408. 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_core_session.c:1771 Session 124 (sofia/default/1077777777@13.127.15.161:1) Ended
  409. 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1077777777@13.127.15.161:1 [CS_DESTROY]
  410. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 124)
  411. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@13.127.15.161:1) State DESTROY
  412. 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:379 sofia/default/1077777777@13.127.15.161:1 SOFIA DESTROY
  413. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1077777777@13.127.15.161:1 Standard DESTROY
  414. 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@13.127.15.161:1) State DESTROY going to sleep
  415. freeswitch@ip-172-31-40-49>

Reply to "Voice broadcast is not working, "