From Gentle Armadillo, 1 Year ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2022-05-19 09:22:03.335284 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/robert@andy3d [c89819ce-a49a-4acc-a52c-3acf3cd9f78b]
  2. 2022-05-19 09:22:03.335284 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_NEW (Cur 1 Tot 22)
  3. 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:10279 sofia/internal/robert@andy3d receiving invite from 172.19.0.22:64561 version: 1.10.33265  64bit
  4. 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.19.0.22:0.
  5. 2022-05-19 09:22:03.335284 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/robert@andy3d) State NEW
  6. 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:2434 detaching session c89819ce-a49a-4acc-a52c-3acf3cd9f78b
  7. 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:2544 Re-attaching to session c89819ce-a49a-4acc-a52c-3acf3cd9f78b
  8. 2022-05-19 09:22:03.355284 [DEBUG] sofia.c:10279 sofia/internal/robert@andy3d receiving invite from 172.19.0.22:64561 version: 1.10.33265  64bit
  9. 2022-05-19 09:22:03.355284 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.19.0.22:0.
  10. 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [received][100]
  11. 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7335 Remote SDP:
  12. v=0
  13. o=- 3861940804 3861940804 IN IP4 172.19.0.22
  14. s=pjmedia
  15. b=AS:1629
  16. t=0 0
  17. a=X-nat:0
  18. m=audio 4002 RTP/AVP 8 0 96 9 97 98 4 99 100 102 18 3 103 104 105 106 107 108 109 110 111 112 113 114 11 10 115 116 101 117 118 119 120
  19. c=IN IP4 172.19.0.22
  20. b=TIAS:1536000
  21. a=rtpmap:8 PCMA/8000
  22. a=rtpmap:0 PCMU/8000
  23. a=rtpmap:96 opus/48000/2
  24. a=fmtp:96 maxplaybackrate=24000;sprop-maxcapturerate=24000;maxaveragebitrate=64000;useinbandfec=1
  25. a=rtpmap:9 G722/8000
  26. a=rtpmap:97 G7221/16000
  27. a=fmtp:97 bitrate=24000
  28. a=rtpmap:98 G7221/16000
  29. a=fmtp:98 bitrate=32000
  30. a=rtpmap:4 G723/8000
  31. a=rtpmap:99 G7221/32000
  32. a=fmtp:99 bitrate=48000
  33. a=rtpmap:100 G7221/32000
  34. a=fmtp:100 bitrate=32000
  35. a=rtpmap:102 G7221/32000
  36. a=fmtp:102 bitrate=24000
  37. a=rtpmap:18 G729/8000
  38. a=rtpmap:3 GSM/8000
  39. a=rtpmap:103 AMR/8000
  40. a=fmtp:103 octet-align=1
  41. a=rtpmap:104 AMR-WB/16000
  42. a=fmtp:104 octet-align=1
  43. a=rtpmap:105 iLBC/8000
  44. a=fmtp:105 mode=30
  45. a=rtpmap:106 speex/32000
  46. a=rtpmap:107 speex/16000
  47. a=rtpmap:108 speex/8000
  48. a=rtpmap:109 SILK/16000
  49. a=fmtp:109 useinbandfec=0
  50. a=rtpmap:110 SILK/8000
  51. a=fmtp:110 useinbandfec=0
  52. a=rtpmap:111 L16/8000
  53. a=rtpmap:112 L16/8000/2
  54. a=rtpmap:113 L16/16000
  55. a=rtpmap:114 L16/16000/2
  56. a=rtpmap:11 L16/44100
  57. a=rtpmap:10 L16/44100/2
  58. a=rtpmap:115 L16/48000
  59. a=rtpmap:116 L16/48000/2
  60. a=rtpmap:101 telephone-event/8000
  61. a=fmtp:101 0-16
  62. a=rtpmap:117 telephone-event/48000
  63. a=fmtp:117 0-16
  64. a=rtpmap:118 telephone-event/16000
  65. a=fmtp:118 0-16
  66. a=rtpmap:119 telephone-event/32000
  67. a=fmtp:119 0-16
  68. a=rtpmap:120 telephone-event/44100
  69. a=fmtp:120 0-16
  70. a=rtcp:4003 IN IP4 172.19.0.22
  71. a=ssrc:1651052188 cname:4e852a1101a15fca
  72.  
  73. 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7724 (sofia/internal/robert@andy3d) State Change CS_NEW -> CS_INIT
  74. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_INIT (Cur 1 Tot 22)
  75. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/robert@andy3d) State INIT
  76. 2022-05-19 09:22:03.395286 [DEBUG] mod_sofia.c:93 sofia/internal/robert@andy3d SOFIA INIT
  77. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:40 sofia/internal/robert@andy3d Standard INIT
  78. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/robert@andy3d) State Change CS_INIT -> CS_ROUTING
  79. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/robert@andy3d) State INIT going to sleep
  80. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_ROUTING (Cur 1 Tot 22)
  81. 2022-05-19 09:22:03.395286 [DEBUG] switch_channel.c:2332 (sofia/internal/robert@andy3d) Callstate Change DOWN -> RINGING
  82. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/robert@andy3d) State ROUTING
  83. 2022-05-19 09:22:03.395286 [DEBUG] mod_sofia.c:154 sofia/internal/robert@andy3d SOFIA ROUTING
  84. 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:236 sofia/internal/robert@andy3d Standard ROUTING
  85. 2022-05-19 09:22:03.395286 [INFO] mod_dialplan_xml.c:637 Processing robert <robert>->john in context public
  86. Dialplan: sofia/internal/robert@andy3d parsing [public->audio_specific_conferences] continue=false
  87. Dialplan: sofia/internal/robert@andy3d Regex (FAIL) [audio_specific_conferences] destination_number(john) =~ /^9999(.*)$/ break=on-false
  88. Dialplan: sofia/internal/robert@andy3d parsing [public->andy-conference-bridge] continue=false
  89. Dialplan: sofia/internal/robert@andy3d Regex (PASS) [andy-conference-bridge] destination_number(john) =~ /^(.*)$/ break=on-false
  90. Dialplan: sofia/internal/robert@andy3d Action set(ignore_sdp_ice=true)
  91. Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com)
  92. Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1)
  93. Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c)
  94. Dialplan: sofia/internal/robert@andy3d Action answer()
  95. Dialplan: sofia/internal/robert@andy3d Action conference(bridge:d5d0e945-edb7-43b2-b375-93d9d24a4d8c@video-mcu-stereo:{originate_timeout=15,media_webrtc=true,origination_caller_id_number=${caller_id_number},origination_caller_id_name=${caller_id_name},sip_h_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com,sip_h_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1,sip_h_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c}${sofia_contact(internal/${destination_number}@${domain_name})})
  96. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/robert@andy3d) State Change CS_ROUTING -> CS_EXECUTE
  97. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/robert@andy3d) State ROUTING going to sleep
  98. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_EXECUTE (Cur 1 Tot 22)
  99. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/robert@andy3d) State EXECUTE
  100. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:209 sofia/internal/robert@andy3d SOFIA EXECUTE
  101. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:329 sofia/internal/robert@andy3d Standard EXECUTE
  102. EXECUTE [depth=0] sofia/internal/robert@andy3d set(ignore_sdp_ice=true)
  103. 2022-05-19 09:22:03.455283 [DEBUG] mod_dptools.c:1672 SET sofia/internal/robert@andy3d [ignore_sdp_ice]=[true]
  104. EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com)
  105. 2022-05-19 09:22:03.455283 [DEBUG] mod_dptools.c:1672 SET sofia/internal/robert@andy3d [sip_rh_X-SyncServer-Ip]=[andy3d-simmt-worker1.simmt.com]
  106. EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1)
  107. 2022-05-19 09:22:03.455283 [DEBUG] mod_dptools.c:1672 SET sofia/internal/robert@andy3d [sip_rh_X-SyncServer-Port]=[88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1]
  108. EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c)
  109. 2022-05-19 09:22:03.455283 [DEBUG] mod_dptools.c:1672 SET sofia/internal/robert@andy3d [sip_rh_X-Conference-Id]=[d5d0e945-edb7-43b2-b375-93d9d24a4d8c]
  110. EXECUTE [depth=0] sofia/internal/robert@andy3d answer()
  111. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:866 Disabling proxy mode due to call answer with no bridge
  112. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  113. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  114. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  115. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  116. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  117. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  118. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  119. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  120. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  121. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  122. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:96:48000:20:0:1]/[opus:116:48000:20:0:1]
  123. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  124. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:96:48000:20:0:1]/[G722:9:8000:20:64000:1]
  125. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:96:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  126. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:96:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  127. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  128. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  129. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  130. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  131. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  132. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:97:16000:20:0:1]/[opus:116:48000:20:0:1]
  133. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
  134. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  135. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  136. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:98:16000:20:0:1]/[opus:116:48000:20:0:1]
  137. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
  138. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  139. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  140. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[opus:116:48000:20:0:1]
  141. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[G722:9:8000:20:64000:1]
  142. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[PCMU:0:8000:20:64000:1]
  143. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G723:4:8000:30:6300:1]/[PCMA:8:8000:20:64000:1]
  144. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:99:32000:20:0:1]/[opus:116:48000:20:0:1]
  145. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:99:32000:20:0:1]/[G722:9:8000:20:64000:1]
  146. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:99:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
  147. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:99:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
  148. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:100:32000:20:0:1]/[opus:116:48000:20:0:1]
  149. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:100:32000:20:0:1]/[G722:9:8000:20:64000:1]
  150. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:100:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
  151. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:100:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
  152. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:102:32000:20:0:1]/[opus:116:48000:20:0:1]
  153. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:102:32000:20:0:1]/[G722:9:8000:20:64000:1]
  154. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:102:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
  155. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G7221:102:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
  156. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  157. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  158. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  159. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  160. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  161. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  162. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  163. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  164. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR:103:8000:20:0:1]/[opus:116:48000:20:0:1]
  165. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR:103:8000:20:0:1]/[G722:9:8000:20:64000:1]
  166. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR:103:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  167. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR:103:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  168. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR-WB:104:16000:20:0:1]/[opus:116:48000:20:0:1]
  169. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR-WB:104:16000:20:0:1]/[G722:9:8000:20:64000:1]
  170. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR-WB:104:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  171. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [AMR-WB:104:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  172. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [iLBC:105:8000:30:0:1]/[opus:116:48000:20:0:1]
  173. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [iLBC:105:8000:30:0:1]/[G722:9:8000:20:64000:1]
  174. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [iLBC:105:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  175. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [iLBC:105:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  176. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:106:32000:20:0:1]/[opus:116:48000:20:0:1]
  177. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:106:32000:20:0:1]/[G722:9:8000:20:64000:1]
  178. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:106:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
  179. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:106:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
  180. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:107:16000:20:0:1]/[opus:116:48000:20:0:1]
  181. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:107:16000:20:0:1]/[G722:9:8000:20:64000:1]
  182. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:107:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  183. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:107:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  184. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:108:8000:20:0:1]/[opus:116:48000:20:0:1]
  185. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:108:8000:20:0:1]/[G722:9:8000:20:64000:1]
  186. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:108:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  187. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [speex:108:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  188. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:109:16000:20:0:1]/[opus:116:48000:20:0:1]
  189. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:109:16000:20:0:1]/[G722:9:8000:20:64000:1]
  190. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:109:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  191. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:109:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  192. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:110:8000:20:0:1]/[opus:116:48000:20:0:1]
  193. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
  194. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  195. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [SILK:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  196. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:111:8000:20:0:1]/[opus:116:48000:20:0:1]
  197. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:111:8000:20:0:1]/[G722:9:8000:20:64000:1]
  198. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:111:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  199. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:111:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  200. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:112:8000:20:0:2]/[opus:116:48000:20:0:1]
  201. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:112:8000:20:0:2]/[G722:9:8000:20:64000:1]
  202. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:112:8000:20:0:2]/[PCMU:0:8000:20:64000:1]
  203. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:112:8000:20:0:2]/[PCMA:8:8000:20:64000:1]
  204. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:113:16000:20:0:1]/[opus:116:48000:20:0:1]
  205. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:113:16000:20:0:1]/[G722:9:8000:20:64000:1]
  206. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:113:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  207. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:113:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  208. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:114:16000:20:0:2]/[opus:116:48000:20:0:1]
  209. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:114:16000:20:0:2]/[G722:9:8000:20:64000:1]
  210. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:114:16000:20:0:2]/[PCMU:0:8000:20:64000:1]
  211. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:114:16000:20:0:2]/[PCMA:8:8000:20:64000:1]
  212. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:11:44100:20:0:1]/[opus:116:48000:20:0:1]
  213. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:11:44100:20:0:1]/[G722:9:8000:20:64000:1]
  214. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:11:44100:20:0:1]/[PCMU:0:8000:20:64000:1]
  215. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:11:44100:20:0:1]/[PCMA:8:8000:20:64000:1]
  216. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:10:44100:20:0:2]/[opus:116:48000:20:0:1]
  217. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:10:44100:20:0:2]/[G722:9:8000:20:64000:1]
  218. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:10:44100:20:0:2]/[PCMU:0:8000:20:64000:1]
  219. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:10:44100:20:0:2]/[PCMA:8:8000:20:64000:1]
  220. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:115:48000:20:0:1]/[opus:116:48000:20:0:1]
  221. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:115:48000:20:0:1]/[G722:9:8000:20:64000:1]
  222. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:115:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  223. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:115:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  224. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:116:48000:20:0:2]/[opus:116:48000:20:0:1]
  225. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:116:48000:20:0:2]/[G722:9:8000:20:64000:1]
  226. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:116:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
  227. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [L16:116:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
  228. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  229. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/robert@andy3d PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  230. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_codec.c:111 sofia/internal/robert@andy3d Original read codec set to PCMA:8
  231. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  232. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5909 sofia/internal/robert@andy3d Set 2833 dtmf send payload to 101 recv payload to 101
  233. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/robert@andy3d] 172.19.0.10 port 50040 -> 172.19.0.22 port 4002 codec: 8 ms: 20
  234. 2022-05-19 09:22:03.455283 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms
  235. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8883 Activating RTCP PORT 4003
  236. 2022-05-19 09:22:03.455283 [DEBUG] switch_rtp.c:4811 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 4003
  237. 2022-05-19 09:22:03.455283 [DEBUG] switch_rtp.c:2632 Setting RTCP remote addr to 172.19.0.22:4003 2
  238. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8975 sofia/internal/robert@andy3d Set 2833 dtmf send payload to 101
  239. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8982 sofia/internal/robert@andy3d Set 2833 dtmf receive payload to 101
  240. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:9005 sofia/internal/robert@andy3d Set rtp dtmf delay to 40
  241. 2022-05-19 09:22:03.455283 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/robert@andy3d!
  242. 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:3565 (sofia/internal/robert@andy3d) Callstate Change RINGING -> EARLY
  243. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/robert@andy3d.
  244. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/robert@andy3d:
  245. v=0
  246. o=FreeSWITCH 1652902083 1652902084 IN IP4 172.19.0.10
  247. s=FreeSWITCH
  248. c=IN IP4 172.19.0.10
  249. t=0 0
  250. m=audio 50040 RTP/AVP 8 101
  251. a=rtpmap:8 PCMA/8000
  252. a=rtpmap:101 telephone-event/8000
  253. a=fmtp:101 0-16
  254. a=ptime:20
  255. a=sendrecv
  256. a=rtcp:50041 IN IP4 172.19.0.10
  257.  
  258. 2022-05-19 09:22:03.455283 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/robert@andy3d] has been answered
  259. 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:3865 (sofia/internal/robert@andy3d) Callstate Change EARLY -> ACTIVE
  260. 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [completed][200]
  261. EXECUTE [depth=0] sofia/internal/robert@andy3d conference(bridge:d5d0e945-edb7-43b2-b375-93d9d24a4d8c@video-mcu-stereo:{originate_timeout=15,media_webrtc=true,origination_caller_id_number=robert,origination_caller_id_name=robert,sip_h_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com,sip_h_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1,sip_h_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c}sofia/internal/sip:john@172.19.0.20:62666;ob)
  262. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:304 Adding layout group grid
  263. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:313 Adding node 1x1 to layout group grid
  264. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:313 Adding node 1x1 to layout group grid
  265. 2022-05-19 09:22:03.455283 [DEBUG] mod_conference.c:3414 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie
  266. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1695 Canvas position 1 applied layout 1x1
  267. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1816 Layout set to 1x1
  268. 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1776 Canvas attached to position 0
  269. 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:1906 (sofia/internal/robert@andy3d) Callstate Change ACTIVE -> RING_WAIT
  270. 2022-05-19 09:22:03.455283 [DEBUG] mod_conference.c:228 Setup timer success interval: 20  samples: 960
  271. 2022-05-19 09:22:03.455283 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  272. 2022-05-19 09:22:03.455283 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/john@172.19.0.20:62666 [faf4b7d6-212e-4299-bd71-90d95eb0f3ef]
  273. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:5089 (sofia/internal/john@172.19.0.20:62666) State Change CS_NEW -> CS_INIT
  274. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_INIT (Cur 2 Tot 23)
  275. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/john@172.19.0.20:62666) State INIT
  276. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:93 sofia/internal/john@172.19.0.20:62666 SOFIA INIT
  277. 2022-05-19 09:22:03.455283 [DEBUG] sofia_glue.c:1618 sofia/internal/john@172.19.0.20:62666 sending invite version: 1.10.33265  64bit
  278. Local SDP:
  279. v=0
  280. o=FreeSWITCH 1652902105 1652902106 IN IP4 172.19.0.10
  281. s=FreeSWITCH
  282. c=IN IP4 172.19.0.10
  283. t=0 0
  284. a=msid-semantic: WMS LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b
  285. m=audio 50018 RTP/SAVPF 8 101
  286. a=rtpmap:8 PCMA/8000
  287. a=rtpmap:101 telephone-event/8000
  288. a=fingerprint:sha-256 1A:80:0F:CA:31:3D:B8:00:BE:6F:9B:43:5A:75:FB:12:32:0F:54:79:14:FF:E8:F6:D4:62:2D:BB:8A:23:0C:C1
  289. a=setup:actpass
  290. a=rtcp-mux
  291. a=rtcp:50018 IN IP4 172.19.0.10
  292. a=ssrc:4137570435 cname:BdWrnSG6nMvqZSBa
  293. a=ssrc:4137570435 msid:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b a0
  294. a=ssrc:4137570435 mslabel:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b
  295. a=ssrc:4137570435 label:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7ba0
  296. a=ice-ufrag:X7WgV9WIbtoCFlD6
  297. a=ice-pwd:pqoD9UznxVDmD5nr2axO9jnb
  298. a=candidate:3287425940 1 udp 659136 172.19.0.10 50018 typ host generation 0
  299. a=candidate:3287425940 2 udp 659136 172.19.0.10 50018 typ host generation 0
  300. a=ptime:20
  301. a=sendrecv
  302.  
  303. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:40 sofia/internal/john@172.19.0.20:62666 Standard INIT
  304. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/john@172.19.0.20:62666) State Change CS_INIT -> CS_ROUTING
  305. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/john@172.19.0.20:62666) State INIT going to sleep
  306. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_ROUTING (Cur 2 Tot 23)
  307. 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/john@172.19.0.20:62666 entering state [calling][0]
  308. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/john@172.19.0.20:62666) State ROUTING
  309. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:154 sofia/internal/john@172.19.0.20:62666 SOFIA ROUTING
  310. 2022-05-19 09:22:03.455283 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/john@172.19.0.20:62666) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  311. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/john@172.19.0.20:62666) State ROUTING going to sleep
  312. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 23)
  313. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/john@172.19.0.20:62666) State CONSUME_MEDIA
  314. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/john@172.19.0.20:62666) State CONSUME_MEDIA going to sleep
  315. 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/john@172.19.0.20:62666 entering state [terminated][488]
  316. 2022-05-19 09:22:03.455283 [NOTICE] sofia.c:8558 Hangup sofia/internal/john@172.19.0.20:62666 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
  317. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_HANGUP (Cur 2 Tot 23)
  318. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/john@172.19.0.20:62666) Callstate Change DOWN -> HANGUP
  319. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/john@172.19.0.20:62666) State HANGUP
  320. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:453 Channel sofia/internal/john@172.19.0.20:62666 hanging up, cause: INCOMPATIBLE_DESTINATION
  321. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:60 sofia/internal/john@172.19.0.20:62666 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  322. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/john@172.19.0.20:62666) State HANGUP going to sleep
  323. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/john@172.19.0.20:62666) State Change CS_HANGUP -> CS_REPORTING
  324. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_REPORTING (Cur 2 Tot 23)
  325. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/john@172.19.0.20:62666) State REPORTING
  326. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:174 sofia/internal/john@172.19.0.20:62666 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  327. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/john@172.19.0.20:62666) State REPORTING going to sleep
  328. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/john@172.19.0.20:62666) State Change CS_REPORTING -> CS_DESTROY
  329. 2022-05-19 09:22:03.455283 [DEBUG] switch_core_session.c:1726 Session 23 (sofia/internal/john@172.19.0.20:62666) Locked, Waiting on external entities
  330. 2022-05-19 09:22:03.475283 [DEBUG] switch_rtp.c:7120 Correct audio RTCP ip/port confirmed.
  331. 2022-05-19 09:22:03.475283 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [ready][200]
  332. 2022-05-19 09:22:03.475283 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
  333. 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1744 Session 23 (sofia/internal/john@172.19.0.20:62666) Ended
  334. 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/john@172.19.0.20:62666 [CS_DESTROY]
  335. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/john@172.19.0.20:62666) Running State Change CS_DESTROY (Cur 1 Tot 23)
  336. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/john@172.19.0.20:62666) State DESTROY
  337. 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:364 sofia/internal/john@172.19.0.20:62666 SOFIA DESTROY
  338. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:181 sofia/internal/john@172.19.0.20:62666 Standard DESTROY
  339. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/john@172.19.0.20:62666) State DESTROY going to sleep
  340. 2022-05-19 09:22:03.475283 [DEBUG] switch_channel.c:2130 (sofia/internal/robert@andy3d) Callstate Change RING_WAIT -> ACTIVE
  341. 2022-05-19 09:22:03.475283 [ERR] mod_conference.c:1590 Cannot create outgoing channel, cause: INCOMPATIBLE_DESTINATION
  342. 2022-05-19 09:22:03.475283 [NOTICE] mod_conference.c:1593 Hangup sofia/internal/robert@andy3d [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
  343. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_session.c:2905 sofia/internal/robert@andy3d skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  344. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/robert@andy3d) State EXECUTE going to sleep
  345. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_HANGUP (Cur 1 Tot 23)
  346. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/robert@andy3d) Callstate Change ACTIVE -> HANGUP
  347. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/robert@andy3d) State HANGUP
  348. 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:447 sofia/internal/robert@andy3d Overriding SIP cause 488 with 488 from the other leg
  349. 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:453 Channel sofia/internal/robert@andy3d hanging up, cause: INCOMPATIBLE_DESTINATION
  350. 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/robert@andy3d
  351. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:60 sofia/internal/robert@andy3d Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  352. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/robert@andy3d) State HANGUP going to sleep
  353. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/robert@andy3d) State Change CS_HANGUP -> CS_REPORTING
  354. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/robert@andy3d) Running State Change CS_REPORTING (Cur 1 Tot 23)
  355. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/robert@andy3d) State REPORTING
  356. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:174 sofia/internal/robert@andy3d Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  357. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/robert@andy3d) State REPORTING going to sleep
  358. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/robert@andy3d) State Change CS_REPORTING -> CS_DESTROY
  359. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_session.c:1726 Session 22 (sofia/internal/robert@andy3d) Locked, Waiting on external entities
  360. 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1744 Session 22 (sofia/internal/robert@andy3d) Ended
  361. 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/robert@andy3d [CS_DESTROY]
  362. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/robert@andy3d) Running State Change CS_DESTROY (Cur 0 Tot 23)
  363. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/robert@andy3d) State DESTROY
  364. 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:364 sofia/internal/robert@andy3d SOFIA DESTROY
  365. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:181 sofia/internal/robert@andy3d Standard DESTROY
  366. 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/robert@andy3d) State DESTROY going to sleep
  367. 2022-05-19 09:22:03.495284 [DEBUG] mod_conference.c:835 Write Lock ON
  368. 2022-05-19 09:22:03.495284 [DEBUG] mod_conference.c:838 Write Lock OFF
  369.