2018-10-03 02:26:01.401614 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-10-03 02:26:01.401614 [NOTICE] switch_channel.c:1104 New Channel sofia/default/919033769913@demo.inextrix.com:5061 [b2d5766c-c510-4ab1-9061-59d9e5db1d69]
2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:4819 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_NEW -> CS_INIT
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_INIT (Cur 1 Tot 16)
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:627 (sofia/default/919033769913@demo.inextrix.com:5061) State INIT
2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:90 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA INIT
2018-10-03 02:26:01.401614 [DEBUG] sofia_glue.c:1295 sofia/default/919033769913@demo.inextrix.com:5061 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1538513887 1538513888 IN IP4 81.95.124.27
s=FreeSWITCH
c=IN IP4 81.95.124.27
t=0 0
m=audio 19674 RTP/AVP 0 8 9 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:40 sofia/default/919033769913@demo.inextrix.com:5061 Standard INIT
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:48 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_INIT -> CS_ROUTING
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:627 (sofia/default/919033769913@demo.inextrix.com:5061) State INIT going to sleep
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 1 Tot 16)
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING
2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:143 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA ROUTING
2018-10-03 02:26:01.401614 [DEBUG] switch_ivr_originate.c:67 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING going to sleep
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 16)
2018-10-03 02:26:01.401614 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [calling][0]
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:662 (sofia/default/919033769913@demo.inextrix.com:5061) State CONSUME_MEDIA
2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:662 (sofia/default/919033769913@demo.inextrix.com:5061) State CONSUME_MEDIA going to sleep
2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [completing][200]
2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=root 1284469274 1284469274 IN IP4 159.203.131.36
s=Asterisk PBX 13.8.0
c=IN IP4 159.203.131.36
t=0 0
m=audio 18402 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=maxptime:150
2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [ready][200]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G722:9:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G722:9:8000:20:64000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/919033769913@demo.inextrix.com:5061 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2018-10-03 02:26:01.641624 [DEBUG] switch_core_codec.c:111 sofia/default/919033769913@demo.inextrix.com:5061 Original read codec set to PCMU:0
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4767 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101 recv payload to 101
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/919033769913@demo.inextrix.com:5061] 81.95.124.27 port 19674 -> 159.203.131.36 port 18402 codec: 0 ms: 20
2018-10-03 02:26:01.641624 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7179 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7186 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf receive payload to 101
2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7209 sofia/default/919033769913@demo.inextrix.com:5061 Set rtp dtmf delay to 40
2018-10-03 02:26:01.641624 [INFO] switch_core_session.c:1619 sofia/default/919033769913@demo.inextrix.com:5061 setting session heartbeat to 10 second(s).
2018-10-03 02:26:01.641624 [NOTICE] sofia.c:8218 Channel [sofia/default/919033769913@demo.inextrix.com:5061] has been answered
2018-10-03 02:26:01.641624 [DEBUG] switch_channel.c:3773 (sofia/default/919033769913@demo.inextrix.com:5061) Callstate Change DOWN -> ACTIVE
2018-10-03 02:26:01.641624 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/919033769913@demo.inextrix.com:5061]
2018-10-03 02:26:01.641624 [DEBUG] switch_ivr.c:2165 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_CONSUME_MEDIA -> CS_ROUTING
2018-10-03 02:26:01.641624 [NOTICE] switch_ivr.c:2172 Transfer sofia/default/919033769913@demo.inextrix.com:5061 to XML[0676338702@default]
2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 1 Tot 16)
2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING
2018-10-03 02:26:01.641624 [DEBUG] mod_sofia.c:143 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA ROUTING
2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:236 sofia/default/919033769913@demo.inextrix.com:5061 Standard ROUTING
2018-10-03 02:26:01.641624 [INFO] mod_dialplan_xml.c:637 Processing 919033769913 <919033769913>->0676338702 in context default
2018-10-03 02:26:01.661612 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5f40468e70 Connected.
2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [LOAD_CONF] Query :SELECT name,value FROM tbl_settings WHERE group_title IN ('global')
2018-10-03 02:26:01.661612 [NOTICE] switch_cpp.cpp:1365 [Audio Conference] [xml_handler] Params:
'Event-Name: REQUEST_PARAMS
Core-UUID: f4183a48-fe2b-4698-83d4-87d80b08381d
FreeSWITCH-Hostname: astpp03
FreeSWITCH-Switchname: astpp03
FreeSWITCH-IPv4: 81.95.124.27
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2018-10-03%2002%3A26%3A01
Event-Date-GMT: Wed,%2003%20Oct%202018%2002%3A26%3A01%20GMT
Event-Date-Timestamp: 1538533561641624
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 29753
Channel-State: CS_ROUTING
Channel-Call-State: ACTIVE
Channel-State-Number: 2
Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
Call-Direction: outbound
Presence-Call-Direction: outbound
Channel-HIT-Dialplan: false
Channel-Call-UUID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: outbound
Caller-Logical-Direction: outbound
Caller-Dialplan: XML
Caller-Caller-ID-Name: 919033769913
Caller-Caller-ID-Number: 919033769913
Caller-Orig-Caller-ID-Name: 919033769913
Caller-Orig-Caller-ID-Number: 919033769913
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 919033769913
Caller-Network-Addr: 159.203.131.36
Caller-ANI: 919033769913
Caller-Destination-Number: 0676338702
Caller-Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
Caller-Source: src/switch_ivr_originate.c
Caller-Transfer-Source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
Caller-Context: default
Caller-RDNIS: 919033769913
Caller-Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
Caller-Profile-Index: 2
Caller-Profile-Created-Time: 1538533561641624
Caller-Channel-Created-Time: 1538533561401614
Caller-Channel-Answered-Time: 1538533561641624
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: outbound
variable_is_outbound: true
variable_uuid: b2d5766c-c510-4ab1-9061-59d9e5db1d69
variable_session_id: 16
variable_sip_profile_name: default
variable_video_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_channel_name: sofia/default/919033769913%40demo.inextrix.com%3A5061
variable_sip_destination_url: sip%3A919033769913%40demo.inextrix.com%3A5061
variable_hangup_after_bridge: true
variable_ignore_early_media: true
variable_conference_enforce_security: true
variable_conference_enter_sound: conference/conf-entry_sound.wav
variable_conference_exit_sound: conference/conf-exit_sound.wav
variable_ann_name: 0
variable_trunk_id: 3
variable_plan_id: 1
variable_prefix: 9
variable_COST: 0.00000
variable_c_cost: 0.00000
variable_n_init_inc: 60
variable_credit: 757.41913
variable_type: customer
variable_contact_id: 0
variable_customer_id: 1
variable_n_inc: 60
variable_n_rate: 0.00000
variable_n_acc: 1
variable_curr: 139
variable_cnf: 1
variable_grp_uuid: 201810030225421556918962
variable_cnf_flag: testrushika%2B%2Bflags%7Bendconf%7Cmoderator%7D
variable_originate_retries: 3
variable_enable_heartbeat_events: 10
variable_origination_caller_id_name: 919033769913
variable_origination_caller_id_number: 919033769913
variable_originate_early_media: false
variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201538513887%201538513888%20IN%20IP4%2081.95.124.27%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2081.95.124.27%0D%0At%3D0%200%0D%0Am%3Daudio%2019674%20RTP/AVP%200%208%209%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
variable_sip_outgoing_contact_uri: %3Csip%3Amod_sofia%4081.95.124.27%3A7651%3E
variable_sip_req_uri: 919033769913%40demo.inextrix.com%3A5061
variable_sofia_profile_name: default
variable_recovery_profile_name: default
variable_sip_local_network_addr: 81.95.124.27
variable_sip_reply_host: 159.203.131.36
variable_sip_reply_port: 5061
variable_sip_network_ip: 159.203.131.36
variable_sip_network_port: 5061
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_sip_user_agent: Asterisk%20PBX%2013.8.0
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH,%20MESSAGE
variable_sip_recover_contact: %3Csip%3A919033769913%40159.203.131.36%3A5061%3E
variable_sip_full_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKerZme8aH16ccj%3Breceived%3D81.95.124.27%3Brport%3D7651
variable_sip_recover_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKerZme8aH16ccj%3Breceived%3D81.95.124.27%3Brport%3D7651
variable_sip_from_display: 919033769913
variable_sip_full_from: %22919033769913%22%20%3Csip%3A919033769913%4081.95.124.27%3E%3Btag%3DQDc5017ymmvag
variable_sip_full_to: %3Csip%3A919033769913%40demo.inextrix.com%3A5061%3E%3Btag%3Das634ae868
variable_sip_from_user: 919033769913
variable_sip_from_uri: 919033769913%4081.95.124.27
variable_sip_from_host: 81.95.124.27
variable_sip_to_user: 919033769913
variable_sip_to_port: 5061
variable_sip_to_uri: 919033769913%40demo.inextrix.com%3A5061
variable_sip_to_host: demo.inextrix.com
variable_sip_contact_user: 919033769913
variable_sip_contact_port: 5061
variable_sip_contact_uri: 919033769913%40159.203.131.36%3A5061
variable_sip_contact_host: 159.203.131.36
variable_sip_to_tag: as634ae868
variable_sip_from_tag: QDc5017ymmvag
variable_sip_cseq: 128930716
variable_sip_call_id: 82e7ac49-4156-1237-49bb-1e86ec3fb419
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201284469274%201284469274%20IN%20IP4%20159.203.131.36%0D%0As%3DAsterisk%20PBX%2013.8.0%0D%0Ac%3DIN%20IP4%20159.203.131.36%0D%0At%3D0%200%0D%0Am%3Daudio%2018402%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dmaxptime%3A150%0D%0A
variable_rtp_use_codec_string: PCMU,PCMA,G722,G729
variable_rtp_audio_recv_pt: 0
variable_rtp_use_codec_name: PCMU
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
variable_read_codec: PCMU
variable_original_read_codec: PCMU
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMU
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_local_media_ip: 81.95.124.27
variable_local_media_port: 19674
variable_advertised_media_ip: 81.95.124.27
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 0
variable_rtp_use_ssrc: 1672817081
variable_rtp_2833_send_payload: 101
variable_rtp_2833_recv_payload: 101
variable_remote_media_ip: 159.203.131.36
variable_remote_media_port: 18402
variable_endpoint_disposition: ANSWER
variable_max_forwards: 70
variable_transfer_history: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
variable_transfer_source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
variable_call_uuid: b2d5766c-c510-4ab1-9061-59d9e5db1d69
Hunt-Direction: outbound
Hunt-Logical-Direction: outbound
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 919033769913
Hunt-Caller-ID-Number: 919033769913
Hunt-Orig-Caller-ID-Name: 919033769913
Hunt-Orig-Caller-ID-Number: 919033769913
Hunt-Callee-ID-Name: Outbound%20Call
Hunt-Callee-ID-Number: 919033769913
Hunt-Network-Addr: 159.203.131.36
Hunt-ANI: 919033769913
Hunt-Destination-Number: 0676338702
Hunt-Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
Hunt-Source: src/switch_ivr_originate.c
Hunt-Transfer-Source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
Hunt-Context: default
Hunt-RDNIS: 919033769913
Hunt-Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
Hunt-Profile-Index: 2
Hunt-Profile-Created-Time: 1538533561641624
Hunt-Channel-Created-Time: 1538533561401614
Hunt-Channel-Answered-Time: 1538533561641624
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false
'
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_value:
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] tag_name:
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_name:
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] section: dialplan
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Dialed number : 0676338702
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : 1
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call conference_flags : testrushika++flags{endconf|moderator}
2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : outbound
2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [GET_CONFERNCE] Query :SELECT t1.*,t2.conference_parameters from tbl_conference as t1,tbl_conference_parameters as t2 where t1.id= 1 and t2.conference_id=t1.id limit 1
2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="Audio Conference Dialplan">
<context name="default">
<extension name="0676338702">
<condition field="destination_number" expression="0676338702">
<action application="set" data="effective_destination_number=0676338702"/>
<action application="set" data="callstart=2018-10-03 02:26:01"/>
<action application="avmd" data="start"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=true"/>
<action application="set" data="ignore_early_media=true"/>
<action application="set" data="playback_terminators=none"/>
<action application="answer"/>
<action application="sleep" data="2000"/>
<action application="playback" data="conference/conf-welcome.wav"/>
<action application="sleep" data="2000"/>
<action application="set" data="conference_controls=plain"/>
<action application="conference" data="0676338702@testrushika++flags{endconf|moderator}"/>
<action application="avmd" data="stop"/>
</condition>
</extension>
<extension name="Announce Conf Count (Inline)">
<condition field="destination_number" expression="^ANNOUNCE_CONF_COUNT_PRIVATE$">
<action application="set" data="conf_count=${conference ${conference_name} list count}"/>
<action application="log" data="INFO ${conf_count}"/>
<action application="say" data="en number pronounced ${conf_count}"/>
</condition>
</extension>
</context>
</section>
</document>
2018-10-03 02:26:01.661612 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5f40468e70 released.
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 parsing [default->0676338702] continue=false
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Regex (PASS) [0676338702] destination_number(0676338702) =~ /0676338702/ break=on-false
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(effective_destination_number=0676338702)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(callstart=2018-10-03 02:26:01)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action avmd(start)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(continue_on_fail=true)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(ignore_early_media=true)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(playback_terminators=none)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action answer()
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action sleep(2000)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action playback(conference/conf-welcome.wav)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action sleep(2000)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(conference_controls=plain)
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action conference(0676338702@testrushika++flags{endconf|moderator})
Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action avmd(stop)
2018-10-03 02:26:01.661612 [INFO] switch_channel.c:3129 sofia/default/919033769913@demo.inextrix.com:5061 Flipping CID from "919033769913" <919033769913> to "Outbound Call" <919033769913>
2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:286 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_EXECUTE
2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING going to sleep
2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_EXECUTE (Cur 1 Tot 16)
2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:650 (sofia/default/919033769913@demo.inextrix.com:5061) State EXECUTE
2018-10-03 02:26:01.661612 [DEBUG] mod_sofia.c:198 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA EXECUTE
2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:328 sofia/default/919033769913@demo.inextrix.com:5061 Standard EXECUTE
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(effective_destination_number=0676338702)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [effective_destination_number]=[0676338702]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(callstart=2018-10-03 02:26:01)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [callstart]=[2018-10-03 02:26:01]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 avmd(start)
2018-10-03 02:26:01.661612 [WARNING] mod_avmd.c:1184 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:834 Avmd dynamic configuration: debug [0], report_status [1], fast_math [0], require_continuous_streak [1], sample_n_continuous_streak [15], sample_n_to_skip [15], simplified_estimation [1], inbound_channel [0], outbound_channel [1]
2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:372 Avmd session initialized, [8000] samples/s
2018-10-03 02:26:01.661612 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/919033769913@demo.inextrix.com:5061
2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:1109 Avmd on channel [sofia/default/919033769913@demo.inextrix.com:5061] started!
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(hangup_after_bridge=true)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [hangup_after_bridge]=[true]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(continue_on_fail=true)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [continue_on_fail]=[true]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(ignore_early_media=true)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [ignore_early_media]=[true]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(playback_terminators=none)
2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [playback_terminators]=[none]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 answer()
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 sleep(2000)
2018-10-03 02:26:02.181609 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-10-03 02:26:02.181609 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 playback(conference/conf-welcome.wav)
2018-10-03 02:26:03.701610 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
2018-10-03 02:26:04.921612 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/conference/conf-welcome.wav
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 sleep(2000)
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(conference_controls=plain)
2018-10-03 02:26:06.921614 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [conference_controls]=[plain]
EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 conference(0676338702@testrushika++flags{endconf|moderator})
2018-10-03 02:26:06.941609 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
2018-10-03 02:26:06.941609 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-10-03 02:26:06.941609 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-10-03 02:26:06.941609 [DEBUG] mod_conference.c:220 Setup timer success interval: 20 samples: 160
2018-10-03 02:26:06.941609 [DEBUG] switch_core_codec.c:223 sofia/default/919033769913@demo.inextrix.com:5061 Push codec L16:100
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '0' to 'mute'
2018-10-03 02:26:06.961620 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d480
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '1' to 'vol talk dn'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d4b0
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '2' to 'vol talk zero'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d4e0
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '3' to 'vol talk up'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d510
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '4' to 'vol listen dn'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d540
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '5' to 'vol listen zero'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d570
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '6' to 'vol listen up'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d5a0
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '7' to 'energy dn'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d5d0
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '8' to 'energy equ'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d600
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '9' to 'energy up'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d630
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '*' to 'deaf mute'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d660
2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '#' to 'execute_application'
2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d690
2018-10-03 02:26:06.961620 [INFO] mod_conference.c:370 Auto recording file: /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-26-06.wav
2018-10-03 02:26:06.961620 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20 samples: 160 from codec PCMU
2018-10-03 02:26:06.961620 [DEBUG] conference_loop.c:1292 Outbound conference channel answered, setting CFLAG_ANSWERED
2018-10-03 02:26:06.961620 [DEBUG] conference_record.c:302 Setup timer success interval: 20 samples: 160
2018-10-03 02:26:11.641612 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:26:21.661688 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:26:31.681678 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
freeswitch@astpp03> show calls
uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
b2d5766c-c510-4ab1-9061-59d9e5db1d69,outbound,2018-10-03 02:26:01,1538533561,sofia/default/919033769913@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,919033769913,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b2d5766c-c510-4ab1-9061-59d9e5db1d69,astpp03,,,,,,,,,,,,,,,,,,,,,,
1 total.
2018-10-03 02:26:41.701777 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:26:51.721681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:01.741646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:11.761609 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:21.781618 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:31.801681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:41.821681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:27:51.841604 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:28:01.861729 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:28:11.881744 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:28:21.901700 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:28:31.921678 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:28:41.941681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
freeswitch@astpp03> show calls
uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
b2d5766c-c510-4ab1-9061-59d9e5db1d69,outbound,2018-10-03 02:26:01,1538533561,sofia/default/919033769913@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,919033769913,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b2d5766c-c510-4ab1-9061-59d9e5db1d69,astpp03,,,,,,,,,,,,,,,,,,,,,,
1 total.
2018-10-03 02:28:51.961678 [DEBUG] mod_nibblebill.c:695 Received request via
2018-10-03 02:29:01.981646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:29:12.001672 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:29:22.021682 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:29:32.041672 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:29:42.061654 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:29:52.081642 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:02.101613 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:03.361616 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-10-03 02:30:03.361616 [NOTICE] switch_channel.c:1104 New Channel sofia/default/447375661939@demo.inextrix.com:5061 [b85448fa-575b-4b84-97a4-52d594b6b823]
2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:4819 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_NEW -> CS_INIT
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_INIT (Cur 2 Tot 17)
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:627 (sofia/default/447375661939@demo.inextrix.com:5061) State INIT
2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:90 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA INIT
2018-10-03 02:30:03.361616 [DEBUG] sofia_glue.c:1295 sofia/default/447375661939@demo.inextrix.com:5061 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1538508525 1538508526 IN IP4 81.95.124.27
s=FreeSWITCH
c=IN IP4 81.95.124.27
t=0 0
m=audio 25278 RTP/AVP 0 8 9 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:40 sofia/default/447375661939@demo.inextrix.com:5061 Standard INIT
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:48 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_INIT -> CS_ROUTING
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:627 (sofia/default/447375661939@demo.inextrix.com:5061) State INIT going to sleep
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 2 Tot 17)
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING
2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:143 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA ROUTING
2018-10-03 02:30:03.361616 [DEBUG] switch_ivr_originate.c:67 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING going to sleep
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 17)
2018-10-03 02:30:03.361616 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [calling][0]
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:662 (sofia/default/447375661939@demo.inextrix.com:5061) State CONSUME_MEDIA
2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:662 (sofia/default/447375661939@demo.inextrix.com:5061) State CONSUME_MEDIA going to sleep
2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [completing][200]
2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=root 776637440 776637440 IN IP4 159.203.131.36
s=Asterisk PBX 13.8.0
c=IN IP4 159.203.131.36
t=0 0
m=audio 14966 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=maxptime:150
2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [ready][200]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G722:9:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G722:9:8000:20:64000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/447375661939@demo.inextrix.com:5061 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2018-10-03 02:30:03.441605 [DEBUG] switch_core_codec.c:111 sofia/default/447375661939@demo.inextrix.com:5061 Original read codec set to PCMU:0
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4767 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101 recv payload to 101
2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/447375661939@demo.inextrix.com:5061] 81.95.124.27 port 25278 -> 159.203.131.36 port 14966 codec: 0 ms: 20
2018-10-03 02:30:03.441605 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7179 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101
2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7186 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf receive payload to 101
2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7209 sofia/default/447375661939@demo.inextrix.com:5061 Set rtp dtmf delay to 40
2018-10-03 02:30:03.461613 [INFO] switch_core_session.c:1619 sofia/default/447375661939@demo.inextrix.com:5061 setting session heartbeat to 10 second(s).
2018-10-03 02:30:03.461613 [NOTICE] sofia.c:8218 Channel [sofia/default/447375661939@demo.inextrix.com:5061] has been answered
2018-10-03 02:30:03.461613 [DEBUG] switch_channel.c:3773 (sofia/default/447375661939@demo.inextrix.com:5061) Callstate Change DOWN -> ACTIVE
2018-10-03 02:30:03.461613 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/447375661939@demo.inextrix.com:5061]
2018-10-03 02:30:03.461613 [DEBUG] switch_ivr.c:2165 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_CONSUME_MEDIA -> CS_ROUTING
2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 2 Tot 17)
2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING
2018-10-03 02:30:03.461613 [DEBUG] mod_sofia.c:143 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA ROUTING
2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:236 sofia/default/447375661939@demo.inextrix.com:5061 Standard ROUTING
2018-10-03 02:30:03.461613 [INFO] mod_dialplan_xml.c:637 Processing 919033769913 <919033769913>->0676338702 in context default
2018-10-03 02:30:03.461613 [NOTICE] switch_ivr.c:2172 Transfer sofia/default/447375661939@demo.inextrix.com:5061 to XML[0676338702@default]
2018-10-03 02:30:03.461613 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5f20073b10 Connected.
2018-10-03 02:30:03.461613 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [LOAD_CONF] Query :SELECT name,value FROM tbl_settings WHERE group_title IN ('global')
2018-10-03 02:30:03.461613 [NOTICE] switch_cpp.cpp:1365 [Audio Conference] [xml_handler] Params:
'Event-Name: REQUEST_PARAMS
Core-UUID: f4183a48-fe2b-4698-83d4-87d80b08381d
FreeSWITCH-Hostname: astpp03
FreeSWITCH-Switchname: astpp03
FreeSWITCH-IPv4: 81.95.124.27
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2018-10-03%2002%3A30%3A03
Event-Date-GMT: Wed,%2003%20Oct%202018%2002%3A30%3A03%20GMT
Event-Date-Timestamp: 1538533803461613
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 29864
Channel-State: CS_ROUTING
Channel-Call-State: ACTIVE
Channel-State-Number: 2
Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
Call-Direction: outbound
Presence-Call-Direction: outbound
Channel-HIT-Dialplan: false
Channel-Call-UUID: b85448fa-575b-4b84-97a4-52d594b6b823
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: outbound
Caller-Logical-Direction: outbound
Caller-Dialplan: XML
Caller-Caller-ID-Name: 919033769913
Caller-Caller-ID-Number: 919033769913
Caller-Orig-Caller-ID-Name: 919033769913
Caller-Orig-Caller-ID-Number: 919033769913
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 447375661939
Caller-Network-Addr: 159.203.131.36
Caller-ANI: 919033769913
Caller-Destination-Number: 0676338702
Caller-Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
Caller-Source: src/switch_ivr_originate.c
Caller-Context: default
Caller-RDNIS: 447375661939
Caller-Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
Caller-Profile-Index: 2
Caller-Profile-Created-Time: 1538533803461613
Caller-Channel-Created-Time: 1538533803361616
Caller-Channel-Answered-Time: 1538533803461613
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: outbound
variable_is_outbound: true
variable_uuid: b85448fa-575b-4b84-97a4-52d594b6b823
variable_session_id: 17
variable_sip_profile_name: default
variable_video_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_channel_name: sofia/default/447375661939%40demo.inextrix.com%3A5061
variable_sip_destination_url: sip%3A447375661939%40demo.inextrix.com%3A5061
variable_hangup_after_bridge: true
variable_ignore_early_media: true
variable_conference_enforce_security: true
variable_conference_enter_sound: conference/conf-entry_sound.wav
variable_conference_exit_sound: conference/conf-exit_sound.wav
variable_ann_name: 0
variable_trunk_id: 3
variable_plan_id: 1
variable_prefix: 4
variable_COST: 0.00000
variable_c_cost: 0.00000
variable_n_init_inc: 60
variable_type: contact
variable_contact_id: 60
variable_customer_id: 1
variable_n_inc: 60
variable_n_rate: 0.00000
variable_n_acc: 1
variable_curr: 139
variable_cnf: 1
variable_grp_uuid: 201810030225421556918962
variable_cnf_flag: testrushika%2B
variable_originate_retries: 3
variable_enable_heartbeat_events: 10
variable_origination_caller_id_name: 919033769913
variable_origination_caller_id_number: 919033769913
variable_originate_early_media: false
variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201538508525%201538508526%20IN%20IP4%2081.95.124.27%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2081.95.124.27%0D%0At%3D0%200%0D%0Am%3Daudio%2025278%20RTP/AVP%200%208%209%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
variable_sip_outgoing_contact_uri: %3Csip%3Amod_sofia%4081.95.124.27%3A7651%3E
variable_sip_req_uri: 447375661939%40demo.inextrix.com%3A5061
variable_sofia_profile_name: default
variable_recovery_profile_name: default
variable_sip_local_network_addr: 81.95.124.27
variable_sip_reply_host: 159.203.131.36
variable_sip_reply_port: 5061
variable_sip_network_ip: 159.203.131.36
variable_sip_network_port: 5061
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_sip_user_agent: Asterisk%20PBX%2013.8.0
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH,%20MESSAGE
variable_sip_recover_contact: %3Csip%3A447375661939%40159.203.131.36%3A5061%3E
variable_sip_full_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKgaj6HycrUrSHS%3Breceived%3D81.95.124.27%3Brport%3D7651
variable_sip_recover_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKgaj6HycrUrSHS%3Breceived%3D81.95.124.27%3Brport%3D7651
variable_sip_from_display: 919033769913
variable_sip_full_from: %22919033769913%22%20%3Csip%3A919033769913%4081.95.124.27%3E%3Btag%3Drp5X2vr2HXjXB
variable_sip_full_to: %3Csip%3A447375661939%40demo.inextrix.com%3A5061%3E%3Btag%3Das50869c30
variable_sip_from_user: 919033769913
variable_sip_from_uri: 919033769913%4081.95.124.27
variable_sip_from_host: 81.95.124.27
variable_sip_to_user: 447375661939
variable_sip_to_port: 5061
variable_sip_to_uri: 447375661939%40demo.inextrix.com%3A5061
variable_sip_to_host: demo.inextrix.com
variable_sip_contact_user: 447375661939
variable_sip_contact_port: 5061
variable_sip_contact_uri: 447375661939%40159.203.131.36%3A5061
variable_sip_contact_host: 159.203.131.36
variable_sip_to_tag: as50869c30
variable_sip_from_tag: rp5X2vr2HXjXB
variable_sip_cseq: 128930837
variable_sip_call_id: 131eec02-4157-1237-49bb-1e86ec3fb419
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%20776637440%20776637440%20IN%20IP4%20159.203.131.36%0D%0As%3DAsterisk%20PBX%2013.8.0%0D%0Ac%3DIN%20IP4%20159.203.131.36%0D%0At%3D0%200%0D%0Am%3Daudio%2014966%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dmaxptime%3A150%0D%0A
variable_rtp_use_codec_string: PCMU,PCMA,G722,G729
variable_rtp_audio_recv_pt: 0
variable_rtp_use_codec_name: PCMU
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
variable_read_codec: PCMU
variable_original_read_codec: PCMU
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMU
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_local_media_ip: 81.95.124.27
variable_local_media_port: 25278
variable_advertised_media_ip: 81.95.124.27
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 0
variable_rtp_use_ssrc: 2142584731
variable_rtp_2833_send_payload: 101
variable_rtp_2833_recv_payload: 101
variable_remote_media_ip: 159.203.131.36
variable_remote_media_port: 14966
variable_endpoint_disposition: ANSWER
variable_max_forwards: 70
variable_call_uuid: b85448fa-575b-4b84-97a4-52d594b6b823
Hunt-Direction: outbound
Hunt-Logical-Direction: outbound
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 919033769913
Hunt-Caller-ID-Number: 919033769913
Hunt-Orig-Caller-ID-Name: 919033769913
Hunt-Orig-Caller-ID-Number: 919033769913
Hunt-Callee-ID-Name: Outbound%20Call
Hunt-Callee-ID-Number: 447375661939
Hunt-Network-Addr: 159.203.131.36
Hunt-ANI: 919033769913
Hunt-Destination-Number: 0676338702
Hunt-Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
Hunt-Source: src/switch_ivr_originate.c
Hunt-Context: default
Hunt-RDNIS: 447375661939
Hunt-Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
Hunt-Profile-Index: 2
Hunt-Profile-Created-Time: 1538533803461613
Hunt-Channel-Created-Time: 1538533803361616
Hunt-Channel-Answered-Time: 1538533803461613
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false
'
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_value:
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_name:
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] tag_name:
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] section: dialplan
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Dialed number : 0676338702
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : 1
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call conference_flags : testrushika+
2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : outbound
2018-10-03 02:30:03.461613 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [GET_CONFERNCE] Query :SELECT t1.*,t2.conference_parameters from tbl_conference as t1,tbl_conference_parameters as t2 where t1.id= 1 and t2.conference_id=t1.id limit 1
2018-10-03 02:30:03.481610 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="Audio Conference Dialplan">
<context name="default">
<extension name="0676338702">
<condition field="destination_number" expression="0676338702">
<action application="set" data="effective_destination_number=0676338702"/>
<action application="set" data="callstart=2018-10-03 02:30:03"/>
<action application="avmd" data="start"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=true"/>
<action application="set" data="ignore_early_media=true"/>
<action application="set" data="playback_terminators=none"/>
<action application="answer"/>
<action application="sleep" data="2000"/>
<action application="playback" data="conference/conf-welcome.wav"/>
<action application="sleep" data="2000"/>
<action application="set" data="conference_controls=plain"/>
<action application="conference" data="0676338702@testrushika+"/>
<action application="avmd" data="stop"/>
</condition>
</extension>
<extension name="Announce Conf Count (Inline)">
<condition field="destination_number" expression="^ANNOUNCE_CONF_COUNT_PRIVATE$">
<action application="set" data="conf_count=${conference ${conference_name} list count}"/>
<action application="log" data="INFO ${conf_count}"/>
<action application="say" data="en number pronounced ${conf_count}"/>
</condition>
</extension>
</context>
</section>
</document>
2018-10-03 02:30:03.481610 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5f20073b10 released.
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 parsing [default->0676338702] continue=false
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Regex (PASS) [0676338702] destination_number(0676338702) =~ /0676338702/ break=on-false
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(effective_destination_number=0676338702)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(callstart=2018-10-03 02:30:03)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action avmd(start)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(continue_on_fail=true)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(ignore_early_media=true)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(playback_terminators=none)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action answer()
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action sleep(2000)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action playback(conference/conf-welcome.wav)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action sleep(2000)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(conference_controls=plain)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action conference(0676338702@testrushika+)
Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action avmd(stop)
2018-10-03 02:30:03.481610 [INFO] switch_channel.c:3129 sofia/default/447375661939@demo.inextrix.com:5061 Flipping CID from "919033769913" <919033769913> to "Outbound Call" <447375661939>
2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:286 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_EXECUTE
2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING going to sleep
2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_EXECUTE (Cur 2 Tot 17)
2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:650 (sofia/default/447375661939@demo.inextrix.com:5061) State EXECUTE
2018-10-03 02:30:03.481610 [DEBUG] mod_sofia.c:198 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA EXECUTE
2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:328 sofia/default/447375661939@demo.inextrix.com:5061 Standard EXECUTE
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(effective_destination_number=0676338702)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [effective_destination_number]=[0676338702]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(callstart=2018-10-03 02:30:03)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [callstart]=[2018-10-03 02:30:03]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 avmd(start)
2018-10-03 02:30:03.481610 [WARNING] mod_avmd.c:1184 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:834 Avmd dynamic configuration: debug [0], report_status [1], fast_math [0], require_continuous_streak [1], sample_n_continuous_streak [15], sample_n_to_skip [15], simplified_estimation [1], inbound_channel [0], outbound_channel [1]
2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:372 Avmd session initialized, [8000] samples/s
2018-10-03 02:30:03.481610 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/447375661939@demo.inextrix.com:5061
2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:1109 Avmd on channel [sofia/default/447375661939@demo.inextrix.com:5061] started!
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(hangup_after_bridge=true)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [hangup_after_bridge]=[true]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(continue_on_fail=true)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [continue_on_fail]=[true]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(ignore_early_media=true)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [ignore_early_media]=[true]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(playback_terminators=none)
2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [playback_terminators]=[none]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 answer()
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 sleep(2000)
2018-10-03 02:30:03.981612 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-10-03 02:30:03.981612 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 playback(conference/conf-welcome.wav)
2018-10-03 02:30:05.481614 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
2018-10-03 02:30:06.421614 [NOTICE] sofia.c:1012 Hangup sofia/default/919033769913@demo.inextrix.com:5061 [CS_EXECUTE] [NORMAL_CLEARING]
2018-10-03 02:30:06.421614 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING
2018-10-03 02:30:06.421614 [DEBUG] mod_conference.c:2247 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [UNBRIDGE] (channel is hungup already)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_media.c:9779 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [HARD_MUTE] (channel is hungup already)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_codec.c:248 sofia/default/919033769913@demo.inextrix.com:5061 Restore previous codec PCMU:0.
2018-10-03 02:30:06.421614 [DEBUG] switch_core_session.c:2815 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:650 (sofia/default/919033769913@demo.inextrix.com:5061) State EXECUTE going to sleep
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_HANGUP (Cur 2 Tot 17)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/919033769913@demo.inextrix.com:5061
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:850 (sofia/default/919033769913@demo.inextrix.com:5061) Callstate Change ACTIVE -> HANGUP
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:852 (sofia/default/919033769913@demo.inextrix.com:5061) State HANGUP
2018-10-03 02:30:06.421614 [DEBUG] mod_sofia.c:438 Channel sofia/default/919033769913@demo.inextrix.com:5061 hanging up, cause: NORMAL_CLEARING
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:60 sofia/default/919033769913@demo.inextrix.com:5061 Standard HANGUP, cause: NORMAL_CLEARING
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:852 (sofia/default/919033769913@demo.inextrix.com:5061) State HANGUP going to sleep
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:619 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_HANGUP -> CS_REPORTING
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_REPORTING (Cur 2 Tot 17)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:938 (sofia/default/919033769913@demo.inextrix.com:5061) State REPORTING
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:174 sofia/default/919033769913@demo.inextrix.com:5061 Standard REPORTING, cause: NORMAL_CLEARING
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:938 (sofia/default/919033769913@demo.inextrix.com:5061) State REPORTING going to sleep
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:610 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_REPORTING -> CS_DESTROY
2018-10-03 02:30:06.421614 [DEBUG] switch_core_session.c:1665 Session 16 (sofia/default/919033769913@demo.inextrix.com:5061) Locked, Waiting on external entities
2018-10-03 02:30:06.421614 [NOTICE] switch_core_session.c:1683 Session 16 (sofia/default/919033769913@demo.inextrix.com:5061) Ended
2018-10-03 02:30:06.421614 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/919033769913@demo.inextrix.com:5061 [CS_DESTROY]
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:741 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_DESTROY (Cur 1 Tot 17)
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:751 (sofia/default/919033769913@demo.inextrix.com:5061) State DESTROY
2018-10-03 02:30:06.421614 [DEBUG] mod_sofia.c:343 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA DESTROY
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:181 sofia/default/919033769913@demo.inextrix.com:5061 Standard DESTROY
2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:751 (sofia/default/919033769913@demo.inextrix.com:5061) State DESTROY going to sleep
2018-10-03 02:30:06.441612 [DEBUG] mod_conference.c:788 Write Lock ON
2018-10-03 02:30:06.441612 [INFO] conference_record.c:417 Recording of /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-26-06.wav Stopped
2018-10-03 02:30:06.441612 [DEBUG] mod_conference.c:791 Write Lock OFF
2018-10-03 02:30:06.721609 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/conference/conf-welcome.wav
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 sleep(2000)
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(conference_controls=plain)
2018-10-03 02:30:08.741615 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [conference_controls]=[plain]
EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 conference(0676338702@testrushika+)
2018-10-03 02:30:08.761608 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
2018-10-03 02:30:08.761608 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-10-03 02:30:08.761608 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2018-10-03 02:30:08.761608 [DEBUG] mod_conference.c:220 Setup timer success interval: 20 samples: 160
2018-10-03 02:30:08.761608 [DEBUG] switch_core_codec.c:223 sofia/default/447375661939@demo.inextrix.com:5061 Push codec L16:100
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '0' to 'mute'
2018-10-03 02:30:08.781610 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20077fd0
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '1' to 'vol talk dn'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078000
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '2' to 'vol talk zero'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078030
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '3' to 'vol talk up'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078060
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '4' to 'vol listen dn'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078090
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '5' to 'vol listen zero'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200780c0
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '6' to 'vol listen up'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200780f0
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '7' to 'energy dn'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078120
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '8' to 'energy equ'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078150
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '9' to 'energy up'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078180
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '*' to 'deaf mute'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200781b0
2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '#' to 'execute_application'
2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200781e0
2018-10-03 02:30:08.781610 [INFO] mod_conference.c:370 Auto recording file: /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-30-08.wav
2018-10-03 02:30:08.781610 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20 samples: 160 from codec PCMU
2018-10-03 02:30:08.781610 [DEBUG] conference_loop.c:1292 Outbound conference channel answered, setting CFLAG_ANSWERED
2018-10-03 02:30:08.781610 [DEBUG] conference_record.c:302 Setup timer success interval: 20 samples: 160
2018-10-03 02:30:13.441671 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:23.461635 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:33.481719 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:43.501607 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:30:53.521767 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:03.541665 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:13.561615 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:23.581774 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:33.601646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:43.621603 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:31:53.641609 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:03.661714 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:13.681673 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:23.701653 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:33.721606 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:43.741712 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:32:53.761642 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:33:03.781608 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:33:13.801645 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:33:23.821613 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:33:33.841619 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
freeswitch@astpp03>
2018-10-03 02:33:43.861648 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
freeswitch@astpp03> show calls
uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
b85448fa-575b-4b84-97a4-52d594b6b823,outbound,2018-10-03 02:30:03,1538533803,sofia/default/447375661939@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,447375661939,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b85448fa-575b-4b84-97a4-52d594b6b823,astpp03,,,,,,,,,,,,,,,,,,,,,,
1 total.
2018-10-03 02:33:53.881724 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:34:03.901611 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2018-10-03 02:34:08.221620 [DEBUG] switch_core_io.c:780 Engaging Read Buffer at 320 bytes vs 89
2018-10-03 02:34:08.221620 [NOTICE] sofia.c:1012 Hangup sofia/default/447375661939@demo.inextrix.com:5061 [CS_EXECUTE] [NORMAL_CLEARING]
2018-10-03 02:34:08.221620 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING
2018-10-03 02:34:08.221620 [DEBUG] mod_conference.c:2247 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [UNBRIDGE] (channel is hungup already)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_media.c:9779 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [HARD_MUTE] (channel is hungup already)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_codec.c:248 sofia/default/447375661939@demo.inextrix.com:5061 Restore previous codec PCMU:0.
2018-10-03 02:34:08.241620 [DEBUG] switch_core_session.c:2815 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:650 (sofia/default/447375661939@demo.inextrix.com:5061) State EXECUTE going to sleep
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_HANGUP (Cur 1 Tot 17)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/447375661939@demo.inextrix.com:5061
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:850 (sofia/default/447375661939@demo.inextrix.com:5061) Callstate Change ACTIVE -> HANGUP
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:852 (sofia/default/447375661939@demo.inextrix.com:5061) State HANGUP
2018-10-03 02:34:08.241620 [DEBUG] mod_sofia.c:438 Channel sofia/default/447375661939@demo.inextrix.com:5061 hanging up, cause: NORMAL_CLEARING
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:60 sofia/default/447375661939@demo.inextrix.com:5061 Standard HANGUP, cause: NORMAL_CLEARING
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:852 (sofia/default/447375661939@demo.inextrix.com:5061) State HANGUP going to sleep
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:619 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_HANGUP -> CS_REPORTING
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_REPORTING (Cur 1 Tot 17)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:938 (sofia/default/447375661939@demo.inextrix.com:5061) State REPORTING
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:174 sofia/default/447375661939@demo.inextrix.com:5061 Standard REPORTING, cause: NORMAL_CLEARING
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:938 (sofia/default/447375661939@demo.inextrix.com:5061) State REPORTING going to sleep
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:610 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_REPORTING -> CS_DESTROY
2018-10-03 02:34:08.241620 [DEBUG] switch_core_session.c:1665 Session 17 (sofia/default/447375661939@demo.inextrix.com:5061) Locked, Waiting on external entities
2018-10-03 02:34:08.241620 [NOTICE] switch_core_session.c:1683 Session 17 (sofia/default/447375661939@demo.inextrix.com:5061) Ended
2018-10-03 02:34:08.241620 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/447375661939@demo.inextrix.com:5061 [CS_DESTROY]
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:741 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_DESTROY (Cur 0 Tot 17)
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/447375661939@demo.inextrix.com:5061) State DESTROY
2018-10-03 02:34:08.241620 [DEBUG] mod_sofia.c:343 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA DESTROY
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:181 sofia/default/447375661939@demo.inextrix.com:5061 Standard DESTROY
2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/447375661939@demo.inextrix.com:5061) State DESTROY going to sleep
2018-10-03 02:34:08.241620 [DEBUG] mod_conference.c:788 Write Lock ON
2018-10-03 02:34:08.241620 [INFO] conference_record.c:417 Recording of /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-30-08.wav Stopped
2018-10-03 02:34:08.241620 [DEBUG] mod_conference.c:791 Write Lock OFF