From Sandro, 5 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: goldenrod; font-weight: bold;"> 2019-09-24 17:53:41.782762 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [received][100]
  2. 2019-09-24 17:53:41.782762 [DEBUG] sofia.c:7058 Remote SDP:
  3. v=0
  4. o=- 2576636 2576637 IN IP4 192.168.16.212
  5. s=-
  6. c=IN IP4 192.168.16.212
  7. t=0 0
  8. m=audio 24088 RTP/AVP 18 8 0 101
  9. a=rtpmap:18 G729/8000
  10. a=fmtp:18 annexb=no
  11. a=rtpmap:8 PCMA/8000
  12. a=rtpmap:0 PCMU/8000
  13. a=rtpmap:101 telephone-event/8000
  14. a=fmtp:101 0-15
  15. a=sendonly
  16. a=ptime:20
  17.  
  18. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  19. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  20. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  21. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  22. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  23. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  24. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  25. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  26. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  27. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  28. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  29. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/204@freeswitch.test.xxx PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  30. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_codec.c:111 sofia/internal/204@freeswitch.test.xxx Original read codec set to PCMA:8
  31. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  32. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
  33. 2019-09-24 17:53:41.782762 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/204@freeswitch.test.xxx] X.Y.Z.102 port 31062 -> 192.168.16.212 port 24088 codec: 8 ms: 20
  34. 2019-09-24 17:53:41.782762 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  35. 2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7109 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101
  36. 2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7116 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf receive payload to 101
  37. 2019-09-24 17:53:41.815849 [DEBUG] switch_core_media.c:7139 sofia/internal/204@freeswitch.test.xxx Set rtp dtmf delay to 40
  38. 2019-09-24 17:53:42.054862 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completed][200]
  39. 2019-09-24 17:53:42.054862 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
  40. 2019-09-24 17:53:42.314861 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [calling][0]
  41. 2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [completing][200]
  42. 2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7058 Remote SDP:
  43. v=0
  44. o=202 1988239396 1988239397 IN IP4 192.168.16.187
  45. s=-
  46. c=IN IP4 192.168.16.187
  47. t=0 0
  48. m=audio 50028 RTP/AVP 18 8 0 2 101
  49. a=rtpmap:18 G729/8000
  50. a=fmtp:18 annexb=no
  51. a=rtpmap:8 PCMA/8000
  52. a=rtpmap:0 PCMU/8000
  53. a=rtpmap:2 G726-32/8000/1
  54. a=rtpmap:101 telephone-event/8000
  55. a=fmtp:101 0-15
  56. a=ptime:20
  57. a=maxptime:80
  58. a=sendrecv
  59. a=rtcp:50029
  60.  
  61. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  62. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  63. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  64. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  65. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  66. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  67. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  68. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  69. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G726-32:2:8000:20:0:1]/[G722:9:8000:20:64000:1]
  70. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  71. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/202@192.168.16.187:65532 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  72. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_codec.c:111 sofia/internal/202@192.168.16.187:65532 Original read codec set to PCMA:8
  73. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  74. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:4747 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf send payload to 101 recv payload to 101
  75. 2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7340 RESTABLISH MEDIA SDP:
  76. v=0
  77. o=FreeSWITCH 1569308594 1569308595 IN IP4 X.Y.Z.102
  78. s=FreeSWITCH
  79. c=IN IP4 X.Y.Z.102
  80. t=0 0
  81. m=audio 31828 RTP/AVP 8 101
  82. a=rtpmap:8 PCMA/8000
  83. a=rtpmap:101 telephone-event/8000
  84. a=fmtp:101 0-16
  85. a=ptime:20
  86. a=sendrecv
  87. a=rtcp:31829 IN IP4 X.Y.Z.102
  88.  
  89. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/202@192.168.16.187:65532] X.Y.Z.102 port 31828 -> 192.168.16.187 port 50028 codec: 8 ms: 20
  90. 2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
  91. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7028 Activating RTCP PORT 50029
  92. 2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:4487 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 50029
  93. 2019-09-24 17:53:42.414911 [DEBUG] switch_rtp.c:2559 Setting RTCP remote addr to 192.168.16.187:50029 2
  94. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7109 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf send payload to 101
  95. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7116 sofia/internal/202@192.168.16.187:65532 Set 2833 dtmf receive payload to 101
  96. 2019-09-24 17:53:42.414911 [DEBUG] switch_core_media.c:7139 sofia/internal/202@192.168.16.187:65532 Set rtp dtmf delay to 40
  97. 2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/202@192.168.16.187:65532 entering state [ready][200]
  98. 2019-09-24 17:53:42.414911 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [calling][0]
  99. 2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completing][200]
  100. 2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7058 Remote SDP:
  101. v=0
  102. o=- 2576636 2576637 IN IP4 192.168.16.212
  103. s=-
  104. c=IN IP4 192.168.16.212
  105. t=0 0
  106. m=audio 24088 RTP/AVP 18 8 0 101
  107. a=rtpmap:18 G729/8000
  108. a=rtpmap:8 PCMA/8000
  109. a=rtpmap:0 PCMU/8000
  110. a=rtpmap:101 telephone-event/8000
  111. a=fmtp:101 0-15
  112. a=ptime:20
  113. a=sendonly
  114.  
  115. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  116. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  117. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  118. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  119. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  120. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  121. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  122. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  123. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  124. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  125. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  126. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  127. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
  128. 2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7340 RESTABLISH MEDIA SDP:
  129. v=0
  130. o=FreeSWITCH 1569309359 1569309361 IN IP4 X.Y.Z.102
  131. s=FreeSWITCH
  132. c=IN IP4 X.Y.Z.102
  133. t=0 0
  134. m=audio 31062 RTP/AVP 8 101
  135. a=rtpmap:8 PCMA/8000
  136. a=rtpmap:101 telephone-event/8000
  137. a=fmtp:101 0-16
  138. a=ptime:20
  139. a=recvonly
  140.  
  141. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/204@freeswitch.test.xxx.
  142. 2019-09-24 17:53:42.434915 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
  143. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:1990 (sofia/internal/204@freeswitch.test.xxx) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA
  144. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
  145. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:1992 (sofia/internal/202@192.168.16.187:65532) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA
  146. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
  147. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/204@freeswitch.test.xxx) State CONSUME_MEDIA
  148. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:944 (sofia/internal/204@freeswitch.test.xxx) State Change CS_CONSUME_MEDIA -> CS_RESET
  149. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA
  150. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:944 (sofia/internal/202@192.168.16.187:65532) State Change CS_CONSUME_MEDIA -> CS_RESET
  151. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA going to sleep
  152. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/204@freeswitch.test.xxx) State CONSUME_MEDIA going to sleep
  153. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_RESET (Cur 2 Tot 100)
  154. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_RESET (Cur 2 Tot 100)
  155. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/202@192.168.16.187:65532) State RESET
  156. 2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:161 sofia/internal/202@192.168.16.187:65532 SOFIA RESET
  157. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:929 sofia/internal/202@192.168.16.187:65532 CUSTOM RESET
  158. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/204@freeswitch.test.xxx) State RESET
  159. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:188 sofia/internal/202@192.168.16.187:65532 Standard RESET
  160. 2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:161 sofia/internal/204@freeswitch.test.xxx SOFIA RESET
  161. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:929 sofia/internal/204@freeswitch.test.xxx CUSTOM RESET
  162. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:936 (sofia/internal/204@freeswitch.test.xxx) State Change CS_RESET -> CS_SOFT_EXECUTE
  163. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/202@192.168.16.187:65532) State RESET going to sleep
  164. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/204@freeswitch.test.xxx) State RESET going to sleep
  165. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/204@freeswitch.test.xxx) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 100)
  166. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/204@freeswitch.test.xxx) State SOFT_EXECUTE
  167. 2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
  168. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:954 sofia/internal/204@freeswitch.test.xxx CUSTOM SOFT_EXECUTE
  169. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:986 (sofia/internal/202@192.168.16.187:65532) State Change CS_RESET -> CS_SOFT_EXECUTE
  170. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 100)
  171. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/202@192.168.16.187:65532) State SOFT_EXECUTE
  172. 2019-09-24 17:53:42.434915 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
  173. 2019-09-24 17:53:42.434915 [DEBUG] switch_ivr_bridge.c:954 sofia/internal/202@192.168.16.187:65532 CUSTOM SOFT_EXECUTE
  174. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:400 sofia/internal/202@192.168.16.187:65532 Standard SOFT_EXECUTE
  175. 2019-09-24 17:53:42.434915 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/202@192.168.16.187:65532) State SOFT_EXECUTE going to sleep
  176. 2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:1469 (sofia/internal/202@192.168.16.187:65532) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
  177. 2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 100)
  178. 2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA
  179. 2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:893 sofia/internal/202@192.168.16.187:65532 CUSTOM HOLD
  180. 2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/202@192.168.16.187:65532) State CONSUME_MEDIA going to sleep
  181. 2019-09-24 17:53:42.454864 [DEBUG] switch_ivr_bridge.c:1574 (sofia/internal/202@192.168.16.187:65532) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  182. 2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/202@192.168.16.187:65532) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 100)
  183. 2019-09-24 17:53:42.454864 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/202@192.168.16.187:65532) State EXCHANGE_MEDIA
  184. 2019-09-24 17:53:42.454864 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  185. 2019-09-24 17:53:42.454864 [DEBUG] switch_channel.c:1844 (sofia/internal/204@freeswitch.test.xxx) Callstate Change ACTIVE -> HELD
  186. 2019-09-24 17:53:42.454864 [DEBUG] mod_sofia.c:1938 Not sending same id again "204" <204>
  187. 2019-09-24 17:53:42.474866 [DEBUG] mod_sofia.c:1938 Not sending same id again "202" <202>
  188. 2019-09-24 17:53:42.514859 [DEBUG] switch_rtp.c:1884 rtcp_stats_init: audio ssrc[1988418925] base_seq[31562]
  189. 2019-09-24 17:53:42.834867 [DEBUG] switch_ivr.c:623 sofia/internal/202@192.168.16.187:65532 Command Execute playback(local_stream://moh)
  190. EXECUTE sofia/internal/202@192.168.16.187:65532 playback(local_stream://moh)
  191. 2019-09-24 17:53:42.834867 [DEBUG] mod_local_stream.c:866 Opening Stream [moh/8000] 8000hz
  192. 2019-09-24 17:53:42.834867 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  193. 2019-09-24 17:53:53.894869 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [received][100]
  194. 2019-09-24 17:53:53.894869 [DEBUG] sofia.c:7058 Remote SDP:
  195. v=0
  196. o=- 2576636 2576639 IN IP4 192.168.16.212
  197. s=-
  198. c=IN IP4 192.168.16.212
  199. t=0 0
  200. m=audio 24088 RTP/AVP 18 8 0 101
  201. a=rtpmap:18 G729/8000
  202. a=rtpmap:8 PCMA/8000
  203. a=rtpmap:0 PCMU/8000
  204. a=rtpmap:101 telephone-event/8000
  205. a=fmtp:101 0-15
  206. a=ptime:20
  207. a=sendrecv
  208.  
  209. 2019-09-24 17:53:54.154870 [DEBUG] switch_ivr_play_say.c:1942 done playing file local_stream://moh
  210. 2019-09-24 17:53:54.154870 [DEBUG] switch_channel.c:2029 (sofia/internal/204@freeswitch.test.xxx) Callstate Change HELD -> UNHELD
  211. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  212. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  213. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  214. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  215. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  216. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  217. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  218. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  219. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  220. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  221. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
  222. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
  223. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:4747 sofia/internal/204@freeswitch.test.xxx Set 2833 dtmf send payload to 101 recv payload to 101
  224. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/204@freeswitch.test.xxx.
  225. 2019-09-24 17:53:54.154870 [DEBUG] sofia.c:7948 Processing updated SDP
  226. 2019-09-24 17:53:54.154870 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [completed][200]
  227. 2019-09-24 17:53:54.154870 [DEBUG] switch_core_io.c:941 (sofia/internal/204@freeswitch.test.xxx) Callstate Change UNHELD -> ACTIVE
  228. 2019-09-24 17:53:54.254861 [DEBUG] sofia.c:7048 Channel sofia/internal/204@freeswitch.test.xxx entering state [ready][200]
  229. 2019-09-24 17:53:54.474860 [INFO] switch_rtp.c:7188 Auto Changing audio port from 192.168.16.212:24088 to X.Y.Z.98:29511