March 2021 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Opus packet loss
by Terry C 15 Mar '21

15 Mar '21

Hi, We use FreeSwitch 1.10.5 with Opus audio for clients. We target voice quality to be wideband. The config settings are below. We also set a jitter buffer in the dialplan. Is there a downside to increasing the packet loss percent to 40-50% other than using more bandwidth? Is there a voice quality impact if you set it too high (what would be considered too high)? Thanks. <param name="use-vbr" value="1"/> <param name="use-dtx" value="1"/> <param name="complexity" value="10"/> <param name="packet-loss-percent" value="30"/> <param name="keep-fec-enabled" value="1"/> <param name="use-jb-lookahead" value="1"/> <param name="maxaveragebitrate" value="40000"/> <param name="maxplaybackrate" value="16000"/> <param name="sprop-maxcapturerate" value="16000"/> <param name="adjust-bitrate" value="1"/> </settings>

4 3

Re: [Freeswitch-users] rtp-timeout-sec VS media_timeout
by David P 13 Mar '21

13 Mar '21

Using FS10.5 installed from packages on Debian Stretch... I wanted to replicate the behavior we had with <param name="rtp-timeout-sec" value="300"/> so that we'll hangup calls in which leg B of a conference has sent no media for 5 mins. I edited our dialplan like this... <action application="conference_set_auto_outcall" data="['conference_member_flags=endconf,jitterbuffer_msec=5p:100p,media_timeout=300000']sofia/gateway/...deleted..."/> and restarted like this... fs_cli -x 'fsctl shutdown elegant restart' But after 8 mins in such a call there was no hangup. ---------- Forwarded message ---------- From: Dragos Oancea <dragos(a)freeswitch.org> To: FreeSWITCH Users Help <freeswitch-users(a)lists.freeswitch.org> Cc: Bcc: Date: Thu, 4 Mar 2021 11:04:26 +0200 Subject: Re: [Freeswitch-users] rtp-timeout-sec VS media_timeout For media timeout there are the following chan vars: media_timeout, media_hold_timeout, media_timeout, media_hold_timeout_video, media_hold_timeout_audio, media_timeout_audio . They are in milliseconds, not seconds like rtp-timeout-sec . <param name="media_timeout" value="300000"/>

1 0

Un-Subscribing on 481
by Joseph Barrero 12 Mar '21

12 Mar '21

Hi, everyone. I recently noticed that Freeswitch continues sending NOTIFY messages even after receiving a 481 from my proxy after not being able to deliver the messages to the UA. This seems to happen when the UA disconnects from the Internet briefly and registers again before the previous subscription expires. Below is the code I found in sofia.c that seems to handle the 481 response. if (status == 481 && sip && !sip->sip_retry_after && sip->sip_call_id && (!sofia_private || !sofia_private->is_call)) { char *sql; sql = switch_mprintf("delete from sip_subscriptions where call_id='%q'", sip->sip_call_id->i_id); switch_assert(sql != NULL); sofia_glue_execute_sql(profile, &sql, SWITCH_TRUE); nua_handle_destroy(nh); } Assuming that the variable sofia_private->is_call evaluates to true when NOTIFY messages are sent during a call, it seems to curtail the removal of the SUBSCRIPTION even after receiving a 481 from the proxy. Is there a drawback to removing a subscription on a 481 response during a call? Would it make sense to simply remove the check if there is a call in place? Is checking ofia_private->is_call there for another purpose? Thanks, Joe Barrero

2 1

Record Frequency Problems
by R G 12 Mar '21

12 Mar '21

Hi all, i've got a weird bug with the record_session function. I added he record_session function into my dialplan and set the record_sample_rate to 16000. When i use Google Chrome the record is fine and the wave file has a 16khz Sample Rate. When i use Mozilla Firefox the Sample Rate is at 32khz. my additions to the dialplan: <action application="set" data="RECORD_READ_ONLY=true"/> <action application="set" data="record_sample_rate=8000"/> <action application="record_session" data="/var/freeswitch/meetings/${strftime(%Y-%m-%d-%H-%M-%S)}_${call_uuid}.wav"/> Kind regards, Robert

1 0

No audio on bridged call
by Mircea Botoca-Huh 12 Mar '21

12 Mar '21

5 9

Re: [Freeswitch-users] using mod_unimrcp to transcribe voicemails
by David P 12 Mar '21

12 Mar '21

One of the ClueCon 2020 talks was by someone who uses mod_unimrcp and has a github project showing its FS integration, iirc.

3 3

Odd RTP skew behavior
by Marc Bernard 12 Mar '21

12 Mar '21

Hello Peeps, I often notice the following behavior on calls between two FreeSwitch servers: https://contattafiles.s3.us-west-1.amazonaws.com/getinfinity/4WUXPK9ibKr018c /Pasted%20Image%3A%20Jan%2029%2C%202021%20-%2010%3A05%3A07am Telco <==> FS Node 1 <== X ==> FS Node 2 <==> Callee UA Caller UA <==> FS Node 2 <== X ==> FS Node 1 <==> Telco We're not doing proxy media or bypass media. There are other calls at the same time that does not have this behavior, so I doubt it is caused by network issues. Does anyone have an idea of what could cause this ? Appreciate any help. Most Kindly, Marc

3 4

Pass sendonly from b-leg to a-leg
by Volodymyr Fedorov 12 Mar '21

12 Mar '21

Hello, I have following scenario : A (GW) -> Freeswitch -> B(Phone) When B-puts call on-hold and send to Freeswtich sendonly attribute, Freeswitch starts to play moh to A-party without re-invite with sendonly. Is there a way to send to A (GW) sendonly attribute using dialplan variable? Thanks for advice! -- Best regards, Volodymyr

2 2

User_Not_Registered
by CRAIG WILSON 11 Mar '21

11 Mar '21

Hi, I am using ASTPP on top of FS. I am receiving an inbound DID. However, my SIP Trace shows error "User_Not_Registered" see (sip trace log below). Running command list_user reveals nothing and sofia_contact reveals "User_Not_Registered". Show Registrations - reveals: 9856937967,myrevbill.com,8vSSYnN7QcIRw5u3nfg5pA..,sofia/sip-ip/sip:9856937967@92.7.187.203:39706;transport=UDP;rinstance=ec30c6121794d08f,1615480452,92.7.187.203,39706,udp,astpp.myrevbill.com, 1 total. Then I ran command below: sofia status profile sip-ip reg Registrations: ================================================================================================= Call-ID: S3gXf-fjxpA9ws47L8bh9w.. User: 9856937967(a)myrevbill.com Contact: "" <sip:9856937967@92.7.187.203:62990;transport=UDP;rinstance=df94511808fbcf07> Agent: Z 5.4.9 rv2.10.11.7 Status: Registered(UDP)(unknown) EXP(2021-03-11 11:03:29) EXPSECS(102) Ping-Status: Reachable Ping-Time: 0.00 Host: astpp.myrevbill.com IP: 92.7.187.203 Port: 62990 Auth-User: 9856937967 Auth-Realm: myrevbill.com MWI-Account: 9856937967(a)myrevbill.com Total items returned: 1 Sip Trace Log: 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_NEW (Cur 1 Tot 1) 2021-03-10 12:20:17.534757 [DEBUG] sofia.c:9873 sofia/sip-ip/01226971834(a)10.0.1.29 receiving invite from 10.0.1.29:5060 version: 1.6.20 64bit 2021-03-10 12:20:17.534757 [DEBUG] sofia.c:9989 IP 10.0.1.29 Approved by acl "default[]". Access Granted. 2021-03-10 12:20:17.534757 [DEBUG] sofia.c:7084 Channel sofia/sip-ip/01226971834(a)10.0.1.29 entering state [received][100] 2021-03-10 12:20:17.534757 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 283383691 283383691 IN IP4 10.0.1.29 s=Asterisk c=IN IP4 10.0.1.29 t=0 0 m=audio 11144 RTP/AVP 0 8 9 4 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:9 G722/8000 a=rtpmap:4 G723/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMU:0:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMA:8:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G723:4:8000:20:6300:1]/[G729:18:8000:20:8000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1] 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:3061 Set Codec sofia/sip-ip/01226971834(a)10.0.1.29 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2021-03-10 12:20:17.534757 [DEBUG] switch_core_codec.c:111 sofia/sip-ip/01226971834(a)10.0.1.29 Original read codec set to PCMU:0 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2021-03-10 12:20:17.534757 [DEBUG] switch_core_media.c:4767 sofia/sip-ip/01226971834(a)10.0.1.29 Set 2833 dtmf send payload to 101 recv payload to 101 2021-03-10 12:20:17.534757 [DEBUG] sofia.c:7507 (sofia/sip-ip/01226971834(a)10.0.1.29) State Change CS_NEW -> CS_INIT 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:603 (sofia/sip-ip/01226971834(a)10.0.1.29) State NEW 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_INIT (Cur 1 Tot 1) 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:627 (sofia/sip-ip/01226971834(a)10.0.1.29) State INIT 2021-03-10 12:20:17.534757 [DEBUG] mod_sofia.c:90 sofia/sip-ip/01226971834(a)10.0.1.29 SOFIA INIT 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:40 sofia/sip-ip/01226971834(a)10.0.1.29 Standard INIT 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:48 (sofia/sip-ip/01226971834(a)10.0.1.29) State Change CS_INIT -> CS_ROUTING 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:627 (sofia/sip-ip/01226971834(a)10.0.1.29) State INIT going to sleep 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_ROUTING (Cur 1 Tot 1) 2021-03-10 12:20:17.534757 [DEBUG] switch_channel.c:2249 (sofia/sip-ip/01226971834(a)10.0.1.29) Callstate Change DOWN -> RINGING 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:643 (sofia/sip-ip/01226971834(a)10.0.1.29) State ROUTING 2021-03-10 12:20:17.534757 [DEBUG] mod_sofia.c:143 sofia/sip-ip/01226971834(a)10.0.1.29 SOFIA ROUTING 2021-03-10 12:20:17.534757 [DEBUG] switch_core_state_machine.c:236 sofia/sip-ip/01226971834(a)10.0.1.29 Standard ROUTING 2021-03-10 12:20:17.534757 [INFO] mod_dialplan_xml.c:637 Processing 01226971834 <01226971834>->03302290443 in context default 2021-03-10 12:20:17.596174 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f3ebc1cae70 Connected. 2021-03-10 12:20:17.596174 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2021-03-10 12:20:17.596174 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2021-03-10 12:20:17.596174 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 03302290443 2021-03-10 12:20:17.596174 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '03302290443' AND status=0 limit 1 2021-03-10 12:20:17.596174 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number : 01226971834 / 01226971834 2021-03-10 12:20:17.596174 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="03302290443" LIMIT 1 2021-03-10 12:20:17.596174 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : inbound 2021-03-10 12:20:17.596174 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("10.0.1.29") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 - SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1)) AND "03302290443" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2021-03-10 12:20:17.634761 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 9856937967] 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "9856937967" AND deleted = 0 limit 1 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="03302290443" AND A.accountid = '13' limit 1 2021-03-10 12:20:17.634761 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 03302290443 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="03302290443" LIMIT 1 2021-03-10 12:20:17.634761 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : inbound 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="03302290443" AND (accountid = 0 OR status = 1) LIMIT 1 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :2 2021-03-10 12:20:17.634761 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^03302290443.*' OR patterns = '^0330229044.*' OR patterns = '^033022904.*' OR patterns = '^03302290.*' OR patterns = '^0330229.*' OR patterns = '^033022.*' OR patterns = '^03302.*' OR patterns = '^0330.*' OR patterns = '^033.*' OR patterns = '^03.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 2 ORDER BY LENGTH(PKGPTR.patterns) DESC 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information =================== 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 13 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 9856937967 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 10000 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid] 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] ======================================================== 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :inbound 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="03302290443" LIMIT 1 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: inbound 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information =================== 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 1.30378 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : 03302290443 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 200 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 13 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================ 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 13 !!! 2021-03-10 12:20:17.655679 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 100 mins! 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] INB_FREE:TRUE 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] free_inbound:1 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 13 AND status=0 LIMIT 1 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "2" AND deleted = 0 limit 1 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML:13 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Userinfo XML:13 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML : 13 2021-03-10 12:20:17.655679 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 100 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_0 2021-03-10 12:20:17.655679 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?> <document type="freeswitch/xml"> <section name="dialplan" description="ASTPP Dialplan"> <context name="default"> <extension name="03302290443"> <condition field="destination_number" expression="03302290443"> <action application="set" data="effective_destination_number=03302290443"/> <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/> <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/> <action application="set" data="callstart=2021-03-10 12:20:17"/> <action application="set" data="hangup_after_bridge=true"/> <action application="set" data="continue_on_fail=TRUE"/> <action application="set" data="account_id=13"/> <action application="set" data="parent_id=0"/> <action application="set" data="entity_id=3"/> <action application="set" data="call_processed=internal"/> <action application="set" data="call_direction=inbound"/> <action application="set" data="accountname=FreePBX"/> <action application="set" data="origination_rates_did=ID:1|CODE:^03302290443.*|DESTINATION:03302290443|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:1.30378|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:200|ACCID:2"/> <action application="set" data="origination_rates=0"/> <action application="set" data="original_caller_id_name=01226971834"/> <action application="set" data="original_caller_id_number=01226971834"/> <action application="set" data="effective_caller_id_name=01226971834"/> <action application="set" data="effective_caller_id_number=01226971834"/> <action application="set" data="receiver_accid=2"/> <action application="export" data="presence_data=x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID"/> <action application="export" data="call_type=0"/> <action application="set" data="calltype=DID-LOCAL"/> <action application="bridge" data="[leg_timeout=0]user/9856937967@${domain_name}"/> <condition field="${cond(${user_data 9856937967@${domain_name} param vm-enabled} == true ? YES : NO)}" expression="^YES$"> <action application="answer"/> <action application="export" data="voicemail_alternate_greet_id=03302290443"/> <action application="voicemail" data="default $${domain_name} 9856937967"/> <anti-action application="hangup" data="${originate_disposition}"/> </condition> </condition> </extension> </context> </section> </document> 2021-03-10 12:20:17.655679 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f3ebc1cae70 released. Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 parsing [default->03302290443] continue=false Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Regex (PASS) [03302290443] destination_number(03302290443) =~ /03302290443/ break=on-false Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(effective_destination_number=03302290443) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(bridge_pre_execute_bleg_app=sched_hangup) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(callstart=2021-03-10 12:20:17) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(hangup_after_bridge=true) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(continue_on_fail=TRUE) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(account_id=13) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(parent_id=0) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(entity_id=3) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(call_processed=internal) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(call_direction=inbound) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(accountname=FreePBX) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(origination_rates_did=ID:1|CODE:^03302290443.*|DESTINATION:03302290443|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:1.30378|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:200|ACCID:2) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(origination_rates=0) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(original_caller_id_name=01226971834) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(original_caller_id_number=01226971834) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(effective_caller_id_name=01226971834) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(effective_caller_id_number=01226971834) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(receiver_accid=2) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action export(presence_data=x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action export(call_type=0) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action set(calltype=DID-LOCAL) Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action bridge([leg_timeout=0]user/9856937967@${domain_name}) |--- Dialplan: Processing recursive conditions level:1 [03302290443_recur_1] require-nested=TRUE |--- Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Regex (PASS) [03302290443_recur_1] ${cond(${user_data 9856937967@${domain_name} param vm-enabled} == true ? YES : NO)}(YES) =~ /^YES$/ break=on-false |--- Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action answer() |--- Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action export(voicemail_alternate_greet_id=03302290443) |--- Dialplan: sofia/sip-ip/01226971834(a)10.0.1.29 Action voicemail(default $${domain_name} 9856937967) 2021-03-10 12:20:17.682238 [DEBUG] switch_core_state_machine.c:286 (sofia/sip-ip/01226971834(a)10.0.1.29) State Change CS_ROUTING -> CS_EXECUTE 2021-03-10 12:20:17.682238 [DEBUG] switch_core_state_machine.c:643 (sofia/sip-ip/01226971834(a)10.0.1.29) State ROUTING going to sleep 2021-03-10 12:20:17.682238 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_EXECUTE (Cur 1 Tot 1) 2021-03-10 12:20:17.682238 [DEBUG] switch_core_state_machine.c:650 (sofia/sip-ip/01226971834(a)10.0.1.29) State EXECUTE 2021-03-10 12:20:17.682238 [DEBUG] mod_sofia.c:198 sofia/sip-ip/01226971834(a)10.0.1.29 SOFIA EXECUTE 2021-03-10 12:20:17.682238 [DEBUG] switch_core_state_machine.c:328 sofia/sip-ip/01226971834(a)10.0.1.29 Standard EXECUTE EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(effective_destination_number=03302290443) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [effective_destination_number]=[03302290443] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(bridge_pre_execute_bleg_app=sched_hangup) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [bridge_pre_execute_bleg_app]=[sched_hangup] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(bridge_pre_execute_bleg_data=+6000 normal_clearing) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(callstart=2021-03-10 12:20:17) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [callstart]=[2021-03-10 12:20:17] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(hangup_after_bridge=true) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [hangup_after_bridge]=[true] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(continue_on_fail=TRUE) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [continue_on_fail]=[TRUE] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(account_id=13) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [account_id]=[13] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(parent_id=0) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [parent_id]=[0] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(entity_id=3) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [entity_id]=[3] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(call_processed=internal) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [call_processed]=[internal] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(call_direction=inbound) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [call_direction]=[inbound] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(accountname=FreePBX) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [accountname]=[FreePBX] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(origination_rates_did=ID:1|CODE:^03302290443.*|DESTINATION:03302290443|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:1.30378|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:200|ACCID:2) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [origination_rates_did]=[ID:1|CODE:^03302290443.*|DESTINATION:03302290443|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:1.30378|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:200|ACCID:2] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(origination_rates=0) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [origination_rates]=[0] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(original_caller_id_name=01226971834) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [original_caller_id_name]=[01226971834] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(original_caller_id_number=01226971834) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [original_caller_id_number]=[01226971834] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(effective_caller_id_name=01226971834) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [effective_caller_id_name]=[01226971834] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(effective_caller_id_number=01226971834) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [effective_caller_id_number]=[01226971834] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(receiver_accid=2) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [receiver_accid]=[2] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 export(presence_data=x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID) 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 export(call_type=0) 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_type]=[0] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 set(calltype=DID-LOCAL) 2021-03-10 12:20:17.682238 [DEBUG] mod_dptools.c:1548 SET sofia/sip-ip/01226971834(a)10.0.1.29 [calltype]=[DID-LOCAL] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 bridge([leg_timeout=0]user/9856937967(a)10.0.1.212) 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1250 sofia/sip-ip/01226971834(a)10.0.1.29 EXPORTING[export_vars] [presence_data]=[x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID] to event 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1250 sofia/sip-ip/01226971834(a)10.0.1.29 EXPORTING[export_vars] [call_type]=[0] to event 2021-03-10 12:20:17.682238 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2021-03-10 12:20:17.682238 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1250 sofia/sip-ip/01226971834(a)10.0.1.29 EXPORTING[export_vars] [presence_data]=[x|||Dial9(9856937967)|||^03302290443.* // 03302290443 // 1.30378||||||DID] to event 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1250 sofia/sip-ip/01226971834(a)10.0.1.29 EXPORTING[export_vars] [call_type]=[0] to event 2021-03-10 12:20:17.682238 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2021-03-10 12:20:17.682238 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED] 2021-03-10 12:20:17.682238 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2021-03-10 12:20:17.682238 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [USER_NOT_REGISTERED] 2021-03-10 12:20:17.682238 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 606 [USER_NOT_REGISTERED] 2021-03-10 12:20:17.682238 [INFO] mod_dptools.c:3436 Originate Failed. Cause: USER_NOT_REGISTERED EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 answer() 2021-03-10 12:20:17.682238 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/sip-ip/01226971834(a)10.0.1.29] 10.0.1.212 port 16840 -> 10.0.1.29 port 11144 codec: 0 ms: 20 2021-03-10 12:20:17.682238 [DEBUG] switch_rtp.c:4159 Not using a timer 2021-03-10 12:20:17.682238 [DEBUG] switch_core_media.c:7180 sofia/sip-ip/01226971834(a)10.0.1.29 Set 2833 dtmf send payload to 101 2021-03-10 12:20:17.682238 [DEBUG] switch_core_media.c:7187 sofia/sip-ip/01226971834(a)10.0.1.29 Set 2833 dtmf receive payload to 101 2021-03-10 12:20:17.682238 [DEBUG] switch_core_media.c:7210 sofia/sip-ip/01226971834(a)10.0.1.29 Set rtp dtmf delay to 40 2021-03-10 12:20:17.682238 [DEBUG] mod_sofia.c:850 Local SDP sofia/sip-ip/01226971834(a)10.0.1.29: v=0 o=FreeSWITCH 1615361977 1615361978 IN IP4 10.0.1.212 s=FreeSWITCH c=IN IP4 10.0.1.212 t=0 0 m=audio 16840 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2021-03-10 12:20:17.682238 [NOTICE] mod_dptools.c:1312 Channel [sofia/sip-ip/01226971834(a)10.0.1.29] has been answered 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:3773 (sofia/sip-ip/01226971834(a)10.0.1.29) Callstate Change RINGING -> ACTIVE EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 export(voicemail_alternate_greet_id=03302290443) 2021-03-10 12:20:17.682238 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [voicemail_alternate_greet_id]=[03302290443] EXECUTE sofia/sip-ip/01226971834(a)10.0.1.29 voicemail(default 10.0.1.212 9856937967) 2021-03-10 12:20:17.715099 [DEBUG] sofia.c:7084 Channel sofia/sip-ip/01226971834(a)10.0.1.29 entering state [completed][200] 2021-03-10 12:20:17.715099 [DEBUG] sofia.c:7084 Channel sofia/sip-ip/01226971834(a)10.0.1.29 entering state [ready][200] 2021-03-10 12:20:49.734765 [NOTICE] sofia.c:1012 Hangup sofia/sip-ip/01226971834(a)10.0.1.29 [CS_EXECUTE] [NORMAL_CLEARING] 2021-03-10 12:20:49.734765 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en] 2021-03-10 12:20:49.734765 [DEBUG] switch_core_session.c:2815 sofia/sip-ip/01226971834(a)10.0.1.29 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:650 (sofia/sip-ip/01226971834(a)10.0.1.29) State EXECUTE going to sleep 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_HANGUP (Cur 1 Tot 1) 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:850 (sofia/sip-ip/01226971834(a)10.0.1.29) Callstate Change ACTIVE -> HANGUP 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:852 (sofia/sip-ip/01226971834(a)10.0.1.29) State HANGUP 2021-03-10 12:20:49.734765 [DEBUG] mod_sofia.c:438 Channel sofia/sip-ip/01226971834(a)10.0.1.29 hanging up, cause: NORMAL_CLEARING 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:60 sofia/sip-ip/01226971834(a)10.0.1.29 Standard HANGUP, cause: NORMAL_CLEARING 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:852 (sofia/sip-ip/01226971834(a)10.0.1.29) State HANGUP going to sleep 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:619 (sofia/sip-ip/01226971834(a)10.0.1.29) State Change CS_HANGUP -> CS_REPORTING 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:584 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_REPORTING (Cur 1 Tot 1) 2021-03-10 12:20:49.734765 [DEBUG] switch_core_state_machine.c:938 (sofia/sip-ip/01226971834(a)10.0.1.29) State REPORTING 2021-03-10 12:20:49.734765 [INFO] mod_json_cdr.c:271 Process [f9793c2e-819a-11eb-b65f-8914a0e01ab2.cdr.json] 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:174 sofia/sip-ip/01226971834(a)10.0.1.29 Standard REPORTING, cause: NORMAL_CLEARING 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:938 (sofia/sip-ip/01226971834(a)10.0.1.29) State REPORTING going to sleep 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:610 (sofia/sip-ip/01226971834(a)10.0.1.29) State Change CS_REPORTING -> CS_DESTROY 2021-03-10 12:20:49.794754 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/sip-ip/01226971834(a)10.0.1.29) Locked, Waiting on external entities 2021-03-10 12:20:49.794754 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/sip-ip/01226971834(a)10.0.1.29) Ended 2021-03-10 12:20:49.794754 [NOTICE] switch_core_session.c:1687 Close Channel sofia/sip-ip/01226971834(a)10.0.1.29 [CS_DESTROY] 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:741 (sofia/sip-ip/01226971834(a)10.0.1.29) Running State Change CS_DESTROY (Cur 0 Tot 1) 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:751 (sofia/sip-ip/01226971834(a)10.0.1.29) State DESTROY 2021-03-10 12:20:49.794754 [DEBUG] mod_sofia.c:343 sofia/sip-ip/01226971834(a)10.0.1.29 SOFIA DESTROY 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:181 sofia/sip-ip/01226971834(a)10.0.1.29 Standard DESTROY 2021-03-10 12:20:49.794754 [DEBUG] switch_core_state_machine.c:751 (sofia/sip-ip/01226971834(a)10.0.1.29) State DESTROY going to sleep freeswitch(a)astpp.myrevbill.com> Thanks!!

1 0

BLF under high load
by Peter Steinbach 11 Mar '21

11 Mar '21

Hello we have a Freeswitch installation for a multi tenant environment. We have extensively tested our Freeswitch for all call scenarios and all worked fine. This is the good news. However, under higher load (>250 Channels and >6000 Subscriptions), presence does not seem to work reliably. I've found some ealier posts on this issue, which seem to lead to the following 2 solutions * use PostgreSQL (we use Mariadb via ODBC, also tested mod_mariadb, which made things worse) * Move presence handling to Kamailio As your environment is based on MySQL, we do not want to move to PostgreSQL without knowing, whether this will improve the situation. So far we are catching events via ESL or even grepping the network and correct the status of presence informatons sent to the phone. However we discover, that even if we send corrected PRESENCE_IN via event socket, our Freeswitch keeps on sending the wrong BLF information afterwards to the phone. Means BLFs are blinking or in state red event if the watched phone is not in a call. So at present we are grepping all presence informations on the network, crosscheck this with the channels table and resend the corrected BLF status via event_socket if needed. But still this is not sufficiant. So I am now asking the community: What is your approach to overcome this? * Do newer Freeswitch versions perform better for this case?(Ours is "FreeSWITCH Version 1.10.5-release+git~20200818T185121Z") * which work have you done to overcome this? * does anybody have an idea how to query all internal presence status from Freewitch or maybe how to correct it? Thank you in advance. Any help is appreciated. Peter

2 3