FreeSWITCH Pastebin

voice Broadcasting is not working.

From Prashant Kumar, 2 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/01a95879
Embed
Viewing differences between and voice Broadcasting is not working.
  1. freeswitch@ip-172-31-40-49>
  2. 2022-09-28 11:21:46.320647 92.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  3. 2022-09-28 11:21:46.520655 92.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  4. 2022-09-28 11:21:54.600730 97.43% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  5. 2022-09-28 11:21:54.800659 97.43% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  6. 2022-09-28 11:22:14.880659 88.17% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  7. 2022-09-28 11:22:15.080656 88.17% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  8. 2022-09-28 11:22:16.560680 87.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  9. 2022-09-28 11:22:16.580657 87.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  10. 2022-09-28 11:22:16.580657 87.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  11. 2022-09-28 11:22:16.580657 87.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  12. 2022-09-28 11:22:24.520678 85.73% [NOTICE] sofia_reg.c:459 Registering 8134791837
  13. 2022-09-28 11:22:24.780681 85.73% [ERR] sofia_reg.c:2677 8134791837 Failed Registration with status Forbidden [403]. failure #3
  14. 2022-09-28 11:22:25.520678 86.60% [WARNING] sofia_reg.c:516 8134791837 Failed Registration [403], setting retry to 90 seconds.
  15. 2022-09-28 11:22:32.420674 88.30% [NOTICE] switch_channel.c:1123 New Channel sofia/default/900090111312715161@13.127.15.161 [e5368728-bfab-47fb-ae5c-439ac7b7ab42]
  16. 2022-09-28 11:22:32.420674 88.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/900090111312715161@13.127.15.161) Running State Change CS_NEW (Cur 1 Tot 3383)
  17. 2022-09-28 11:22:32.420674 88.30% [INFO] sofia.c:10462 sofia/default/900090111312715161@13.127.15.161 receiving invite from 146.70.88.246:58539 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 1840783406-1731873435-674215418
  18. 2022-09-28 11:22:32.420674 88.30% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 146.70.88.246:0.
  19. 2022-09-28 11:22:32.420674 88.30% [DEBUG] sofia.c:2419 detaching session e5368728-bfab-47fb-ae5c-439ac7b7ab42
  20. 2022-09-28 11:22:32.420674 88.30% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [90009011972598112101@13.127.15.161] from ip 146.70.88.246
  21. 2022-09-28 11:22:32.420674 88.30% [DEBUG] switch_core_state_machine.c:600 (sofia/default/900090111312715161@13.127.15.161) State NEW
  22. 2022-09-28 11:22:42.440647 95.07% [WARNING] switch_core_state_machine.c:684 e5368728-bfab-47fb-ae5c-439ac7b7ab42 sofia/default/900090111312715161@13.127.15.161 Abandoned
  23. 2022-09-28 11:22:42.440647 95.07% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/default/900090111312715161@13.127.15.161 [CS_NEW] [WRONG_CALL_STATE]
  24. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/900090111312715161@13.127.15.161) Running State Change CS_HANGUP (Cur 1 Tot 3383)
  25. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/900090111312715161@13.127.15.161) Callstate Change DOWN -> HANGUP
  26. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/900090111312715161@13.127.15.161) State HANGUP
  27. 2022-09-28 11:22:42.440647 95.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/900090111312715161@13.127.15.161 hanging up, cause: WRONG_CALL_STATE
  28. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/900090111312715161@13.127.15.161 Standard HANGUP, cause: WRONG_CALL_STATE
  29. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/900090111312715161@13.127.15.161) State HANGUP going to sleep
  30. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/900090111312715161@13.127.15.161) State Change CS_HANGUP -> CS_REPORTING
  31. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/900090111312715161@13.127.15.161) Running State Change CS_REPORTING (Cur 1 Tot 3383)
  32. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/900090111312715161@13.127.15.161) State REPORTING
  33. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/900090111312715161@13.127.15.161 Standard REPORTING, cause: WRONG_CALL_STATE
  34. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/900090111312715161@13.127.15.161) State REPORTING going to sleep
  35. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/900090111312715161@13.127.15.161) State Change CS_REPORTING -> CS_DESTROY
  36. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_session.c:1753 Session 3383 (sofia/default/900090111312715161@13.127.15.161) Locked, Waiting on external entities
  37. 2022-09-28 11:22:42.440647 95.07% [NOTICE] switch_core_session.c:1771 Session 3383 (sofia/default/900090111312715161@13.127.15.161) Ended
  38. 2022-09-28 11:22:42.440647 95.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/900090111312715161@13.127.15.161 [CS_DESTROY]
  39. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/900090111312715161@13.127.15.161) Running State Change CS_DESTROY (Cur 0 Tot 3383)
  40. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/900090111312715161@13.127.15.161) State DESTROY
  41. 2022-09-28 11:22:42.440647 95.07% [DEBUG] mod_sofia.c:379 sofia/default/900090111312715161@13.127.15.161 SOFIA DESTROY
  42. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/900090111312715161@13.127.15.161 Standard DESTROY
  43. 2022-09-28 11:22:42.440647 95.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/900090111312715161@13.127.15.161) State DESTROY going to sleep
  44. 2022-09-28 11:22:47.080658 94.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  45. 2022-09-28 11:22:47.100658 94.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  46. 2022-09-28 11:23:19.140650 76.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  47. 2022-09-28 11:23:19.300672 76.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  48. 2022-09-28 11:23:19.300672 76.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  49. 2022-09-28 11:23:19.480658 76.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  50. 2022-09-28 11:23:21.120669 76.37% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  51. 2022-09-28 11:23:21.160667 75.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  52. 2022-09-28 11:23:21.340650 75.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  53. 2022-09-28 11:23:21.360664 75.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  54. 2022-09-28 11:23:51.520648 82.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [123@13.127.15.161] from ip 103.240.35.46
  55. 2022-09-28 11:23:51.540668 82.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1911@13.127.15.161] from ip 103.240.35.46
  56. 2022-09-28 11:23:56.620658 81.03% [NOTICE] sofia_reg.c:459 Registering 8134791837
  57. 2022-09-28 11:23:56.900685 81.03% [ERR] sofia_reg.c:2677 8134791837 Failed Registration with status Forbidden [403]. failure #4
  58. 2022-09-28 11:23:57.620693 80.50% [WARNING] sofia_reg.c:516 8134791837 Failed Registration [403], setting retry to 120 seconds.
  59. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  60. 2022-09-28 11:24:03.560650 74.87% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@127.0.0.1:5060 [0229b1be-5386-4026-9dea-80e728cabde0]
  61. 2022-09-28 11:24:03.560650 74.87% [DEBUG] mod_sofia.c:5121 (sofia/default/1515121325@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  62. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3384)
  63. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@127.0.0.1:5060) State INIT
  64. 2022-09-28 11:24:03.560650 74.87% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@127.0.0.1:5060 SOFIA INIT
  65. 2022-09-28 11:24:03.560650 74.87% [INFO] sofia_glue.c:1651 sofia/default/1515121325@127.0.0.1:5060 sending invite call-id: (null)
  66. 2022-09-28 11:24:03.560650 74.87% [DEBUG] sofia_glue.c:1654 sofia/default/1515121325@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  67. Local SDP:
  68. v=0
  69. o=FreeSWITCH 1664347185 1664347186 IN IP4 172.31.40.49
  70. s=FreeSWITCH
  71. c=IN IP4 172.31.40.49
  72. t=0 0
  73. m=audio 17058 RTP/AVP 8 0 101
  74. a=rtpmap:8 PCMA/8000
  75. a=rtpmap:0 PCMU/8000
  76. a=rtpmap:101 telephone-event/8000
  77. a=fmtp:101 0-15
  78. a=ptime:20
  79. a=sendrecv
  80.  
  81. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@127.0.0.1:5060 Standard INIT
  82. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  83. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@127.0.0.1:5060) State INIT going to sleep
  84. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3384)
  85. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@127.0.0.1:5060) State ROUTING
  86. 2022-09-28 11:24:03.560650 74.87% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@127.0.0.1:5060 SOFIA ROUTING
  87. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1515121325@127.0.0.1:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  88. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@127.0.0.1:5060) State ROUTING going to sleep
  89. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 3384)
  90. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@127.0.0.1:5060) State CONSUME_MEDIA
  91. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@127.0.0.1:5060) State CONSUME_MEDIA going to sleep
  92. 2022-09-28 11:24:03.560650 74.87% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@127.0.0.1:5060 entering state [calling][0]
  93. 2022-09-28 11:24:03.560650 74.87% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@127.0.0.1:5060 entering state [terminated][503]
  94. 2022-09-28 11:24:03.560650 74.87% [NOTICE] sofia.c:8738 Hangup sofia/default/1515121325@127.0.0.1:5060 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  95. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3384)
  96. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  97. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@127.0.0.1:5060) State HANGUP
  98. 2022-09-28 11:24:03.560650 74.87% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  99. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  100. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@127.0.0.1:5060) State HANGUP going to sleep
  101. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  102. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3384)
  103. 2022-09-28 11:24:03.560650 74.87% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@127.0.0.1:5060) State REPORTING
  104. 2022-09-28 11:24:03.560650 74.87% [INFO] mod_json_cdr.c:271 Process [0229b1be-5386-4026-9dea-80e728cabde0.cdr.json]
  105. 2022-09-28 11:24:03.580658 74.87% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  106. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  107. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@127.0.0.1:5060) State REPORTING going to sleep
  108. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  109. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_session.c:1753 Session 3384 (sofia/default/1515121325@127.0.0.1:5060) Locked, Waiting on external entities
  110. 2022-09-28 11:24:04.160647 74.07% [NOTICE] switch_core_session.c:1771 Session 3384 (sofia/default/1515121325@127.0.0.1:5060) Ended
  111. 2022-09-28 11:24:04.160647 74.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@127.0.0.1:5060 [CS_DESTROY]
  112. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3384)
  113. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@127.0.0.1:5060) State DESTROY
  114. 2022-09-28 11:24:04.160647 74.07% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@127.0.0.1:5060 SOFIA DESTROY
  115. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@127.0.0.1:5060 Standard DESTROY
  116. 2022-09-28 11:24:04.160647 74.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@127.0.0.1:5060) State DESTROY going to sleep
  117. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  118. 2022-09-28 11:24:05.600647 74.03% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@127.0.0.1:5060 [df336261-d5ec-4a10-afbd-9815672f18d3]
  119. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:5121 (sofia/default/2536854752@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  120. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3385)
  121. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@127.0.0.1:5060) State INIT
  122. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@127.0.0.1:5060 SOFIA INIT
  123. 2022-09-28 11:24:05.600647 74.03% [INFO] sofia_glue.c:1651 sofia/default/2536854752@127.0.0.1:5060 sending invite call-id: (null)
  124. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia_glue.c:1654 sofia/default/2536854752@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  125. Local SDP:
  126. v=0
  127. o=FreeSWITCH 1664346827 1664346828 IN IP4 172.31.40.49
  128. s=FreeSWITCH
  129. c=IN IP4 172.31.40.49
  130. t=0 0
  131. m=audio 17418 RTP/AVP 8 0 101
  132. a=rtpmap:8 PCMA/8000
  133. a=rtpmap:0 PCMU/8000
  134. a=rtpmap:101 telephone-event/8000
  135. a=fmtp:101 0-15
  136. a=ptime:20
  137. a=sendrecv
  138.  
  139. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@127.0.0.1:5060 Standard INIT
  140. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  141. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@127.0.0.1:5060) State INIT going to sleep
  142. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3385)
  143. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@127.0.0.1:5060 entering state [calling][0]
  144. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@127.0.0.1:5060 entering state [terminated][503]
  145. 2022-09-28 11:24:05.600647 74.03% [NOTICE] sofia.c:8738 Hangup sofia/default/2536854752@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  146. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@127.0.0.1:5060) State ROUTING
  147. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@127.0.0.1:5060 SOFIA ROUTING
  148. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@127.0.0.1:5060) State ROUTING going to sleep
  149. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3385)
  150. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  151. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@127.0.0.1:5060) State HANGUP
  152. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  153. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  154. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@127.0.0.1:5060) State HANGUP going to sleep
  155. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  156. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3385)
  157. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@127.0.0.1:5060) State REPORTING
  158. 2022-09-28 11:24:05.600647 74.03% [INFO] mod_json_cdr.c:271 Process [df336261-d5ec-4a10-afbd-9815672f18d3.cdr.json]
  159. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  160. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  161. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@127.0.0.1:5060) State REPORTING going to sleep
  162. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  163. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_session.c:1753 Session 3385 (sofia/default/2536854752@127.0.0.1:5060) Locked, Waiting on external entities
  164. 2022-09-28 11:24:05.600647 74.03% [NOTICE] switch_core_session.c:1771 Session 3385 (sofia/default/2536854752@127.0.0.1:5060) Ended
  165. 2022-09-28 11:24:05.600647 74.03% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@127.0.0.1:5060 [CS_DESTROY]
  166. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3385)
  167. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@127.0.0.1:5060) State DESTROY
  168. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@127.0.0.1:5060 SOFIA DESTROY
  169. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@127.0.0.1:5060 Standard DESTROY
  170. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@127.0.0.1:5060) State DESTROY going to sleep
  171. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  172. 2022-09-28 11:24:05.600647 74.03% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@127.0.0.1:5060 [afa6c99c-ab20-4e4a-8580-b86d5f3f9cb9]
  173. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:5121 (sofia/default/1554856322@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  174. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3386)
  175. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@127.0.0.1:5060) State INIT
  176. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@127.0.0.1:5060 SOFIA INIT
  177. 2022-09-28 11:24:05.600647 74.03% [INFO] sofia_glue.c:1651 sofia/default/1554856322@127.0.0.1:5060 sending invite call-id: (null)
  178. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia_glue.c:1654 sofia/default/1554856322@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  179. Local SDP:
  180. v=0
  181. o=FreeSWITCH 1664346825 1664346826 IN IP4 172.31.40.49
  182. s=FreeSWITCH
  183. c=IN IP4 172.31.40.49
  184. t=0 0
  185. m=audio 17420 RTP/AVP 8 0 101
  186. a=rtpmap:8 PCMA/8000
  187. a=rtpmap:0 PCMU/8000
  188. a=rtpmap:101 telephone-event/8000
  189. a=fmtp:101 0-15
  190. a=ptime:20
  191. a=sendrecv
  192.  
  193. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@127.0.0.1:5060 Standard INIT
  194. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  195. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@127.0.0.1:5060) State INIT going to sleep
  196. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3386)
  197. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@127.0.0.1:5060 entering state [calling][0]
  198. 2022-09-28 11:24:05.600647 74.03% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@127.0.0.1:5060 entering state [terminated][503]
  199. 2022-09-28 11:24:05.600647 74.03% [NOTICE] sofia.c:8738 Hangup sofia/default/1554856322@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  200. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@127.0.0.1:5060) State ROUTING
  201. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@127.0.0.1:5060 SOFIA ROUTING
  202. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@127.0.0.1:5060) State ROUTING going to sleep
  203. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3386)
  204. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  205. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@127.0.0.1:5060) State HANGUP
  206. 2022-09-28 11:24:05.600647 74.03% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  207. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  208. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@127.0.0.1:5060) State HANGUP going to sleep
  209. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  210. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3386)
  211. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@127.0.0.1:5060) State REPORTING
  212. 2022-09-28 11:24:05.600647 74.03% [INFO] mod_json_cdr.c:271 Process [afa6c99c-ab20-4e4a-8580-b86d5f3f9cb9.cdr.json]
  213. 2022-09-28 11:24:05.600647 74.03% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  214. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  215. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@127.0.0.1:5060) State REPORTING going to sleep
  216. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  217. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_session.c:1753 Session 3386 (sofia/default/1554856322@127.0.0.1:5060) Locked, Waiting on external entities
  218. 2022-09-28 11:24:05.620649 74.03% [NOTICE] switch_core_session.c:1771 Session 3386 (sofia/default/1554856322@127.0.0.1:5060) Ended
  219. 2022-09-28 11:24:05.620649 74.03% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@127.0.0.1:5060 [CS_DESTROY]
  220. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3386)
  221. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@127.0.0.1:5060) State DESTROY
  222. 2022-09-28 11:24:05.620649 74.03% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@127.0.0.1:5060 SOFIA DESTROY
  223. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@127.0.0.1:5060 Standard DESTROY
  224. 2022-09-28 11:24:05.620649 74.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@127.0.0.1:5060) State DESTROY going to sleep
  225. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  226. 2022-09-28 11:24:07.620648 72.07% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@127.0.0.1:5060 [eeb03201-8baa-456c-8c23-5dbcff3457a4]
  227. 2022-09-28 11:24:07.620648 72.07% [DEBUG] mod_sofia.c:5121 (sofia/default/1547964325@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  228. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3387)
  229. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@127.0.0.1:5060) State INIT
  230. 2022-09-28 11:24:07.620648 72.07% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@127.0.0.1:5060 SOFIA INIT
  231. 2022-09-28 11:24:07.620648 72.07% [INFO] sofia_glue.c:1651 sofia/default/1547964325@127.0.0.1:5060 sending invite call-id: (null)
  232. 2022-09-28 11:24:07.620648 72.07% [DEBUG] sofia_glue.c:1654 sofia/default/1547964325@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  233. Local SDP:
  234. v=0
  235. o=FreeSWITCH 1664341303 1664341304 IN IP4 172.31.40.49
  236. s=FreeSWITCH
  237. c=IN IP4 172.31.40.49
  238. t=0 0
  239. m=audio 22944 RTP/AVP 8 0 101
  240. a=rtpmap:8 PCMA/8000
  241. a=rtpmap:0 PCMU/8000
  242. a=rtpmap:101 telephone-event/8000
  243. a=fmtp:101 0-15
  244. a=ptime:20
  245. a=sendrecv
  246.  
  247. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@127.0.0.1:5060 Standard INIT
  248. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  249. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@127.0.0.1:5060) State INIT going to sleep
  250. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3387)
  251. 2022-09-28 11:24:07.620648 72.07% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@127.0.0.1:5060 entering state [calling][0]
  252. 2022-09-28 11:24:07.620648 72.07% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@127.0.0.1:5060 entering state [terminated][503]
  253. 2022-09-28 11:24:07.620648 72.07% [NOTICE] sofia.c:8738 Hangup sofia/default/1547964325@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  254. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@127.0.0.1:5060) State ROUTING
  255. 2022-09-28 11:24:07.620648 72.07% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@127.0.0.1:5060 SOFIA ROUTING
  256. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@127.0.0.1:5060) State ROUTING going to sleep
  257. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3387)
  258. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  259. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@127.0.0.1:5060) State HANGUP
  260. 2022-09-28 11:24:07.620648 72.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  261. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  262. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@127.0.0.1:5060) State HANGUP going to sleep
  263. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  264. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3387)
  265. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@127.0.0.1:5060) State REPORTING
  266. 2022-09-28 11:24:07.620648 72.07% [INFO] mod_json_cdr.c:271 Process [eeb03201-8baa-456c-8c23-5dbcff3457a4.cdr.json]
  267. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  268. 2022-09-28 11:24:07.620648 72.07% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  269. 2022-09-28 11:24:07.620648 72.07% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1030303044@127.0.0.1:5060 [951ba294-ce81-4e4f-93bc-bf221f0ed6c5]
  270. 2022-09-28 11:24:07.620648 72.07% [DEBUG] mod_sofia.c:5121 (sofia/default/1030303044@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  271. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@127.0.0.1:5060) Running State Change CS_INIT (Cur 2 Tot 3388)
  272. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@127.0.0.1:5060) State INIT
  273. 2022-09-28 11:24:07.640719 72.07% [DEBUG] mod_sofia.c:97 sofia/default/1030303044@127.0.0.1:5060 SOFIA INIT
  274. 2022-09-28 11:24:07.640719 72.07% [INFO] sofia_glue.c:1651 sofia/default/1030303044@127.0.0.1:5060 sending invite call-id: (null)
  275. 2022-09-28 11:24:07.640719 72.07% [DEBUG] sofia_glue.c:1654 sofia/default/1030303044@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  276. Local SDP:
  277. v=0
  278. o=FreeSWITCH 1664345241 1664345242 IN IP4 172.31.40.49
  279. s=FreeSWITCH
  280. c=IN IP4 172.31.40.49
  281. t=0 0
  282. m=audio 19006 RTP/AVP 8 0 101
  283. a=rtpmap:8 PCMA/8000
  284. a=rtpmap:0 PCMU/8000
  285. a=rtpmap:101 telephone-event/8000
  286. a=fmtp:101 0-15
  287. a=ptime:20
  288. a=sendrecv
  289.  
  290. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:40 sofia/default/1030303044@127.0.0.1:5060 Standard INIT
  291. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1030303044@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  292. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@127.0.0.1:5060) State INIT going to sleep
  293. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 2 Tot 3388)
  294. 2022-09-28 11:24:07.640719 72.07% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@127.0.0.1:5060 entering state [calling][0]
  295. 2022-09-28 11:24:07.640719 72.07% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@127.0.0.1:5060 entering state [terminated][503]
  296. 2022-09-28 11:24:07.640719 72.07% [NOTICE] sofia.c:8738 Hangup sofia/default/1030303044@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  297. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@127.0.0.1:5060) State ROUTING
  298. 2022-09-28 11:24:07.640719 72.07% [DEBUG] mod_sofia.c:158 sofia/default/1030303044@127.0.0.1:5060 SOFIA ROUTING
  299. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@127.0.0.1:5060) State ROUTING going to sleep
  300. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 2 Tot 3388)
  301. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1030303044@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  302. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@127.0.0.1:5060) State HANGUP
  303. 2022-09-28 11:24:07.640719 72.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/1030303044@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  304. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/1030303044@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  305. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@127.0.0.1:5060) State HANGUP going to sleep
  306. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1030303044@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  307. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 2 Tot 3388)
  308. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@127.0.0.1:5060) State REPORTING
  309. 2022-09-28 11:24:07.640719 72.07% [INFO] mod_json_cdr.c:271 Process [951ba294-ce81-4e4f-93bc-bf221f0ed6c5.cdr.json]
  310. 2022-09-28 11:24:07.640719 72.07% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  311. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/1547964325@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  312. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@127.0.0.1:5060) State REPORTING going to sleep
  313. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1547964325@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  314. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_session.c:1753 Session 3387 (sofia/default/1547964325@127.0.0.1:5060) Locked, Waiting on external entities
  315. 2022-09-28 11:24:08.120649 72.07% [NOTICE] switch_core_session.c:1771 Session 3387 (sofia/default/1547964325@127.0.0.1:5060) Ended
  316. 2022-09-28 11:24:08.120649 72.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1547964325@127.0.0.1:5060 [CS_DESTROY]
  317. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1547964325@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 1 Tot 3388)
  318. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@127.0.0.1:5060) State DESTROY
  319. 2022-09-28 11:24:08.120649 72.07% [DEBUG] mod_sofia.c:379 sofia/default/1547964325@127.0.0.1:5060 SOFIA DESTROY
  320. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/1547964325@127.0.0.1:5060 Standard DESTROY
  321. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@127.0.0.1:5060) State DESTROY going to sleep
  322. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/1030303044@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  323. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@127.0.0.1:5060) State REPORTING going to sleep
  324. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1030303044@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  325. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_session.c:1753 Session 3388 (sofia/default/1030303044@127.0.0.1:5060) Locked, Waiting on external entities
  326. 2022-09-28 11:24:08.120649 72.07% [NOTICE] switch_core_session.c:1771 Session 3388 (sofia/default/1030303044@127.0.0.1:5060) Ended
  327. 2022-09-28 11:24:08.120649 72.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1030303044@127.0.0.1:5060 [CS_DESTROY]
  328. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1030303044@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3388)
  329. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@127.0.0.1:5060) State DESTROY
  330. 2022-09-28 11:24:08.120649 72.07% [DEBUG] mod_sofia.c:379 sofia/default/1030303044@127.0.0.1:5060 SOFIA DESTROY
  331. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/1030303044@127.0.0.1:5060 Standard DESTROY
  332. 2022-09-28 11:24:08.120649 72.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@127.0.0.1:5060) State DESTROY going to sleep
  333. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  334. 2022-09-28 11:24:09.640658 70.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1039999999@127.0.0.1:5060 [d5167806-ec44-45cd-a0a1-8c3cb46d0faf]
  335. 2022-09-28 11:24:09.640658 70.93% [DEBUG] mod_sofia.c:5121 (sofia/default/1039999999@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  336. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3389)
  337. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@127.0.0.1:5060) State INIT
  338. 2022-09-28 11:24:09.640658 70.93% [DEBUG] mod_sofia.c:97 sofia/default/1039999999@127.0.0.1:5060 SOFIA INIT
  339. 2022-09-28 11:24:09.640658 70.93% [INFO] sofia_glue.c:1651 sofia/default/1039999999@127.0.0.1:5060 sending invite call-id: (null)
  340. 2022-09-28 11:24:09.640658 70.93% [DEBUG] sofia_glue.c:1654 sofia/default/1039999999@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  341. Local SDP:
  342. v=0
  343. o=FreeSWITCH 1664339101 1664339102 IN IP4 172.31.40.49
  344. s=FreeSWITCH
  345. c=IN IP4 172.31.40.49
  346. t=0 0
  347. m=audio 25148 RTP/AVP 8 0 101
  348. a=rtpmap:8 PCMA/8000
  349. a=rtpmap:0 PCMU/8000
  350. a=rtpmap:101 telephone-event/8000
  351. a=fmtp:101 0-15
  352. a=ptime:20
  353. a=sendrecv
  354.  
  355. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/1039999999@127.0.0.1:5060 Standard INIT
  356. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1039999999@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  357. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@127.0.0.1:5060) State INIT going to sleep
  358. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3389)
  359. 2022-09-28 11:24:09.640658 70.93% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@127.0.0.1:5060 entering state [calling][0]
  360. 2022-09-28 11:24:09.640658 70.93% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@127.0.0.1:5060 entering state [terminated][503]
  361. 2022-09-28 11:24:09.640658 70.93% [NOTICE] sofia.c:8738 Hangup sofia/default/1039999999@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  362. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@127.0.0.1:5060) State ROUTING
  363. 2022-09-28 11:24:09.640658 70.93% [DEBUG] mod_sofia.c:158 sofia/default/1039999999@127.0.0.1:5060 SOFIA ROUTING
  364. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@127.0.0.1:5060) State ROUTING going to sleep
  365. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3389)
  366. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1039999999@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  367. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@127.0.0.1:5060) State HANGUP
  368. 2022-09-28 11:24:09.640658 70.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/1039999999@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  369. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/1039999999@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  370. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@127.0.0.1:5060) State HANGUP going to sleep
  371. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1039999999@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  372. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3389)
  373. 2022-09-28 11:24:09.640658 70.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@127.0.0.1:5060) State REPORTING
  374. 2022-09-28 11:24:09.640658 70.93% [INFO] mod_json_cdr.c:271 Process [d5167806-ec44-45cd-a0a1-8c3cb46d0faf.cdr.json]
  375. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  376. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/1039999999@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  377. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@127.0.0.1:5060) State REPORTING going to sleep
  378. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1039999999@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  379. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_session.c:1753 Session 3389 (sofia/default/1039999999@127.0.0.1:5060) Locked, Waiting on external entities
  380. 2022-09-28 11:24:09.660640 70.93% [NOTICE] switch_core_session.c:1771 Session 3389 (sofia/default/1039999999@127.0.0.1:5060) Ended
  381. 2022-09-28 11:24:09.660640 70.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1039999999@127.0.0.1:5060 [CS_DESTROY]
  382. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1039999999@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3389)
  383. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@127.0.0.1:5060) State DESTROY
  384. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:379 sofia/default/1039999999@127.0.0.1:5060 SOFIA DESTROY
  385. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/1039999999@127.0.0.1:5060 Standard DESTROY
  386. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@127.0.0.1:5060) State DESTROY going to sleep
  387. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  388. 2022-09-28 11:24:09.660640 70.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1077777777@127.0.0.1:5060 [3f0c278e-d714-4727-9202-3b7bfa7fc250]
  389. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:5121 (sofia/default/1077777777@127.0.0.1:5060) State Change CS_NEW -> CS_INIT
  390. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@127.0.0.1:5060) Running State Change CS_INIT (Cur 1 Tot 3390)
  391. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@127.0.0.1:5060) State INIT
  392. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:97 sofia/default/1077777777@127.0.0.1:5060 SOFIA INIT
  393. 2022-09-28 11:24:09.660640 70.93% [INFO] sofia_glue.c:1651 sofia/default/1077777777@127.0.0.1:5060 sending invite call-id: (null)
  394. 2022-09-28 11:24:09.660640 70.93% [DEBUG] sofia_glue.c:1654 sofia/default/1077777777@127.0.0.1:5060 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  395. Local SDP:
  396. v=0
  397. o=FreeSWITCH 1664338989 1664338990 IN IP4 172.31.40.49
  398. s=FreeSWITCH
  399. c=IN IP4 172.31.40.49
  400. t=0 0
  401. m=audio 25260 RTP/AVP 8 0 101
  402. a=rtpmap:8 PCMA/8000
  403. a=rtpmap:0 PCMU/8000
  404. a=rtpmap:101 telephone-event/8000
  405. a=fmtp:101 0-15
  406. a=ptime:20
  407. a=sendrecv
  408.  
  409. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/1077777777@127.0.0.1:5060 Standard INIT
  410. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1077777777@127.0.0.1:5060) State Change CS_INIT -> CS_ROUTING
  411. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@127.0.0.1:5060) State INIT going to sleep
  412. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@127.0.0.1:5060) Running State Change CS_ROUTING (Cur 1 Tot 3390)
  413. 2022-09-28 11:24:09.660640 70.93% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@127.0.0.1:5060 entering state [calling][0]
  414. 2022-09-28 11:24:09.660640 70.93% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@127.0.0.1:5060 entering state [terminated][503]
  415. 2022-09-28 11:24:09.660640 70.93% [NOTICE] sofia.c:8738 Hangup sofia/default/1077777777@127.0.0.1:5060 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
  416. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@127.0.0.1:5060) State ROUTING
  417. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:158 sofia/default/1077777777@127.0.0.1:5060 SOFIA ROUTING
  418. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@127.0.0.1:5060) State ROUTING going to sleep
  419. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@127.0.0.1:5060) Running State Change CS_HANGUP (Cur 1 Tot 3390)
  420. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1077777777@127.0.0.1:5060) Callstate Change DOWN -> HANGUP
  421. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@127.0.0.1:5060) State HANGUP
  422. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/1077777777@127.0.0.1:5060 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  423. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/1077777777@127.0.0.1:5060 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  424. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@127.0.0.1:5060) State HANGUP going to sleep
  425. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1077777777@127.0.0.1:5060) State Change CS_HANGUP -> CS_REPORTING
  426. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@127.0.0.1:5060) Running State Change CS_REPORTING (Cur 1 Tot 3390)
  427. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@127.0.0.1:5060) State REPORTING
  428. 2022-09-28 11:24:09.660640 70.93% [INFO] mod_json_cdr.c:271 Process [3f0c278e-d714-4727-9202-3b7bfa7fc250.cdr.json]
  429. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  430. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/1077777777@127.0.0.1:5060 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  431. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@127.0.0.1:5060) State REPORTING going to sleep
  432. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1077777777@127.0.0.1:5060) State Change CS_REPORTING -> CS_DESTROY
  433. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_session.c:1753 Session 3390 (sofia/default/1077777777@127.0.0.1:5060) Locked, Waiting on external entities
  434. 2022-09-28 11:24:09.660640 70.93% [NOTICE] switch_core_session.c:1771 Session 3390 (sofia/default/1077777777@127.0.0.1:5060) Ended
  435. 2022-09-28 11:24:09.660640 70.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1077777777@127.0.0.1:5060 [CS_DESTROY]
  436. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1077777777@127.0.0.1:5060) Running State Change CS_DESTROY (Cur 0 Tot 3390)
  437. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@127.0.0.1:5060) State DESTROY
  438. 2022-09-28 11:24:09.660640 70.93% [DEBUG] mod_sofia.c:379 sofia/default/1077777777@127.0.0.1:5060 SOFIA DESTROY
  439. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/1077777777@127.0.0.1:5060 Standard DESTROY
  440. 2022-09-28 11:24:09.660640 70.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@127.0.0.1:5060) State DESTROY going to sleep
  441. 2022-09-28 11:24:25.580677 75.23% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [4082586355@13.127.15.161] from ip 103.240.35.46
  442. freeswitch@ip-172-31-40-49> /exit
  443.  
  444. You have new mail in /var/mail/root

Reply to "voice Broadcasting is not working."