freeswitch@astpp03> 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:
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 SESSION_HEARTBEAT! 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:
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