2022-05-19 09:22:03.335284 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/robert@andy3d [c89819ce-a49a-4acc-a52c-3acf3cd9f78b] 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) 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 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.19.0.22:0. 2022-05-19 09:22:03.335284 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/robert@andy3d) State NEW 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:2434 detaching session c89819ce-a49a-4acc-a52c-3acf3cd9f78b 2022-05-19 09:22:03.335284 [DEBUG] sofia.c:2544 Re-attaching to session c89819ce-a49a-4acc-a52c-3acf3cd9f78b 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 2022-05-19 09:22:03.355284 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.19.0.22:0. 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [received][100] 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7335 Remote SDP: v=0 o=- 3861940804 3861940804 IN IP4 172.19.0.22 s=pjmedia b=AS:1629 t=0 0 a=X-nat:0 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 c=IN IP4 172.19.0.22 b=TIAS:1536000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:96 opus/48000/2 a=fmtp:96 maxplaybackrate=24000;sprop-maxcapturerate=24000;maxaveragebitrate=64000;useinbandfec=1 a=rtpmap:9 G722/8000 a=rtpmap:97 G7221/16000 a=fmtp:97 bitrate=24000 a=rtpmap:98 G7221/16000 a=fmtp:98 bitrate=32000 a=rtpmap:4 G723/8000 a=rtpmap:99 G7221/32000 a=fmtp:99 bitrate=48000 a=rtpmap:100 G7221/32000 a=fmtp:100 bitrate=32000 a=rtpmap:102 G7221/32000 a=fmtp:102 bitrate=24000 a=rtpmap:18 G729/8000 a=rtpmap:3 GSM/8000 a=rtpmap:103 AMR/8000 a=fmtp:103 octet-align=1 a=rtpmap:104 AMR-WB/16000 a=fmtp:104 octet-align=1 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=30 a=rtpmap:106 speex/32000 a=rtpmap:107 speex/16000 a=rtpmap:108 speex/8000 a=rtpmap:109 SILK/16000 a=fmtp:109 useinbandfec=0 a=rtpmap:110 SILK/8000 a=fmtp:110 useinbandfec=0 a=rtpmap:111 L16/8000 a=rtpmap:112 L16/8000/2 a=rtpmap:113 L16/16000 a=rtpmap:114 L16/16000/2 a=rtpmap:11 L16/44100 a=rtpmap:10 L16/44100/2 a=rtpmap:115 L16/48000 a=rtpmap:116 L16/48000/2 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:117 telephone-event/48000 a=fmtp:117 0-16 a=rtpmap:118 telephone-event/16000 a=fmtp:118 0-16 a=rtpmap:119 telephone-event/32000 a=fmtp:119 0-16 a=rtpmap:120 telephone-event/44100 a=fmtp:120 0-16 a=rtcp:4003 IN IP4 172.19.0.22 a=ssrc:1651052188 cname:4e852a1101a15fca 2022-05-19 09:22:03.395286 [DEBUG] sofia.c:7724 (sofia/internal/robert@andy3d) State Change CS_NEW -> CS_INIT 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) 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/robert@andy3d) State INIT 2022-05-19 09:22:03.395286 [DEBUG] mod_sofia.c:93 sofia/internal/robert@andy3d SOFIA INIT 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:40 sofia/internal/robert@andy3d Standard INIT 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/robert@andy3d) State Change CS_INIT -> CS_ROUTING 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/robert@andy3d) State INIT going to sleep 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) 2022-05-19 09:22:03.395286 [DEBUG] switch_channel.c:2332 (sofia/internal/robert@andy3d) Callstate Change DOWN -> RINGING 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/robert@andy3d) State ROUTING 2022-05-19 09:22:03.395286 [DEBUG] mod_sofia.c:154 sofia/internal/robert@andy3d SOFIA ROUTING 2022-05-19 09:22:03.395286 [DEBUG] switch_core_state_machine.c:236 sofia/internal/robert@andy3d Standard ROUTING 2022-05-19 09:22:03.395286 [INFO] mod_dialplan_xml.c:637 Processing robert ->john in context public Dialplan: sofia/internal/robert@andy3d parsing [public->audio_specific_conferences] continue=false Dialplan: sofia/internal/robert@andy3d Regex (FAIL) [audio_specific_conferences] destination_number(john) =~ /^9999(.*)$/ break=on-false Dialplan: sofia/internal/robert@andy3d parsing [public->andy-conference-bridge] continue=false Dialplan: sofia/internal/robert@andy3d Regex (PASS) [andy-conference-bridge] destination_number(john) =~ /^(.*)$/ break=on-false Dialplan: sofia/internal/robert@andy3d Action set(ignore_sdp_ice=true) Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com) Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1) Dialplan: sofia/internal/robert@andy3d Action set(sip_rh_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c) Dialplan: sofia/internal/robert@andy3d Action answer() 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})}) 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/robert@andy3d) State Change CS_ROUTING -> CS_EXECUTE 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/robert@andy3d) State ROUTING going to sleep 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) 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/robert@andy3d) State EXECUTE 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:209 sofia/internal/robert@andy3d SOFIA EXECUTE 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:329 sofia/internal/robert@andy3d Standard EXECUTE EXECUTE [depth=0] sofia/internal/robert@andy3d set(ignore_sdp_ice=true) 2022-05-19 09:22:03.455283 [DEBUG] mod_dptools.c:1672 SET sofia/internal/robert@andy3d [ignore_sdp_ice]=[true] EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-SyncServer-Ip=andy3d-simmt-worker1.simmt.com) 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] EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-SyncServer-Port=88f144ce-09ba-43f1-b032-15255f5aedf6.nakama1) 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] EXECUTE [depth=0] sofia/internal/robert@andy3d set(sip_rh_X-Conference-Id=d5d0e945-edb7-43b2-b375-93d9d24a4d8c) 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] EXECUTE [depth=0] sofia/internal/robert@andy3d answer() 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:866 Disabling proxy mode due to call answer with no bridge 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] 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] 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] 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] 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 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] 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] 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] 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 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] 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] 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 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] 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] 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] 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] 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] 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 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 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] 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000 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 2022-05-19 09:22:03.455283 [DEBUG] switch_core_codec.c:111 sofia/internal/robert@andy3d Original read codec set to PCMA:8 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000 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 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 2022-05-19 09:22:03.455283 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8883 Activating RTCP PORT 4003 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 2022-05-19 09:22:03.455283 [DEBUG] switch_rtp.c:2632 Setting RTCP remote addr to 172.19.0.22:4003 2 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8975 sofia/internal/robert@andy3d Set 2833 dtmf send payload to 101 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8982 sofia/internal/robert@andy3d Set 2833 dtmf receive payload to 101 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:9005 sofia/internal/robert@andy3d Set rtp dtmf delay to 40 2022-05-19 09:22:03.455283 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/robert@andy3d! 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:3565 (sofia/internal/robert@andy3d) Callstate Change RINGING -> EARLY 2022-05-19 09:22:03.455283 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/robert@andy3d. 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/robert@andy3d: v=0 o=FreeSWITCH 1652902083 1652902084 IN IP4 172.19.0.10 s=FreeSWITCH c=IN IP4 172.19.0.10 t=0 0 m=audio 50040 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv a=rtcp:50041 IN IP4 172.19.0.10 2022-05-19 09:22:03.455283 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/robert@andy3d] has been answered 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:3865 (sofia/internal/robert@andy3d) Callstate Change EARLY -> ACTIVE 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [completed][200] 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) 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:304 Adding layout group grid 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:313 Adding node 1x1 to layout group grid 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:313 Adding node 1x1 to layout group grid 2022-05-19 09:22:03.455283 [DEBUG] mod_conference.c:3414 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1695 Canvas position 1 applied layout 1x1 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1816 Layout set to 1x1 2022-05-19 09:22:03.455283 [DEBUG] conference_video.c:1776 Canvas attached to position 0 2022-05-19 09:22:03.455283 [DEBUG] switch_channel.c:1906 (sofia/internal/robert@andy3d) Callstate Change ACTIVE -> RING_WAIT 2022-05-19 09:22:03.455283 [DEBUG] mod_conference.c:228 Setup timer success interval: 20 samples: 960 2022-05-19 09:22:03.455283 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables 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] 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 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) 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/john@172.19.0.20:62666) State INIT 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:93 sofia/internal/john@172.19.0.20:62666 SOFIA INIT 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 Local SDP: v=0 o=FreeSWITCH 1652902105 1652902106 IN IP4 172.19.0.10 s=FreeSWITCH c=IN IP4 172.19.0.10 t=0 0 a=msid-semantic: WMS LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b m=audio 50018 RTP/SAVPF 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 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 a=setup:actpass a=rtcp-mux a=rtcp:50018 IN IP4 172.19.0.10 a=ssrc:4137570435 cname:BdWrnSG6nMvqZSBa a=ssrc:4137570435 msid:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b a0 a=ssrc:4137570435 mslabel:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7b a=ssrc:4137570435 label:LpfV0k5oSFgycmxkleFVsYmQXcHPAl7ba0 a=ice-ufrag:X7WgV9WIbtoCFlD6 a=ice-pwd:pqoD9UznxVDmD5nr2axO9jnb a=candidate:3287425940 1 udp 659136 172.19.0.10 50018 typ host generation 0 a=candidate:3287425940 2 udp 659136 172.19.0.10 50018 typ host generation 0 a=ptime:20 a=sendrecv 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:40 sofia/internal/john@172.19.0.20:62666 Standard INIT 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 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 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) 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/john@172.19.0.20:62666 entering state [calling][0] 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/john@172.19.0.20:62666) State ROUTING 2022-05-19 09:22:03.455283 [DEBUG] mod_sofia.c:154 sofia/internal/john@172.19.0.20:62666 SOFIA ROUTING 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 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 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) 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 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 2022-05-19 09:22:03.455283 [DEBUG] sofia.c:7325 Channel sofia/internal/john@172.19.0.20:62666 entering state [terminated][488] 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] 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) 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 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/john@172.19.0.20:62666) State HANGUP 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 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 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 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 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) 2022-05-19 09:22:03.455283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/john@172.19.0.20:62666) State REPORTING 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 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 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 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 2022-05-19 09:22:03.475283 [DEBUG] switch_rtp.c:7120 Correct audio RTCP ip/port confirmed. 2022-05-19 09:22:03.475283 [DEBUG] sofia.c:7325 Channel sofia/internal/robert@andy3d entering state [ready][200] 2022-05-19 09:22:03.475283 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1744 Session 23 (sofia/internal/john@172.19.0.20:62666) Ended 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] 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) 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/john@172.19.0.20:62666) State DESTROY 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:364 sofia/internal/john@172.19.0.20:62666 SOFIA DESTROY 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:181 sofia/internal/john@172.19.0.20:62666 Standard DESTROY 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 2022-05-19 09:22:03.475283 [DEBUG] switch_channel.c:2130 (sofia/internal/robert@andy3d) Callstate Change RING_WAIT -> ACTIVE 2022-05-19 09:22:03.475283 [ERR] mod_conference.c:1590 Cannot create outgoing channel, cause: INCOMPATIBLE_DESTINATION 2022-05-19 09:22:03.475283 [NOTICE] mod_conference.c:1593 Hangup sofia/internal/robert@andy3d [CS_EXECUTE] [INCOMPATIBLE_DESTINATION] 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) 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/robert@andy3d) State EXECUTE going to sleep 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) 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/robert@andy3d) Callstate Change ACTIVE -> HANGUP 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/robert@andy3d) State HANGUP 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 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:453 Channel sofia/internal/robert@andy3d hanging up, cause: INCOMPATIBLE_DESTINATION 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/robert@andy3d 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:60 sofia/internal/robert@andy3d Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/robert@andy3d) State HANGUP going to sleep 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/robert@andy3d) State Change CS_HANGUP -> CS_REPORTING 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) 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/robert@andy3d) State REPORTING 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:174 sofia/internal/robert@andy3d Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/robert@andy3d) State REPORTING going to sleep 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/robert@andy3d) State Change CS_REPORTING -> CS_DESTROY 2022-05-19 09:22:03.475283 [DEBUG] switch_core_session.c:1726 Session 22 (sofia/internal/robert@andy3d) Locked, Waiting on external entities 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1744 Session 22 (sofia/internal/robert@andy3d) Ended 2022-05-19 09:22:03.475283 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/robert@andy3d [CS_DESTROY] 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) 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/robert@andy3d) State DESTROY 2022-05-19 09:22:03.475283 [DEBUG] mod_sofia.c:364 sofia/internal/robert@andy3d SOFIA DESTROY 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:181 sofia/internal/robert@andy3d Standard DESTROY 2022-05-19 09:22:03.475283 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/robert@andy3d) State DESTROY going to sleep 2022-05-19 09:22:03.495284 [DEBUG] mod_conference.c:835 Write Lock ON 2022-05-19 09:22:03.495284 [DEBUG] mod_conference.c:838 Write Lock OFF