ff4e24f6 2018-07-20 12:55:03.212221 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/9000@10.10.0.190:5060 [ff4e24f6-3887-45b8-8fea-df129c4d554a] ff4e24f6 2018-07-20 12:55:03.212221 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_NEW (Cur 1 Tot 1) ff4e24f6 2018-07-20 12:55:03.212221 [DEBUG] sofia.c:9873 sofia/internal/9000@10.10.0.190:5060 receiving invite from 10.10.0.30:5060 version: 1.6.20 -37-987c9b9 64bit 2018-07-20 12:55:03.212221 [DEBUG] sofia.c:10044 IP 10.10.0.30 Rejected by acl "domains". Falling back to Digest auth. ff4e24f6 2018-07-20 12:55:03.212221 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/9000@10.10.0.190:5060) State NEW 2018-07-20 12:55:03.212221 [DEBUG] sofia.c:2334 detaching session ff4e24f6-3887-45b8-8fea-df129c4d554a 2018-07-20 12:55:03.212221 [DEBUG] sofia.c:2442 Re-attaching to session ff4e24f6-3887-45b8-8fea-df129c4d554a ff4e24f6 2018-07-20 12:55:03.232217 [DEBUG] sofia.c:9873 sofia/internal/9000@10.10.0.190:5060 receiving invite from 10.10.0.30:5060 version: 1.6.20 -37-987c9b9 64bit 2018-07-20 12:55:03.232217 [DEBUG] sofia.c:10044 IP 10.10.0.30 Rejected by acl "domains". Falling back to Digest auth. 2018-07-20 12:55:03.532224 [DEBUG] switch_xml.c:2068 caching lookup for user 9000@10.10.0.190 for 30000 milliseconds ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [received][100] ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 20088 20088 IN IP4 10.10.0.30 s=SDP data c=IN IP4 10.10.0.30 t=0 0 m=audio 12442 RTP/AVP 9 0 8 18 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] sofia.c:7486 (sofia/internal/9000@10.10.0.190:5060) State Change CS_NEW -> CS_INIT ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_INIT (Cur 1 Tot 1) ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9000@10.10.0.190:5060) State INIT ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] mod_sofia.c:90 sofia/internal/9000@10.10.0.190:5060 SOFIA INIT ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:40 sofia/internal/9000@10.10.0.190:5060 Standard INIT ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/9000@10.10.0.190:5060) State Change CS_INIT -> CS_ROUTING ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9000@10.10.0.190:5060) State INIT going to sleep ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_ROUTING (Cur 1 Tot 1) ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_channel.c:2249 (sofia/internal/9000@10.10.0.190:5060) Callstate Change DOWN -> RINGING ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/9000@10.10.0.190:5060) State ROUTING ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] mod_sofia.c:143 sofia/internal/9000@10.10.0.190:5060 SOFIA ROUTING ff4e24f6 2018-07-20 12:55:03.532224 [DEBUG] switch_core_state_machine.c:236 sofia/internal/9000@10.10.0.190:5060 Standard ROUTING ff4e24f6 2018-07-20 12:55:03.532224 [INFO] mod_dialplan_xml.c:637 Processing 9000 <9000>->6060 in context default ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Bell_Ring] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Bell_Ring] destination_number(6060) =~ /^9010$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Earthquake] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Earthquake] destination_number(6060) =~ /^9011$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Fire] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Fire] destination_number(6060) =~ /^9012$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lightning] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lightning] destination_number(6060) =~ /^9014$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-National_Anthem] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-National_Anthem] destination_number(6060) =~ /^9015$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Tornado] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Tornado] destination_number(6060) =~ /^9016$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lockout] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lockout] destination_number(6060) =~ /^9017$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lockdown] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lockdown] destination_number(6060) =~ /^9018$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Custom_Announcement] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Custom_Announcement] destination_number(6060) =~ /^9999$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->unloop] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->global-intercept] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [global-intercept] destination_number(6060) =~ /^886$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->school_sipcall_intercom] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [school_sipcall_intercom] destination_number(6060) =~ /^(9000)$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->school_sipcall_emergency] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [school_sipcall_emergency] destination_number(6060) =~ /^(9050)$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->group-intercept] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group-intercept] destination_number(6060) =~ /^\*8$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->intercept-ext] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [intercept-ext] destination_number(6060) =~ /^\*\*(\d+)$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->redial] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [redial] destination_number(6060) =~ /^(redial|870)$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->global] continue=true ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Absolute Condition [global] ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-last_dial/global/${uuid}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->eavesdrop] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [eavesdrop] destination_number(6060) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->eavesdrop] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [eavesdrop] destination_number(6060) =~ /^779$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->call_return] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [call_return] destination_number(6060) =~ /^\*69$|^869$|^lcr$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->extension-intercom] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [extension-intercom] destination_number(6060) =~ /^8([1-3]\d{3})$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->main_ivr] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [main_ivr] destination_number(6060) =~ /^6000$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->group_dial_paging_zones] continue=false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (PASS) [group_dial_paging_zones] destination_number(6060) =~ /^6060$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(group_id_name=All Call) INLINE ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(group_id_name=All Call) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [group_id_name]=[All Call] ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6061$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6062$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6001$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6002$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6003$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6004$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6000$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6005$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6006$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6200$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(6060) =~ /^6012$/ break=never ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (PASS) [group_dial_paging_zones] destination_number(6060) =~ /^6(\d{3})$/ break=on-false ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_caller_id_name=${group_id_name}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_caller_id_number=6060) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_timeout=60) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_flags=mute) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_announce=tone_stream://%(250,0,800)) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(conference_auto_outcall_prefix={sip_auto_answer=true}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action set(sip_exclude_contact=${network_addr}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action conference_set_auto_outcall(${group_call(060)}) ff4e24f6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action conference(madboss_intercom1@default+flags{endconf|deaf}) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/9000@10.10.0.190:5060) State Change CS_ROUTING -> CS_EXECUTE ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/9000@10.10.0.190:5060) State ROUTING going to sleep ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_EXECUTE (Cur 1 Tot 1) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/9000@10.10.0.190:5060) State EXECUTE ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] mod_sofia.c:198 sofia/internal/9000@10.10.0.190:5060 SOFIA EXECUTE ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_core_state_machine.c:328 sofia/internal/9000@10.10.0.190:5060 Standard EXECUTE ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-spymap/9000/ff4e24f6-3887-45b8-8fea-df129c4d554a) ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-last_dial/9000/6060) ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-last_dial/global/ff4e24f6-3887-45b8-8fea-df129c4d554a) ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 export(RFC2822_DATE=Fri, 20 Jul 2018 12:55:04 -0700) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 20 Jul 2018 12:55:04 -0700] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_caller_id_name=All Call) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_caller_id_name]=[All Call] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_caller_id_number=6060) ff4e24f6 2018-07-20 12:55:04.572234 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_caller_id_number]=[6060] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_timeout=60) ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_timeout]=[60] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_flags=mute) ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_flags]=[mute] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_announce=tone_stream://%(250,0,800)) ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_announce]=[tone_stream://%(250,0,800)] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(conference_auto_outcall_prefix={sip_auto_answer=true}) ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [conference_auto_outcall_prefix]=[{sip_auto_answer=true}] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 set(sip_exclude_contact=10.10.0.30) ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_dptools.c:1548 SET sofia/internal/9000@10.10.0.190:5060 [sip_exclude_contact]=[10.10.0.30] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_session.c:2647 Application conference_set_auto_outcall Requires media! pre_answering channel sofia/internal/9000@10.10.0.190:5060 ff4e24f6 2018-07-20 12:55:04.592218 [INFO] switch_core_session.c:2649 Sending early media ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/9000@10.10.0.190:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_codec.c:111 sofia/internal/9000@10.10.0.190:5060 Original read codec set to PCMU:0 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:4767 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf send payload to 101 recv payload to 101 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/9000@10.10.0.190:5060] 10.10.0.190 port 30644 -> 10.10.0.30 port 12442 codec: 0 ms: 20 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:7180 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf send payload to 101 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:7187 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf receive payload to 101 ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:7210 sofia/internal/9000@10.10.0.190:5060 Set rtp dtmf delay to 40 ff4e24f6 2018-07-20 12:55:04.592218 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/9000@10.10.0.190:5060! ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_channel.c:3474 (sofia/internal/9000@10.10.0.190:5060) Callstate Change RINGING -> EARLY ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/9000@10.10.0.190:5060. ff4e24f6 2018-07-20 12:55:04.592218 [DEBUG] mod_sofia.c:2364 Ring SDP: v=0 o=FreeSWITCH 1532085860 1532085861 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 30644 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 conference_set_auto_outcall([^^:sip_invite_domain=10.10.0.190:presence_id=1000@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1101@10.10.0.190]sofia/internal/sip:1101@10.10.0.33:5060,[^^:sip_invite_domain=10.10.0.190:presence_id=1102@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1103@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1104@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1105@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1106@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1107@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1108@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1109@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1110@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1111@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1112@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1113@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1114@10.10.0.190]sofia/internal/sip:1114@10.10.0.36:5060,[^^:sip_invite_domain=10.10.0.190:presence_id=1115@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1116@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1117@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1118@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1119@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1120@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1121@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1122@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1123@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1124@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1125@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1126@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1127@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1128@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1129@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1130@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=1131@10.10.0.190]sofia/internal/sip:1131@10.10.0.35:5060,[^^:sip_invite_domain=10.10.0.190:presence_id=2201@10.10.0.190]sofia/internal/sip:2201@10.10.0.34:5060,[^^:sip_invite_domain=10.10.0.190:presence_id=2202@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2203@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2204@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2205@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2206@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2207@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2208@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=2209@10.10.0.190]error/user_not_registered,[^^:sip_invite_domain=10.10.0.190:presence_id=3001@10.10.0.190]sofia/internal/sip:3001@10.10.0.201,[^^:sip_invite_domain=10.10.0.190:presence_id=5001@10.10.0.190]sofia/internal/sip:5001@10.10.0.37:5060) ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [early][183] ff4e24f6 EXECUTE sofia/internal/9000@10.10.0.190:5060 conference(madboss_intercom1@default+flags{endconf|deaf}) ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/9000@10.10.0.190:5060. ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/9000@10.10.0.190:5060: v=0 o=FreeSWITCH 1532085860 1532085862 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 30644 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv ff4e24f6 2018-07-20 12:55:17.232227 [NOTICE] mod_conference.c:1673 Channel [sofia/internal/9000@10.10.0.190:5060] has been answered ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] switch_channel.c:3773 (sofia/internal/9000@10.10.0.190:5060) Callstate Change EARLY -> ACTIVE ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [completed][200] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:3084 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_codec.c:223 sofia/internal/9000@10.10.0.190:5060 Push codec L16:100 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:220 Setup timer success interval: 20 samples: 160 2018-07-20 12:55:17.232227 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '0' to 'mute' 2018-07-20 12:55:17.232227 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046a20 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '*' to 'deaf mute' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046a50 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046a80 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046ab0 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046ae0 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046b10 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046b40 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046b70 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046ba0 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046bd0 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046c00 2018-07-20 12:55:17.232227 [DEBUG] conference_member.c:128 sofia/internal/9000@10.10.0.190:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbea0046c30 ff4e24f6 2018-07-20 12:55:17.232227 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 24ecc9c6 2018-07-20 12:55:17.232227 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1101@10.10.0.33:5060 [24ecc9c6-1dc9-4477-9e77-2b540b43b82b] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:4819 (sofia/internal/1101@10.10.0.33:5060) State Change CS_NEW -> CS_INIT 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables e5e6183d 2018-07-20 12:55:17.232227 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1114@10.10.0.36:5060 [e5e6183d-d7a9-43f7-8df0-a1ef55e97fe5] 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:4819 (sofia/internal/1114@10.10.0.36:5060) State Change CS_NEW -> CS_INIT 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_INIT (Cur 3 Tot 3) 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1101@10.10.0.33:5060) State INIT 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:90 sofia/internal/1101@10.10.0.33:5060 SOFIA INIT 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_INIT (Cur 3 Tot 3) 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] sofia_glue.c:1295 sofia/internal/1101@10.10.0.33:5060 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532095023 1532095024 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 21494 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1114@10.10.0.36:5060) State INIT e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:90 sofia/internal/1114@10.10.0.36:5060 SOFIA INIT 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1101@10.10.0.33:5060 Standard INIT 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1101@10.10.0.33:5060) State Change CS_INIT -> CS_ROUTING 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1101@10.10.0.33:5060) State INIT going to sleep 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_ROUTING (Cur 3 Tot 3) 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1101@10.10.0.33:5060) State ROUTING 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:143 sofia/internal/1101@10.10.0.33:5060 SOFIA ROUTING 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1101@10.10.0.33:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1101@10.10.0.33:5060) State ROUTING going to sleep 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 3) 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 0cf8ec7a 2018-07-20 12:55:17.232227 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1131@10.10.0.35:5060 [0cf8ec7a-fece-4ebe-976e-8184b9768df9] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] sofia_glue.c:1295 sofia/internal/1114@10.10.0.36:5060 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532089039 1532089040 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 27478 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1114@10.10.0.36:5060 Standard INIT e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1114@10.10.0.36:5060) State Change CS_INIT -> CS_ROUTING e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1114@10.10.0.36:5060) State INIT going to sleep 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_ROUTING (Cur 4 Tot 4) 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] sofia.c:7084 Channel sofia/internal/1101@10.10.0.33:5060 entering state [calling][0] 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1101@10.10.0.33:5060) State CONSUME_MEDIA 0cf8ec7a 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:4819 (sofia/internal/1131@10.10.0.35:5060) State Change CS_NEW -> CS_INIT 24ecc9c6 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1101@10.10.0.33:5060) State CONSUME_MEDIA going to sleep e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] sofia.c:7084 Channel sofia/internal/1114@10.10.0.36:5060 entering state [calling][0] e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1114@10.10.0.36:5060) State ROUTING e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:143 sofia/internal/1114@10.10.0.36:5060 SOFIA ROUTING e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1114@10.10.0.36:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1114@10.10.0.36:5060) State ROUTING going to sleep 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 4) 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1114@10.10.0.36:5060) State CONSUME_MEDIA e5e6183d 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1114@10.10.0.36:5060) State CONSUME_MEDIA going to sleep 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 0cf8ec7a 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_INIT (Cur 4 Tot 4) 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 0cf8ec7a 2018-07-20 12:55:17.232227 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1131@10.10.0.35:5060) State INIT 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 0cf8ec7a 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:90 sofia/internal/1131@10.10.0.35:5060 SOFIA INIT 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] f5d2f201 2018-07-20 12:55:17.232227 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/3001@10.10.0.201 [f5d2f201-bc99-4f77-97f7-47ab7be0a282] f5d2f201 2018-07-20 12:55:17.232227 [DEBUG] mod_sofia.c:4819 (sofia/internal/3001@10.10.0.201) State Change CS_NEW -> CS_INIT 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 2018-07-20 12:55:17.232227 [DEBUG] mod_conference.c:1603 Launching BG Thread for outcall 0cf8ec7a 2018-07-20 12:55:17.232227 [DEBUG] sofia_glue.c:1295 sofia/internal/1131@10.10.0.35:5060 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532091247 1532091248 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 25270 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2018-07-20 12:55:17.252223 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-07-20 12:55:17.252223 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.232227 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1131@10.10.0.35:5060 Standard INIT 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1131@10.10.0.35:5060) State Change CS_INIT -> CS_ROUTING 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1131@10.10.0.35:5060) State INIT going to sleep f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_INIT (Cur 6 Tot 6) f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3001@10.10.0.201) State INIT 5f3e9751 2018-07-20 12:55:17.252223 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/5001@10.10.0.37:5060 [5f3e9751-fb7d-48c1-812c-ffae5fee1559] f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:90 sofia/internal/3001@10.10.0.201 SOFIA INIT 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:4819 (sofia/internal/5001@10.10.0.37:5060) State Change CS_NEW -> CS_INIT 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_ROUTING (Cur 6 Tot 6) 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] sofia.c:7084 Channel sofia/internal/1131@10.10.0.35:5060 entering state [calling][0] 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1131@10.10.0.35:5060) State ROUTING 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:143 sofia/internal/1131@10.10.0.35:5060 SOFIA ROUTING 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1131@10.10.0.35:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1131@10.10.0.35:5060) State ROUTING going to sleep 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 6) 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1131@10.10.0.35:5060) State CONSUME_MEDIA 0cf8ec7a 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1131@10.10.0.35:5060) State CONSUME_MEDIA going to sleep 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2018-07-20 12:55:17.252223 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED e10e6bbc 2018-07-20 12:55:17.252223 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/2201@10.10.0.34:5060 [e10e6bbc-b423-4b6f-af69-bb188b7277f1] 2018-07-20 12:55:17.232227 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:4819 (sofia/internal/2201@10.10.0.34:5060) State Change CS_NEW -> CS_INIT 2018-07-20 12:55:17.252223 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 2018-07-20 12:55:17.252223 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2018-07-20 12:55:17.252223 [ERR] mod_conference.c:1418 Cannot create outgoing channel, cause: USER_NOT_REGISTERED 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_INIT (Cur 7 Tot 7) 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/5001@10.10.0.37:5060) State INIT e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_INIT (Cur 7 Tot 7) 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:90 sofia/internal/5001@10.10.0.37:5060 SOFIA INIT e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2201@10.10.0.34:5060) State INIT e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:90 sofia/internal/2201@10.10.0.34:5060 SOFIA INIT 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] sofia_glue.c:1295 sofia/internal/5001@10.10.0.37:5060 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532089095 1532089096 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 27422 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] sofia_glue.c:1295 sofia/internal/2201@10.10.0.34:5060 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532093607 1532093608 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 22910 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2201@10.10.0.34:5060 Standard INIT e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2201@10.10.0.34:5060) State Change CS_INIT -> CS_ROUTING f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] sofia_glue.c:1295 sofia/internal/3001@10.10.0.201 sending invite version: 1.6.20 -37-987c9b9 64bit Local SDP: v=0 o=FreeSWITCH 1532090945 1532090946 IN IP4 10.10.0.190 s=FreeSWITCH c=IN IP4 10.10.0.190 t=0 0 m=audio 25572 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:40 sofia/internal/3001@10.10.0.201 Standard INIT 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:40 sofia/internal/5001@10.10.0.37:5060 Standard INIT f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/3001@10.10.0.201) State Change CS_INIT -> CS_ROUTING 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/5001@10.10.0.37:5060) State Change CS_INIT -> CS_ROUTING f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3001@10.10.0.201) State INIT going to sleep 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/5001@10.10.0.37:5060) State INIT going to sleep f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_ROUTING (Cur 7 Tot 7) f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3001@10.10.0.201) State ROUTING f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:143 sofia/internal/3001@10.10.0.201 SOFIA ROUTING f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/3001@10.10.0.201) State Change CS_ROUTING -> CS_CONSUME_MEDIA f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3001@10.10.0.201) State ROUTING going to sleep f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 7) 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_ROUTING (Cur 7 Tot 7) 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] sofia.c:7084 Channel sofia/internal/5001@10.10.0.37:5060 entering state [calling][0] 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/5001@10.10.0.37:5060) State ROUTING 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:143 sofia/internal/5001@10.10.0.37:5060 SOFIA ROUTING 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/5001@10.10.0.37:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/5001@10.10.0.37:5060) State ROUTING going to sleep 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 7) e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2201@10.10.0.34:5060) State INIT going to sleep 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/5001@10.10.0.37:5060) State CONSUME_MEDIA 5f3e9751 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/5001@10.10.0.37:5060) State CONSUME_MEDIA going to sleep f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [calling][0] f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3001@10.10.0.201) State CONSUME_MEDIA f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3001@10.10.0.201) State CONSUME_MEDIA going to sleep e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_ROUTING (Cur 7 Tot 7) e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] sofia.c:7084 Channel sofia/internal/2201@10.10.0.34:5060 entering state [calling][0] e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2201@10.10.0.34:5060) State ROUTING e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] mod_sofia.c:143 sofia/internal/2201@10.10.0.34:5060 SOFIA ROUTING e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2201@10.10.0.34:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2201@10.10.0.34:5060) State ROUTING going to sleep e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 7) e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2201@10.10.0.34:5060) State CONSUME_MEDIA e10e6bbc 2018-07-20 12:55:17.252223 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2201@10.10.0.34:5060) State CONSUME_MEDIA going to sleep f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [proceeding][180] f5d2f201 2018-07-20 12:55:17.252223 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/3001@10.10.0.201! f5d2f201 2018-07-20 12:55:17.252223 [DEBUG] switch_channel.c:3346 (sofia/internal/3001@10.10.0.201) Callstate Change DOWN -> RINGING 2018-07-20 12:55:17.252223 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz ff4e24f6 2018-07-20 12:55:17.272219 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [ready][200] ff4e24f6 2018-07-20 12:55:17.272219 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [completing][200] f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=3001 1052092996 591622851 IN IP4 10.10.0.201 s=- c=IN IP4 10.10.0.201 t=0 0 m=audio 8000 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=ptime:20 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [ready][200] f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/3001@10.10.0.201 PCMU/8000 20 ms 160 samples 64000 bits 1 channels f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_codec.c:111 sofia/internal/3001@10.10.0.201 Original read codec set to PCMU:0 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4738 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:4767 sofia/internal/3001@10.10.0.201 Set 2833 dtmf send payload to 101 recv payload to 101 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/3001@10.10.0.201] 10.10.0.190 port 25572 -> 10.10.0.201 port 8000 codec: 0 ms: 20 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:7180 sofia/internal/3001@10.10.0.201 Set 2833 dtmf send payload to 101 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:7187 sofia/internal/3001@10.10.0.201 Set 2833 dtmf receive payload to 101 f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_core_media.c:7210 sofia/internal/3001@10.10.0.201 Set rtp dtmf delay to 40 f5d2f201 2018-07-20 12:55:17.272219 [NOTICE] sofia.c:8218 Channel [sofia/internal/3001@10.10.0.201] has been answered f5d2f201 2018-07-20 12:55:17.272219 [DEBUG] switch_channel.c:3773 (sofia/internal/3001@10.10.0.201) Callstate Change RINGING -> ACTIVE 2018-07-20 12:55:17.292233 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/3001@10.10.0.201] f5d2f201 2018-07-20 12:55:17.292233 [INFO] switch_channel.c:3129 sofia/internal/3001@10.10.0.201 Flipping CID from "All Call" <6060> to "Outbound Call" <3001> f5d2f201 2018-07-20 12:55:17.292233 [DEBUG] mod_conference.c:1482 (sofia/internal/3001@10.10.0.201) State Change CS_CONSUME_MEDIA -> CS_EXECUTE f5d2f201 2018-07-20 12:55:17.292233 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_EXECUTE (Cur 7 Tot 7) f5d2f201 2018-07-20 12:55:17.292233 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/3001@10.10.0.201) State EXECUTE f5d2f201 2018-07-20 12:55:17.292233 [DEBUG] mod_sofia.c:198 sofia/internal/3001@10.10.0.201 SOFIA EXECUTE f5d2f201 2018-07-20 12:55:17.292233 [DEBUG] switch_core_state_machine.c:328 sofia/internal/3001@10.10.0.201 Standard EXECUTE f5d2f201 EXECUTE sofia/internal/3001@10.10.0.201 conference(madboss_intercom1+flags{}) 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1101@10.10.0.33:5060 entering state [completing][200] 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 1 1 IN IP4 10.10.0.33 s=Barix SIP c=IN IP4 10.10.0.33 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrcv 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1101@10.10.0.33:5060 entering state [ready][200] 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1101@10.10.0.33:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_codec.c:111 sofia/internal/1101@10.10.0.33:5060 Original read codec set to PCMU:0 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4767 sofia/internal/1101@10.10.0.33:5060 Set 2833 dtmf send payload to 101 recv payload to 101 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1101@10.10.0.33:5060] 10.10.0.190 port 21494 -> 10.10.0.33 port 5004 codec: 0 ms: 20 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1114@10.10.0.36:5060 entering state [completing][200] e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 1 1 IN IP4 10.10.0.36 s=Barix SIP c=IN IP4 10.10.0.36 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrcv 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7180 sofia/internal/1101@10.10.0.33:5060 Set 2833 dtmf send payload to 101 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7187 sofia/internal/1101@10.10.0.33:5060 Set 2833 dtmf receive payload to 101 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7210 sofia/internal/1101@10.10.0.33:5060 Set rtp dtmf delay to 40 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1114@10.10.0.36:5060 entering state [ready][200] 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1131@10.10.0.35:5060 entering state [completing][200] 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 1 1 IN IP4 10.10.0.35 s=Barix SIP c=IN IP4 10.10.0.35 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrcv 24ecc9c6 2018-07-20 12:55:17.312222 [NOTICE] sofia.c:8218 Channel [sofia/internal/1101@10.10.0.33:5060] has been answered 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/1131@10.10.0.35:5060 entering state [ready][200] e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1114@10.10.0.36:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_codec.c:111 sofia/internal/1114@10.10.0.36:5060 Original read codec set to PCMU:0 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/1131@10.10.0.35:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_codec.c:111 sofia/internal/1131@10.10.0.35:5060 Original read codec set to PCMU:0 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4767 sofia/internal/1114@10.10.0.36:5060 Set 2833 dtmf send payload to 101 recv payload to 101 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1114@10.10.0.36:5060] 10.10.0.190 port 27478 -> 10.10.0.36 port 5004 codec: 0 ms: 20 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4767 sofia/internal/1131@10.10.0.35:5060 Set 2833 dtmf send payload to 101 recv payload to 101 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/1131@10.10.0.35:5060] 10.10.0.190 port 25270 -> 10.10.0.35 port 5004 codec: 0 ms: 20 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_channel.c:3773 (sofia/internal/1101@10.10.0.33:5060) Callstate Change DOWN -> ACTIVE 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7180 sofia/internal/1114@10.10.0.36:5060 Set 2833 dtmf send payload to 101 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7187 sofia/internal/1114@10.10.0.36:5060 Set 2833 dtmf receive payload to 101 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7210 sofia/internal/1114@10.10.0.36:5060 Set rtp dtmf delay to 40 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7180 sofia/internal/1131@10.10.0.35:5060 Set 2833 dtmf send payload to 101 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7187 sofia/internal/1131@10.10.0.35:5060 Set 2833 dtmf receive payload to 101 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7210 sofia/internal/1131@10.10.0.35:5060 Set rtp dtmf delay to 40 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/5001@10.10.0.37:5060 entering state [completing][200] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 1 1 IN IP4 10.10.0.37 s=Barix SIP c=IN IP4 10.10.0.37 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrcv e5e6183d 2018-07-20 12:55:17.312222 [NOTICE] sofia.c:8218 Channel [sofia/internal/1114@10.10.0.36:5060] has been answered 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/5001@10.10.0.37:5060 entering state [ready][200] 0cf8ec7a 2018-07-20 12:55:17.312222 [NOTICE] sofia.c:8218 Channel [sofia/internal/1131@10.10.0.35:5060] has been answered 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/5001@10.10.0.37:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_codec.c:111 sofia/internal/5001@10.10.0.37:5060 Original read codec set to PCMU:0 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4767 sofia/internal/5001@10.10.0.37:5060 Set 2833 dtmf send payload to 101 recv payload to 101 e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_channel.c:3773 (sofia/internal/1114@10.10.0.36:5060) Callstate Change DOWN -> ACTIVE 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/5001@10.10.0.37:5060] 10.10.0.190 port 27422 -> 10.10.0.37 port 5004 codec: 0 ms: 20 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 0cf8ec7a 2018-07-20 12:55:17.312222 [DEBUG] switch_channel.c:3773 (sofia/internal/1131@10.10.0.35:5060) Callstate Change DOWN -> ACTIVE e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/2201@10.10.0.34:5060 entering state [completing][200] e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 1 1 IN IP4 10.10.0.34 s=Barix SIP c=IN IP4 10.10.0.34 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrcv e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] sofia.c:7084 Channel sofia/internal/2201@10.10.0.34:5060 entering state [ready][200] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7180 sofia/internal/5001@10.10.0.37:5060 Set 2833 dtmf send payload to 101 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7187 sofia/internal/5001@10.10.0.37:5060 Set 2833 dtmf receive payload to 101 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7210 sofia/internal/5001@10.10.0.37:5060 Set rtp dtmf delay to 40 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/2201@10.10.0.34:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_codec.c:111 sofia/internal/2201@10.10.0.34:5060 Original read codec set to PCMU:0 5f3e9751 2018-07-20 12:55:17.312222 [NOTICE] sofia.c:8218 Channel [sofia/internal/5001@10.10.0.37:5060] has been answered e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:4767 sofia/internal/2201@10.10.0.34:5060 Set 2833 dtmf send payload to 101 recv payload to 101 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/2201@10.10.0.34:5060] 10.10.0.190 port 22910 -> 10.10.0.34 port 5004 codec: 0 ms: 20 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 5f3e9751 2018-07-20 12:55:17.312222 [DEBUG] switch_channel.c:3773 (sofia/internal/5001@10.10.0.37:5060) Callstate Change DOWN -> ACTIVE e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7180 sofia/internal/2201@10.10.0.34:5060 Set 2833 dtmf send payload to 101 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7187 sofia/internal/2201@10.10.0.34:5060 Set 2833 dtmf receive payload to 101 e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_core_media.c:7210 sofia/internal/2201@10.10.0.34:5060 Set rtp dtmf delay to 40 e10e6bbc 2018-07-20 12:55:17.312222 [NOTICE] sofia.c:8218 Channel [sofia/internal/2201@10.10.0.34:5060] has been answered e10e6bbc 2018-07-20 12:55:17.312222 [DEBUG] switch_channel.c:3773 (sofia/internal/2201@10.10.0.34:5060) Callstate Change DOWN -> ACTIVE 2018-07-20 12:55:17.312222 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/1101@10.10.0.33:5060] 2018-07-20 12:55:17.312222 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/1114@10.10.0.36:5060] 24ecc9c6 2018-07-20 12:55:17.312222 [INFO] switch_channel.c:3129 sofia/internal/1101@10.10.0.33:5060 Flipping CID from "All Call" <6060> to "Outbound Call" <1101> 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] mod_conference.c:1482 (sofia/internal/1101@10.10.0.33:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_EXECUTE (Cur 7 Tot 7) 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1101@10.10.0.33:5060) State EXECUTE 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] mod_sofia.c:198 sofia/internal/1101@10.10.0.33:5060 SOFIA EXECUTE 24ecc9c6 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1101@10.10.0.33:5060 Standard EXECUTE 24ecc9c6 EXECUTE sofia/internal/1101@10.10.0.33:5060 conference(madboss_intercom1+flags{}) e5e6183d 2018-07-20 12:55:17.312222 [INFO] switch_channel.c:3129 sofia/internal/1114@10.10.0.36:5060 Flipping CID from "All Call" <6060> to "Outbound Call" <1114> e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] mod_conference.c:1482 (sofia/internal/1114@10.10.0.36:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_EXECUTE (Cur 7 Tot 7) e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1114@10.10.0.36:5060) State EXECUTE e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] mod_sofia.c:198 sofia/internal/1114@10.10.0.36:5060 SOFIA EXECUTE e5e6183d 2018-07-20 12:55:17.312222 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1114@10.10.0.36:5060 Standard EXECUTE e5e6183d EXECUTE sofia/internal/1114@10.10.0.36:5060 conference(madboss_intercom1+flags{}) 2018-07-20 12:55:17.332226 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/1131@10.10.0.35:5060] 0cf8ec7a 2018-07-20 12:55:17.332226 [INFO] switch_channel.c:3129 sofia/internal/1131@10.10.0.35:5060 Flipping CID from "All Call" <6060> to "Outbound Call" <1131> 0cf8ec7a 2018-07-20 12:55:17.332226 [DEBUG] mod_conference.c:1482 (sofia/internal/1131@10.10.0.35:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 0cf8ec7a 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_EXECUTE (Cur 7 Tot 7) 0cf8ec7a 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1131@10.10.0.35:5060) State EXECUTE 0cf8ec7a 2018-07-20 12:55:17.332226 [DEBUG] mod_sofia.c:198 sofia/internal/1131@10.10.0.35:5060 SOFIA EXECUTE 0cf8ec7a 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1131@10.10.0.35:5060 Standard EXECUTE 0cf8ec7a EXECUTE sofia/internal/1131@10.10.0.35:5060 conference(madboss_intercom1+flags{}) 2018-07-20 12:55:17.332226 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/5001@10.10.0.37:5060] 2018-07-20 12:55:17.332226 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/2201@10.10.0.34:5060] 5f3e9751 2018-07-20 12:55:17.332226 [INFO] switch_channel.c:3129 sofia/internal/5001@10.10.0.37:5060 Flipping CID from "All Call" <6060> to "Outbound Call" <5001> 5f3e9751 2018-07-20 12:55:17.332226 [DEBUG] mod_conference.c:1482 (sofia/internal/5001@10.10.0.37:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 5f3e9751 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_EXECUTE (Cur 7 Tot 7) e10e6bbc 2018-07-20 12:55:17.332226 [INFO] switch_channel.c:3129 sofia/internal/2201@10.10.0.34:5060 Flipping CID from "All Call" <6060> to "Outbound Call" <2201> e10e6bbc 2018-07-20 12:55:17.332226 [DEBUG] mod_conference.c:1482 (sofia/internal/2201@10.10.0.34:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 5f3e9751 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/5001@10.10.0.37:5060) State EXECUTE 5f3e9751 2018-07-20 12:55:17.332226 [DEBUG] mod_sofia.c:198 sofia/internal/5001@10.10.0.37:5060 SOFIA EXECUTE 5f3e9751 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:328 sofia/internal/5001@10.10.0.37:5060 Standard EXECUTE e10e6bbc 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_EXECUTE (Cur 7 Tot 7) 5f3e9751 EXECUTE sofia/internal/5001@10.10.0.37:5060 conference(madboss_intercom1+flags{}) e10e6bbc 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/2201@10.10.0.34:5060) State EXECUTE e10e6bbc 2018-07-20 12:55:17.332226 [DEBUG] mod_sofia.c:198 sofia/internal/2201@10.10.0.34:5060 SOFIA EXECUTE e10e6bbc 2018-07-20 12:55:17.332226 [DEBUG] switch_core_state_machine.c:328 sofia/internal/2201@10.10.0.34:5060 Standard EXECUTE e10e6bbc EXECUTE sofia/internal/2201@10.10.0.34:5060 conference(madboss_intercom1+flags{}) f5d2f201 2018-07-20 12:55:17.332226 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. ff4e24f6 2018-07-20 12:55:17.352226 [DEBUG] conference_member.c:1425 Queueing file 'tone_stream://%(500,0,640)' for play 24ecc9c6 2018-07-20 12:55:17.612221 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. e5e6183d 2018-07-20 12:55:17.612221 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 0cf8ec7a 2018-07-20 12:55:17.612221 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 5f3e9751 2018-07-20 12:55:17.612221 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. e10e6bbc 2018-07-20 12:55:17.612221 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. f5d2f201 2018-07-20 12:55:17.672223 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 24ecc9c6 2018-07-20 12:55:17.712225 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 0cf8ec7a 2018-07-20 12:55:17.712225 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms e5e6183d 2018-07-20 12:55:17.712225 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 5f3e9751 2018-07-20 12:55:17.712225 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms e10e6bbc 2018-07-20 12:55:17.712225 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms f5d2f201 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) f5d2f201 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms f5d2f201 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms f5d2f201 2018-07-20 12:55:17.932216 [DEBUG] switch_core_codec.c:223 sofia/internal/3001@10.10.0.201 Push codec L16:100 2018-07-20 12:55:17.932216 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '0' to 'mute' 2018-07-20 12:55:17.932216 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083200 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '*' to 'deaf mute' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083230 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '9' to 'energy up' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083260 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '8' to 'energy equ' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083290 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '7' to 'energy dn' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c0832c0 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '3' to 'vol talk up' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c0832f0 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083320 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083350 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '6' to 'vol listen up' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083380 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c0833b0 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c0833e0 2018-07-20 12:55:17.932216 [DEBUG] conference_member.c:128 sofia/internal/3001@10.10.0.201 binding '#' to 'hangup' 2018-07-20 12:55:17.932216 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4c083410 f5d2f201 2018-07-20 12:55:17.932216 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU 24ecc9c6 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) 24ecc9c6 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms 24ecc9c6 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms 24ecc9c6 2018-07-20 12:55:17.972226 [DEBUG] switch_core_codec.c:223 sofia/internal/1101@10.10.0.33:5060 Push codec L16:100 2018-07-20 12:55:17.972226 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '0' to 'mute' 2018-07-20 12:55:17.972226 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030570 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '*' to 'deaf mute' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe300305a0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe300305d0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030600 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030630 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030660 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030690 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe300306c0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe300306f0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030720 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030750 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1101@10.10.0.33:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe30030780 24ecc9c6 2018-07-20 12:55:17.972226 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU e5e6183d 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) 0cf8ec7a 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) e5e6183d 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms e5e6183d 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms 0cf8ec7a 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms e5e6183d 2018-07-20 12:55:17.972226 [DEBUG] switch_core_codec.c:223 sofia/internal/1114@10.10.0.36:5060 Push codec L16:100 0cf8ec7a 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2018-07-20 12:55:17.972226 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 0cf8ec7a 2018-07-20 12:55:17.972226 [DEBUG] switch_core_codec.c:223 sofia/internal/1131@10.10.0.35:5060 Push codec L16:100 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '0' to 'mute' 2018-07-20 12:55:17.972226 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe340371f0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '*' to 'deaf mute' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037220 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037250 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037280 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe340372b0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe340372e0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037310 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037340 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037370 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe340373a0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe340373d0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1114@10.10.0.36:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe34037400 2018-07-20 12:55:17.972226 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '0' to 'mute' 2018-07-20 12:55:17.972226 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540817c0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '*' to 'deaf mute' e5e6183d 2018-07-20 12:55:17.972226 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540817f0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081820 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081850 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081880 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540818b0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540818e0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081910 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081940 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe54081970 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540819a0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/1131@10.10.0.35:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe540819d0 0cf8ec7a 2018-07-20 12:55:17.972226 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU 5f3e9751 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) 5f3e9751 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms 5f3e9751 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms 5f3e9751 2018-07-20 12:55:17.972226 [DEBUG] switch_core_codec.c:223 sofia/internal/5001@10.10.0.37:5060 Push codec L16:100 2018-07-20 12:55:17.972226 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '0' to 'mute' 2018-07-20 12:55:17.972226 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ac00 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '*' to 'deaf mute' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ac30 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ac60 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ac90 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403acc0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403acf0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ad20 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ad50 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ad80 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403adb0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ade0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/5001@10.10.0.37:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0x7fbe4403ae10 5f3e9751 2018-07-20 12:55:17.972226 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU e10e6bbc 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(250,0,800) e10e6bbc 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms e10e6bbc 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms e10e6bbc 2018-07-20 12:55:17.972226 [DEBUG] switch_core_codec.c:223 sofia/internal/2201@10.10.0.34:5060 Push codec L16:100 2018-07-20 12:55:17.972226 [ERR] switch_core_video.c:1789 Error open png: /var/lib/freeswitch/images/default-mute.png 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '0' to 'mute' 2018-07-20 12:55:17.972226 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32b70 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '*' to 'deaf mute' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fbe737a5dd0 data: 0xa32ba0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '9' to 'energy up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32bd0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '8' to 'energy equ' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32c00 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '7' to 'energy dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32c30 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '3' to 'vol talk up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32c60 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '2' to 'vol talk zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32c90 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '1' to 'vol talk dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32cc0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '6' to 'vol listen up' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32cf0 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '5' to 'vol listen zero' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32d20 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '4' to 'vol listen dn' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32d50 2018-07-20 12:55:17.972226 [DEBUG] conference_member.c:128 sofia/internal/2201@10.10.0.34:5060 binding '#' to 'hangup' 2018-07-20 12:55:17.972226 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fbe737a5dd0 data: 0xa32d80 e10e6bbc 2018-07-20 12:55:17.972226 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMU ff4e24f6 2018-07-20 12:55:19.472217 [NOTICE] sofia.c:1012 Hangup sofia/internal/9000@10.10.0.190:5060 [CS_EXECUTE] [NORMAL_CLEARING] ff4e24f6 2018-07-20 12:55:19.492234 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] mod_conference.c:2247 sofia/internal/9000@10.10.0.190:5060 skip receive message [UNBRIDGE] (channel is hungup already) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_media.c:9780 sofia/internal/9000@10.10.0.190:5060 skip receive message [HARD_MUTE] (channel is hungup already) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_codec.c:248 sofia/internal/9000@10.10.0.190:5060 Restore previous codec PCMU:0. ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_session.c:2815 sofia/internal/9000@10.10.0.190:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/9000@10.10.0.190:5060) State EXECUTE going to sleep ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_HANGUP (Cur 7 Tot 7) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9000@10.10.0.190:5060) Callstate Change ACTIVE -> HANGUP ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/9000@10.10.0.190:5060) State HANGUP ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] mod_sofia.c:438 Channel sofia/internal/9000@10.10.0.190:5060 hanging up, cause: NORMAL_CLEARING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:60 sofia/internal/9000@10.10.0.190:5060 Standard HANGUP, cause: NORMAL_CLEARING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/9000@10.10.0.190:5060) State HANGUP going to sleep ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/9000@10.10.0.190:5060) State Change CS_HANGUP -> CS_REPORTING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_REPORTING (Cur 7 Tot 7) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/9000@10.10.0.190:5060) State REPORTING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:174 sofia/internal/9000@10.10.0.190:5060 Standard REPORTING, cause: NORMAL_CLEARING ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/9000@10.10.0.190:5060) State REPORTING going to sleep ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/9000@10.10.0.190:5060) State Change CS_REPORTING -> CS_DESTROY ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/internal/9000@10.10.0.190:5060) Locked, Waiting on external entities ff4e24f6 2018-07-20 12:55:19.492234 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/internal/9000@10.10.0.190:5060) Ended ff4e24f6 2018-07-20 12:55:19.492234 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/9000@10.10.0.190:5060 [CS_DESTROY] ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_DESTROY (Cur 6 Tot 7) ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/9000@10.10.0.190:5060) State DESTROY ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] mod_sofia.c:343 sofia/internal/9000@10.10.0.190:5060 SOFIA DESTROY ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:181 sofia/internal/9000@10.10.0.190:5060 Standard DESTROY ff4e24f6 2018-07-20 12:55:19.492234 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/9000@10.10.0.190:5060) State DESTROY going to sleep 2018-07-20 12:55:20.012221 [DEBUG] mod_conference.c:706 Conference madboss_intercom1: endconf grace time exceeded (0) 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:719 Ending pending outcall channels for Conference: 'madboss_intercom1' e10e6bbc 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/2201@10.10.0.34:5060 [CS_EXECUTE] [NORMAL_CLEARING] 5f3e9751 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/5001@10.10.0.37:5060 [CS_EXECUTE] [NORMAL_CLEARING] 0cf8ec7a 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/1131@10.10.0.35:5060 [CS_EXECUTE] [NORMAL_CLEARING] e5e6183d 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/1114@10.10.0.36:5060 [CS_EXECUTE] [NORMAL_CLEARING] 24ecc9c6 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/1101@10.10.0.33:5060 [CS_EXECUTE] [NORMAL_CLEARING] f5d2f201 2018-07-20 12:55:20.012221 [NOTICE] mod_conference.c:742 Hangup sofia/internal/3001@10.10.0.201 [CS_EXECUTE] [NORMAL_CLEARING] 2018-07-20 12:55:20.012221 [DEBUG] mod_conference.c:788 Write Lock ON 24ecc9c6 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/1101@10.10.0.33:5060 skip receive message [UNBRIDGE] (channel is hungup already) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/1101@10.10.0.33:5060 skip receive message [HARD_MUTE] (channel is hungup already) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/1101@10.10.0.33:5060 Restore previous codec PCMU:0. 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/1101@10.10.0.33:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1101@10.10.0.33:5060) State EXECUTE going to sleep 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_HANGUP (Cur 6 Tot 7) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1101@10.10.0.33:5060) Callstate Change ACTIVE -> HANGUP 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1101@10.10.0.33:5060) State HANGUP 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1101@10.10.0.33:5060 hanging up, cause: NORMAL_CLEARING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/1101@10.10.0.33:5060 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1101@10.10.0.33:5060 Standard HANGUP, cause: NORMAL_CLEARING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1101@10.10.0.33:5060) State HANGUP going to sleep 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1101@10.10.0.33:5060) State Change CS_HANGUP -> CS_REPORTING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_REPORTING (Cur 6 Tot 7) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1101@10.10.0.33:5060) State REPORTING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1101@10.10.0.33:5060 Standard REPORTING, cause: NORMAL_CLEARING 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1101@10.10.0.33:5060) State REPORTING going to sleep 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1101@10.10.0.33:5060) State Change CS_REPORTING -> CS_DESTROY 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 2 (sofia/internal/1101@10.10.0.33:5060) Locked, Waiting on external entities 24ecc9c6 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 2 (sofia/internal/1101@10.10.0.33:5060) Ended 24ecc9c6 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1101@10.10.0.33:5060 [CS_DESTROY] 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1101@10.10.0.33:5060) Running State Change CS_DESTROY (Cur 5 Tot 7) 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1101@10.10.0.33:5060) State DESTROY 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/1101@10.10.0.33:5060 SOFIA DESTROY 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1101@10.10.0.33:5060 Standard DESTROY 24ecc9c6 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1101@10.10.0.33:5060) State DESTROY going to sleep e5e6183d 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/1114@10.10.0.36:5060 skip receive message [UNBRIDGE] (channel is hungup already) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/1114@10.10.0.36:5060 skip receive message [HARD_MUTE] (channel is hungup already) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/1114@10.10.0.36:5060 Restore previous codec PCMU:0. 0cf8ec7a 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/1131@10.10.0.35:5060 skip receive message [UNBRIDGE] (channel is hungup already) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/1131@10.10.0.35:5060 skip receive message [HARD_MUTE] (channel is hungup already) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/1114@10.10.0.36:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/1131@10.10.0.35:5060 Restore previous codec PCMU:0. e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1114@10.10.0.36:5060) State EXECUTE going to sleep e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_HANGUP (Cur 5 Tot 7) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1114@10.10.0.36:5060) Callstate Change ACTIVE -> HANGUP 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/1131@10.10.0.35:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1131@10.10.0.35:5060) State EXECUTE going to sleep 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_HANGUP (Cur 5 Tot 7) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1114@10.10.0.36:5060) State HANGUP e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1114@10.10.0.36:5060 hanging up, cause: NORMAL_CLEARING 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1131@10.10.0.35:5060) Callstate Change ACTIVE -> HANGUP 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1131@10.10.0.35:5060) State HANGUP 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1131@10.10.0.35:5060 hanging up, cause: NORMAL_CLEARING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/1114@10.10.0.36:5060 e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1114@10.10.0.36:5060 Standard HANGUP, cause: NORMAL_CLEARING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1114@10.10.0.36:5060) State HANGUP going to sleep e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1114@10.10.0.36:5060) State Change CS_HANGUP -> CS_REPORTING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_REPORTING (Cur 5 Tot 7) e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1114@10.10.0.36:5060) State REPORTING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1114@10.10.0.36:5060 Standard REPORTING, cause: NORMAL_CLEARING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1114@10.10.0.36:5060) State REPORTING going to sleep 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/1131@10.10.0.35:5060 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1131@10.10.0.35:5060 Standard HANGUP, cause: NORMAL_CLEARING 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1131@10.10.0.35:5060) State HANGUP going to sleep 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1131@10.10.0.35:5060) State Change CS_HANGUP -> CS_REPORTING e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1114@10.10.0.36:5060) State Change CS_REPORTING -> CS_DESTROY e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 3 (sofia/internal/1114@10.10.0.36:5060) Locked, Waiting on external entities e5e6183d 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 3 (sofia/internal/1114@10.10.0.36:5060) Ended e5e6183d 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1114@10.10.0.36:5060 [CS_DESTROY] 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_REPORTING (Cur 5 Tot 7) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1131@10.10.0.35:5060) State REPORTING 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1131@10.10.0.35:5060 Standard REPORTING, cause: NORMAL_CLEARING 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1131@10.10.0.35:5060) State REPORTING going to sleep e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1114@10.10.0.36:5060) Running State Change CS_DESTROY (Cur 4 Tot 7) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1131@10.10.0.35:5060) State Change CS_REPORTING -> CS_DESTROY 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 4 (sofia/internal/1131@10.10.0.35:5060) Locked, Waiting on external entities 0cf8ec7a 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 4 (sofia/internal/1131@10.10.0.35:5060) Ended 0cf8ec7a 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/1131@10.10.0.35:5060 [CS_DESTROY] e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1114@10.10.0.36:5060) State DESTROY e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/1114@10.10.0.36:5060 SOFIA DESTROY e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1114@10.10.0.36:5060 Standard DESTROY e5e6183d 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1114@10.10.0.36:5060) State DESTROY going to sleep 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1131@10.10.0.35:5060) Running State Change CS_DESTROY (Cur 3 Tot 7) 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1131@10.10.0.35:5060) State DESTROY 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/1131@10.10.0.35:5060 SOFIA DESTROY 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1131@10.10.0.35:5060 Standard DESTROY 0cf8ec7a 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1131@10.10.0.35:5060) State DESTROY going to sleep 5f3e9751 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/5001@10.10.0.37:5060 skip receive message [UNBRIDGE] (channel is hungup already) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/5001@10.10.0.37:5060 skip receive message [HARD_MUTE] (channel is hungup already) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/5001@10.10.0.37:5060 Restore previous codec PCMU:0. 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/5001@10.10.0.37:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/5001@10.10.0.37:5060) State EXECUTE going to sleep 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_HANGUP (Cur 3 Tot 7) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/5001@10.10.0.37:5060) Callstate Change ACTIVE -> HANGUP 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/5001@10.10.0.37:5060) State HANGUP 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/5001@10.10.0.37:5060 hanging up, cause: NORMAL_CLEARING f5d2f201 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/3001@10.10.0.201 skip receive message [UNBRIDGE] (channel is hungup already) f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/3001@10.10.0.201 skip receive message [HARD_MUTE] (channel is hungup already) f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/3001@10.10.0.201 Restore previous codec PCMU:0. f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/3001@10.10.0.201 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/3001@10.10.0.201) State EXECUTE going to sleep f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_HANGUP (Cur 3 Tot 7) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/5001@10.10.0.37:5060 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/5001@10.10.0.37:5060 Standard HANGUP, cause: NORMAL_CLEARING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/5001@10.10.0.37:5060) State HANGUP going to sleep 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/5001@10.10.0.37:5060) State Change CS_HANGUP -> CS_REPORTING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_REPORTING (Cur 3 Tot 7) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/5001@10.10.0.37:5060) State REPORTING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/3001@10.10.0.201) Callstate Change ACTIVE -> HANGUP 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/5001@10.10.0.37:5060 Standard REPORTING, cause: NORMAL_CLEARING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/5001@10.10.0.37:5060) State REPORTING going to sleep f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3001@10.10.0.201) State HANGUP f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/3001@10.10.0.201 hanging up, cause: NORMAL_CLEARING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/5001@10.10.0.37:5060) State Change CS_REPORTING -> CS_DESTROY 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 6 (sofia/internal/5001@10.10.0.37:5060) Locked, Waiting on external entities 5f3e9751 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 6 (sofia/internal/5001@10.10.0.37:5060) Ended 5f3e9751 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/5001@10.10.0.37:5060 [CS_DESTROY] e10e6bbc 2018-07-20 12:55:20.032219 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/5001@10.10.0.37:5060) Running State Change CS_DESTROY (Cur 2 Tot 7) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:2247 sofia/internal/2201@10.10.0.34:5060 skip receive message [UNBRIDGE] (channel is hungup already) 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/5001@10.10.0.37:5060) State DESTROY 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/5001@10.10.0.37:5060 SOFIA DESTROY 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/5001@10.10.0.37:5060 Standard DESTROY 5f3e9751 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/5001@10.10.0.37:5060) State DESTROY going to sleep e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_media.c:9780 sofia/internal/2201@10.10.0.34:5060 skip receive message [HARD_MUTE] (channel is hungup already) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_codec.c:248 sofia/internal/2201@10.10.0.34:5060 Restore previous codec PCMU:0. 2018-07-20 12:55:20.032219 [DEBUG] mod_conference.c:791 Write Lock OFF e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:2815 sofia/internal/2201@10.10.0.34:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/2201@10.10.0.34:5060) State EXECUTE going to sleep e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_HANGUP (Cur 2 Tot 7) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2201@10.10.0.34:5060) Callstate Change ACTIVE -> HANGUP f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/3001@10.10.0.201 e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/2201@10.10.0.34:5060) State HANGUP f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/3001@10.10.0.201 Standard HANGUP, cause: NORMAL_CLEARING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3001@10.10.0.201) State HANGUP going to sleep e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:438 Channel sofia/internal/2201@10.10.0.34:5060 hanging up, cause: NORMAL_CLEARING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/3001@10.10.0.201) State Change CS_HANGUP -> CS_REPORTING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_REPORTING (Cur 2 Tot 7) f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3001@10.10.0.201) State REPORTING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/3001@10.10.0.201 Standard REPORTING, cause: NORMAL_CLEARING f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3001@10.10.0.201) State REPORTING going to sleep f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/3001@10.10.0.201) State Change CS_REPORTING -> CS_DESTROY f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 5 (sofia/internal/3001@10.10.0.201) Locked, Waiting on external entities f5d2f201 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 5 (sofia/internal/3001@10.10.0.201) Ended f5d2f201 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/3001@10.10.0.201 [CS_DESTROY] f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/3001@10.10.0.201) Running State Change CS_DESTROY (Cur 1 Tot 7) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/2201@10.10.0.34:5060 f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3001@10.10.0.201) State DESTROY f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/3001@10.10.0.201 SOFIA DESTROY e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2201@10.10.0.34:5060 Standard HANGUP, cause: NORMAL_CLEARING e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/2201@10.10.0.34:5060) State HANGUP going to sleep f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/3001@10.10.0.201 Standard DESTROY f5d2f201 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3001@10.10.0.201) State DESTROY going to sleep e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/2201@10.10.0.34:5060) State Change CS_HANGUP -> CS_REPORTING e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_REPORTING (Cur 1 Tot 7) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/2201@10.10.0.34:5060) State REPORTING e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2201@10.10.0.34:5060 Standard REPORTING, cause: NORMAL_CLEARING e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/2201@10.10.0.34:5060) State REPORTING going to sleep e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/2201@10.10.0.34:5060) State Change CS_REPORTING -> CS_DESTROY e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_session.c:1665 Session 7 (sofia/internal/2201@10.10.0.34:5060) Locked, Waiting on external entities e10e6bbc 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1683 Session 7 (sofia/internal/2201@10.10.0.34:5060) Ended e10e6bbc 2018-07-20 12:55:20.032219 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/2201@10.10.0.34:5060 [CS_DESTROY] e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/2201@10.10.0.34:5060) Running State Change CS_DESTROY (Cur 0 Tot 7) e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/2201@10.10.0.34:5060) State DESTROY e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] mod_sofia.c:343 sofia/internal/2201@10.10.0.34:5060 SOFIA DESTROY e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2201@10.10.0.34:5060 Standard DESTROY e10e6bbc 2018-07-20 12:55:20.032219 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/2201@10.10.0.34:5060) State DESTROY going to sleep