FreeSWITCH Pastebin

RAFA call stuck

From Dhruvil, 1 Year ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/31a66e83
Embed
Download Paste or View Raw
  1. span style="color: goldenrod; font-weight: bold;"> 2023-10-09 09:12:21.232123 99.63% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  2. 2023-10-09 09:12:21.232123 99.63% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  3. 2023-10-09 09:12:21.232123 99.63% [DEBUG] sofia.c:7487 Channel sofia/default/621299820@cloud.masip.es:7070 entering state [received][100]
  4. 2023-10-09 09:12:21.232123 99.63% [DEBUG] sofia.c:7497 Remote SDP:
  5. v=0
  6. o=root 1162917654 1162917655 IN IP4 54.72.32.66
  7. s=Asterisk PBX 13.20.0
  8. c=IN IP4 54.72.32.66
  9. t=0 0
  10. m=audio 9056 RTP/AVP 8 0 18 101
  11. a=rtpmap:8 PCMA/8000
  12. a=rtpmap:0 PCMU/8000
  13. a=rtpmap:18 G729/8000
  14. a=fmtp:18 annexb=no
  15. a=rtpmap:101 telephone-event/8000
  16. a=fmtp:101 0-16
  17. a=maxptime:150
  18.  
  19. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  20. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  21. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  22. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  23. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  24. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  25. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  26. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  27. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[G729:18:8000:20:8000:1]
  28. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  29. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMA:8:8000:20:64000:1]
  30. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMU:0:8000:20:64000:1]
  31. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  32. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/621299820@cloud.masip.es:7070 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  33. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_codec.c:111 sofia/default/621299820@cloud.masip.es:7070 Original read codec set to PCMA:8
  34. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  35. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_media.c:5850 sofia/default/621299820@cloud.masip.es:7070 Set 2833 dtmf send payload to 101 recv payload to 101
  36. 2023-10-09 09:12:21.232123 99.63% [DEBUG] sofia.c:7921 (sofia/default/621299820@cloud.masip.es:7070) State Change CS_NEW -> CS_INIT
  37. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:600 (sofia/default/621299820@cloud.masip.es:7070) State NEW
  38. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621299820@cloud.masip.es:7070) Running State Change CS_INIT (Cur 361 Tot 4028)
  39. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621299820@cloud.masip.es:7070) State INIT
  40. 2023-10-09 09:12:21.232123 99.63% [DEBUG] mod_sofia.c:97 sofia/default/621299820@cloud.masip.es:7070 SOFIA INIT
  41. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/621299820@cloud.masip.es:7070 Standard INIT
  42. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/621299820@cloud.masip.es:7070) State Change CS_INIT -> CS_ROUTING
  43. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621299820@cloud.masip.es:7070) State INIT going to sleep
  44. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621299820@cloud.masip.es:7070) Running State Change CS_ROUTING (Cur 361 Tot 4028)
  45. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_channel.c:2380 (sofia/default/621299820@cloud.masip.es:7070) Callstate Change DOWN -> RINGING
  46. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/621299820@cloud.masip.es:7070) State ROUTING
  47. 2023-10-09 09:12:21.232123 99.63% [DEBUG] mod_sofia.c:158 sofia/default/621299820@cloud.masip.es:7070 SOFIA ROUTING
  48. 2023-10-09 09:12:21.232123 99.63% [DEBUG] switch_core_state_machine.c:230 sofia/default/621299820@cloud.masip.es:7070 Standard ROUTING
  49. 2023-10-09 09:12:21.232123 99.63% [INFO] mod_dialplan_xml.c:639 Processing 621299820 <621299820>->34957491324 in context default
  50. 2023-10-09 09:12:24.932130 99.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/685544733@54.170.60.120 [fddc13be-b6e4-4b8f-b0b7-489eac2ef67d]
  51. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/685544733@54.170.60.120) Running State Change CS_NEW (Cur 362 Tot 4029)
  52. 2023-10-09 09:12:24.932130 99.67% [INFO] sofia.c:10453 sofia/default/685544733@54.170.60.120 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 36178580_123965866@54.170.60.120
  53. 2023-10-09 09:12:24.932130 99.67% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  54. 2023-10-09 09:12:24.932130 99.67% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  55. 2023-10-09 09:12:24.932130 99.67% [DEBUG] sofia.c:7487 Channel sofia/default/685544733@54.170.60.120 entering state [received][100]
  56. 2023-10-09 09:12:24.932130 99.67% [DEBUG] sofia.c:7497 Remote SDP:
  57. v=0
  58. o=Sonus_UAC 354784 390202 IN IP4 54.170.60.120
  59. s=SIP Media Capabilities
  60. c=IN IP4 54.170.60.120
  61. t=0 0
  62. m=audio 46536 RTP/AVP 8 18 0 99 13
  63. a=rtpmap:8 PCMA/8000
  64. a=rtpmap:18 G729/8000
  65. a=rtpmap:0 PCMU/8000
  66. a=rtpmap:99 telephone-event/8000
  67. a=fmtp:99 0-15
  68. a=rtpmap:13 CN/8000
  69. a=ptime:20
  70.  
  71. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  72. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  73. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  74. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  75. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  76. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  77. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  78. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  79. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  80. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  81. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  82. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  83. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 99@8000
  84. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/685544733@54.170.60.120 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  85. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_codec.c:111 sofia/default/685544733@54.170.60.120 Original read codec set to PCMA:8
  86. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 99@8000
  87. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_media.c:5850 sofia/default/685544733@54.170.60.120 Set 2833 dtmf send payload to 99 recv payload to 99
  88. 2023-10-09 09:12:24.932130 99.67% [DEBUG] sofia.c:7921 (sofia/default/685544733@54.170.60.120) State Change CS_NEW -> CS_INIT
  89. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/685544733@54.170.60.120) State NEW
  90. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/685544733@54.170.60.120) Running State Change CS_INIT (Cur 362 Tot 4029)
  91. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/685544733@54.170.60.120) State INIT
  92. 2023-10-09 09:12:24.932130 99.67% [DEBUG] mod_sofia.c:97 sofia/default/685544733@54.170.60.120 SOFIA INIT
  93. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/685544733@54.170.60.120 Standard INIT
  94. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/685544733@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  95. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/685544733@54.170.60.120) State INIT going to sleep
  96. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/685544733@54.170.60.120) Running State Change CS_ROUTING (Cur 362 Tot 4029)
  97. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_channel.c:2380 (sofia/default/685544733@54.170.60.120) Callstate Change DOWN -> RINGING
  98. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/685544733@54.170.60.120) State ROUTING
  99. 2023-10-09 09:12:24.932130 99.67% [DEBUG] mod_sofia.c:158 sofia/default/685544733@54.170.60.120 SOFIA ROUTING
  100. 2023-10-09 09:12:24.932130 99.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/685544733@54.170.60.120 Standard ROUTING
  101. 2023-10-09 09:12:24.932130 99.67% [INFO] mod_dialplan_xml.c:639 Processing 685544733 <685544733>->935752015 in context default
  102. 2023-10-09 09:12:25.452129 99.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/621272995@34.159.82.151 [0f366938-019f-4e37-98ac-1c8646a4f78b]
  103. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621272995@34.159.82.151) Running State Change CS_NEW (Cur 363 Tot 4030)
  104. 2023-10-09 09:12:25.452129 99.67% [INFO] sofia.c:10453 sofia/default/621272995@34.159.82.151 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: cdad4ad7-e126-123c-bca6-42010a9c0009
  105. 2023-10-09 09:12:25.452129 99.67% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  106. 2023-10-09 09:12:25.452129 99.67% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  107. 2023-10-09 09:12:25.452129 99.67% [DEBUG] sofia.c:7487 Channel sofia/default/621272995@34.159.82.151 entering state [received][100]
  108. 2023-10-09 09:12:25.452129 99.67% [DEBUG] sofia.c:7497 Remote SDP:
  109. v=0
  110. o=FreeSWITCH 1696820281 1696820282 IN IP4 34.159.82.151
  111. s=FreeSWITCH
  112. c=IN IP4 34.159.82.151
  113. t=0 0
  114. m=audio 22464 RTP/AVP 0 8 3 101 13
  115. a=rtpmap:0 PCMU/8000
  116. a=rtpmap:8 PCMA/8000
  117. a=rtpmap:3 GSM/8000
  118. a=rtpmap:101 telephone-event/8000
  119. a=fmtp:101 0-16
  120. a=rtpmap:13 CN/8000
  121. a=ptime:20
  122.  
  123. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  124. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  125. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  126. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  127. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  128. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  129. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  130. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  131. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  132. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  133. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  134. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  135. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/621272995@34.159.82.151 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  136. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_codec.c:111 sofia/default/621272995@34.159.82.151 Original read codec set to PCMU:0
  137. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  138. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_media.c:5850 sofia/default/621272995@34.159.82.151 Set 2833 dtmf send payload to 101 recv payload to 101
  139. 2023-10-09 09:12:25.452129 99.67% [DEBUG] sofia.c:7921 (sofia/default/621272995@34.159.82.151) State Change CS_NEW -> CS_INIT
  140. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/621272995@34.159.82.151) State NEW
  141. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621272995@34.159.82.151) Running State Change CS_INIT (Cur 363 Tot 4030)
  142. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621272995@34.159.82.151) State INIT
  143. 2023-10-09 09:12:25.452129 99.67% [DEBUG] mod_sofia.c:97 sofia/default/621272995@34.159.82.151 SOFIA INIT
  144. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/621272995@34.159.82.151 Standard INIT
  145. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/621272995@34.159.82.151) State Change CS_INIT -> CS_ROUTING
  146. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621272995@34.159.82.151) State INIT going to sleep
  147. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621272995@34.159.82.151) Running State Change CS_ROUTING (Cur 363 Tot 4030)
  148. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_channel.c:2380 (sofia/default/621272995@34.159.82.151) Callstate Change DOWN -> RINGING
  149. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/621272995@34.159.82.151) State ROUTING
  150. 2023-10-09 09:12:25.452129 99.67% [DEBUG] mod_sofia.c:158 sofia/default/621272995@34.159.82.151 SOFIA ROUTING
  151. 2023-10-09 09:12:25.452129 99.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/621272995@34.159.82.151 Standard ROUTING
  152. 2023-10-09 09:12:25.452129 99.67% [INFO] mod_dialplan_xml.c:639 Processing 621272995 <621272995>->34673258429 in context default
  153. 2023-10-09 09:12:25.692128 99.63% [NOTICE] switch_channel.c:1123 New Channel sofia/default/989400@cloud.masip.es:7075 [290841fd-9ff8-4559-a405-0042c089da79]
  154. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/989400@cloud.masip.es:7075) Running State Change CS_NEW (Cur 364 Tot 4031)
  155. 2023-10-09 09:12:25.692128 99.63% [INFO] sofia.c:10453 sofia/default/989400@cloud.masip.es:7075 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 7e9b4f2338288396433e711d40b911f3@cloud.masip.es
  156. 2023-10-09 09:12:25.692128 99.63% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  157. 2023-10-09 09:12:25.692128 99.63% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  158. 2023-10-09 09:12:25.692128 99.63% [DEBUG] sofia.c:7487 Channel sofia/default/989400@cloud.masip.es:7075 entering state [received][100]
  159. 2023-10-09 09:12:25.692128 99.63% [DEBUG] sofia.c:7497 Remote SDP:
  160. v=0
  161. o=root 213310072 213310073 IN IP4 18.202.74.117
  162. s=Asterisk PBX 13.20.0
  163. c=IN IP4 18.202.74.117
  164. t=0 0
  165. m=audio 19948 RTP/AVP 8 0 18 101
  166. a=rtpmap:8 PCMA/8000
  167. a=rtpmap:0 PCMU/8000
  168. a=rtpmap:18 G729/8000
  169. a=fmtp:18 annexb=no
  170. a=rtpmap:101 telephone-event/8000
  171. a=fmtp:101 0-16
  172. a=maxptime:150
  173.  
  174. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  175. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  176. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  177. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  178. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  179. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  180. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  181. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  182. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[G729:18:8000:20:8000:1]
  183. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  184. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMA:8:8000:20:64000:1]
  185. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMU:0:8000:20:64000:1]
  186. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  187. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/989400@cloud.masip.es:7075 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  188. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_codec.c:111 sofia/default/989400@cloud.masip.es:7075 Original read codec set to PCMA:8
  189. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  190. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_media.c:5850 sofia/default/989400@cloud.masip.es:7075 Set 2833 dtmf send payload to 101 recv payload to 101
  191. 2023-10-09 09:12:25.692128 99.63% [DEBUG] sofia.c:7921 (sofia/default/989400@cloud.masip.es:7075) State Change CS_NEW -> CS_INIT
  192. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:600 (sofia/default/989400@cloud.masip.es:7075) State NEW
  193. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/989400@cloud.masip.es:7075) Running State Change CS_INIT (Cur 364 Tot 4031)
  194. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/989400@cloud.masip.es:7075) State INIT
  195. 2023-10-09 09:12:25.692128 99.63% [DEBUG] mod_sofia.c:97 sofia/default/989400@cloud.masip.es:7075 SOFIA INIT
  196. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/989400@cloud.masip.es:7075 Standard INIT
  197. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/989400@cloud.masip.es:7075) State Change CS_INIT -> CS_ROUTING
  198. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/989400@cloud.masip.es:7075) State INIT going to sleep
  199. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/989400@cloud.masip.es:7075) Running State Change CS_ROUTING (Cur 364 Tot 4031)
  200. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_channel.c:2380 (sofia/default/989400@cloud.masip.es:7075) Callstate Change DOWN -> RINGING
  201. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/989400@cloud.masip.es:7075) State ROUTING
  202. 2023-10-09 09:12:25.692128 99.63% [DEBUG] mod_sofia.c:158 sofia/default/989400@cloud.masip.es:7075 SOFIA ROUTING
  203. 2023-10-09 09:12:25.692128 99.63% [DEBUG] switch_core_state_machine.c:230 sofia/default/989400@cloud.masip.es:7075 Standard ROUTING
  204. 2023-10-09 09:12:25.692128 99.63% [INFO] mod_dialplan_xml.c:639 Processing 981064110 <981064110>->34687854101 in context default
  205. 2023-10-09 09:12:31.712125 99.63% [NOTICE] switch_channel.c:1123 New Channel sofia/default/621278522@34.159.82.151 [aa78704a-25b3-4763-a8e3-88d32e056a0d]
  206. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621278522@34.159.82.151) Running State Change CS_NEW (Cur 365 Tot 4032)
  207. 2023-10-09 09:12:31.712125 99.63% [INFO] sofia.c:10453 sofia/default/621278522@34.159.82.151 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: d1688bf5-e126-123c-bca6-42010a9c0009
  208. 2023-10-09 09:12:31.712125 99.63% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  209. 2023-10-09 09:12:31.712125 99.63% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  210. 2023-10-09 09:12:31.712125 99.63% [DEBUG] sofia.c:7487 Channel sofia/default/621278522@34.159.82.151 entering state [received][100]
  211. 2023-10-09 09:12:31.712125 99.63% [DEBUG] sofia.c:7497 Remote SDP:
  212. v=0
  213. o=FreeSWITCH 1696816201 1696816202 IN IP4 34.159.82.151
  214. s=FreeSWITCH
  215. c=IN IP4 34.159.82.151
  216. t=0 0
  217. m=audio 26550 RTP/AVP 0 8 3 101 13
  218. a=rtpmap:0 PCMU/8000
  219. a=rtpmap:8 PCMA/8000
  220. a=rtpmap:3 GSM/8000
  221. a=rtpmap:101 telephone-event/8000
  222. a=fmtp:101 0-16
  223. a=rtpmap:13 CN/8000
  224. a=ptime:20
  225.  
  226. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  227. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  228. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  229. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  230. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  231. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  232. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  233. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  234. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  235. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  236. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  237. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  238. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/621278522@34.159.82.151 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  239. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_codec.c:111 sofia/default/621278522@34.159.82.151 Original read codec set to PCMU:0
  240. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  241. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_media.c:5850 sofia/default/621278522@34.159.82.151 Set 2833 dtmf send payload to 101 recv payload to 101
  242. 2023-10-09 09:12:31.712125 99.63% [DEBUG] sofia.c:7921 (sofia/default/621278522@34.159.82.151) State Change CS_NEW -> CS_INIT
  243. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:600 (sofia/default/621278522@34.159.82.151) State NEW
  244. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621278522@34.159.82.151) Running State Change CS_INIT (Cur 365 Tot 4032)
  245. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621278522@34.159.82.151) State INIT
  246. 2023-10-09 09:12:31.712125 99.63% [DEBUG] mod_sofia.c:97 sofia/default/621278522@34.159.82.151 SOFIA INIT
  247. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/621278522@34.159.82.151 Standard INIT
  248. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/621278522@34.159.82.151) State Change CS_INIT -> CS_ROUTING
  249. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621278522@34.159.82.151) State INIT going to sleep
  250. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621278522@34.159.82.151) Running State Change CS_ROUTING (Cur 365 Tot 4032)
  251. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_channel.c:2380 (sofia/default/621278522@34.159.82.151) Callstate Change DOWN -> RINGING
  252. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/621278522@34.159.82.151) State ROUTING
  253. 2023-10-09 09:12:31.712125 99.63% [DEBUG] mod_sofia.c:158 sofia/default/621278522@34.159.82.151 SOFIA ROUTING
  254. 2023-10-09 09:12:31.712125 99.63% [DEBUG] switch_core_state_machine.c:230 sofia/default/621278522@34.159.82.151 Standard ROUTING
  255. 2023-10-09 09:12:31.712125 99.63% [INFO] mod_dialplan_xml.c:639 Processing 621278522 <621278522>->34722712908 in context default
  256. 2023-10-09 09:12:32.452132 99.63% [NOTICE] switch_channel.c:1123 New Channel sofia/default/18889201327@204.10.205.149 [1c0b444f-b5eb-4838-a0df-614a5c46791e]
  257. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_NEW (Cur 366 Tot 4033)
  258. 2023-10-09 09:12:32.452132 99.63% [INFO] sofia.c:10453 sofia/default/18889201327@204.10.205.149 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 1494038178_11262951@204.10.205.149
  259. 2023-10-09 09:12:32.452132 99.63% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  260. 2023-10-09 09:12:32.452132 99.63% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  261. 2023-10-09 09:12:32.452132 99.63% [DEBUG] sofia.c:7487 Channel sofia/default/18889201327@204.10.205.149 entering state [received][100]
  262. 2023-10-09 09:12:32.452132 99.63% [DEBUG] sofia.c:7497 Remote SDP:
  263. v=0
  264. o=Sonus_UAC 22943 32405 IN IP4 204.10.205.149
  265. s=SIP Media Capabilities
  266. c=IN IP4 204.10.205.150
  267. t=0 0
  268. m=audio 21898 RTP/AVP 8 18 0 100
  269. a=rtpmap:8 PCMA/8000
  270. a=rtpmap:18 G729/8000
  271. a=fmtp:18 annexb=no
  272. a=rtpmap:0 PCMU/8000
  273. a=rtpmap:100 telephone-event/8000
  274. a=fmtp:100 0-15
  275. a=ptime:20
  276.  
  277. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  278. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  279. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  280. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  281. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  282. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  283. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  284. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  285. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  286. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  287. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  288. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  289. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  290. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/18889201327@204.10.205.149 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  291. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_codec.c:111 sofia/default/18889201327@204.10.205.149 Original read codec set to PCMA:8
  292. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  293. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_media.c:5850 sofia/default/18889201327@204.10.205.149 Set 2833 dtmf send payload to 100 recv payload to 100
  294. 2023-10-09 09:12:32.452132 99.63% [DEBUG] sofia.c:7921 (sofia/default/18889201327@204.10.205.149) State Change CS_NEW -> CS_INIT
  295. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:600 (sofia/default/18889201327@204.10.205.149) State NEW
  296. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_INIT (Cur 366 Tot 4033)
  297. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889201327@204.10.205.149) State INIT
  298. 2023-10-09 09:12:32.452132 99.63% [DEBUG] mod_sofia.c:97 sofia/default/18889201327@204.10.205.149 SOFIA INIT
  299. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/18889201327@204.10.205.149 Standard INIT
  300. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/18889201327@204.10.205.149) State Change CS_INIT -> CS_ROUTING
  301. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889201327@204.10.205.149) State INIT going to sleep
  302. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_ROUTING (Cur 366 Tot 4033)
  303. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_channel.c:2380 (sofia/default/18889201327@204.10.205.149) Callstate Change DOWN -> RINGING
  304. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/18889201327@204.10.205.149) State ROUTING
  305. 2023-10-09 09:12:32.452132 99.63% [DEBUG] mod_sofia.c:158 sofia/default/18889201327@204.10.205.149 SOFIA ROUTING
  306. 2023-10-09 09:12:32.452132 99.63% [DEBUG] switch_core_state_machine.c:230 sofia/default/18889201327@204.10.205.149 Standard ROUTING
  307. 2023-10-09 09:12:32.452132 99.63% [INFO] mod_dialplan_xml.c:639 Processing 18889201327 <18889201327>->34999718444 in context default
  308. 2023-10-09 09:12:38.292129 99.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/960201328@cloud.masip.es:7070 [ceffa75f-ceb9-4973-8056-430c03080d9a]
  309. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/960201328@cloud.masip.es:7070) Running State Change CS_NEW (Cur 367 Tot 4034)
  310. 2023-10-09 09:12:38.292129 99.77% [INFO] sofia.c:10453 sofia/default/960201328@cloud.masip.es:7070 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 736881aa2d878b1a50ae14a40e252ade@cloud.masip.es
  311. 2023-10-09 09:12:38.292129 99.77% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  312. 2023-10-09 09:12:38.292129 99.77% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  313. 2023-10-09 09:12:38.292129 99.77% [DEBUG] sofia.c:7487 Channel sofia/default/960201328@cloud.masip.es:7070 entering state [received][100]
  314. 2023-10-09 09:12:38.292129 99.77% [DEBUG] sofia.c:7497 Remote SDP:
  315. v=0
  316. o=root 2101427551 2101427552 IN IP4 52.212.10.46
  317. s=Asterisk PBX 16.6.1
  318. c=IN IP4 52.212.10.46
  319. t=0 0
  320. m=audio 23324 RTP/AVP 8 101
  321. a=rtpmap:8 PCMA/8000
  322. a=rtpmap:101 telephone-event/8000
  323. a=fmtp:101 0-16
  324. a=maxptime:150
  325. a=rtcp-mux
  326.  
  327. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  328. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  329. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  330. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  331. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  332. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/960201328@cloud.masip.es:7070 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  333. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_codec.c:111 sofia/default/960201328@cloud.masip.es:7070 Original read codec set to PCMA:8
  334. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  335. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_media.c:5850 sofia/default/960201328@cloud.masip.es:7070 Set 2833 dtmf send payload to 101 recv payload to 101
  336. 2023-10-09 09:12:38.292129 99.77% [DEBUG] sofia.c:7921 (sofia/default/960201328@cloud.masip.es:7070) State Change CS_NEW -> CS_INIT
  337. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/960201328@cloud.masip.es:7070) State NEW
  338. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/960201328@cloud.masip.es:7070) Running State Change CS_INIT (Cur 367 Tot 4034)
  339. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/960201328@cloud.masip.es:7070) State INIT
  340. 2023-10-09 09:12:38.292129 99.77% [DEBUG] mod_sofia.c:97 sofia/default/960201328@cloud.masip.es:7070 SOFIA INIT
  341. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:40 sofia/default/960201328@cloud.masip.es:7070 Standard INIT
  342. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:48 (sofia/default/960201328@cloud.masip.es:7070) State Change CS_INIT -> CS_ROUTING
  343. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/960201328@cloud.masip.es:7070) State INIT going to sleep
  344. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/960201328@cloud.masip.es:7070) Running State Change CS_ROUTING (Cur 367 Tot 4034)
  345. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_channel.c:2380 (sofia/default/960201328@cloud.masip.es:7070) Callstate Change DOWN -> RINGING
  346. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:640 (sofia/default/960201328@cloud.masip.es:7070) State ROUTING
  347. 2023-10-09 09:12:38.292129 99.77% [DEBUG] mod_sofia.c:158 sofia/default/960201328@cloud.masip.es:7070 SOFIA ROUTING
  348. 2023-10-09 09:12:38.292129 99.77% [DEBUG] switch_core_state_machine.c:230 sofia/default/960201328@cloud.masip.es:7070 Standard ROUTING
  349. 2023-10-09 09:12:38.292129 99.77% [INFO] mod_dialplan_xml.c:639 Processing 960201328 <960201328>->34654214055 in context default
  350. 2023-10-09 09:12:43.912122 99.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/650367995@54.170.60.120 [60c621b3-68e0-4739-91bb-b894497ece62]
  351. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/650367995@54.170.60.120) Running State Change CS_NEW (Cur 368 Tot 4035)
  352. 2023-10-09 09:12:43.912122 99.77% [INFO] sofia.c:10453 sofia/default/650367995@54.170.60.120 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 35653766_15687262@54.170.60.120
  353. 2023-10-09 09:12:43.912122 99.77% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  354. 2023-10-09 09:12:43.912122 99.77% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  355. 2023-10-09 09:12:43.912122 99.77% [DEBUG] sofia.c:7487 Channel sofia/default/650367995@54.170.60.120 entering state [received][100]
  356. 2023-10-09 09:12:43.912122 99.77% [DEBUG] sofia.c:7497 Remote SDP:
  357. v=0
  358. o=Sonus_UAC 896875 726771 IN IP4 54.170.60.120
  359. s=SIP Media Capabilities
  360. c=IN IP4 54.170.60.120
  361. t=0 0
  362. m=audio 46398 RTP/AVP 8 18 0 97 13
  363. a=rtpmap:8 PCMA/8000
  364. a=rtpmap:18 G729/8000
  365. a=rtpmap:0 PCMU/8000
  366. a=rtpmap:97 telephone-event/8000
  367. a=fmtp:97 0-15
  368. a=rtpmap:13 CN/8000
  369. a=ptime:20
  370.  
  371. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  372. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  373. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  374. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  375. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  376. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  377. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  378. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  379. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  380. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  381. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  382. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  383. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 97@8000
  384. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/650367995@54.170.60.120 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  385. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_codec.c:111 sofia/default/650367995@54.170.60.120 Original read codec set to PCMA:8
  386. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 97@8000
  387. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_media.c:5850 sofia/default/650367995@54.170.60.120 Set 2833 dtmf send payload to 97 recv payload to 97
  388. 2023-10-09 09:12:43.912122 99.77% [DEBUG] sofia.c:7921 (sofia/default/650367995@54.170.60.120) State Change CS_NEW -> CS_INIT
  389. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/650367995@54.170.60.120) State NEW
  390. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/650367995@54.170.60.120) Running State Change CS_INIT (Cur 368 Tot 4035)
  391. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/650367995@54.170.60.120) State INIT
  392. 2023-10-09 09:12:43.912122 99.77% [DEBUG] mod_sofia.c:97 sofia/default/650367995@54.170.60.120 SOFIA INIT
  393. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:40 sofia/default/650367995@54.170.60.120 Standard INIT
  394. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:48 (sofia/default/650367995@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  395. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/650367995@54.170.60.120) State INIT going to sleep
  396. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/650367995@54.170.60.120) Running State Change CS_ROUTING (Cur 368 Tot 4035)
  397. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_channel.c:2380 (sofia/default/650367995@54.170.60.120) Callstate Change DOWN -> RINGING
  398. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:640 (sofia/default/650367995@54.170.60.120) State ROUTING
  399. 2023-10-09 09:12:43.912122 99.77% [DEBUG] mod_sofia.c:158 sofia/default/650367995@54.170.60.120 SOFIA ROUTING
  400. 2023-10-09 09:12:43.912122 99.77% [DEBUG] switch_core_state_machine.c:230 sofia/default/650367995@54.170.60.120 Standard ROUTING
  401. 2023-10-09 09:12:43.912122 99.77% [INFO] mod_dialplan_xml.c:639 Processing 650367995 <650367995>->931358123 in context default
  402. 2023-10-09 09:12:44.012122 99.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/621277145@34.159.82.151 [12141d45-2269-4411-845f-785c75ea4078]
  403. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621277145@34.159.82.151) Running State Change CS_NEW (Cur 369 Tot 4036)
  404. 2023-10-09 09:12:44.012122 99.77% [INFO] sofia.c:10453 sofia/default/621277145@34.159.82.151 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: d8be362d-e126-123c-bca6-42010a9c0009
  405. 2023-10-09 09:12:44.012122 99.77% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  406. 2023-10-09 09:12:44.012122 99.77% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  407. 2023-10-09 09:12:44.012122 99.77% [DEBUG] sofia.c:7487 Channel sofia/default/621277145@34.159.82.151 entering state [received][100]
  408. 2023-10-09 09:12:44.012122 99.77% [DEBUG] sofia.c:7497 Remote SDP:
  409. v=0
  410. o=FreeSWITCH 1696823659 1696823660 IN IP4 34.159.82.151
  411. s=FreeSWITCH
  412. c=IN IP4 34.159.82.151
  413. t=0 0
  414. m=audio 19104 RTP/AVP 0 8 3 101 13
  415. a=rtpmap:0 PCMU/8000
  416. a=rtpmap:8 PCMA/8000
  417. a=rtpmap:3 GSM/8000
  418. a=rtpmap:101 telephone-event/8000
  419. a=fmtp:101 0-16
  420. a=rtpmap:13 CN/8000
  421. a=ptime:20
  422.  
  423. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  424. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  425. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  426. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  427. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  428. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  429. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  430. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  431. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  432. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  433. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  434. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  435. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/621277145@34.159.82.151 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  436. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_codec.c:111 sofia/default/621277145@34.159.82.151 Original read codec set to PCMU:0
  437. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  438. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_media.c:5850 sofia/default/621277145@34.159.82.151 Set 2833 dtmf send payload to 101 recv payload to 101
  439. 2023-10-09 09:12:44.012122 99.77% [DEBUG] sofia.c:7921 (sofia/default/621277145@34.159.82.151) State Change CS_NEW -> CS_INIT
  440. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/621277145@34.159.82.151) State NEW
  441. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621277145@34.159.82.151) Running State Change CS_INIT (Cur 369 Tot 4036)
  442. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621277145@34.159.82.151) State INIT
  443. 2023-10-09 09:12:44.012122 99.77% [DEBUG] mod_sofia.c:97 sofia/default/621277145@34.159.82.151 SOFIA INIT
  444. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:40 sofia/default/621277145@34.159.82.151 Standard INIT
  445. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:48 (sofia/default/621277145@34.159.82.151) State Change CS_INIT -> CS_ROUTING
  446. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621277145@34.159.82.151) State INIT going to sleep
  447. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621277145@34.159.82.151) Running State Change CS_ROUTING (Cur 369 Tot 4036)
  448. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_channel.c:2380 (sofia/default/621277145@34.159.82.151) Callstate Change DOWN -> RINGING
  449. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:640 (sofia/default/621277145@34.159.82.151) State ROUTING
  450. 2023-10-09 09:12:44.012122 99.77% [DEBUG] mod_sofia.c:158 sofia/default/621277145@34.159.82.151 SOFIA ROUTING
  451. 2023-10-09 09:12:44.012122 99.77% [DEBUG] switch_core_state_machine.c:230 sofia/default/621277145@34.159.82.151 Standard ROUTING
  452. 2023-10-09 09:12:44.012122 99.77% [INFO] mod_dialplan_xml.c:639 Processing 621277145 <621277145>->34606787497 in context default
  453. 2023-10-09 09:12:44.612129 99.70% [NOTICE] switch_channel.c:1123 New Channel sofia/default/910603185@54.170.60.120 [183005bc-2d35-427b-bc96-f66d490074e1]
  454. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/910603185@54.170.60.120) Running State Change CS_NEW (Cur 370 Tot 4037)
  455. 2023-10-09 09:12:44.612129 99.70% [INFO] sofia.c:10453 sofia/default/910603185@54.170.60.120 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 36178582_77242726@54.170.60.120
  456. 2023-10-09 09:12:44.612129 99.70% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  457. 2023-10-09 09:12:44.612129 99.70% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  458. 2023-10-09 09:12:44.612129 99.70% [DEBUG] sofia.c:7487 Channel sofia/default/910603185@54.170.60.120 entering state [received][100]
  459. 2023-10-09 09:12:44.612129 99.70% [DEBUG] sofia.c:7497 Remote SDP:
  460. v=0
  461. o=Sonus_UAC 684198 573768 IN IP4 54.170.60.120
  462. s=SIP Media Capabilities
  463. c=IN IP4 54.170.60.120
  464. t=0 0
  465. m=audio 46410 RTP/AVP 8 18 0 96 13
  466. a=rtpmap:8 PCMA/8000
  467. a=rtpmap:18 G729/8000
  468. a=fmtp:18 annexb=no
  469. a=rtpmap:0 PCMU/8000
  470. a=rtpmap:96 telephone-event/8000
  471. a=fmtp:96 0-15
  472. a=rtpmap:13 CN/8000
  473. a=ptime:20
  474.  
  475. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  476. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  477. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  478. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  479. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  480. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  481. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  482. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  483. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  484. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  485. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  486. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  487. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 96@8000
  488. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/910603185@54.170.60.120 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  489. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_codec.c:111 sofia/default/910603185@54.170.60.120 Original read codec set to PCMA:8
  490. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 96@8000
  491. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_media.c:5850 sofia/default/910603185@54.170.60.120 Set 2833 dtmf send payload to 96 recv payload to 96
  492. 2023-10-09 09:12:44.612129 99.70% [DEBUG] sofia.c:7921 (sofia/default/910603185@54.170.60.120) State Change CS_NEW -> CS_INIT
  493. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:600 (sofia/default/910603185@54.170.60.120) State NEW
  494. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/910603185@54.170.60.120) Running State Change CS_INIT (Cur 370 Tot 4037)
  495. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/910603185@54.170.60.120) State INIT
  496. 2023-10-09 09:12:44.612129 99.70% [DEBUG] mod_sofia.c:97 sofia/default/910603185@54.170.60.120 SOFIA INIT
  497. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:40 sofia/default/910603185@54.170.60.120 Standard INIT
  498. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:48 (sofia/default/910603185@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  499. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/910603185@54.170.60.120) State INIT going to sleep
  500. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/910603185@54.170.60.120) Running State Change CS_ROUTING (Cur 370 Tot 4037)
  501. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_channel.c:2380 (sofia/default/910603185@54.170.60.120) Callstate Change DOWN -> RINGING
  502. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/910603185@54.170.60.120) State ROUTING
  503. 2023-10-09 09:12:44.612129 99.70% [DEBUG] mod_sofia.c:158 sofia/default/910603185@54.170.60.120 SOFIA ROUTING
  504. 2023-10-09 09:12:44.612129 99.70% [DEBUG] switch_core_state_machine.c:230 sofia/default/910603185@54.170.60.120 Standard ROUTING
  505. 2023-10-09 09:12:44.612129 99.70% [INFO] mod_dialplan_xml.c:639 Processing 910603185 <910603185>->971695722 in context default
  506. 2023-10-09 09:12:49.172127 99.70% [NOTICE] switch_channel.c:1123 New Channel sofia/default/937323595@cloud.masip.es:7075 [ec09fae2-66af-423a-a12e-ed73d4f6bdb7]
  507. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/937323595@cloud.masip.es:7075) Running State Change CS_NEW (Cur 371 Tot 4038)
  508. 2023-10-09 09:12:49.172127 99.70% [INFO] sofia.c:10453 sofia/default/937323595@cloud.masip.es:7075 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 6b393cf8625025f718c4ac7603d79212@cloud.masip.es
  509. 2023-10-09 09:12:49.172127 99.70% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  510. 2023-10-09 09:12:49.172127 99.70% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  511. 2023-10-09 09:12:49.172127 99.70% [DEBUG] sofia.c:7487 Channel sofia/default/937323595@cloud.masip.es:7075 entering state [received][100]
  512. 2023-10-09 09:12:49.172127 99.70% [DEBUG] sofia.c:7497 Remote SDP:
  513. v=0
  514. o=root 2100822419 2100822420 IN IP4 99.80.162.61
  515. s=Asterisk PBX 13.20.0
  516. c=IN IP4 99.80.162.61
  517. t=0 0
  518. m=audio 16668 RTP/AVP 8 0 18 101
  519. a=rtpmap:8 PCMA/8000
  520. a=rtpmap:0 PCMU/8000
  521. a=rtpmap:18 G729/8000
  522. a=fmtp:18 annexb=no
  523. a=rtpmap:101 telephone-event/8000
  524. a=fmtp:101 0-16
  525. a=maxptime:150
  526.  
  527. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  528. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  529. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  530. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  531. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  532. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  533. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  534. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  535. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[G729:18:8000:20:8000:1]
  536. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  537. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMA:8:8000:20:64000:1]
  538. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:150:8000:1]/[PCMU:0:8000:20:64000:1]
  539. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  540. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/937323595@cloud.masip.es:7075 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  541. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_codec.c:111 sofia/default/937323595@cloud.masip.es:7075 Original read codec set to PCMA:8
  542. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  543. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_media.c:5850 sofia/default/937323595@cloud.masip.es:7075 Set 2833 dtmf send payload to 101 recv payload to 101
  544. 2023-10-09 09:12:49.172127 99.70% [DEBUG] sofia.c:7921 (sofia/default/937323595@cloud.masip.es:7075) State Change CS_NEW -> CS_INIT
  545. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:600 (sofia/default/937323595@cloud.masip.es:7075) State NEW
  546. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/937323595@cloud.masip.es:7075) Running State Change CS_INIT (Cur 371 Tot 4038)
  547. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/937323595@cloud.masip.es:7075) State INIT
  548. 2023-10-09 09:12:49.172127 99.70% [DEBUG] mod_sofia.c:97 sofia/default/937323595@cloud.masip.es:7075 SOFIA INIT
  549. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:40 sofia/default/937323595@cloud.masip.es:7075 Standard INIT
  550. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:48 (sofia/default/937323595@cloud.masip.es:7075) State Change CS_INIT -> CS_ROUTING
  551. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/937323595@cloud.masip.es:7075) State INIT going to sleep
  552. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/937323595@cloud.masip.es:7075) Running State Change CS_ROUTING (Cur 371 Tot 4038)
  553. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_channel.c:2380 (sofia/default/937323595@cloud.masip.es:7075) Callstate Change DOWN -> RINGING
  554. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/937323595@cloud.masip.es:7075) State ROUTING
  555. 2023-10-09 09:12:49.172127 99.70% [DEBUG] mod_sofia.c:158 sofia/default/937323595@cloud.masip.es:7075 SOFIA ROUTING
  556. 2023-10-09 09:12:49.172127 99.70% [DEBUG] switch_core_state_machine.c:230 sofia/default/937323595@cloud.masip.es:7075 Standard ROUTING
  557. 2023-10-09 09:12:49.172127 99.70% [INFO] mod_dialplan_xml.c:639 Processing 937323595 <937323595>->34665618513 in context default
  558. 2023-10-09 09:12:50.152123 99.70% [NOTICE] switch_channel.c:1123 New Channel sofia/default/854565220@34.159.82.151 [96877a7a-3d66-4f5f-bd71-532ea14f56d4]
  559. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/854565220@34.159.82.151) Running State Change CS_NEW (Cur 372 Tot 4039)
  560. 2023-10-09 09:12:50.152123 99.70% [INFO] sofia.c:10453 sofia/default/854565220@34.159.82.151 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: dc66baa3-e126-123c-bca6-42010a9c0009
  561. 2023-10-09 09:12:50.152123 99.70% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  562. 2023-10-09 09:12:50.152123 99.70% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  563. 2023-10-09 09:12:50.152123 99.70% [DEBUG] sofia.c:7487 Channel sofia/default/854565220@34.159.82.151 entering state [received][100]
  564. 2023-10-09 09:12:50.152123 99.70% [DEBUG] sofia.c:7497 Remote SDP:
  565. v=0
  566. o=FreeSWITCH 1696817550 1696817551 IN IP4 34.159.82.151
  567. s=FreeSWITCH
  568. c=IN IP4 34.159.82.151
  569. t=0 0
  570. m=audio 25220 RTP/AVP 0 8 3 101 13
  571. a=rtpmap:0 PCMU/8000
  572. a=rtpmap:8 PCMA/8000
  573. a=rtpmap:3 GSM/8000
  574. a=rtpmap:101 telephone-event/8000
  575. a=fmtp:101 0-16
  576. a=rtpmap:13 CN/8000
  577. a=ptime:20
  578.  
  579. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  580. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  581. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  582. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  583. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  584. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  585. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  586. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  587. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  588. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  589. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  590. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  591. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/854565220@34.159.82.151 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  592. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_codec.c:111 sofia/default/854565220@34.159.82.151 Original read codec set to PCMU:0
  593. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  594. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_media.c:5850 sofia/default/854565220@34.159.82.151 Set 2833 dtmf send payload to 101 recv payload to 101
  595. 2023-10-09 09:12:50.152123 99.70% [DEBUG] sofia.c:7921 (sofia/default/854565220@34.159.82.151) State Change CS_NEW -> CS_INIT
  596. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:600 (sofia/default/854565220@34.159.82.151) State NEW
  597. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/854565220@34.159.82.151) Running State Change CS_INIT (Cur 372 Tot 4039)
  598. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/854565220@34.159.82.151) State INIT
  599. 2023-10-09 09:12:50.152123 99.70% [DEBUG] mod_sofia.c:97 sofia/default/854565220@34.159.82.151 SOFIA INIT
  600. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:40 sofia/default/854565220@34.159.82.151 Standard INIT
  601. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:48 (sofia/default/854565220@34.159.82.151) State Change CS_INIT -> CS_ROUTING
  602. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/854565220@34.159.82.151) State INIT going to sleep
  603. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/854565220@34.159.82.151) Running State Change CS_ROUTING (Cur 372 Tot 4039)
  604. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_channel.c:2380 (sofia/default/854565220@34.159.82.151) Callstate Change DOWN -> RINGING
  605. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/854565220@34.159.82.151) State ROUTING
  606. 2023-10-09 09:12:50.152123 99.70% [DEBUG] mod_sofia.c:158 sofia/default/854565220@34.159.82.151 SOFIA ROUTING
  607. 2023-10-09 09:12:50.152123 99.70% [DEBUG] switch_core_state_machine.c:230 sofia/default/854565220@34.159.82.151 Standard ROUTING
  608. 2023-10-09 09:12:50.152123 99.70% [INFO] mod_dialplan_xml.c:639 Processing 854565220 <854565220>->34605511527 in context default
  609. 2023-10-09 09:12:53.252127 99.70% [NOTICE] switch_channel.c:1123 New Channel sofia/default/646679973@54.170.60.120 [432065d1-e0ee-4024-89ab-887ba45c19ce]
  610. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/646679973@54.170.60.120) Running State Change CS_NEW (Cur 373 Tot 4040)
  611. 2023-10-09 09:12:53.252127 99.70% [INFO] sofia.c:10453 sofia/default/646679973@54.170.60.120 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 572525409_131500705@54.170.60.120
  612. 2023-10-09 09:12:53.252127 99.70% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  613. 2023-10-09 09:12:53.252127 99.70% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  614. 2023-10-09 09:12:53.252127 99.70% [DEBUG] sofia.c:7487 Channel sofia/default/646679973@54.170.60.120 entering state [received][100]
  615. 2023-10-09 09:12:53.252127 99.70% [DEBUG] sofia.c:7497 Remote SDP:
  616. v=0
  617. o=Sonus_UAC 120821 10683 IN IP4 54.170.60.120
  618. s=SIP Media Capabilities
  619. c=IN IP4 54.170.60.120
  620. t=0 0
  621. m=audio 45222 RTP/AVP 8 18 0 100 13
  622. a=rtpmap:8 PCMA/8000
  623. a=rtpmap:18 G729/8000
  624. a=fmtp:18 annexb=no
  625. a=rtpmap:0 PCMU/8000
  626. a=rtpmap:100 telephone-event/8000
  627. a=fmtp:100 0-15
  628. a=rtpmap:13 CN/8000
  629. a=ptime:20
  630.  
  631. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  632. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  633. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  634. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  635. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  636. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  637. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  638. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  639. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  640. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  641. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  642. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  643. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  644. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/646679973@54.170.60.120 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  645. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_codec.c:111 sofia/default/646679973@54.170.60.120 Original read codec set to PCMA:8
  646. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  647. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_media.c:5850 sofia/default/646679973@54.170.60.120 Set 2833 dtmf send payload to 100 recv payload to 100
  648. 2023-10-09 09:12:53.252127 99.70% [DEBUG] sofia.c:7921 (sofia/default/646679973@54.170.60.120) State Change CS_NEW -> CS_INIT
  649. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:600 (sofia/default/646679973@54.170.60.120) State NEW
  650. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/646679973@54.170.60.120) Running State Change CS_INIT (Cur 373 Tot 4040)
  651. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/646679973@54.170.60.120) State INIT
  652. 2023-10-09 09:12:53.252127 99.70% [DEBUG] mod_sofia.c:97 sofia/default/646679973@54.170.60.120 SOFIA INIT
  653. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:40 sofia/default/646679973@54.170.60.120 Standard INIT
  654. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:48 (sofia/default/646679973@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  655. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:624 (sofia/default/646679973@54.170.60.120) State INIT going to sleep
  656. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/646679973@54.170.60.120) Running State Change CS_ROUTING (Cur 373 Tot 4040)
  657. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_channel.c:2380 (sofia/default/646679973@54.170.60.120) Callstate Change DOWN -> RINGING
  658. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:640 (sofia/default/646679973@54.170.60.120) State ROUTING
  659. 2023-10-09 09:12:53.252127 99.70% [DEBUG] mod_sofia.c:158 sofia/default/646679973@54.170.60.120 SOFIA ROUTING
  660. 2023-10-09 09:12:53.252127 99.70% [DEBUG] switch_core_state_machine.c:230 sofia/default/646679973@54.170.60.120 Standard ROUTING
  661. 2023-10-09 09:12:53.252127 99.70% [INFO] mod_dialplan_xml.c:639 Processing 646679973 <646679973>->854565220 in context default
  662. 2023-10-09 09:12:54.432128 99.73% [NOTICE] switch_channel.c:1123 New Channel sofia/default/18886930169@204.10.205.149 [e1c9280e-e2cb-4992-8939-acab05c29ea6]
  663. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18886930169@204.10.205.149) Running State Change CS_NEW (Cur 374 Tot 4041)
  664. 2023-10-09 09:12:54.432128 99.73% [INFO] sofia.c:10453 sofia/default/18886930169@204.10.205.149 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 1493508171_125575429@204.10.205.149
  665. 2023-10-09 09:12:54.432128 99.73% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  666. 2023-10-09 09:12:54.432128 99.73% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  667. 2023-10-09 09:12:54.432128 99.73% [DEBUG] sofia.c:7487 Channel sofia/default/18886930169@204.10.205.149 entering state [received][100]
  668. 2023-10-09 09:12:54.432128 99.73% [DEBUG] sofia.c:7497 Remote SDP:
  669. v=0
  670. o=Sonus_UAC 20414 1952 IN IP4 204.10.205.149
  671. s=SIP Media Capabilities
  672. c=IN IP4 204.10.205.150
  673. t=0 0
  674. m=audio 12866 RTP/AVP 8 18 0 100
  675. a=rtpmap:8 PCMA/8000
  676. a=rtpmap:18 G729/8000
  677. a=fmtp:18 annexb=no
  678. a=rtpmap:0 PCMU/8000
  679. a=rtpmap:100 telephone-event/8000
  680. a=fmtp:100 0-15
  681. a=ptime:20
  682.  
  683. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  684. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  685. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  686. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  687. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  688. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  689. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  690. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  691. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  692. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  693. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  694. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  695. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  696. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/18886930169@204.10.205.149 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  697. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_codec.c:111 sofia/default/18886930169@204.10.205.149 Original read codec set to PCMA:8
  698. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  699. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_media.c:5850 sofia/default/18886930169@204.10.205.149 Set 2833 dtmf send payload to 100 recv payload to 100
  700. 2023-10-09 09:12:54.432128 99.73% [DEBUG] sofia.c:7921 (sofia/default/18886930169@204.10.205.149) State Change CS_NEW -> CS_INIT
  701. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:600 (sofia/default/18886930169@204.10.205.149) State NEW
  702. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18886930169@204.10.205.149) Running State Change CS_INIT (Cur 374 Tot 4041)
  703. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18886930169@204.10.205.149) State INIT
  704. 2023-10-09 09:12:54.432128 99.73% [DEBUG] mod_sofia.c:97 sofia/default/18886930169@204.10.205.149 SOFIA INIT
  705. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:40 sofia/default/18886930169@204.10.205.149 Standard INIT
  706. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:48 (sofia/default/18886930169@204.10.205.149) State Change CS_INIT -> CS_ROUTING
  707. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18886930169@204.10.205.149) State INIT going to sleep
  708. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18886930169@204.10.205.149) Running State Change CS_ROUTING (Cur 374 Tot 4041)
  709. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_channel.c:2380 (sofia/default/18886930169@204.10.205.149) Callstate Change DOWN -> RINGING
  710. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/18886930169@204.10.205.149) State ROUTING
  711. 2023-10-09 09:12:54.432128 99.73% [DEBUG] mod_sofia.c:158 sofia/default/18886930169@204.10.205.149 SOFIA ROUTING
  712. 2023-10-09 09:12:54.432128 99.73% [DEBUG] switch_core_state_machine.c:230 sofia/default/18886930169@204.10.205.149 Standard ROUTING
  713. 2023-10-09 09:12:54.432128 99.73% [INFO] mod_dialplan_xml.c:639 Processing 18886930169 <18886930169>->34622222333 in context default
  714. 2023-10-09 09:12:56.292130 99.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/601569991@34.159.82.151 [8fd1104e-c4e5-4b29-84b9-49b01591bb16]
  715. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/601569991@34.159.82.151) Running State Change CS_NEW (Cur 375 Tot 4042)
  716. 2023-10-09 09:12:56.292130 99.77% [INFO] sofia.c:10453 sofia/default/601569991@34.159.82.151 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: e00f980b-e126-123c-bca6-42010a9c0009
  717. 2023-10-09 09:12:56.292130 99.77% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  718. 2023-10-09 09:12:56.292130 99.77% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  719. 2023-10-09 09:12:56.292130 99.77% [DEBUG] sofia.c:7487 Channel sofia/default/601569991@34.159.82.151 entering state [received][100]
  720. 2023-10-09 09:12:56.292130 99.77% [DEBUG] sofia.c:7497 Remote SDP:
  721. v=0
  722. o=FreeSWITCH 1696824060 1696824061 IN IP4 34.159.82.151
  723. s=FreeSWITCH
  724. c=IN IP4 34.159.82.151
  725. t=0 0
  726. m=audio 18716 RTP/AVP 0 8 3 101 13
  727. a=rtpmap:0 PCMU/8000
  728. a=rtpmap:8 PCMA/8000
  729. a=rtpmap:3 GSM/8000
  730. a=rtpmap:101 telephone-event/8000
  731. a=fmtp:101 0-16
  732. a=rtpmap:13 CN/8000
  733. a=ptime:20
  734.  
  735. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  736. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  737. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  738. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  739. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  740. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  741. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  742. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  743. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  744. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  745. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  746. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  747. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/601569991@34.159.82.151 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  748. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_codec.c:111 sofia/default/601569991@34.159.82.151 Original read codec set to PCMU:0
  749. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  750. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_media.c:5850 sofia/default/601569991@34.159.82.151 Set 2833 dtmf send payload to 101 recv payload to 101
  751. 2023-10-09 09:12:56.292130 99.77% [DEBUG] sofia.c:7921 (sofia/default/601569991@34.159.82.151) State Change CS_NEW -> CS_INIT
  752. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/601569991@34.159.82.151) State NEW
  753. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/601569991@34.159.82.151) Running State Change CS_INIT (Cur 375 Tot 4042)
  754. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/601569991@34.159.82.151) State INIT
  755. 2023-10-09 09:12:56.292130 99.77% [DEBUG] mod_sofia.c:97 sofia/default/601569991@34.159.82.151 SOFIA INIT
  756. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:40 sofia/default/601569991@34.159.82.151 Standard INIT
  757. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:48 (sofia/default/601569991@34.159.82.151) State Change CS_INIT -> CS_ROUTING
  758. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/601569991@34.159.82.151) State INIT going to sleep
  759. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/601569991@34.159.82.151) Running State Change CS_ROUTING (Cur 375 Tot 4042)
  760. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_channel.c:2380 (sofia/default/601569991@34.159.82.151) Callstate Change DOWN -> RINGING
  761. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:640 (sofia/default/601569991@34.159.82.151) State ROUTING
  762. 2023-10-09 09:12:56.292130 99.77% [DEBUG] mod_sofia.c:158 sofia/default/601569991@34.159.82.151 SOFIA ROUTING
  763. 2023-10-09 09:12:56.292130 99.77% [DEBUG] switch_core_state_machine.c:230 sofia/default/601569991@34.159.82.151 Standard ROUTING
  764. 2023-10-09 09:12:56.292130 99.77% [INFO] mod_dialplan_xml.c:639 Processing 601569991 <601569991>->34606989533 in context default
  765. 2023-10-09 09:12:59.112126 99.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/621140063@cloud.masip.es [cbee6903-21c1-4123-8a4b-721463e6248c]
  766. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621140063@cloud.masip.es) Running State Change CS_NEW (Cur 376 Tot 4043)
  767. 2023-10-09 09:12:59.112126 99.77% [INFO] sofia.c:10453 sofia/default/621140063@cloud.masip.es receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 5791fff67e25987d6d67ebf26912b4a2@cloud.masip.es
  768. 2023-10-09 09:12:59.112126 99.77% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  769. 2023-10-09 09:12:59.112126 99.77% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  770. 2023-10-09 09:12:59.112126 99.77% [DEBUG] sofia.c:7487 Channel sofia/default/621140063@cloud.masip.es entering state [received][100]
  771. 2023-10-09 09:12:59.112126 99.77% [DEBUG] sofia.c:7497 Remote SDP:
  772. v=0
  773. o=root 315311467 315311467 IN IP4 51.91.107.138
  774. s=DialApplet
  775. c=IN IP4 51.91.107.138
  776. t=0 0
  777. m=audio 10304 RTP/AVP 3 8 0 101
  778. a=rtpmap:3 GSM/8000
  779. a=rtpmap:8 PCMA/8000
  780. a=rtpmap:0 PCMU/8000
  781. a=rtpmap:101 telephone-event/8000
  782. a=fmtp:101 0-16
  783. a=ptime:20
  784.  
  785. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  786. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  787. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  788. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  789. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  790. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  791. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  792. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  793. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  794. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  795. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  796. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  797. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/621140063@cloud.masip.es PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  798. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_codec.c:111 sofia/default/621140063@cloud.masip.es Original read codec set to PCMA:8
  799. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  800. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_media.c:5850 sofia/default/621140063@cloud.masip.es Set 2833 dtmf send payload to 101 recv payload to 101
  801. 2023-10-09 09:12:59.112126 99.77% [DEBUG] sofia.c:7921 (sofia/default/621140063@cloud.masip.es) State Change CS_NEW -> CS_INIT
  802. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/621140063@cloud.masip.es) State NEW
  803. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621140063@cloud.masip.es) Running State Change CS_INIT (Cur 376 Tot 4043)
  804. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621140063@cloud.masip.es) State INIT
  805. 2023-10-09 09:12:59.112126 99.77% [DEBUG] mod_sofia.c:97 sofia/default/621140063@cloud.masip.es SOFIA INIT
  806. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:40 sofia/default/621140063@cloud.masip.es Standard INIT
  807. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:48 (sofia/default/621140063@cloud.masip.es) State Change CS_INIT -> CS_ROUTING
  808. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:624 (sofia/default/621140063@cloud.masip.es) State INIT going to sleep
  809. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/621140063@cloud.masip.es) Running State Change CS_ROUTING (Cur 376 Tot 4043)
  810. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_channel.c:2380 (sofia/default/621140063@cloud.masip.es) Callstate Change DOWN -> RINGING
  811. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:640 (sofia/default/621140063@cloud.masip.es) State ROUTING
  812. 2023-10-09 09:12:59.112126 99.77% [DEBUG] mod_sofia.c:158 sofia/default/621140063@cloud.masip.es SOFIA ROUTING
  813. 2023-10-09 09:12:59.112126 99.77% [DEBUG] switch_core_state_machine.c:230 sofia/default/621140063@cloud.masip.es Standard ROUTING
  814. 2023-10-09 09:12:59.112126 99.77% [INFO] mod_dialplan_xml.c:639 Processing 621140063 <621140063>->34604259488 in context default
  815. 2023-10-09 09:13:03.632124 99.80% [NOTICE] switch_channel.c:1123 New Channel sofia/default/604220346@52.211.114.106 [f1986827-41c6-402e-a908-05d63d11bb9d]
  816. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_NEW (Cur 377 Tot 4044)
  817. 2023-10-09 09:13:03.632124 99.80% [INFO] sofia.c:10453 sofia/default/604220346@52.211.114.106 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 536908139_63684305@52.211.114.106
  818. 2023-10-09 09:13:03.632124 99.80% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  819. 2023-10-09 09:13:03.632124 99.80% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  820. 2023-10-09 09:13:03.632124 99.80% [DEBUG] sofia.c:7487 Channel sofia/default/604220346@52.211.114.106 entering state [received][100]
  821. 2023-10-09 09:13:03.632124 99.80% [DEBUG] sofia.c:7497 Remote SDP:
  822. v=0
  823. o=Sonus_UAC 168488 557051 IN IP4 52.211.114.106
  824. s=SIP Media Capabilities
  825. c=IN IP4 52.211.114.106
  826. t=0 0
  827. m=audio 40672 RTP/AVP 8 18 0 97 13
  828. a=rtpmap:8 PCMA/8000
  829. a=rtpmap:18 G729/8000
  830. a=rtpmap:0 PCMU/8000
  831. a=rtpmap:97 telephone-event/8000
  832. a=fmtp:97 0-15
  833. a=rtpmap:13 CN/8000
  834. a=ptime:20
  835.  
  836. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  837. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  838. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  839. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  840. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  841. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  842. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  843. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  844. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  845. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  846. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  847. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  848. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 97@8000
  849. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/604220346@52.211.114.106 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  850. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_codec.c:111 sofia/default/604220346@52.211.114.106 Original read codec set to PCMA:8
  851. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 97@8000
  852. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_media.c:5850 sofia/default/604220346@52.211.114.106 Set 2833 dtmf send payload to 97 recv payload to 97
  853. 2023-10-09 09:13:03.632124 99.80% [DEBUG] sofia.c:7921 (sofia/default/604220346@52.211.114.106) State Change CS_NEW -> CS_INIT
  854. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:600 (sofia/default/604220346@52.211.114.106) State NEW
  855. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_INIT (Cur 377 Tot 4044)
  856. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/604220346@52.211.114.106) State INIT
  857. 2023-10-09 09:13:03.632124 99.80% [DEBUG] mod_sofia.c:97 sofia/default/604220346@52.211.114.106 SOFIA INIT
  858. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:40 sofia/default/604220346@52.211.114.106 Standard INIT
  859. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:48 (sofia/default/604220346@52.211.114.106) State Change CS_INIT -> CS_ROUTING
  860. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/604220346@52.211.114.106) State INIT going to sleep
  861. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_ROUTING (Cur 377 Tot 4044)
  862. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_channel.c:2380 (sofia/default/604220346@52.211.114.106) Callstate Change DOWN -> RINGING
  863. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/604220346@52.211.114.106) State ROUTING
  864. 2023-10-09 09:13:03.632124 99.80% [DEBUG] mod_sofia.c:158 sofia/default/604220346@52.211.114.106 SOFIA ROUTING
  865. 2023-10-09 09:13:03.632124 99.80% [DEBUG] switch_core_state_machine.c:230 sofia/default/604220346@52.211.114.106 Standard ROUTING
  866. 2023-10-09 09:13:03.632124 99.80% [INFO] mod_dialplan_xml.c:639 Processing 604220346 <604220346>->933111100 in context default
  867. 2023-10-09 09:13:19.752131 99.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/18889203254@204.10.205.149 [9f94c40f-c90a-4ef2-a310-8598d290ee8e]
  868. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889203254@204.10.205.149) Running State Change CS_NEW (Cur 378 Tot 4045)
  869. 2023-10-09 09:13:19.752131 99.93% [INFO] sofia.c:10453 sofia/default/18889203254@204.10.205.149 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 1493956718_113591902@204.10.205.149
  870. 2023-10-09 09:13:19.752131 99.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/604220346@52.211.114.106 [5bb40844-34fc-4bfd-a8b5-d45203b15d85]
  871. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  872. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_NEW (Cur 379 Tot 4046)
  873. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  874. 2023-10-09 09:13:19.752131 99.93% [INFO] sofia.c:10453 sofia/default/604220346@52.211.114.106 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 536908140_65894376@52.211.114.106
  875. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  876. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  877. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7487 Channel sofia/default/18889203254@204.10.205.149 entering state [received][100]
  878. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7497 Remote SDP:
  879. v=0
  880. o=Sonus_UAC 17517 7828 IN IP4 204.10.205.149
  881. s=SIP Media Capabilities
  882. c=IN IP4 204.10.205.150
  883. t=0 0
  884. m=audio 22852 RTP/AVP 8 18 0 100
  885. a=rtpmap:8 PCMA/8000
  886. a=rtpmap:18 G729/8000
  887. a=fmtp:18 annexb=no
  888. a=rtpmap:0 PCMU/8000
  889. a=rtpmap:100 telephone-event/8000
  890. a=fmtp:100 0-15
  891. a=ptime:20
  892.  
  893. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  894. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  895. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  896. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  897. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  898. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  899. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  900. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  901. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  902. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  903. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  904. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  905. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  906. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/18889203254@204.10.205.149 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  907. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_codec.c:111 sofia/default/18889203254@204.10.205.149 Original read codec set to PCMA:8
  908. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  909. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5850 sofia/default/18889203254@204.10.205.149 Set 2833 dtmf send payload to 100 recv payload to 100
  910. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7921 (sofia/default/18889203254@204.10.205.149) State Change CS_NEW -> CS_INIT
  911. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/18889203254@204.10.205.149) State NEW
  912. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889203254@204.10.205.149) Running State Change CS_INIT (Cur 379 Tot 4046)
  913. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889203254@204.10.205.149) State INIT
  914. 2023-10-09 09:13:19.752131 99.93% [DEBUG] mod_sofia.c:97 sofia/default/18889203254@204.10.205.149 SOFIA INIT
  915. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/18889203254@204.10.205.149 Standard INIT
  916. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/18889203254@204.10.205.149) State Change CS_INIT -> CS_ROUTING
  917. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889203254@204.10.205.149) State INIT going to sleep
  918. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889203254@204.10.205.149) Running State Change CS_ROUTING (Cur 379 Tot 4046)
  919. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_channel.c:2380 (sofia/default/18889203254@204.10.205.149) Callstate Change DOWN -> RINGING
  920. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/18889203254@204.10.205.149) State ROUTING
  921. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7487 Channel sofia/default/604220346@52.211.114.106 entering state [received][100]
  922. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7497 Remote SDP:
  923. v=0
  924. o=Sonus_UAC 998687 233085 IN IP4 52.211.114.106
  925. s=SIP Media Capabilities
  926. c=IN IP4 52.211.114.106
  927. t=0 0
  928. m=audio 40492 RTP/AVP 8 18 0 97 13
  929. a=rtpmap:8 PCMA/8000
  930. a=rtpmap:18 G729/8000
  931. a=rtpmap:0 PCMU/8000
  932. a=rtpmap:97 telephone-event/8000
  933. a=fmtp:97 0-15
  934. a=rtpmap:13 CN/8000
  935. a=ptime:20
  936.  
  937. 2023-10-09 09:13:19.752131 99.93% [DEBUG] mod_sofia.c:158 sofia/default/18889203254@204.10.205.149 SOFIA ROUTING
  938. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/18889203254@204.10.205.149 Standard ROUTING
  939. 2023-10-09 09:13:19.752131 99.93% [INFO] mod_dialplan_xml.c:639 Processing 18889203254 <18889203254>->34671467670 in context default
  940. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  941. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  942. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  943. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  944. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  945. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  946. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  947. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  948. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  949. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  950. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  951. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  952. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 97@8000
  953. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/604220346@52.211.114.106 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  954. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_codec.c:111 sofia/default/604220346@52.211.114.106 Original read codec set to PCMA:8
  955. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 97@8000
  956. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_media.c:5850 sofia/default/604220346@52.211.114.106 Set 2833 dtmf send payload to 97 recv payload to 97
  957. 2023-10-09 09:13:19.752131 99.93% [DEBUG] sofia.c:7921 (sofia/default/604220346@52.211.114.106) State Change CS_NEW -> CS_INIT
  958. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/604220346@52.211.114.106) State NEW
  959. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_INIT (Cur 379 Tot 4046)
  960. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/604220346@52.211.114.106) State INIT
  961. 2023-10-09 09:13:19.752131 99.93% [DEBUG] mod_sofia.c:97 sofia/default/604220346@52.211.114.106 SOFIA INIT
  962. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/604220346@52.211.114.106 Standard INIT
  963. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/604220346@52.211.114.106) State Change CS_INIT -> CS_ROUTING
  964. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/604220346@52.211.114.106) State INIT going to sleep
  965. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/604220346@52.211.114.106) Running State Change CS_ROUTING (Cur 379 Tot 4046)
  966. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_channel.c:2380 (sofia/default/604220346@52.211.114.106) Callstate Change DOWN -> RINGING
  967. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/604220346@52.211.114.106) State ROUTING
  968. 2023-10-09 09:13:19.752131 99.93% [DEBUG] mod_sofia.c:158 sofia/default/604220346@52.211.114.106 SOFIA ROUTING
  969. 2023-10-09 09:13:19.752131 99.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/604220346@52.211.114.106 Standard ROUTING
  970. 2023-10-09 09:13:19.752131 99.93% [INFO] mod_dialplan_xml.c:639 Processing 604220346 <604220346>->933111100 in context default
  971. 2023-10-09 09:13:23.152126 99.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/600369508@54.170.60.120 [b02d4b0b-a634-4fbc-95a3-572e7908e08e]
  972. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/600369508@54.170.60.120) Running State Change CS_NEW (Cur 380 Tot 4047)
  973. 2023-10-09 09:13:23.152126 99.90% [INFO] sofia.c:10453 sofia/default/600369508@54.170.60.120 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 35653770_133950060@54.170.60.120
  974. 2023-10-09 09:13:23.152126 99.90% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  975. 2023-10-09 09:13:23.152126 99.90% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  976. 2023-10-09 09:13:23.152126 99.90% [DEBUG] sofia.c:7487 Channel sofia/default/600369508@54.170.60.120 entering state [received][100]
  977. 2023-10-09 09:13:23.152126 99.90% [DEBUG] sofia.c:7497 Remote SDP:
  978. v=0
  979. o=Sonus_UAC 599589 378631 IN IP4 54.170.60.120
  980. s=SIP Media Capabilities
  981. c=IN IP4 54.170.60.120
  982. t=0 0
  983. m=audio 46164 RTP/AVP 8 0 18 100
  984. a=rtpmap:8 PCMA/8000
  985. a=rtpmap:0 PCMU/8000
  986. a=rtpmap:18 G729/8000
  987. a=fmtp:18 annexb=no
  988. a=rtpmap:100 telephone-event/8000
  989. a=fmtp:100 0-15
  990. a=ptime:20
  991.  
  992. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  993. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  994. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  995. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  996. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  997. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  998. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  999. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1000. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  1001. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1002. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1003. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1004. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  1005. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/600369508@54.170.60.120 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1006. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_codec.c:111 sofia/default/600369508@54.170.60.120 Original read codec set to PCMA:8
  1007. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  1008. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_media.c:5850 sofia/default/600369508@54.170.60.120 Set 2833 dtmf send payload to 100 recv payload to 100
  1009. 2023-10-09 09:13:23.152126 99.90% [DEBUG] sofia.c:7921 (sofia/default/600369508@54.170.60.120) State Change CS_NEW -> CS_INIT
  1010. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/600369508@54.170.60.120) State NEW
  1011. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/600369508@54.170.60.120) Running State Change CS_INIT (Cur 380 Tot 4047)
  1012. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/600369508@54.170.60.120) State INIT
  1013. 2023-10-09 09:13:23.152126 99.90% [DEBUG] mod_sofia.c:97 sofia/default/600369508@54.170.60.120 SOFIA INIT
  1014. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/600369508@54.170.60.120 Standard INIT
  1015. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/600369508@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  1016. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/600369508@54.170.60.120) State INIT going to sleep
  1017. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/600369508@54.170.60.120) Running State Change CS_ROUTING (Cur 380 Tot 4047)
  1018. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_channel.c:2380 (sofia/default/600369508@54.170.60.120) Callstate Change DOWN -> RINGING
  1019. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/600369508@54.170.60.120) State ROUTING
  1020. 2023-10-09 09:13:23.152126 99.90% [DEBUG] mod_sofia.c:158 sofia/default/600369508@54.170.60.120 SOFIA ROUTING
  1021. 2023-10-09 09:13:23.152126 99.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/600369508@54.170.60.120 Standard ROUTING
  1022. 2023-10-09 09:13:23.152126 99.90% [INFO] mod_dialplan_xml.c:639 Processing 600369508 <600369508>->933113862 in context default
  1023. 2023-10-09 09:13:27.972127 99.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/18889201327@204.10.205.149 [62ac4a24-b187-49c9-9c84-f8f1ee93aeb8]
  1024. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_NEW (Cur 381 Tot 4048)
  1025. 2023-10-09 09:13:27.972127 99.90% [INFO] sofia.c:10453 sofia/default/18889201327@204.10.205.149 receiving invite from 34.243.39.183:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 1493591524_68549819@204.10.205.149
  1026. 2023-10-09 09:13:27.972127 99.90% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.243.39.183:0.
  1027. 2023-10-09 09:13:27.972127 99.90% [DEBUG] sofia.c:10576 IP 34.243.39.183 Approved by acl "default[]". Access Granted.
  1028. 2023-10-09 09:13:27.972127 99.90% [DEBUG] sofia.c:7487 Channel sofia/default/18889201327@204.10.205.149 entering state [received][100]
  1029. 2023-10-09 09:13:27.972127 99.90% [DEBUG] sofia.c:7497 Remote SDP:
  1030. v=0
  1031. o=Sonus_UAC 29049 9463 IN IP4 204.10.205.149
  1032. s=SIP Media Capabilities
  1033. c=IN IP4 204.10.205.150
  1034. t=0 0
  1035. m=audio 20092 RTP/AVP 8 18 0 100
  1036. a=rtpmap:8 PCMA/8000
  1037. a=rtpmap:18 G729/8000
  1038. a=fmtp:18 annexb=no
  1039. a=rtpmap:0 PCMU/8000
  1040. a=rtpmap:100 telephone-event/8000
  1041. a=fmtp:100 0-15
  1042. a=ptime:20
  1043.  
  1044. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1045. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1046. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1047. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1048. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  1049. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1050. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1051. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1052. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1053. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1054. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1055. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1056. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 100@8000
  1057. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/18889201327@204.10.205.149 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1058. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_codec.c:111 sofia/default/18889201327@204.10.205.149 Original read codec set to PCMA:8
  1059. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 100@8000
  1060. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_media.c:5850 sofia/default/18889201327@204.10.205.149 Set 2833 dtmf send payload to 100 recv payload to 100
  1061. 2023-10-09 09:13:27.972127 99.90% [DEBUG] sofia.c:7921 (sofia/default/18889201327@204.10.205.149) State Change CS_NEW -> CS_INIT
  1062. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/18889201327@204.10.205.149) State NEW
  1063. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_INIT (Cur 381 Tot 4048)
  1064. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889201327@204.10.205.149) State INIT
  1065. 2023-10-09 09:13:27.972127 99.90% [DEBUG] mod_sofia.c:97 sofia/default/18889201327@204.10.205.149 SOFIA INIT
  1066. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/18889201327@204.10.205.149 Standard INIT
  1067. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/18889201327@204.10.205.149) State Change CS_INIT -> CS_ROUTING
  1068. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/18889201327@204.10.205.149) State INIT going to sleep
  1069. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/18889201327@204.10.205.149) Running State Change CS_ROUTING (Cur 381 Tot 4048)
  1070. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_channel.c:2380 (sofia/default/18889201327@204.10.205.149) Callstate Change DOWN -> RINGING
  1071. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/18889201327@204.10.205.149) State ROUTING
  1072. 2023-10-09 09:13:27.972127 99.90% [DEBUG] mod_sofia.c:158 sofia/default/18889201327@204.10.205.149 SOFIA ROUTING
  1073. 2023-10-09 09:13:27.972127 99.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/18889201327@204.10.205.149 Standard ROUTING
  1074. 2023-10-09 09:13:27.972127 99.90% [INFO] mod_dialplan_xml.c:639 Processing 18889201327 <18889201327>->34620544312 in context default
  1075. 2023-10-09 09:13:29.032126 99.87% [NOTICE] switch_channel.c:1123 New Channel sofia/default/679974931@52.211.114.106 [46a16eff-171a-4047-a25b-a964b9ba92bc]
  1076. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/679974931@52.211.114.106) Running State Change CS_NEW (Cur 382 Tot 4049)
  1077. 2023-10-09 09:13:29.032126 99.87% [INFO] sofia.c:10453 sofia/default/679974931@52.211.114.106 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 561615_74180481@52.211.114.106
  1078. 2023-10-09 09:13:29.032126 99.87% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  1079. 2023-10-09 09:13:29.032126 99.87% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  1080. 2023-10-09 09:13:29.032126 99.87% [DEBUG] sofia.c:7487 Channel sofia/default/679974931@52.211.114.106 entering state [received][100]
  1081. 2023-10-09 09:13:29.032126 99.87% [DEBUG] sofia.c:7497 Remote SDP:
  1082. v=0
  1083. o=Sonus_UAC 16101 207348 IN IP4 52.211.114.106
  1084. s=SIP Media Capabilities
  1085. c=IN IP4 52.211.114.106
  1086. t=0 0
  1087. m=audio 40684 RTP/AVP 8 18 0 110 13
  1088. a=rtpmap:8 PCMA/8000
  1089. a=rtpmap:18 G729/8000
  1090. a=fmtp:18 annexb=no
  1091. a=rtpmap:0 PCMU/8000
  1092. a=rtpmap:110 telephone-event/8000
  1093. a=fmtp:110 0-15
  1094. a=rtpmap:13 CN/8000
  1095. a=ptime:20
  1096.  
  1097. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1098. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1099. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1100. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1101. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  1102. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1103. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1104. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1105. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1106. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1107. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1108. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1109. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 110@8000
  1110. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/679974931@52.211.114.106 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1111. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_codec.c:111 sofia/default/679974931@52.211.114.106 Original read codec set to PCMA:8
  1112. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 110@8000
  1113. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_media.c:5850 sofia/default/679974931@52.211.114.106 Set 2833 dtmf send payload to 110 recv payload to 110
  1114. 2023-10-09 09:13:29.032126 99.87% [DEBUG] sofia.c:7921 (sofia/default/679974931@52.211.114.106) State Change CS_NEW -> CS_INIT
  1115. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:600 (sofia/default/679974931@52.211.114.106) State NEW
  1116. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/679974931@52.211.114.106) Running State Change CS_INIT (Cur 382 Tot 4049)
  1117. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/679974931@52.211.114.106) State INIT
  1118. 2023-10-09 09:13:29.032126 99.87% [DEBUG] mod_sofia.c:97 sofia/default/679974931@52.211.114.106 SOFIA INIT
  1119. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:40 sofia/default/679974931@52.211.114.106 Standard INIT
  1120. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:48 (sofia/default/679974931@52.211.114.106) State Change CS_INIT -> CS_ROUTING
  1121. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/679974931@52.211.114.106) State INIT going to sleep
  1122. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/679974931@52.211.114.106) Running State Change CS_ROUTING (Cur 382 Tot 4049)
  1123. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_channel.c:2380 (sofia/default/679974931@52.211.114.106) Callstate Change DOWN -> RINGING
  1124. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/679974931@52.211.114.106) State ROUTING
  1125. 2023-10-09 09:13:29.032126 99.87% [DEBUG] mod_sofia.c:158 sofia/default/679974931@52.211.114.106 SOFIA ROUTING
  1126. 2023-10-09 09:13:29.032126 99.87% [DEBUG] switch_core_state_machine.c:230 sofia/default/679974931@52.211.114.106 Standard ROUTING
  1127. 2023-10-09 09:13:29.032126 99.87% [INFO] mod_dialplan_xml.c:639 Processing 679974931 <679974931>->911610027 in context default
  1128. 2023-10-09 09:13:29.832130 99.87% [NOTICE] switch_channel.c:1123 New Channel sofia/default/915955000@54.170.60.120 [d3e06498-8132-4595-9a5b-7a3b69f1ea51]
  1129. 2023-10-09 09:13:29.832130 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/915955000@54.170.60.120) Running State Change CS_NEW (Cur 383 Tot 4050)
  1130. 2023-10-09 09:13:29.852125 99.87% [INFO] sofia.c:10453 sofia/default/915955000@54.170.60.120 receiving invite from 34.252.123.120:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 572525411_99561295@54.170.60.120
  1131. 2023-10-09 09:13:29.852125 99.87% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 34.252.123.120:0.
  1132. 2023-10-09 09:13:29.852125 99.87% [DEBUG] sofia.c:10576 IP 34.252.123.120 Approved by acl "default[]". Access Granted.
  1133. 2023-10-09 09:13:29.852125 99.87% [DEBUG] sofia.c:7487 Channel sofia/default/915955000@54.170.60.120 entering state [received][100]
  1134. 2023-10-09 09:13:29.852125 99.87% [DEBUG] sofia.c:7497 Remote SDP:
  1135. v=0
  1136. o=Sonus_UAC 148591 612224 IN IP4 54.170.60.120
  1137. s=SIP Media Capabilities
  1138. c=IN IP4 54.170.60.120
  1139. t=0 0
  1140. m=audio 45992 RTP/AVP 18 0 8 127 13
  1141. a=rtpmap:18 G729/8000
  1142. a=fmtp:18 annexb=no
  1143. a=rtpmap:0 PCMU/8000
  1144. a=rtpmap:8 PCMA/8000
  1145. a=rtpmap:127 telephone-event/8000
  1146. a=fmtp:127 0-15
  1147. a=rtpmap:13 CN/8000
  1148. a=ptime:20
  1149.  
  1150. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  1151. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1152. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1153. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1154. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1155. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1156. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1157. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1158. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1159. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1160. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1161. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1162. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 127@8000
  1163. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/915955000@54.170.60.120 G729/8000 20 ms 160 samples 8000 bits 1 channels
  1164. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_codec.c:111 sofia/default/915955000@54.170.60.120 Original read codec set to G729:18
  1165. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 127@8000
  1166. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_media.c:5850 sofia/default/915955000@54.170.60.120 Set 2833 dtmf send payload to 127 recv payload to 127
  1167. 2023-10-09 09:13:29.852125 99.87% [DEBUG] sofia.c:7921 (sofia/default/915955000@54.170.60.120) State Change CS_NEW -> CS_INIT
  1168. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:600 (sofia/default/915955000@54.170.60.120) State NEW
  1169. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/915955000@54.170.60.120) Running State Change CS_INIT (Cur 383 Tot 4050)
  1170. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/915955000@54.170.60.120) State INIT
  1171. 2023-10-09 09:13:29.852125 99.87% [DEBUG] mod_sofia.c:97 sofia/default/915955000@54.170.60.120 SOFIA INIT
  1172. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:40 sofia/default/915955000@54.170.60.120 Standard INIT
  1173. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:48 (sofia/default/915955000@54.170.60.120) State Change CS_INIT -> CS_ROUTING
  1174. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/915955000@54.170.60.120) State INIT going to sleep
  1175. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/915955000@54.170.60.120) Running State Change CS_ROUTING (Cur 383 Tot 4050)
  1176. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_channel.c:2380 (sofia/default/915955000@54.170.60.120) Callstate Change DOWN -> RINGING
  1177. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/915955000@54.170.60.120) State ROUTING
  1178. 2023-10-09 09:13:29.852125 99.87% [DEBUG] mod_sofia.c:158 sofia/default/915955000@54.170.60.120 SOFIA ROUTING
  1179. 2023-10-09 09:13:29.852125 99.87% [DEBUG] switch_core_state_machine.c:230 sofia/default/915955000@54.170.60.120 Standard ROUTING
  1180. 2023-10-09 09:13:29.852125 99.87% [INFO] mod_dialplan_xml.c:639 Processing 915955000 <915955000>->960201328 in context default
  1181.  

Reply to "RAFA call stuck"