March 2021 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Mod_lua: dbh open to many connections to database
by António Silva 29 Mar '21

29 Mar '21

Hi, After migrate an old server from debian jessie to debian buster, hit a problem of cpu load increasing and block the server at 100% of cpu usage. I notice that was the number of postgresql open process. Doing a simple sipp of 40cps to stress the server I notice that the problem is in a lua script, the script check if the caller is a table (database postgres) and allows or not the call. in debian jessie the db handles open (using db_cache status) are less than 20, but now in debian buster it scales to more that 100, and I start to see error messages that postgress cannot open more connections (that is true I’ve limited to 100 connections). Anyone experience this problem? I also try to set the max-db-handles open to 50, in switch.xml, but it ignores this parameter - I think that is only use in internal modules and not for lua scripts… Can I limit the number of open connections when using FS dbh? The function I get to fetch the result: filename = "pgsql://host=/var/run/postgresql/ user=postgres dbname=list" callback_query_fetch = function (row) if (single_row == true) then result = row return 1 -- break the loop end if (result == false) then result = {} end table.insert(result, row) return 0 end function db.fetch_single(filename, sql) local dbh = freeswitch.Dbh(filename) result = false single_row = true if (dbh:query(sql, callback_query_fetch) == false) then dbh:release() return false end dbh:release() return result end -- Saludos / Regards / Cumprimentos António Silva

1 1

No 200 Okay on Re-Invite (Hold)
by Hamid Hashmi 25 Mar '21

25 Mar '21

1. A called B through FS server with bypass-media=true and proxy-hold=true. 2. A holds Call after Call is answered. 3. FS relayed INVITE with SDP Attribute Sendonly to B. 4. B responded with 200 Okay with SDP Attribute recvonly. 5. FS doesn't relay 200 okay back to A. What I am doing wrong ? Is there any variable I need to set with proxy-hold ? Regards Hamid R. Hashmi

1 0

Re: [Freeswitch-users] FW: User_Not_Registered
by Martin Paterson 25 Mar '21

25 Mar '21

Your registration is to <number>@myrevbill.com, but the bridge is going to <number>@10.0.1.29. Best wishes, Martin. Martin Paterson, Pattersong Music Reduced orchestrations of G&S *From:* FreeSWITCH-users <freeswitch-users-bounces(a)lists.freeswitch.org> *On > Behalf Of *CRAIG WILSON > *Sent:* 11 March 2021 16:50 > *To:* freeswitch-users(a)lists.freeswitch.org > *Subject:* [Freeswitch-users] User_Not_Registered > > > > 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

Using TLS certificate with intermediate CA
by Thilo-Alexander Ginkel 24 Mar '21

24 Mar '21

Hello everyone, I am currently struggling to get FreeSWITCH (1.10.5-release-17-25569c1631~64bit) to send the intermediate CA certificate for a Let's Encrypt X.509 certificate to be used for protecting SIPS traffic. I included the certificate chain in agent.pem: -- 8< -- -----BEGIN EC PARAMETERS----- *REDACTED* -----END EC PARAMETERS----- -----BEGIN EC PRIVATE KEY----- *REDACTED* -----END EC PRIVATE KEY----- -----BEGIN CERTIFICATE----- *SERVER CERT* -----END CERTIFICATE----- -----BEGIN CERTIFICATE----- *INTERMEDIATE CERT* -----END CERTIFICATE----- -- 8< -- Still, clients are complaining about an invalid CA and openssl s_client hints at only the server cert being sent in the server hello. What did I miss? Thanks, Thilo

2 1

Stopping TTS when play_and_detect_speech gets MRCP START-OF-INPUT
by mayamatakeshi 21 Mar '21

21 Mar '21

Hi, I'm trying play_and_detect_speech with an ESL app but START-OF-INPUT doesn't interrupt the TTS being played. Is this expected? ( https://freeswitch.org/confluence/display/FREESWITCH/mod_dptools%3A+play_an… is not clear about it) Or maybe I need to set some channel variable for this to work.

2 5

Check if an audio file exists to play an alternative one if not
by Lucas Ducculi 19 Mar '21

19 Mar '21

Different macros are defined in the phrases.xml file, I want to know if it is possible to implement a condition in the macro to check if the audio file exists and if not, play an alternative one. Thanks. <macro name="greeting"> <input pattern="(.*)"> <match> <action function="play-file" data="/home/admin/freeswitch/conf/lang/en/ivr/wav/greeting.wav"/> </match> </input> </macro> -- Lucas Eduardo Dúcculi Software Developer lucaseduc(a)hotmail.com +54 0358 4853837 lucas(a)toptive.co ------------------------------ TOP TIER DEVELOPERS Av. Italia 1684 - Plata Alta, Río Cuarto, Cba. Argentina www.toptive.co [image: LinkedIn icon] <https://www.linkedin.com/company/toptive> [image: Facebook icon] <https://www.facebook.com/toptive/> [image: Facebook icon] <https://www.instagram.com/toptierdevelopers/> [image: Facebook icon] <https://medium.com/@toptive> This email is confidential and for the exclusive use of the person(s) to whom it is addressed. If you have received this email by mistake we ask you to immediately notify the person who sent it.

1 0

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

19 Mar '21

> > Yes, we want to hangup if media_timeout expires on leg b of the conference. This seems to work but seemingly only after reboot, not the 'elegant' restart I tried initially. Btw, still eager for any news on 'unified-plan' support. ---------- Forwarded message ---------- > From: Brian West <brian(a)freeswitch.com> > To: FreeSWITCH Users Help <freeswitch-users(a)lists.freeswitch.org> > Cc: > Bcc: > Date: Wed, 17 Mar 2021 22:46:54 -0500 > Subject: Re: [Freeswitch-users] rtp-timeout-sec VS media_timeout > Are you trying to hang the call up? > > On Mon, Mar 15, 2021 at 5:45 PM David P <davidswalkabout(a)gmail.com> wrote: > >> Although I didn't see that adding... >> >> media_timeout=300000 >> >> ...to our conference settings have any effect after restarting FS10.5 >> this way... >> >> fs_cli -x 'fsctl shutdown elegant restart' >> >> ...I do see it having an effect now. I suspect there's been a reboot >> since then. FYI >> >> On Sun, Mar 14, 2021 at 1:00 AM < >> freeswitch-users-request(a)lists.freeswitch.org> wrote: >> >>> >>> ---------- Forwarded message ---------- >>> From: David P <davidswalkabout(a)gmail.com> >>> To: FreeSWITCH Users Help <freeswitch-users(a)lists.freeswitch.org> >>> Cc: >>> Bcc: >>> Date: Sat, 13 Mar 2021 16:15:55 +1300 >>> Subject: Re: [Freeswitch-users] rtp-timeout-sec VS media_timeout >>> 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. >>> >> >>

1 0

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

18 Mar '21

Although I didn't see that adding... media_timeout=300000 ...to our conference settings have any effect after restarting FS10.5 this way... fs_cli -x 'fsctl shutdown elegant restart' ...I do see it having an effect now. I suspect there's been a reboot since then. FYI On Sun, Mar 14, 2021 at 1:00 AM < freeswitch-users-request(a)lists.freeswitch.org> wrote: > > ---------- Forwarded message ---------- > From: David P <davidswalkabout(a)gmail.com> > To: FreeSWITCH Users Help <freeswitch-users(a)lists.freeswitch.org> > Cc: > Bcc: > Date: Sat, 13 Mar 2021 16:15:55 +1300 > Subject: Re: [Freeswitch-users] rtp-timeout-sec VS media_timeout > 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. >

2 1

Will fail2ban work for this?
by Steven Schoch 17 Mar '21

17 Mar '21

I just set up a new FreeSWITCH system on my home network, and set a forward for port 5080 to connect to Flowroute. While I'm debugging some call routing stuff, my logs are getting overrun with stuff like this: 2021-03-16 15:52:02.267501 [NOTICE] switch_channel.c:1118 New Channel sofia/external/7750@<my IP> [2de89b87-cd07-4c0f-b9fb-3da8e5a68d37] 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:585 (sofia/external/7750@<my IP>) Running State Change CS_NEW (Cur 1 Tot 7822) 2021-03-16 15:52:02.267501 [DEBUG] sofia.c:10280 sofia/external/7750@<my IP> receiving invite from 80.94.93.12:62635 version: 1.10.5 -release-17-25569c1631 64bit 2021-03-16 15:52:02.267501 [DEBUG] sofia.c:7326 Channel sofia/external/7750@<my IP> entering state [received][100] 2021-03-16 15:52:02.267501 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=- 81921704 81921704 IN IP4 0.0.0.0 s=pplsip c=IN IP4 0.0.0.0 t=0 0 m=audio 7628 RTP/AVP 100 6 0 8 3 18 5 101 a=rtpmap:100 speex/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 a=alt:1 1 : DF50DC48 0000001F 0.0.0.0 7628 2021-03-16 15:52:02.267501 [DEBUG] sofia.c:7739 (sofia/external/7750@<my IP>) State Change CS_NEW -> CS_INIT 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:604 (sofia/external/7750@<my IP>) State NEW 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:585 (sofia/external/7750@<my IP>) Running State Change CS_INIT (Cur 1 Tot 7822) 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:628 (sofia/external/7750@<my IP>) State INIT 2021-03-16 15:52:02.267501 [DEBUG] mod_sofia.c:93 sofia/external/7750@<my IP> SOFIA INIT 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:40 sofia/external/7750@<my IP> Standard INIT 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:48 (sofia/external/7750@<my IP>) State Change CS_INIT -> CS_ROUTING 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:628 (sofia/external/7750@<my IP>) State INIT going to sleep 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:585 (sofia/external/7750@<my IP>) Running State Change CS_ROUTING (Cur 1 Tot 7822) 2021-03-16 15:52:02.267501 [DEBUG] switch_channel.c:2332 (sofia/external/7750@<my IP>) Callstate Change DOWN -> RINGING 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:644 (sofia/external/7750@<my IP>) State ROUTING 2021-03-16 15:52:02.267501 [DEBUG] mod_sofia.c:154 sofia/external/7750@<my IP> SOFIA ROUTING 2021-03-16 15:52:02.267501 [DEBUG] switch_core_state_machine.c:236 sofia/external/7750@<my IP> Standard ROUTING 2021-03-16 15:52:02.267501 [INFO] mod_dialplan_xml.c:637 Processing 7750 <7750>->900442037697855 in context public I thought fail2ban was designed for stuff like this, but I don't see any auth attempts here (I set "log-auth-failures" to "true"). These are coming in a bit faster than 1 per second. It appears they are dialing random extensions. How can I make them stop? -- Steve

6 6

Faxing with FreeSWITCH
by bruce＠sqls.net 16 Mar '21

16 Mar '21

Hello! I'm working on setting up a FreeSWITCH/Flowroute fax server. The server is connected without any nat on a 20MB fiber internet connection. I've built some lua scripts that allow me to receive and send faxes and log all the faxes to a sqlite database. I even have a management script that can be called from the fs_cli to add fax2email routes and adjust settings. It's still a pretty rough implementation but overall it's working fairly well but I have two main questions that I hope someone could help me with. 1) I would like to detect "voice calls" or maybe I want to detect fax calls? There's a dialplan function to listen for and detect a fax caller but I'm having trouble implementing it in my lua script. It seems it's a non-blocking function, maybe? Because it just walks right over that line instead of waiting and listening for any fax tones. I thought it would block the script for x seconds and listen for a fax tone and return a result based on what it found. Can someone help me accomplish something similar to that? 2) So far, by far... my most common error is "The call dropped prematurely". I'm not sure how to start debugging the error to figure out what's causing it and how (if I can) solve it. I do have pcap's of the calls from pcapsipdump and I also have the freeswitch log of each call (my lua script pulls and saves that for each fax call). I've opened the pcap files up in wireshark but I don't know what I'm looking for and I think that might be over my head at the moment. Can anyone give me any help on what this error is normally caused by or what I could look for and/or test? Here are a couple of example logs that ended with "The call dropped prematurely". http://bmts.us/faxlogs/FAX-2e913a50-3a4f-4abc-9882-a323a97e5138.log http://bmts.us/faxlogs/FAX-59d02d9d-c4ba-44c2-8edd-a1c9142c7c1c.log

6 11