- FreeSWITCH-users - FreeSWITCH Mailing Lists

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

Installing Modules
by Rasheed Kalapurackal 10 Mar '21

10 Mar '21

Hello , I completed the installation of Freeswitch on Debian 10 . Now i need to install an additional module in to the Freeswitch . Is it possible to install Modules in to an already deployed Freeswitch instance ? Any quick help on this would be greatly appreciated. Thanks Abdul Rasheed

5 6

NOTIFYs Event
by Vishal Pai 10 Mar '21

10 Mar '21

Hello Everyone Few days back we had a issue with Notify request from park+*5903(a)abc.com the freeswitch was sending the notify request but the endpoint side was responding late on checking it out it was router on which sip alg was enabled so we disable the that and all started working to normal. My question is that is there any way to identify such issues and block the IP or unregistered that EXT automatically via fail2ban or any other way is possible. Thanks

1 0

Freeswitch DTMF issue - wrong timestamp base
by Arslan Saeed 10 Mar '21

10 Mar '21

Hi All, We have come across an issue where freeswitch ((Version 1.10.5 -release-17-25569c1631 64bit) seems to be doing something wrong with regards to RFC4733 DTMF event packets generation. What we see is that freeswitch would start using a new timestamp base for the RTP event packets (sometimes even for a few RTP packets preceeding the DTMF packetS) and then at the end of the DTMF event revert to older timestamp base. Although the marker bit is set on when new timestamp base is used but as far as I know this is not RFC compliant and causes issues for some of the PBX devices. RFC 4733, section 2.5.1.2 says: DTMF digits and other named telephone events are carried as part of the audio stream, and they MUST use the same sequence number and timestamp base as the regular audio channel to simplify the generation of audio waveforms at a gateway.``` https://tools.ietf.org/html/rfc4733#section-2.5.1 -- I think if freeswitch were to change the timestamp for DTMF event packets then it must also change the RTP SSRC at same time (which does not happen) Although I don't understand the need to change timestamp base at all for the start of DTMF event packets or even with the few proceeding RTP packets. I have tried to use following parameter but that does not help <param name="auto-rtp-bugs" data="CHANGE_SSRC_ON_MARKER"/> I have also tried to test with these additional parameters (in order to infleunce freeswitch to not use a new timestamp base for the start of dtmf event packets) but no change in freeswitch behaviour. <param name="pass-rfc2833" value="true"/> <param name="rtp-rewrite-timestamps"value="true"/> <param name="auto-rtp-bugs"value="CISCO_SKIP_MARK_BIT_2833"/> <param name="auto-rtp-bugs"value="clear"/> In addition I have tried to use some jitter related sofia profile parameters but no affect. Another DTMF related issue that I have seen with freeswitch is that it sends DTMF event packets in a short burst and not evenly spaced out per the codec sample rate. For example if RTP is being sent at 20ms, freeswitch would send dtmf event packets at different interval like it may send 8ms apart or 13 ms apat and at times it sends multiple event packets just a few ms apart. To fix that behaviour, when we explicity set the RTP-timer-name to "soft" using <param name="rtp-timer-name" value="soft"/> that fixes it and ensures evenly spaced DTMF packets say 20 ms apart (that fixes interop with some of the devices) Any help is highly appreciated. Thanks Arslan

2 1

using mod_unimrcp to transcribe voicemails
by Lewis Bergman 10 Mar '21

10 Mar '21

Does anyone have guidance or code to get this to work? I have looked at the transcription documentation in Freeswitch and it all seems to be related to command line type implementations using Google, Wartson, Azure, etc, with curl. using a FusionPBX Lua script ( /var/www/fusionpbx/app/scripts/resources/scripts/app/voicemail/resources/functions/record_message.lua ) If someone has made voicemail transcription work with mod_unimrcp I would be grateful in hearing how it was done. It seems like some Lua programming might be required at the minimum. If making it work is described an idea of where to start looking for documentation would be great. -- Lewis Bergman 325-439-0533 Cell

2 3

Problem with answered call and multiple re-invites
by Jose Fco. Irles Durá 09 Mar '21

09 Mar '21

Hi, I have an audio problem with some user agents (Alcatel OXO) that sends multiple re-invites. Example scenario: FreeSWITCH with public IP (example: 1.1.1.1) Alcatel behind NAT (private IP 192.168.20.200, public IP 2.2.2.2) 1. I send the call to Alcatel and the sdp negotiation works well. The remote port for RTP is 32014. In the capture I can see that FreeSWITCH sends first RTP packets to the IP 192.168.20.200 and port 32014. Later, the audio stream from Alcatel arrives from IP 2.2.2.2 and port 32014 and FreeSWITCH changes and sends RTP to 2.2.2.2:32014 (rtp auto adjust), so I can listen the audio (the Alcatel has an IVR menu). 2. In the IVR menu I press one option and the Alcatel sends me an INVITE changing media. It sends a new port (32016), FreeSWITCH accepts the re-invite without changing its source port. In the capture I can see that FreeSWITCH sends first RTP packets to the IP 192.168.20.200 and port 32016, but when the first packets from the real IP:PORT (2.2.2.2:32016) arrives, FreeSWITCH don't change its RTP stream to the new port, so I can't hear the audio from the Alcatel. 3. Later, FreeSWITCH receives another re-invite changing remote port to 32020. First, FreeSWITCH sends audio to the private IP:PORT (192.168.20.200:32020), but when it receives the real stream from 2.2.2.2:32020, it doesn't change to the new IP:PORT (2.2.2.2:32020). >From point 2, I don't hear anything. This problem happens with the last stable version (1.10.5). I think that it's a bug, but I don't know if there are some config that change that behaviour. I have uploaded the capture (png): https://i.imgur.com/emvnaCt.png Best regards -- Jose Fco. Irles Durá

1 0

Freeswitch loacally acks 200 in bypass media scenarios
by David VILLAUME 05 Mar '21

05 Mar '21

Hello, In bypass-media mode freeswitch locally acks the 200 ok (SDP), that leads to some Race condition when the party that answered the 200 sends a quick reinvite after Just after the Ack. I tried on 1.10.1 and 1.10.5, with and without late-negociation, but couldn’t figure how to avoid this. Has one of you experienced it or have any idea about this ? SBC FREESWITCH PBX +--------- ----------+--------- ----------+ | INVITE (SDP) | | | --------------------------> | | | 100 Trying | | | <-------------------------- | | | | INVITE (SDP) | | | --------------------------> | | | 100 Trying | | | <-------------------------- | | | 180 Ringing | | | <-------------------------- | | 180 Ringing | | | <-------------------------- | | | | 200 OK (SDP) | | | <-------------------------- | | | ACK | | | --------------------------> | | 200 OK (SDP) | | | <-------------------------- | | | | INVITE (SDP) | | | <-------------------------- | | | 100 | | | --------------------------> | | INVITE (SDP) | | | <-------------------------- | | | 100 | | | --------------------------> | | | 491 | | | --------------------------> | | | | 491 | | | --------------------------> | Regards, David

2 3

mod_sndfile error called from mod_tts_commandline - [WARNING] mod_sndfile.c:281 Error Opening File
by Lewis Bergman 05 Mar '21

05 Mar '21

Hello all. I am pretty new to Freeswitch and have an experimental container on Proxmox 6.3 Debian 10 to play with. The goal is to get mod_unimrcp working. To that end, I have mod_tts_commandline (installed from binary with FusionPBX as part of the default install) listed in modules.conf.xml /etc/freeswitch/autoload_configs/modules.conf.xml  <load module="mod_unimrcp"/> <load module="mod_tts_commandline"/> <load module="mod_flite"/> I have tried it with and without mod_flite enabled. Mod_tts_commandline refuses to pull a sound file and the debug log seems to blame mod_sndfile. This is using the example from the mod_tts_commandline page <https://freeswitch.org/confluence/display/FREESWITCH/mod_tts_commandline#mo…> as dialplan shows following the debug log output below: 2021-02-26 15:19:24.713040 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/102(a)66.151.243.45] has been answered 2021-02-26 15:19:24.713040 [DEBUG] switch_channel.c:3865 (sofia/internal/ 102(a)66.151.243.45) Callstate Change RINGING -> ACTIVE 2021-02-26 15:19:24.713040 [DEBUG] sofia.c:7326 Channel sofia/internal/ 102(a)66.151.243.45 entering state [completed][200] EXECUTE [depth=0] sofia/internal/102(a)66.151.243.45 speak(tts_commandline|pico|This is an example of using tts_commandline) 2021-02-26 15:19:24.713040 [DEBUG] switch_ivr_play_say.c:3023 OPEN TTS tts_commandline 2021-02-26 15:19:24.713040 [DEBUG] switch_ivr_play_say.c:3033 Raw Codec Activated 2021-02-26 15:19:24.713040 [DEBUG] mod_tts_commandline.c:160 Executing: echo 'This is an example of using tts_commandline' | text2wave -f 8000 > '/tmp/0665a30d-2bb8-40fe-b5a6-9625bd58306e.tmp.wav' 2021-02-26 15:19:25.433013 [WARNING] mod_sndfile.c:281 Error Opening File [/tmp/0665a30d-2bb8-40fe-b5a6-9625bd58306e.tmp.wav] [File contains data in an unknown format.] 2021-02-26 15:19:25.433013 [ERR] mod_tts_commandline.c:170 Failed to open file: /tmp/0665a30d-2bb8-40fe-b5a6-9625bd58306e.tmp.wav 2021-02-26 15:19:25.433013 [DEBUG] switch_ivr_play_say.c:2741 Speaking text: This is an example of using tts_commandline 2021-02-26 15:19:25.433013 [DEBUG] sofia.c:7326 Channel sofia/internal/ 102(a)66.151.243.45 entering state [ready][200] 2021-02-26 15:19:25.433013 [DEBUG] switch_ivr_play_say.c:2905 done speaking text 2021-02-26 15:19:25.433013 [NOTICE] switch_core_state_machine.c:386 sofia/internal/102(a)66.151.243.45 has executed the last dialplan instruction, hanging up. 2021-02-26 15:19:25.433013 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/102(a)66.151.243.45 [CS_EXECUTE] [NORMAL_CLEARING] The dialplan for extension I am testing looks like this: <extension name="tts_commandline_test"> <condition field="destination_number" expression="^561$"> <action application="speak" data="tts_commandline|pico|This is an example of using tts_commandline"/> </condition> </extension> The code lines mentioned in debug seem to be pretty mundane, not that I really know what I am looking for: >From mod_tts_commnandline line 160: switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_DEBUG, "Executing: %s\n", message); I tried setting the /tmp directory to 777 but that didn't help and the error output was identical. mod_tts_commandline never reports an error on creation and it looks like the next part of of mod_tts_commandline would print an error if it did like this: if (switch_system(message, SWITCH_TRUE) < 0) { switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_ERROR, "Failed to execute command: %s\n", message); ret = SWITCH_STATUS_FALSE; goto done; } if (switch_core_file_open(info->fh, info->file, 0, //number_of_channels, info->rate, //samples_per_second, SWITCH_FILE_FLAG_READ | SWITCH_FILE_DATA_SHORT, NULL) !=SWITCH_STATUS_SUCCESS) { switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_ERROR, "Failed to open file: %s\n", info->file); ret = SWITCH_STATUS_FALSE; goto done; } Starting at line 279 in mod_sndfile (sorry for the space formatting): if (!context->handle) { if (sndfile_perform_open(context, path, mode, handle) != SWITCH_STATUS_SUCCESS) { switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "Error Opening File [%s] [%s]\n", path, sf_strerror(context->handle)); status = SWITCH_STATUS_GENERR; goto end; } } According to the dptools docs <https://freeswitch.org/confluence/display/FREESWITCH/mod_dptools%3A+playbac…> and libsndfile wav has been supported forever and it would seem highly unlikely that is an issue. It seems like mod_tts isn't writing the file but also isn't reporting an error. I have started fresh with two new containers twice and a full VM once all with the same error resulting. I check the /tmp directory and no files are present but I don't know if they are being deleted by some cleanup process or just not created. Not knowing C, it seems like mod_sndfile never received a file handle but that is just a guess. Other Freeswitch apps are writing to the /tmp directory (at least there are some files in there). Maybe someone sees an error from mod_tts_commandline I don't see or knows I am doing something stupid. Any help would be appreciated. I only found 2 references to the error in the mail history and neither had anything to do with a similar issue. Freeswitch version reports: FreeSWITCH Version 1.10.5-release-17-25569c1631~64bit (-release-17-25569c1631 64bit) FusionPBX version, in case it matters, is : 4.5.21 OS is Debian 10 Buster -- Lewis Bergman

4 6