June 2011 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Can't make mod_rtmp
by peely 07 Sep '11

07 Sep '11

Hi, I'm having trouble building FreeSWITCH with the latest mod_rtmp goodness. On Ubuntu X64 compiling with gcc I get: cc1: warnings being treated as errors /usr/src/freeswitch/src/mod/endpoints/mod_rtmp/mod_rtmp.c: In function ‘rtmp_function’: /usr/src/freeswitch/src/mod/endpoints/mod_rtmp/mod_rtmp.c:1612:114: error: comparison between ‘switch_call_cause_t’ and ‘enum <anonymous>’ I'm not sure if I need to do anything else, I just naively added endpoints/mod_rtmp to modules.conf and did a make clean install? Thanks, Neil. -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Can-t-make-mod-rtmp-tp6488229… Sent from the freeswitch-users mailing list archive at Nabble.com.

6 8

Re: [Freeswitch-users] Radius/Authentication/Authorization
by Abid Saleem 06 Aug '11

06 Aug '11

Dear Tihomir, Any update and help on the below please. Abid From: abid_freeswitch(a)live.com To: tculjaga(a)gmail.com CC: neal(a)wanlink.com Subject: Re: [Freeswitch-users] Radius/Authentication/Authorization Date: Wed, 31 Mar 2010 19:35:32 +0500 Dear Tihomir, Thank you very much for the configuration example but in which files to place these configurations. Please bear with me because I am new to FreeSwitch and if you could provide complete steps. Also when I compile FS and load the module mod_rad_auth in conf/autoload_configs/modules.conf.xml, I get an error while starting FS as follows. 2010-03-31 19:32:29.399466 [CRIT] switch_loadable_module.c:882 Error Loading module /usr/local/freeswitch/mod/mod_rad_auth.so**/usr/local/freeswitch/mod/mod_rad_auth.so: undefined symbol: rc_conf_str** Please advise. Thanks. Regards-----------Abid Saleem --Forwarded Message Attachment-- From: tculjaga(a)gmail.com CC: neal(a)wanlink.com To: freeswitch-users(a)lists.freeswitch.org Date: Tue, 30 Mar 2010 21:42:03 +0200 Subject: Re: [Freeswitch-users] Radius/Authentication/Authorization hello, here is an example <configuration name="rad_auth.conf" description="radius authentification module"> <settings>   </settings> <client> <param name="authserver" value="10.1.1.10:1812:gateway"/> <param name="dictionary" value="/usr/local/etc/radiusclient/dictionary.all"/> <param name="seqfile" value="/var/run/radius.seq"/> <param name="mapfile" value="/usr/local/etc/radiusclient/port-id-map"/> <param name="default_realm" value=""/> <param name="radius_timeout" value="3"/> <param name="radius_retries" value="2"/> <param name="radius_deadtime" value="0"/> <param name="bindaddr" value="*"/> </client> <vsas>   <param name="h323-conf-id" id="24" value="CALLID" pec="9" expr="1" direction="in"/> <param name="h323-prompt-id" id="104" value="SERVICENUM" pec="9" expr="1" direction="in"/> <param name="Cisco-AVPair" id="1" value="TRANSACTIONID" pec="9" expr="1" direction="in"/> <param name="Calling-Station-Id" id="31" value="CALLINGNUMBER" pec="0" expr="1" direction="in"/> <param name="NAS-Port-Type" id="61" value="0" pec="0" expr="0" direction="in"/> <param name="NAS-Port-Id" id="87" value="ISDN 3/0:D:14" pec="0" expr="0" direction="in"/> <param name="Login-User" id="1" value="1" pec="0" expr="0" direction="in"/>  <param name="BILING_MODEL" id="109" value="billing_model" pec="9" expr="0" direction="out"/> <param name="CREDIT_AMOUNT" id="101" value="credit_amount" pec="9" expr="0" direction="out"/> <param name="CURRENCY" id="110" value="currency" pec="9" expr="0" direction="out"/> <param name="PREFFERED_LANG" id="107" value="preffered_lang" pec="9" expr="0" direction="out"/> <param name="CREDIT_TIME" id="102" value="credit_time" pec="9" expr="0" direction="out"/> <param name="H323-IVR-IN:DIRATION" id="1" value="h323_ivr_duration" pec="9" expr="0" direction="out"/> <param name="RADIUS_RETURN_CODE" id="103" value="return_code" pec="9" expr="0" direction="out"/>  </vsas> </configuration> in the dialplan you need to trigger auth as: <action application="auth_function" data="in ${DIALED_NUMBER}, in ${USERNAME}, in ${PASSWD}, out AUTH_RESULT"/> there are two behaviours: 1. authorize the call according to username&pass and dialed number - if authorized, the radius server returns credit time towards the dialed number 2. authorize the call according to username&pass - if authorized, the radius server returns the current account balance <extension name="unitest_rad-ANI-auth"> <condition field="destination_number" expression="^601$"> <action application="log" data="INFO PRIJE RAD_AUTH "/> <action inline="true" application="set" data="CALLID=h323-conf-id=${uuid}"/> <action inline="true" application="set" data="SERVICENUM=h323-prompt-id=${destination_number}"/> <action inline="true" application="set" data="TRANSACTIONID=h323-ivr-out=transactionID:1234"/>  <action inline="true" application="set" data="CALLINGNUMBER=38516060333"/> <action inline="true" application="set" data="USERNAME=38516060333"/>  <action inline="true" application="set" data="PASSWD=003282"/>  <action application="sleep" data="2000"/> <action application="auth_function" data="in ${DIALED_NUMBER}, in ${USERNAME}, in ${PASSWD}, out AUTH_RESULT"/> <action application="log" data="INFO AUTH_RESULT=${AUTH_RESULT}"/> <action application="log" data="INFO billing_model=${billing_model}"/> <action application="log" data="INFO credit_amount=${credit_amount}"/> <action application="log" data="INFO currency=${currency}"/> <action application="log" data="INFO preffered_lang=${preffered_lang}"/> <action application="log" data="INFO credit_time=${credit_time}"/> <action application="log" data="INFO h323_ivr_duration=${h323_ivr_duration}"/> <action application="log" data="INFO return_code=${return_code}"/>  </condition> </extension> <extension name="unitest_rad-ANI-balance"> <condition field="destination_number" expression="^602$"> <action application="log" data="INFO PRIJE RAD_AUTH "/> <action inline="true" application="set" data="CALLID=h323-conf-id=${uuid}"/> <action inline="true" application="set" data="SERVICENUM=h323-prompt-id=${destination_number}"/> <action inline="true" application="set" data="TRANSACTIONID=h323-ivr-out=transactionID:1234"/>  <action inline="true" application="set" data="CALLINGNUMBER=38516060333"/> <action inline="true" application="set" data="USERNAME=38516060333"/>  <action inline="true" application="set" data="PASSWD=003282"/> <action inline="true" application="set" data="DIALED_NUMBER=16094191500"/> <action application="sleep" data="2000"/> <action application="auth_function" data="in ${DIALED_NUMBER}, in ${USERNAME}, in ${PASSWD}, out AUTH_RESULT"/> <action application="log" data="INFO AUTH_RESULT=${AUTH_RESULT}"/> <action application="log" data="INFO billing_model=${billing_model}"/> <action application="log" data="INFO credit_amount=${credit_amount}"/> <action application="log" data="INFO currency=${currency}"/> <action application="log" data="INFO preffered_lang=${preffered_lang}"/> <action application="log" data="INFO credit_time=${credit_time}"/> <action application="log" data="INFO h323_ivr_duration=${h323_ivr_duration}"/> <action application="log" data="INFO return_code=${return_code}"/>  </condition> </extension> will update the wiki by the end of the week. you have enough information for now. Tihomir. On Tue, Mar 30, 2010 at 1:52 PM, Abid Saleem <abid_freeswitch(a)live.com> wrote: Hi Neal and other Contributors to FS, I recieved an answer on the list that mod_rad_auth is ready. I upgraded FS to download and install it by "make current" and it is successfully built and installed. Could you please outline the detailed steps to do all this configuration. If it is still not ready, is there any other method that somebody has already implemented like perl scripts etc. Any help in this regard is badly required. THanks for your cooperation. Regards----------Abid SaleemSr. Product Manager Hotmail: Powerful Free email with security by Microsoft. Get it now. _________________________________________________________________ Hotmail: Powerful Free email with security by Microsoft. https://signup.live.com/signup.aspx?id=60969

4 8

api_hangup_hook and hupall
by Dome Charoenyost 04 Aug '11

04 Aug '11

I use api_hangup_hook for do something when call hangup. but when i use hupall command from CLI api_hangup_hook do nothing. Is it bug ? Dome C.

3 3

ESL: not bein able to determine when SIP gateway is down when originating a call
by Anton VG 02 Aug '11

02 Aug '11

I'm trying to catch an error, in case I would dial wrong (non existent) gateway (intentionally!) I'm running ESL outbound listener, subscribing to all events, if I do bgapi 'originate' to a live gateway - there are normal events flow, and I can track what is happening. But if I issue originate to a gateway, which is not configured or simply down - there are no any events fired. I only have an error on FS console 2011-05-14 20:58:13.072927 [ERR] mod_sofia.c:4044 Invalid Gateway following by 2011-05-14 20:58:13.072927 [ERR] switch_ivr_originate.c:2447 Cannot create outgoing channel of type [sofia] cause: [INVALID_NUMBER_FORMAT] But HOW to catch the given in ESL? sofia.c seems just does not have event code for that cases if (profile_name && !profile_found) { switch_log_printf(SWITCH_CHANNEL_LOG, SWITCH_LOG_WARNING, "No Such Profile '%s'\n", profile_name); >status = SWITCH_STATUS_FALSE; } logically there should be a proper way to determine that gateway is bad in my ESL dialplan, by catching the proper event/reply/whatever, For the moment i did trick: esl.api('sofia status gateway GatewayWhichIsDown') When in production, and there is more than a single route, there will be plenty of cases, when you dial a bad gateway, so there should be a way for ESL dialplan to determine that a gateway is not callable for a moment, the reason WHY and to retry with another one. The trick above is bad, since: 1. blocking api query, before evey single gateway call attempt. 2. Gateway maybe known in UP state, but the state is stale, in dial in fact will go to DOWN gateway. So, ESL dialplan will screw in that case Any clue?

4 4

Load Balance Trunks
by Abid Saleem 29 Jul '11

29 Jul '11

Hi All, Please help me the following 2 issues. 1- I have 100 Trunks from my SIP Provider. My provider restricts me to send only 120 minutes call duration per Trunk per each day. This means around 30 calls per Trunk per day with an ACD of 3 minutes. Please help me how to configure this if it is possible? 2- I need to send P-Preferred-Identity<sip:xxxxxxxxx@domain.com> in SIP header for each Trunk while dialing out to provider. Please help how to configure this. Thanks in Advance. Rgrds-----------Abid SaleemTechnical Manager NGNTerminus Technologies

7 14

Complete wholesale app in freeswitch
by David Villasmil 21 Jul '11

21 Jul '11

Hello Guys, I'm finishing a "complete" wholesale application created on freeswitch and I was wondering whether it would be a good idea to put it up on the wiki. I just don't know how. Features include all the following parameters configurable via web interface: - Profile creation based on server IP where traffic is received. You can have multiple IPs, system will create multiple profiles/diaplans so it can differentiate. - i.e. offer to the same customer a "gold" routing on IP1 and cheap routing on IP2 - Customer add/modify/delete - IP source - Rates for client routes based on areacode - Prepaid or postpaid. - When cutomer balance is 0, no more calls are allowed. - limit max channels - Media by-pass - When by-passed, customer and provider will exchange RTPs directly. Else, server will be in the middle. - Provider add/modify/delete - costs for provider routes based on areacode - limit max channels - Routing based on areacode, gives great granularity. - Routes can be assigned multiple gateways/providers which can in turn be distributed based on weigth. Includes overflow to next configured GW. - Basic financial report generation (totals) by customer/provider - Basic traffic ASR/ACD report (totals) by cutomer/provider - Basic user administration. (No access level, only total access) - CDR export to csv file. I also have a prepaid card app... no web interface on that one though... Thanks all David

22 55

Leg-A is automatically disconnected on Leg-B orginate failure
by dma 19 Jul '11

19 Jul '11

I am creating a call-back solution. After leg-A answers, I originate leg-B call. After receiving SIP 183 from Leg-B, I bridge the 2 legs. However, in some cases, leg-A is automatically disconnected by FreeSwitch on leg-B failure, for example, DESTINATION_OUT_OF_ORDER. The application is not given a chance to handle leg-B failure event. This should not be a correct scenario because I never set "hangup-after-bridge", which is false by default. The right way should be, FreeSwitch doesn't hang up leg-A automatically, but give a chance for the application to decide what to do. Please see the logs below: ================================================= 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 0 = [origination_caller_id_number=03996563750914] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 1 = [originate_timeout=30] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 2 = [ccd_session_id=20110610105829676824] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 3 = [sip_cid_type=pid] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 4 = [privacy=yes] 2011-06-10 11:09:55.370506 [NOTICE] switch_channel.c:808 New Channel sofia/external/03996590031055(a)203.208.207.212 [ea57b74b-a8c2-4fea-9683-98054dc03a79] 2011-06-10 11:09:55.370506 [DEBUG] mod_sofia.c:4129 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_NEW -> CS_INIT 2011-06-10 11:09:55.370506 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.370506 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_INIT 2011-06-10 11:09:55.370506 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996590031055(a)203.208.207.212) State INIT 2011-06-10 11:09:55.370506 [DEBUG] mod_sofia.c:84 sofia/external/03996590031055(a)203.208.207.212 SOFIA INIT send 984 bytes to udp/[203.208.207.212]:5060 at 03:09:55.477997: ------------------------------------------------------------------------ INVITE sip:03996590031055@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKXjQ7eFpKypy5D Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212> Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 204 X-FS-Support: update_display P-Asserted-Identity: "" <sip:03996563750914@202.73.56.46> v=0 o=FreeSWITCH 1307645395 1307645396 IN IP4 202.73.56.46 s=FreeSWITCH c=IN IP4 202.73.56.46 t=0 0 m=audio 30000 RTP/AVP 18 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-06-10 11:09:55.371674 [DEBUG] mod_sofia.c:124 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_INIT -> CS_ROUTING 2011-06-10 11:09:55.371674 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.371674 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [calling][0] 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996590031055(a)203.208.207.212) State INIT going to sleep 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_ROUTING 2011-06-10 11:09:55.373478 [DEBUG] switch_channel.c:1657 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change DOWN -> RINGING 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996590031055(a)203.208.207.212) State ROUTING 2011-06-10 11:09:55.373478 [DEBUG] mod_sofia.c:147 sofia/external/03996590031055(a)203.208.207.212 SOFIA ROUTING 2011-06-10 11:09:55.373478 [DEBUG] switch_ivr_originate.c:66 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-06-10 11:09:55.373478 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996590031055(a)203.208.207.212) State ROUTING going to sleep 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_CONSUME_MEDIA 2011-06-10 11:09:55.374485 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996590031055(a)203.208.207.212) State CONSUME_MEDIA 2011-06-10 11:09:55.374485 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996590031055(a)203.208.207.212) State CONSUME_MEDIA going to sleep recv 307 bytes from udp/[203.208.207.212]:5060 at 03:09:55.485130: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212> Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 669 bytes from udp/[203.208.207.212]:5060 at 03:09:56.677788: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 1 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:09:56.571839 [INFO] sofia.c:729 sofia/external/03996590031055(a)203.208.207.212 Update Callee ID to "Outbound Call" <03996590031055> 2011-06-10 11:09:56.571839 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [proceeding][183] 2011-06-10 11:09:56.571839 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 421265648 1 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:4467 Audio Codec Compare [G729:18:8000:20:8000]/[G729:18:8000:20:8000] 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:2757 Set Codec sofia/external/03996590031055(a)203.208.207.212 G729/8000 20 ms 160 samples 8000 bits 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:4565 Set 2833 dtmf send payload to 101 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:2987 AUDIO RTP [sofia/external/03996590031055(a)203.208.207.212] 10.1.1.46 port 30000 -> 203.208.207.196 port 30792 codec: 18 ms: 20 2011-06-10 11:09:56.571839 [DEBUG] switch_rtp.c:1607 Starting timer [soft] 160 bytes per 20ms 2011-06-10 11:09:56.573731 [DEBUG] sofia_glue.c:3228 Set 2833 dtmf send payload to 101 2011-06-10 11:09:56.573731 [DEBUG] sofia_glue.c:3233 Set 2833 dtmf receive payload to 101 2011-06-10 11:09:56.573731 [NOTICE] sofia_glue.c:3680 Pre-Answer sofia/external/03996590031055(a)203.208.207.212! 2011-06-10 11:09:56.573731 [DEBUG] switch_channel.c:2627 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change RINGING -> EARLY 2011-06-10 11:09:56.573731 [DEBUG] switch_ivr_originate.c:3408 Originate Resulted in Success: [sofia/external/03996590031055(a)203.208.207.212] 2011-06-10 11:09:56.573731 [DEBUG] mod_commands.c:3205 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2011-06-10 11:09:56.573731 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996590031055(a)203.208.207.212) State EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] mod_sofia.c:240 sofia/external/03996590031055(a)203.208.207.212 SOFIA EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:157 sofia/external/03996590031055(a)203.208.207.212 Standard EXECUTE EXECUTE sofia/external/03996590031055(a)203.208.207.212 park() 2011-06-10 11:09:56.618770 [DEBUG] switch_rtp.c:2933 Correct ip/port confirmed. recv 669 bytes from udp/[203.208.207.212]:5060 at 03:10:01.562021: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 2 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:01.455944 [INFO] sofia.c:729 sofia/external/03996590031055(a)203.208.207.212 Update Callee ID to "03996590031055" <03996590031055> 2011-06-10 11:10:01.455944 [DEBUG] sofia.c:4641 Channel sofia/external/03996590031055(a)203.208.207.212 skipping state [proceeding][183] recv 669 bytes from udp/[203.208.207.212]:5060 at 03:10:01.563178: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 3 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:01.457169 [DEBUG] sofia.c:4641 Channel sofia/external/03996590031055(a)203.208.207.212 skipping state [proceeding][183] recv 749 bytes from udp/[203.208.207.212]:5060 at 03:10:27.365284: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Contact: <sip:203.208.207.212:5060> Allow-Events: refer Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Supported: 100rel, timer, replaces Content-Length: 189 v=0 o=- 421265648 4 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:27.258092 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [completing][200] 2011-06-10 11:10:27.258092 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 421265648 4 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 send 405 bytes to udp/[203.208.207.212]:5060 at 03:10:27.366562: ------------------------------------------------------------------------ ACK sip:203.208.207.212:5060 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKyUg0ga7pUZmrS Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 ACK Contact: <sip:mod_sofia@202.73.56.46:5080> Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:27.260267 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [ready][200] 2011-06-10 11:10:27.260267 [DEBUG] switch_channel.c:2782 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change EARLY -> ACTIVE 2011-06-10 11:10:27.260267 [NOTICE] sofia.c:5175 Channel [sofia/external/03996590031055(a)203.208.207.212] has been answered 2011-06-10 11:10:27.264178 [DEBUG] switch_scheduler.c:214 Added task 23 switch_ivr_schedule_hangup (ea57b74b-a8c2-4fea-9683-98054dc03a79) to run at 1307676927 2011-06-10 11:10:27.265202 [DEBUG] switch_core_session.c:954 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 0 = [origination_caller_id_number=03996590031055] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 1 = [ccd_session_id=20110610105829676824] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 2 = [sip_cid_type=pid] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 3 = [privacy=yes] 2011-06-10 11:10:27.266218 [NOTICE] switch_channel.c:808 New Channel sofia/external/03996563750914(a)203.208.207.212 [30228d2b-756a-4a98-871d-db63a2955b52] 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:4129 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_NEW -> CS_INIT 2011-06-10 11:10:27.266218 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_INIT 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996563750914(a)203.208.207.212) State INIT 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:84 sofia/external/03996563750914(a)203.208.207.212 SOFIA INIT 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:124 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_INIT -> CS_ROUTING 2011-06-10 11:10:27.266218 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996563750914(a)203.208.207.212) State INIT going to sleep 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_ROUTING 2011-06-10 11:10:27.266218 [DEBUG] switch_channel.c:1657 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change DOWN -> RINGING 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996563750914(a)203.208.207.212) State ROUTING send 984 bytes to udp/[203.208.207.212]:5060 at 03:10:27.373220: ------------------------------------------------------------------------ INVITE sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:147 sofia/external/03996563750914(a)203.208.207.212 SOFIA ROUTING Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 204 X-FS-Support: update_display P-Asserted-Identity: "" <sip:03996590031055@202.73.56.46> v=0 o=FreeSWITCH 1307646863 1307646864 IN IP4 202.73.56.46 s=FreeSWITCH c=IN IP4 202.73.56.46 t=0 0 m=audio 28564 RTP/AVP 18 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-06-10 11:10:27.267630 [DEBUG] sofia.c:4646 Channel sofia/external/03996563750914(a)203.208.207.212 entering state [calling][0] 2011-06-10 11:10:27.267630 [DEBUG] switch_ivr_originate.c:66 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996563750914(a)203.208.207.212) State ROUTING going to sleep 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996563750914(a)203.208.207.212) State CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996563750914(a)203.208.207.212) State CONSUME_MEDIA going to sleep recv 307 bytes from udp/[203.208.207.212]:5060 at 03:10:27.378710: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:27.278484 [DEBUG] switch_ivr.c:563 sofia/external/03996590031055(a)203.208.207.212 Command Execute playback(/usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav) EXECUTE sofia/external/03996590031055(a)203.208.207.212 playback(/usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav) 2011-06-10 11:10:27.278484 [DEBUG] switch_core_file.c:176 File /usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav sample rate 11025 doesn't match requested rate 8000 2011-06-10 11:10:27.278484 [WARNING] switch_core_file.c:189 File has 2 channels, muxing to mono will occur. 2011-06-10 11:10:27.278484 [DEBUG] switch_ivr_play_say.c:1244 Codec Activated L16@8000hz 2 channels 20ms 2011-06-10 11:10:27.298764 [INFO] mod_com_g729.c:119 ENCODER CREATE - 0x2aaab00310c0 0x2aaab00b20c0 recv 666 bytes from udp/[203.208.207.212]:5060 at 03:10:28.618472: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 186 v=0 o=- 131082 1 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:28.513195 [INFO] sofia.c:729 sofia/external/03996563750914(a)203.208.207.212 Update Callee ID to "Outbound Call" <03996563750914> 2011-06-10 11:10:28.513195 [DEBUG] sofia.c:4646 Channel sofia/external/03996563750914(a)203.208.207.212 entering state [proceeding][183] 2011-06-10 11:10:28.513195 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 131082 1 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:4467 Audio Codec Compare [G729:18:8000:20:8000]/[G729:18:8000:20:8000] 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:2757 Set Codec sofia/external/03996563750914(a)203.208.207.212 G729/8000 20 ms 160 samples 8000 bits 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:4565 Set 2833 dtmf send payload to 101 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:2987 AUDIO RTP [sofia/external/03996563750914(a)203.208.207.212] 10.1.1.46 port 28564 -> 203.208.207.195 port 45002 codec: 18 ms: 20 2011-06-10 11:10:28.513195 [DEBUG] switch_rtp.c:1607 Starting timer [soft] 160 bytes per 20ms 2011-06-10 11:10:28.514938 [DEBUG] sofia_glue.c:3228 Set 2833 dtmf send payload to 101 2011-06-10 11:10:28.514938 [DEBUG] sofia_glue.c:3233 Set 2833 dtmf receive payload to 101 2011-06-10 11:10:28.514938 [NOTICE] sofia_glue.c:3680 Pre-Answer sofia/external/03996563750914(a)203.208.207.212! 2011-06-10 11:10:28.514938 [DEBUG] switch_channel.c:2627 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change RINGING -> EARLY 2011-06-10 11:10:28.514938 [DEBUG] switch_ivr_originate.c:3408 Originate Resulted in Success: [sofia/external/03996563750914(a)203.208.207.212] 2011-06-10 11:10:28.514938 [DEBUG] mod_commands.c:3205 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2011-06-10 11:10:28.514938 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996563750914(a)203.208.207.212) State EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] mod_sofia.c:240 sofia/external/03996563750914(a)203.208.207.212 SOFIA EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:157 sofia/external/03996563750914(a)203.208.207.212 Standard EXECUTE EXECUTE sofia/external/03996563750914(a)203.208.207.212 park() 2011-06-10 11:10:28.516887 [DEBUG] switch_core_session.c:954 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_ivr_bridge.c:1480 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_EXECUTE -> CS_HIBERNATE 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_ivr_bridge.c:1482 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_EXECUTE -> CS_HIBERNATE 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:771 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:771 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_play_say.c:1581 done playing file 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr.c:563 sofia/external/03996590031055(a)203.208.207.212 Command Execute playback(tone_stream://%(2000,4000,440,480);loops=10) 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996563750914(a)203.208.207.212) State EXECUTE going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996563750914(a)203.208.207.212) State HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] mod_sofia.c:221 sofia/external/03996563750914(a)203.208.207.212 SOFIA HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_bridge.c:731 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_HIBERNATE -> CS_RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996563750914(a)203.208.207.212) State HIBERNATE going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996563750914(a)203.208.207.212) State RESET 2011-06-10 11:10:28.519223 [DEBUG] mod_sofia.c:165 sofia/external/03996563750914(a)203.208.207.212 SOFIA RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_bridge.c:716 sofia/external/03996563750914(a)203.208.207.212 CUSTOM RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:66 sofia/external/03996563750914(a)203.208.207.212 Standard RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996563750914(a)203.208.207.212) State RESET going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_session.c:709 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] recv 666 bytes from udp/[203.208.207.212]:5060 at 03:10:30.540814: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 186 v=0 o=- 131082 2 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:30.435309 [INFO] sofia.c:729 sofia/external/03996563750914(a)203.208.207.212 Update Callee ID to "03996563750914" <03996563750914> 2011-06-10 11:10:30.435309 [DEBUG] sofia.c:4641 Channel sofia/external/03996563750914(a)203.208.207.212 skipping state [proceeding][183] 2011-06-10 11:10:30.691404 [WARNING] switch_core_session.c:1940 Cannot execute app 'playback' media required on an outbound channel that does not have media established 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996590031055(a)203.208.207.212) State EXECUTE going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996590031055(a)203.208.207.212) State HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:221 sofia/external/03996590031055(a)203.208.207.212 SOFIA HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:731 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_HIBERNATE -> CS_RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996590031055(a)203.208.207.212) State HIBERNATE going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996590031055(a)203.208.207.212) State RESET 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:165 sofia/external/03996590031055(a)203.208.207.212 SOFIA RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:716 sofia/external/03996590031055(a)203.208.207.212 CUSTOM RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:723 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_RESET -> CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996590031055(a)203.208.207.212) State RESET going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996590031055(a)203.208.207.212) State SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:558 SOFIA SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:741 sofia/external/03996590031055(a)203.208.207.212 CUSTOM SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:761 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_RESET -> CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996563750914(a)203.208.207.212) State SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:558 SOFIA SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:741 sofia/external/03996563750914(a)203.208.207.212 CUSTOM SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:204 sofia/external/03996563750914(a)203.208.207.212 Standard SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996563750914(a)203.208.207.212) State SOFT_EXECUTE going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2535 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change EARLY -> HANGUP 2011-06-10 11:10:30.711798 [NOTICE] switch_ivr_originate.c:1045 Hangup sofia/external/03996563750914(a)203.208.207.212 [CS_SOFT_EXECUTE] [DESTINATION_OUT_OF_ORDER] 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2551 Send signal sofia/external/03996563750914(a)203.208.207.212 [KILL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2535 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change ACTIVE -> HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996563750914(a)203.208.207.212) State HANGUP 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:457 Channel sofia/external/03996563750914(a)203.208.207.212 hanging up, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:510 Sending CANCEL to sofia/external/03996563750914(a)203.208.207.212 send 390 bytes to udp/[203.208.207.212]:5060 at 03:10:30.818391: ------------------------------------------------------------------------ CANCEL sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 CANCEL Reason: Q.850;cause=27;text="DESTINATION_OUT_OF_ORDER" Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:30.711798 [NOTICE] switch_ivr_bridge.c:772 Hangup sofia/external/03996590031055(a)203.208.207.212 [CS_SOFT_EXECUTE] [ORIGINATOR_CANCEL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:46 sofia/external/03996563750914(a)203.208.207.212 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996563750914(a)203.208.207.212) State HANGUP going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:351 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_HANGUP -> CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996563750914(a)203.208.207.212) State REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:53 sofia/external/03996563750914(a)203.208.207.212 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996563750914(a)203.208.207.212) State REPORTING going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2551 Send signal sofia/external/03996590031055(a)203.208.207.212 [KILL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996590031055(a)203.208.207.212) State SOFT_EXECUTE going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:345 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_REPORTING -> CS_DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1288 Session 40 (sofia/external/03996563750914(a)203.208.207.212) Locked, Waiting on external entities 2011-06-10 11:10:30.711798 [NOTICE] switch_core_session.c:1306 Session 40 (sofia/external/03996563750914(a)203.208.207.212) Ended 2011-06-10 11:10:30.711798 [NOTICE] switch_core_session.c:1308 Close Channel sofia/external/03996563750914(a)203.208.207.212 [CS_DESTROY] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:449 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change HANGUP -> DOWN 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:452 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996563750914(a)203.208.207.212) State DESTROY 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:362 sofia/external/03996563750914(a)203.208.207.212 SOFIA DESTROY 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaaac013028 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaaac013028 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaaac013088 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaaac013088 (nil) 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:60 sofia/external/03996563750914(a)203.208.207.212 Standard DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996563750914(a)203.208.207.212) State DESTROY going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996590031055(a)203.208.207.212) State HANGUP 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:457 Channel sofia/external/03996590031055(a)203.208.207.212 hanging up, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:500 Sending BYE to sofia/external/03996590031055(a)203.208.207.212 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:46 sofia/external/03996590031055(a)203.208.207.212 Standard HANGUP, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996590031055(a)203.208.207.212) State HANGUP going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:351 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_HANGUP -> CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996590031055(a)203.208.207.212) State REPORTING 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:53 sofia/external/03996590031055(a)203.208.207.212 Standard REPORTING, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996590031055(a)203.208.207.212) State REPORTING going to sleep 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:345 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_REPORTING -> CS_DESTROY 2011-06-10 11:10:30.714001 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.714001 [DEBUG] switch_core_session.c:1288 Session 39 (sofia/external/03996590031055(a)203.208.207.212) Locked, Waiting on external entities 2011-06-10 11:10:30.714001 [NOTICE] switch_core_session.c:1306 Session 39 (sofia/external/03996590031055(a)203.208.207.212) Ended 2011-06-10 11:10:30.714001 [NOTICE] switch_core_session.c:1308 Close Channel sofia/external/03996590031055(a)203.208.207.212 [CS_DESTROY] 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:449 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change HANGUP -> DOWN 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:452 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_DESTROY 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996590031055(a)203.208.207.212) State DESTROY 2011-06-10 11:10:30.714001 [DEBUG] mod_sofia.c:362 sofia/external/03996590031055(a)203.208.207.212 SOFIA DESTROY 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaab0031060 (nil) 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaab0031060 (nil) 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaab00310c0 0x2aaab00b20c0 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaab00310c0 (nil) send 662 bytes to udp/[203.208.207.212]:5060 at 03:10:30.820530: ------------------------------------------------------------------------ BYE sip:203.208.207.212:5060 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bK0D3Hm08XNH1Xg Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501634 BYE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Reason: FreeSWITCH;cause=487;text="ORIGINATOR_CANCEL" Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:30.715878 [INFO] mod_com_g729.c:83 ENCODER DESTROY - 0x2aaab00310c0 0x2aaab00b20c0 2011-06-10 11:10:30.715878 [DEBUG] switch_core_state_machine.c:60 sofia/external/03996590031055(a)203.208.207.212 Standard DESTROY 2011-06-10 11:10:30.715878 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996590031055(a)203.208.207.212) State DESTROY going to sleep recv 383 bytes from udp/[203.208.207.212]:5060 at 03:10:30.823302: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D00000u00000001QXU3LU Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 CANCEL Contact: <sip:203.208.207.212:5060> Content-Length: 0 ------------------------------------------------------------------------ recv 411 bytes from udp/[203.208.207.212]:5060 at 03:10:30.824765: ------------------------------------------------------------------------ SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Reason: SIP;cause=487;text="Request Terminated" Content-Length: 0 ------------------------------------------------------------------------ send 371 bytes to udp/[203.208.207.212]:5060 at 03:10:30.824891: ------------------------------------------------------------------------ ACK sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 380 bytes from udp/[203.208.207.212]:5060 at 03:10:30.826375: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bK0D3Hm08XNH1Xg;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501634 BYE -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Leg-A-is-automatically-discon… Sent from the freeswitch-users mailing list archive at Nabble.com.

4 14

Hanged up callers doubt
by Leonardo P. Bidinoto 18 Jul '11

18 Jul '11

Hi everyone! I having some troubles with my FS box(CentOS 5.3 64-bit, Xeon(R) CPU 2.66GHz with 8 cores , 8GbRAM, (git-bc19d28 2011-04-25 15-53-54 -0400) ). Sometimes, when a user calls to my box and hangs up, the user's channel remains on FS when i execute a "show channels" command. with "uuid_exists" command, im receiving "false". looking at freeswitch log, the last message for the channels is: 0eeb975f-2ae9-4f7d-9888-1478faf87d2d 2011-05-11 00:05:57.555142 [DEBUG] switch_core_session.c:1286 Session 38 (sofia/external/2562687580(a)10.0.70.6) Locked, Waiting on external entities Im not receiving "Ended" State after the previous message. did someone have seen this issue? or know a way to remove from freeswitch? Thanks all. -- Leonardo Pires Bidinoto Voice Technology www.voicetechnology.com.br

4 14

FreeTDM - Sangoma B700 - ISDN connection questions - UK
by John 14 Jul '11

14 Jul '11

Hello, I have just set up a FreeSWITCH box with a Sangoma B700 connected to 2 x ISDN2e lines (each 2B+D, so 4 voice channels) in UK, so supplied by BT Openreach. There are a number of anomalies that I am trying to solve. [One of these is that 1 line is dead, but that is for BT Openreach to resolve rather than anyone on this list.] I have a few questions, but as they are all related, I hope you don't mind them in one post. Some basics first. The box is an Atom dual-core with 2GB of memory and a Sangoma B700 card. It's built with Ubuntu 10.04.2 LTS server 64-bit, patched and up to date, and also runs dhcpd, lighttpd and sshd. I built the Sangoma ISDN libraries and FreeSWITCH using the latest git versions I could ('make current' about 2 weeks ago). We have incoming and outgoing calls working, but some incoming calls ring in the caller's ear, but nothing appears on the FreeSWITCH console, and others fail. Rebooting the server cures this. We have had problems with lines being disconnected and then reconnected, and it seems that FreeTDM/wanrouter/whatever doesn't recognise previously disconnected lines coming back into use, because a reboot finds lines that were previously reported disconnected ('wanrouter status' or 'ftdm list'). We have ongoing problems with one line that is 'disconnected' (wanpipe2/wp2) but the queries I am describing below apply equally when both lines are connected and working. 1. There seems to be a lot of ISDN 'chatter' with channels going up and down all the time even when the switch is completely idle. Is this normal? Here is my /log 7 showing two of the cycles (which appear to be about 50s apart): 2011-06-06 22:01:54.992467 [DEBUG] ftmod_sangoma_isdn_stack_rcv.c:681 [SNGISDN Q921] wp1: Protocol: Data Link connection DN(4): Disconnect initiated(263) 2011-06-06 22:02:29.952464 [WARNING] ftmod_sangoma_isdn_stack_rcv.c:748 [SNGISDN Q931] s1: Interface: Down(261): Dchan(285) 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c1][1:1] Signalling link status changed to DOWN 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c2][1:2] Signalling link status changed to DOWN 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c3][1:3] Signalling link status changed to DOWN 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c1][1:1] Setting availability rate to:5 2011-06-06 22:02:29.952464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c1][1:1] Setting availability rate to:5 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c2][1:2] Setting availability rate to:5 2011-06-06 22:02:29.952464 [INFO] mod_freetdm.c:2345 1:1 signalling changed to :DOWN 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c2][1:2] Setting availability rate to:5 2011-06-06 22:02:29.952464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c3][1:3] Setting availability rate to:5 2011-06-06 22:02:29.952464 [INFO] mod_freetdm.c:2345 1:2 signalling changed to :DOWN 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c3][1:3] Setting availability rate to:5 2011-06-06 22:02:29.952464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.952464 [INFO] mod_freetdm.c:2345 1:3 signalling changed to :DOWN 2011-06-06 22:02:29.952464 [INFO] ftmod_sangoma_isdn_stack_rcv.c:631 Received RESTART CFM (dChan:1 ces:0 type:1) 2011-06-06 22:02:29.952464 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:1179 Receved RESTART, but Restart Indicator IE not present 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_stack_rcv.c:681 [SNGISDN Q921] wp1: Protocol: Data Link connection UP(3): UA frame with F-bit = 1(258) 2011-06-06 22:02:29.972464 [INFO] ftmod_sangoma_isdn_stack_rcv.c:631 Received RESTART CFM (dChan:1 ces:0 type:0) 2011-06-06 22:02:29.972464 [INFO] ftmod_sangoma_isdn_stack_rcv.c:729 [SNGISDN Q931] s1: Interface: UP(260): Dchan(285) 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c1][1:1] Signalling link status changed to UP 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c2][1:2] Signalling link status changed to UP 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c3][1:3] Signalling link status changed to UP 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c1][1:1] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c1][1:1] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c2][1:2] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c2][1:2] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c3][1:3] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c3][1:3] Setting availability rate to:10 2011-06-06 22:02:29.972464 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:1179 Receved RESTART, but Restart Indicator IE not present 2011-06-06 22:02:29.972464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.972464 [INFO] mod_freetdm.c:2345 1:1 signalling changed to :UP 2011-06-06 22:02:29.972464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.972464 [INFO] mod_freetdm.c:2345 1:2 signalling changed to :UP 2011-06-06 22:02:29.972464 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:02:29.972464 [INFO] mod_freetdm.c:2345 1:3 signalling changed to :UP 2011-06-06 22:03:19.912462 [DEBUG] ftmod_sangoma_isdn_stack_rcv.c:681 [SNGISDN Q921] wp1: Protocol: Data Link connection DN(4): Disconnect initiated(263) 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_stack_rcv.c:681 [SNGISDN Q921] wp1: Protocol: Data Link connection UP(3): UA frame with F-bit = 1(258) 2011-06-06 22:03:19.932483 [INFO] ftmod_sangoma_isdn_stack_rcv.c:631 Received RESTART CFM (dChan:1 ces:0 type:0) 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:1179 Receved RESTART, but Restart Indicator IE not present 2011-06-06 22:03:19.932483 [INFO] ftmod_sangoma_isdn_stack_rcv.c:729 [SNGISDN Q931] s1: Interface: UP(260): Dchan(285) 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c1][1:1] Signalling link status changed to UP 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c2][1:2] Signalling link status changed to UP 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_cntrl.c:43 [s1c3][1:3] Signalling link status changed to UP 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c1][1:1] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c1][1:1] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c2][1:2] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c2][1:2] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:200 [s1c3][1:3] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c3][1:3] Setting availability rate to:10 2011-06-06 22:03:19.932483 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:03:19.932483 [INFO] mod_freetdm.c:2345 1:1 signalling changed to :UP 2011-06-06 22:03:19.932483 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:03:19.932483 [INFO] mod_freetdm.c:2345 1:2 signalling changed to :UP 2011-06-06 22:03:19.932483 [DEBUG] mod_freetdm.c:2268 got clear channel sig [SIGSTATUS_CHANGED] 2011-06-06 22:03:19.932483 [INFO] mod_freetdm.c:2345 1:3 signalling changed to :UP freeswitch@internal> ftdm list +OK span: 1 (wp1) type: Sangoma (ISDN) physical_status: ok signaling_status: UP chan_count: 3 dialplan: XML context: public dial_regex: fail_dial_regex: hold_music: analog_options: none +OK span: 2 (wp2) type: Sangoma (ISDN) physical_status: alarmed signaling_status: DOWN chan_count: 3 dialplan: XML context: public dial_regex: fail_dial_regex: hold_music: analog_options: none +OK span: 3 (FXS) type: analog physical_status: ok signaling_status: UP chan_count: 2 dialplan: XML context: default dial_regex: fail_dial_regex: hold_music: analog_options: none # wanrouter status Devices currently active: wanpipe1 wanpipe2 wanpipe3 Wanpipe Config: Device name | Protocol Map | Adapter | IRQ | Slot/IO | If's | CLK | Baud rate | wanpipe1 | N/A | A500/B700| 20 | 0 | 1 | N/A | 0 | wanpipe2 | N/A | A500/B700| 20 | 0 | 1 | N/A | 0 | wanpipe3 | N/A | A200/A400/B600/B700/B800| 20 | 0 | 1 | N/A | 0 | Wanrouter Status: Device name | Protocol | Station | Status | wanpipe1 | AFT ISDN | N/A | Connected | wanpipe2 | AFT ISDN | N/A | Disconnected | wanpipe3 | A-ANALOG | N/A | Connected | # cat /etc/wanpipe/wanrouter.rc #!/bin/sh # .. comments snipped ... ROUTER_BOOT=YES WAN_CONF_DIR=/etc/wanpipe WAN_INTR_DIR=/etc/wanpipe/interfaces WAN_BIN_DIR=/usr/sbin WAN_LOG=/var/log/wanrouter WAN_LOCK=/var/lock/wanrouter WAN_LOCK_DIR=/var/lock WAN_IP_FORWARD=NO NEW_IF_TYPE=NO WAN_LIB_DIR=/etc/wanpipe/lib WAN_ADSL_LIST=/etc/wanpipe/wan_adsl.list WAN_ANNEXG_LOAD=NO WAN_SCTP_LOAD=NO WAN_LIP_LOAD=NO WAN_DYN_WANCONFIG=NO WAN_SCRIPTS_DIR=/etc/wanpipe/scripts WAN_FIRMWARE_DIR=/etc/wanpipe/firmware WAN_DEVICES_REV_STOP_ORDER=YES WAN_DEVICES="wanpipe1 wanpipe2 wanpipe3 " # cat /etc/wanpipe/wanpipe1.conf #================================================ # WANPIPE1 Configuration File #================================================ # # Note: This file was generated automatically # by /usr/local/sbin/setup-sangoma program. # # If you want to edit this file, it is # recommended that you use wancfg program # to do so. #================================================ # Sangoma Technologies Inc. #================================================ [devices] wanpipe1 = WAN_AFT_ISDN_BRI, Comment [interfaces] w1g1 = wanpipe1, , TDM_VOICE_API, Comment [wanpipe1] CARD_TYPE = AFT S514CPU = A CommPort = PRI AUTO_PCISLOT = NO PCISLOT = 0 PCIBUS = 5 FE_MEDIA = BRI FE_LINE = 1 TDMV_LAW = ALAW RM_BRI_CLOCK_MASTER = NO MTU = 1500 UDPPORT = 9000 TTL = 255 IGNORE_FRONT_END = NO TDMV_SPAN = 1 TE_AIS_MAINTENANCE = NO #NO: defualt YES: Start port in AIS Blue Alarm and keep line down #wanpipemon -i w1g1 -c Ttx_ais_off to disable AIS maintenance mode #wanpipemon -i w1g1 -c Ttx_ais_on to enable AIS maintenance mode TDMV_HW_DTMF = YES # YES: receive dtmf events from hardware TDMV_HW_FAX_DETECT = YES # YES: receive fax 1100hz events from hardware HWEC_OPERATION_MODE = OCT_NORMAL # OCT_NORMAL: echo cancelation enabled with nlp (default) # OCT_SPEECH: improves software tone detection by disabling NLP (echo possible) # OCT_NO_ECHO:disables echo cancelation but allows VQE/tone functions. HWEC_DTMF_REMOVAL = NO # NO: default YES: remove dtmf out of incoming media (must have hwdtmf enabled) HWEC_NOISE_REDUCTION = NO # NO: default YES: reduces noise on the line - could break fax HWEC_ACUSTIC_ECHO = NO # NO: default YES: enables acustic echo cancelation HWEC_NLP_DISABLE = NO # NO: default YES: guarantees software tone detection (possible echo) HWEC_TX_AUTO_GAIN = 0 # 0: disable -40-0: default tx audio level to be maintained (-20 default) HWEC_RX_AUTO_GAIN = 0 # 0: disable -40-0: default tx audio level to be maintained (-20 default) HWEC_TX_GAIN = 0 # 0: disable -24-24: db values to be applied to tx signal HWEC_RX_GAIN = 0 # 0: disable -24-24: db values to be applied to tx signal [w1g1] ACTIVE_CH = ALL TDMV_HWEC = YES MTU = 80 ... and wanpipe2 was also automatically generated so just has '2' for FE_LINE and TDMV_SPAN instead of '1'. # cat /usr/local/freeswitch/conf/freetdm.conf [span wanpipe wp1] trunk_type => bri group=1 b-channel => 1:1-2 d-channel => 1:3 [span wanpipe wp2] trunk_type => bri group=1 b-channel => 2:1-2 d-channel => 2:3 [span wanpipe FXS] name => freetdm trunk_type => fxs group => grp2 fxs-channel => 3:1 trunk_type => fxs group => grp2 fxs-channel => 3:2 # cat /usr/local/freeswitch/conf/autoload_configs/freetdm.conf.xml <configuration name="freetdm.conf" description="Freetdm Configuration"> <settings> <param name="debug" value="0"/>    </settings> <config_profiles> <profile name="my_bri_te_1"> <param name="switchtype" value="euroisdn" /> <param name="interface" value="cpe"/> <param name="facility" value="no" /> </profile> </config_profiles> <sangoma_bri_spans> <param name="dialplan" value="XML"/> <param name="context" value="public"/> <param name="dialplan" value="XML"/> <param name="context" value="public"/> </sangoma_bri_spans> <analog_spans>  <param name="dialplan" value="XML"/> <param name="context" value="default"/>     </analog_spans> </configuration> What other config files do I need to supply? I don't want to deluge the list any more in my first post! 2. We have 2 Single Number DDI numbers configured over the 4 channels. I want to set up outgoing calls so that they can appear to come from either of the two numbers, but at present the outgoing CLI appears to be overridden by the telco (BT) to only use one of the numbers. Has anyone got this working in UK, and what is the format for the outbound_caller_id_number: last 6 digits or full 11 digits? I note that the inbound called number is only the last 6 digits. 3. I have built FreeSWITCH from git and installed at /usr/local/... and then followed the steps on the Ubuntu page in the Wiki to set up the run control scripts, etc, and run FS non-root as freeswitch:daemon. With FreeTDM, I have discovered that the /dev/wan* devices are owned by root:root, and so are inaccessible to FS running as non-root. So for now I have added a line in /etc/init.d/freeswitch to 'chgrp freeswitch /dev/wan*'. This is not the most elegant solution, because 'wanrouter restart' (which seems to be my best friend at the moment) resets the ownership to root:root. I have tried grepping to see where the mknods are for these devices, but have been unsuccessful. Is there a better place to 'permanently' change the device ownership? Thanks for all the great support I have already got just from editing my Wiki User page; this is a friendly group! John

13 31

Call drops after 30 seconds
by Matthew Ralston 14 Jul '11

14 Jul '11

Hi, I'm having a problem at the moment with calls being successfully set up, with two-way audio, being terminated by FreeSWITCH after 30 seconds. Internal calls (i.e. between SIP phones on the same LAN segment as the FreeSWITCH box) work flawlessly. The problem arises when at least one of the handsets is located elsewhere on the Internet. This behaviour is exhibited under the following circumstances: - A-leg only call, e.g. to voicemail when the handset is at another location on the Internet - A-leg-B-leg call if one or both of the handsets are at another location on the Internet - Inbound calls from our external SIP provider - Outbound calls to our external SIP provider So it is obvious that the problem is related to the SIP going via the Internet, but I'm having trouble understanding why. Whilst debugging this problem I have placed the FreeSWITCH box is in the DMZ on our router, so there should not be any ports blocked. The FreeSWITCH box itself is not running a software firewall. The calls themselves are absolutely fine for the first 30 seconds - each party can hear the other talking fine. The fact that the call is consistently dropped after 30 seconds (give or take a second or two for PDD) suggests that some timeout is being triggered. When FreeSWITCH terminates the call, the following is logged to the console: 2011-06-22 13:33:50.514941 [DEBUG] sofia.c:4787 Channel sofia/internal/1006(a)public.ip.removed entering state [terminating][0] 2011-06-22 13:33:50.514941 [DEBUG] switch_channel.c:2641 (sofia/internal/1006(a)public.ip.removed) Callstate Change ACTIVE -> HANGUP 2011-06-22 13:33:50.514941 [NOTICE] sofia.c:5508 Hangup sofia/internal/1006(a)public.ip.removed [CS_EXECUTE] [NORMAL_UNSPECIFIED] 2011-06-22 13:33:50.514941 [DEBUG] switch_channel.c:2657 Send signal sofia/internal/1006(a)public.ip.removed [KILL] 2011-06-22 13:33:50.514941 [DEBUG] switch_core_session.c:1118 Send signal sofia/internal/1006(a)public.ip.removed [BREAK] 2011-06-22 13:33:50.534966 [DEBUG] switch_ivr_play_say.c:1649 done playing file 2011-06-22 13:33:50.625988 [DEBUG] switch_ivr_play_say.c:244 Handle play-file:[voicemail/vm-press.wav] (en:en) 2011-06-22 13:33:50.727027 [DEBUG] switch_core_session.c:2063 sofia/internal/1006(a)public.ip.removed skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/1006(a)public.ip.removed) State EXECUTE going to sleep 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/1006(a)public.ip.removed) Running State Change CS_HANGUP 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/1006(a)public.ip.removed) State HANGUP 2011-06-22 13:33:50.727027 [DEBUG] mod_sofia.c:458 Channel sofia/internal/1006(a)public.ip.removed hanging up, cause: NORMAL_UNSPECIFIED 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1006(a)public.ip.removed Standard HANGUP, cause: NORMAL_UNSPECIFIED 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/1006(a)public.ip.removed) State HANGUP going to sleep 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/1006(a)public.ip.removed) State Change CS_HANGUP -> CS_REPORTING 2011-06-22 13:33:50.727027 [DEBUG] switch_core_session.c:1118 Send signal sofia/internal/1006(a)public.ip.removed [BREAK] 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/1006(a)public.ip.removed) Running State Change CS_REPORTING 2011-06-22 13:33:50.727027 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/1006(a)public.ip.removed) State REPORTING 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1006(a)public.ip.removed Standard REPORTING, cause: NORMAL_UNSPECIFIED 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/1006(a)public.ip.removed) State REPORTING going to sleep 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:350 (sofia/internal/1006(a)public.ip.removed) State Change CS_REPORTING -> CS_DESTROY 2011-06-22 13:33:50.740064 [DEBUG] switch_core_session.c:1118 Send signal sofia/internal/1006(a)public.ip.removed [BREAK] 2011-06-22 13:33:50.740064 [DEBUG] switch_core_session.c:1290 Session 5 (sofia/internal/1006(a)public.ip.removed) Locked, Waiting on external entities 2011-06-22 13:33:50.740064 [NOTICE] switch_core_session.c:1308 Session 5 (sofia/internal/1006(a)public.ip.removed) Ended 2011-06-22 13:33:50.740064 [NOTICE] switch_core_session.c:1310 Close Channel sofia/internal/1006(a)public.ip.removed [CS_DESTROY] 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/1006(a)public.ip.removed) Callstate Change HANGUP -> DOWN 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:457 (sofia/internal/1006(a)public.ip.removed) Running State Change CS_DESTROY 2011-06-22 13:33:50.740064 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1006(a)public.ip.removed) State DESTROY 2011-06-22 13:33:50.740064 [DEBUG] mod_sofia.c:363 sofia/internal/1006(a)public.ip.removed SOFIA DESTROY 2011-06-22 13:33:50.780056 [DEBUG] switch_nat.c:570 unmapped public port 31484 protocol UDP to localport 31484 2011-06-22 13:33:50.840070 [DEBUG] switch_nat.c:570 unmapped public port 31485 protocol UDP to localport 31485 2011-06-22 13:33:50.840070 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1006(a)public.ip.removed Standard DESTROY 2011-06-22 13:33:50.840070 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/1006(a)public.ip.removed) State DESTROY going to sleep The above example was from an externally situated SIP phone ringing voicemail (4000) on FreeSWITCH. I have experimented changing various timers and timeouts in the config of FreeSWITCH (one at a time, being careful to put them back afterwards!) but been unable to resolve the issue. Incidentally, we have no long term intention of running off-site SIP phones with the PBX and I'm hoping not to have to leave it in the DMZ either, it's just like that for debugging. What is a real issue is the calls to our external SIP provider (i.e. outbound calls) being dropped. Any suggestions would be greatly appreciated. Thanks, Matthew Ralston Web Developer & IT Consultant matt(a)mralston.co.uk www.mralston.com

6 8