August 2009 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Freeswitch performance as a redirecting server
by Tihomir Culjaga 26 Aug '09

26 Aug '09

Hello, i'm trying to use freeswitch as a redirecting server meaning FS has to receive an INVITE and according to some rules it will redirect calls to other destinations. CALLING_USER FREESWITCH SOMEWHERE INVITE -------------------------------> <------------------------------ 100 Trying <------------------------------ 302 Moved Temporary ACK -------------------------------> INVITE---------------------------------------------------------------------------------> Well, wverything works well except i have perfromance issues .... on my HW FS cannot do more than 40 CPS (INVITE answered by 302 Moved Temporary). When i increase the rate, FS starts delaying 302 response. Right at 50 CPS i see "calls" being build up in FS and the delay begining to grow. When i observe the machine, load average is almost nothing (load average: 1.41, 0.61, 0.60) CPU never goes to 100%, and i see only one thread taking most load... all others are just sitting there with 1-5 % CPU time. This looks to me as FS handles 302 messages in a single thread?!?! tculjaga@FS:/usr/local/freeswitch/conf/dialplan$ top -H top - 10:41:37 up 167 days, 20:42, 3 users, load average: 1.41, 0.61, 0.60 Tasks: 83 total, 2 running, 81 sleeping, 0 stopped, 0 zombie Cpu(s): 25.3%us, 1.5%sy, 0.0%ni, 30.3%id, 42.7%wa, 0.0%hi, 0.2%si, 0.0%st Mem: 2074520k total, 571244k used, 1503276k free, 259604k buffers Swap: 2650684k total, 3020k used, 2647664k free, 153868k cached PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 4814 root 20 0 34188 20m 3780 S 38 1.0 3:10.29 freeswitch 4800 root 20 0 34188 20m 3780 S 6 1.0 0:08.26 freeswitch 4798 root 20 0 34188 20m 3780 R 5 1.0 0:24.46 freeswitch 4787 root 20 0 34188 20m 3780 S 2 1.0 0:11.24 freeswitch 4794 root 20 0 34188 20m 3780 S 1 1.0 0:11.42 freeswitch 4803 root 20 0 34188 20m 3780 S 1 1.0 0:11.74 freeswitch 4788 root 20 0 34188 20m 3780 S 1 1.0 0:02.96 freeswitch 4804 root 20 0 34188 20m 3780 S 1 1.0 0:01.64 freeswitch 4807 root 20 0 34188 20m 3780 S 1 1.0 0:01.68 freeswitch 4811 root 20 0 34188 20m 3780 S 1 1.0 0:02.50 freeswitch cat /proc/cpuinfo processor : 0 vendor_id : GenuineIntel cpu family : 6 model : 15 model name : Intel(R) Xeon(R) CPU 5140 @ 2.33GHz stepping : 6 cpu MHz : 2333.560 cache size : 4096 KB physical id : 0 siblings : 2 core id : 0 cpu cores : 2 apicid : 0 initial apicid : 0 fdiv_bug : no hlt_bug : no f00f_bug : no coma_bug : no fpu : yes fpu_exception : yes cpuid level : 10 wp : yes flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe lm constant_tsc arch_perfmon pebs bts pni monitor ds_cpl vmx est tm2 ssse3 cx16 xtpr dca lahf_lm bogomips : 4670.78 clflush size : 64 power management: processor : 1 vendor_id : GenuineIntel cpu family : 6 model : 15 model name : Intel(R) Xeon(R) CPU 5140 @ 2.33GHz stepping : 6 cpu MHz : 2333.560 cache size : 4096 KB physical id : 0 siblings : 2 core id : 1 cpu cores : 2 apicid : 1 initial apicid : 1 fdiv_bug : no hlt_bug : no f00f_bug : no coma_bug : no fpu : yes fpu_exception : yes cpuid level : 10 wp : yes flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe lm constant_tsc arch_perfmon pebs bts pni monitor ds_cpl vmx est tm2 ssse3 cx16 xtpr dca lahf_lm bogomips : 4666.82 clflush size : 64 power management: uname -a Linux l01sipindir1 2.6.26-1-686 #1 SMP Sat Jan 10 18:29:31 UTC 2009 i686 GNU/Linux Of course, i've tuned the machine up ulimit -c unlimited ulimit -d unlimited ulimit -f unlimited ulimit -i unlimited ulimit -n 999999 ulimit -q unlimited ulimit -u unlimited ulimit -v unlimited ulimit -x unlimited ulimit -s 240 ulimit -l unlimited ulimit -a Started FS with minimum modules but still 40 CPS seems to be the limit. So, is there any way to improve performance? Tihomir.

9 25

bind_meta_app: "already broadcasting..broadcast aborted" if bound via event socket
by Harry Vangberg 26 Aug '09

26 Aug '09

Hello. After pestering the IRC channel for a few days with useless information, I think I've finally made a breakthrough, but I have no idea why it works this way. Basically, what I want to achieve is: A calls in and is bridged to B (1234). If B presses *1, he should be taken out, and A be bridged to C (8888) instead. Pretty simple. With the following dialplan it works very nicely: <extension name="ivr"> <condition field="destination_number" expression="^(\d+)$"> <action application="set" data="bypass_media=false"/> <action application="answer"/> <action application="bind_meta_app" data="1 b a bridge::sofia/gateway/secretgw/8888"/> <action application="bridge" data="sofia/gateway/secretgw/1234" /> </condition> </extension> On the other hand, if I try to replicate the above via an outbound event socket it fails with a: "already broadcasting...broadcast aborted". In this case my dialplan XML looks like this: <extension name="ff-ivr"> <condition field="destination_number" expression="^(.*)$"> <action application="set" data="bypass_media=false"/> <action application="socket" data="127.0.0.1:8084 full"/> </condition> </extension> And this is the event socket communication (in reality I use a Ruby framework, do a bunch of database calls etc., but for the sake of clarity I'm mimicking it with a netcat session): -------------------------------- $ nc -v -l 127.0.0.1 8084 connect Event-Name: CHANNEL_DATA Core-UUID: ba78636a-9241-11de-ac94-b736da546252 FreeSWITCH-Hostname: ip-10-226-231-225 FreeSWITCH-IPv4: 10.226.231.225 FreeSWITCH-IPv6: %3A%3A1 Event-Date-Local: 2009-08-26%2013%3A43%3A20 Event-Date-GMT: Wed,%2026%20Aug%202009%2013%3A43%3A20%20GMT Event-Date-Timestamp: 1251294200244673 Event-Calling-File: mod_event_socket.c Event-Calling-Function: parse_command Event-Calling-Line-Number: 1482 Channel-Username: hemmeligt Channel-Dialplan: XML Channel-Caller-ID-Name: hemmeligt Channel-Caller-ID-Number: hemmeligt Channel-Network-Addr: 129.142.224.250 Channel-Destination-Number: 77344541 Channel-Unique-ID: 681102bc-9246-11de-ac94-b736da546252 Channel-Source: mod_sofia Channel-Context: public Channel-Channel-Name: sofia/external/hemmeligt%40129.142.224.250 Channel-Profile-Index: 1 Channel-Profile-Created-Time: 1251294195778388 Channel-Channel-Created-Time: 1251294195778388 Channel-Channel-Answered-Time: 0 Channel-Channel-Progress-Time: 0 Channel-Channel-Progress-Media-Time: 0 Channel-Channel-Hangup-Time: 0 Channel-Channel-Transfer-Time: 0 Channel-Screen-Bit: false Channel-Privacy-Hide-Name: true Channel-Privacy-Hide-Number: true Channel-State: CS_EXECUTE Channel-State-Number: 4 Channel-Name: sofia/external/hemmeligt%40129.142.224.250 Unique-ID: 681102bc-9246-11de-ac94-b736da546252 Call-Direction: inbound Presence-Call-Direction: inbound Answer-State: ringing Channel-Read-Codec-Name: PCMA Channel-Read-Codec-Rate: 8000 Channel-Write-Codec-Name: PCMA Channel-Write-Codec-Rate: 8000 Caller-Username: hemmeligt Caller-Dialplan: XML Caller-Caller-ID-Name: hemmeligt Caller-Caller-ID-Number: hemmeligt Caller-Network-Addr: 129.142.224.250 Caller-Destination-Number: 77344541 Caller-Unique-ID: 681102bc-9246-11de-ac94-b736da546252 Caller-Source: mod_sofia Caller-Context: public Caller-Channel-Name: sofia/external/hemmeligt%40129.142.224.250 Caller-Profile-Index: 1 Caller-Profile-Created-Time: 1251294195778388 Caller-Channel-Created-Time: 1251294195778388 Caller-Channel-Answered-Time: 0 Caller-Channel-Progress-Time: 0 Caller-Channel-Progress-Media-Time: 0 Caller-Channel-Hangup-Time: 0 Caller-Channel-Transfer-Time: 0 Caller-Screen-Bit: false Caller-Privacy-Hide-Name: true Caller-Privacy-Hide-Number: true variable_sip_received_ip: 129.142.224.250 variable_sip_received_port: 5060 variable_sip_via_protocol: udp variable_sip_from_user: hemmeligt variable_sip_from_uri: hemmeligt%40129.142.224.250 variable_sip_from_host: 129.142.224.250 variable_sip_from_user_stripped: hemmeligt variable_sip_from_tag: as19641342 variable_sofia_profile_name: external variable_sip_Remote-Party-ID: %22hemmeligt%22%20%3Csip%3Ahemmeligt%40129.142.224.250%3E%3Bprivacy%3Dfull%3Bscreen%3Dno variable_sip_cid_type: rpid variable_sip_req_user: 77344541 variable_sip_req_port: 5080 variable_sip_req_uri: 77344541%4079.125.42.248%3A5080 variable_sip_req_host: 79.125.42.248 variable_sip_to_user: 77344541 variable_sip_to_port: 5080 variable_sip_to_uri: 77344541%4079.125.42.248%3A5080 variable_sip_to_host: 79.125.42.248 variable_sip_contact_user: hemmeligt variable_sip_contact_uri: hemmeligt%40129.142.224.250 variable_sip_contact_host: 129.142.224.250 variable_channel_name: sofia/external/hemmeligt%40129.142.224.250 variable_sip_call_id: 1aa24dab25cf03f07933f7136822ceff%40129.142.224.250 variable_sip_user_agent: Condor_gw1 variable_sip_via_host: 129.142.224.250 variable_sip_via_port: 5060 variable_sip_via_rport: 5060 variable_max_forwards: 70 variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%2018982%2018982%20IN%20IP4%20129.142.224.250%0D%0As%3Dsession%0D%0Ac%3DIN%20IP4%20129.142.224.250%0D%0At%3D0%200%0D%0Am%3Daudio%2015430%20RTP/AVP%208%200%203%2097%20101%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A97%20iLBC/8000%0D%0Aa%3Dfmtp%3A97%20mode%3D30%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A variable_remote_media_ip: 129.142.224.250 variable_remote_media_port: 15430 variable_read_codec: PCMA variable_read_rate: 8000 variable_write_codec: PCMA variable_write_rate: 8000 variable_endpoint_disposition: RECEIVED variable_bypass_media: false variable_current_application_data: 127.0.0.1%3A8084%20full variable_current_application: socket variable_socket_host: 127.0.0.1 Content-Type: command/reply Reply-Text: %2BOK%0A Socket-Mode: static Control: full sendmsg call-command: execute execute-app-name: answer Content-Type: command/reply Reply-Text: +OK sendmsg call-command: execute execute-app-name: bind_meta_app execute-app-arg: 1 b a bridge::sofia/gateway/secretgw/8888 Content-Type: command/reply Reply-Text: +OK sendmsg call-command: execute execute-app-name: bridge execute-app-arg: sofia/gateway/secretgw/1234 Content-Type: command/reply Reply-Text: +OK -------------------------------- And on the FreeSWITCH side: 2009-08-26 13:43:15.778388 [NOTICE] switch_channel.c:602 New Channel sofia/external/hemmeligt(a)129.142.224.250 [681102bc-9246-11de-ac94-b736da546252] 2009-08-26 13:43:15.778388 [DEBUG] sofia.c:3302 Channel sofia/external/hemmeligt(a)129.142.224.250 entering state [received][100] 2009-08-26 13:43:15.778388 [DEBUG] sofia.c:3309 Remote SDP: v=0 o=root 18982 18982 IN IP4 129.142.224.250 s=session c=IN IP4 129.142.224.250 t=0 0 m=audio 15430 RTP/AVP 8 0 3 97 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 2009-08-26 13:43:15.778388 [DEBUG] sofia_glue.c:3132 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20] 2009-08-26 13:43:15.778388 [DEBUG] sofia_glue.c:2090 Set Codec sofia/external/hemmeligt(a)129.142.224.250 PCMA/8000 20 ms 160 samples 2009-08-26 13:43:15.778388 [DEBUG] sofia_glue.c:3092 Set 2833 dtmf payload to 101 2009-08-26 13:43:15.778388 [DEBUG] sofia.c:3468 (sofia/external/hemmeligt(a)129.142.224.250) State Change CS_NEW -> CS_INIT 2009-08-26 13:43:15.778388 [DEBUG] switch_core_session.c:932 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:398 (sofia/external/hemmeligt(a)129.142.224.250) Running State Change CS_INIT 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:481 (sofia/external/hemmeligt(a)129.142.224.250) State INIT 2009-08-26 13:43:15.778388 [DEBUG] mod_sofia.c:83 sofia/external/hemmeligt(a)129.142.224.250 SOFIA INIT 2009-08-26 13:43:15.778388 [DEBUG] mod_sofia.c:111 (sofia/external/hemmeligt(a)129.142.224.250) State Change CS_INIT -> CS_ROUTING 2009-08-26 13:43:15.778388 [DEBUG] switch_core_session.c:932 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:481 (sofia/external/hemmeligt(a)129.142.224.250) State INIT going to sleep 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:398 (sofia/external/hemmeligt(a)129.142.224.250) Running State Change CS_ROUTING 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:484 (sofia/external/hemmeligt(a)129.142.224.250) State ROUTING 2009-08-26 13:43:15.778388 [DEBUG] mod_sofia.c:130 sofia/external/hemmeligt(a)129.142.224.250 SOFIA ROUTING 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:78 sofia/external/hemmeligt(a)129.142.224.250 Standard ROUTING 2009-08-26 13:43:15.778388 [INFO] mod_dialplan_xml.c:315 Processing hemmeligt->77344541 in context public Dialplan: sofia/external/hemmeligt(a)129.142.224.250 parsing [public->ff-ivr] continue=false Dialplan: sofia/external/hemmeligt(a)129.142.224.250 Regex (PASS) [ff-ivr] destination_number(77344541) =~ /^(.*)$/ break=on-false Dialplan: sofia/external/hemmeligt(a)129.142.224.250 Action set(bypass_media=false) Dialplan: sofia/external/hemmeligt(a)129.142.224.250 Action socket(127.0.0.1:8084 full) 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:114 (sofia/external/hemmeligt(a)129.142.224.250) State Change CS_ROUTING -> CS_EXECUTE 2009-08-26 13:43:15.778388 [DEBUG] switch_core_session.c:932 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:484 (sofia/external/hemmeligt(a)129.142.224.250) State ROUTING going to sleep 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:398 (sofia/external/hemmeligt(a)129.142.224.250) Running State Change CS_EXECUTE 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:491 (sofia/external/hemmeligt(a)129.142.224.250) State EXECUTE 2009-08-26 13:43:15.778388 [DEBUG] mod_sofia.c:173 sofia/external/hemmeligt(a)129.142.224.250 SOFIA EXECUTE 2009-08-26 13:43:15.778388 [DEBUG] switch_core_state_machine.c:151 sofia/external/hemmeligt(a)129.142.224.250 Standard EXECUTE EXECUTE sofia/external/hemmeligt(a)129.142.224.250 set(bypass_media=false) 2009-08-26 13:43:15.778388 [DEBUG] mod_dptools.c:748 sofia/external/hemmeligt(a)129.142.224.250 SET [bypass_media]=[false] EXECUTE sofia/external/hemmeligt(a)129.142.224.250 socket(127.0.0.1:8084 full) 2009-08-26 13:43:21.701447 [DEBUG] switch_ivr.c:540 sofia/external/hemmeligt(a)129.142.224.250 Command Execute answer() EXECUTE sofia/external/hemmeligt(a)129.142.224.250 answer() 2009-08-26 13:43:21.701447 [DEBUG] mod_dptools.c:649 sofia/external/hemmeligt(a)129.142.224.250 receive message [ANSWER] 2009-08-26 13:43:21.701447 [DEBUG] sofia_glue.c:2324 AUDIO RTP [sofia/external/hemmeligt(a)129.142.224.250] 10.226.231.225 port 19016 -> 129.142.224.250 port 15430 codec: 8 ms: 20 2009-08-26 13:43:21.701447 [DEBUG] switch_rtp.c:1138 Starting timer [soft] 160 bytes per 20ms 2009-08-26 13:43:21.702447 [DEBUG] mod_sofia.c:536 Local SDP sofia/external/hemmeligt(a)129.142.224.250: v=0 o=FreeSWITCH 1251275185 1251275186 IN IP4 79.125.42.248 s=FreeSWITCH c=IN IP4 79.125.42.248 t=0 0 m=audio 19016 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2009-08-26 13:43:21.702447 [DEBUG] switch_core_session.c:630 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:21.702447 [NOTICE] mod_dptools.c:649 Channel [sofia/external/hemmeligt(a)129.142.224.250] has been answered 2009-08-26 13:43:21.702447 [DEBUG] switch_channel.c:182 sofia/external/hemmeligt(a)129.142.224.250 receive message [AUDIO_SYNC] 2009-08-26 13:43:21.702447 [DEBUG] sofia.c:3302 Channel sofia/external/hemmeligt(a)129.142.224.250 entering state [completed][200] 2009-08-26 13:43:21.782616 [DEBUG] sofia.c:3302 Channel sofia/external/hemmeligt(a)129.142.224.250 entering state [ready][200] 2009-08-26 13:43:26.388737 [DEBUG] switch_ivr.c:540 sofia/external/hemmeligt(a)129.142.224.250 Command Execute bind_meta_app(1 b a bridge::sofia/gateway/secretgw/8888) EXECUTE sofia/external/hemmeligt(a)129.142.224.250 bind_meta_app(1 b a bridge::sofia/gateway/secretgw/8888) 2009-08-26 13:43:26.388737 [INFO] switch_ivr_async.c:1795 Bound B-Leg: 1 bridge::sofia/gateway/secretgw/8888 2009-08-26 13:43:29.684236 [DEBUG] switch_ivr.c:540 sofia/external/hemmeligt(a)129.142.224.250 Command Execute bridge(sofia/gateway/secretgw/1234) EXECUTE sofia/external/hemmeligt(a)129.142.224.250 bridge(sofia/gateway/secretgw/1234) 2009-08-26 13:43:29.684236 [NOTICE] switch_channel.c:602 New Channel sofia/external/1234 [705ad5d8-9246-11de-ac94-b736da546252] 2009-08-26 13:43:29.684236 [DEBUG] mod_sofia.c:2809 (sofia/external/1234) State Change CS_NEW -> CS_INIT 2009-08-26 13:43:29.684236 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_INIT 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:481 (sofia/external/1234) State INIT 2009-08-26 13:43:29.684236 [DEBUG] mod_sofia.c:83 sofia/external/1234 SOFIA INIT 2009-08-26 13:43:29.684236 [DEBUG] mod_sofia.c:111 (sofia/external/1234) State Change CS_INIT -> CS_ROUTING 2009-08-26 13:43:29.684236 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:29.684236 [DEBUG] sofia.c:3302 Channel sofia/external/1234 entering state [calling][0] 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:481 (sofia/external/1234) State INIT going to sleep 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_ROUTING 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:484 (sofia/external/1234) State ROUTING 2009-08-26 13:43:29.684236 [DEBUG] mod_sofia.c:130 sofia/external/1234 SOFIA ROUTING 2009-08-26 13:43:29.684236 [DEBUG] switch_ivr_originate.c:63 (sofia/external/1234) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-08-26 13:43:29.684236 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:484 (sofia/external/1234) State ROUTING going to sleep 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_CONSUME_MEDIA 2009-08-26 13:43:29.684236 [DEBUG] switch_core_state_machine.c:503 (sofia/external/1234) State CONSUME_MEDIA 2009-08-26 13:43:29.731221 [DEBUG] sofia.c:3302 Channel sofia/external/1234 entering state [calling][0] 2009-08-26 13:43:30.660221 [DEBUG] sofia.c:3302 Channel sofia/external/1234 entering state [proceeding][183] 2009-08-26 13:43:30.660221 [DEBUG] sofia.c:3309 Remote SDP: v=0 o=root 18982 18982 IN IP4 129.142.224.250 s=session c=IN IP4 129.142.224.250 t=0 0 m=audio 12480 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 2009-08-26 13:43:30.660221 [DEBUG] sofia_glue.c:3132 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20] 2009-08-26 13:43:30.660221 [DEBUG] sofia_glue.c:2090 Set Codec sofia/external/1234 PCMA/8000 20 ms 160 samples 2009-08-26 13:43:30.660221 [DEBUG] sofia_glue.c:3092 Set 2833 dtmf payload to 101 2009-08-26 13:43:30.660221 [DEBUG] sofia_glue.c:2324 AUDIO RTP [sofia/external/1234] 10.226.231.225 port 25540 -> 129.142.224.250 port 12480 codec: 8 ms: 20 2009-08-26 13:43:30.660221 [DEBUG] switch_rtp.c:1138 Starting timer [soft] 160 bytes per 20ms 2009-08-26 13:43:30.661084 [NOTICE] sofia_glue.c:2759 Pre-Answer sofia/external/1234! 2009-08-26 13:43:30.661084 [DEBUG] switch_channel.c:1778 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:30.665170 [DEBUG] switch_ivr_originate.c:2061 Originate Resulted in Success: [sofia/external/1234] 2009-08-26 13:43:30.665170 [DEBUG] switch_channel.c:182 sofia/external/1234 receive message [AUDIO_SYNC] 2009-08-26 13:43:30.665170 [DEBUG] switch_channel.c:182 sofia/external/hemmeligt(a)129.142.224.250 receive message [AUDIO_SYNC] 2009-08-26 13:43:30.665170 [DEBUG] switch_ivr_bridge.c:889 sofia/external/1234 receive message [BRIDGE] 2009-08-26 13:43:30.665170 [DEBUG] switch_core_session.c:630 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:30.665170 [DEBUG] switch_ivr_bridge.c:896 sofia/external/hemmeligt(a)129.142.224.250 receive message [BRIDGE] 2009-08-26 13:43:30.665170 [DEBUG] switch_core_session.c:630 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:30.665170 [DEBUG] switch_ivr_bridge.c:940 (sofia/external/1234) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-08-26 13:43:30.665170 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:30.705074 [DEBUG] switch_core_state_machine.c:503 (sofia/external/1234) State CONSUME_MEDIA going to sleep 2009-08-26 13:43:30.705074 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_EXCHANGE_MEDIA 2009-08-26 13:43:30.705074 [DEBUG] switch_core_state_machine.c:494 (sofia/external/1234) State EXCHANGE_MEDIA 2009-08-26 13:43:30.705074 [DEBUG] mod_sofia.c:430 SOFIA LOOPBACK 2009-08-26 13:43:36.642173 [DEBUG] sofia.c:3302 Channel sofia/external/1234 entering state [ready][200] 2009-08-26 13:43:36.642173 [DEBUG] switch_channel.c:1891 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:36.642173 [NOTICE] sofia.c:3752 Channel [sofia/external/1234] has been answered 2009-08-26 13:43:36.642173 [DEBUG] switch_channel.c:182 sofia/external/1234 receive message [AUDIO_SYNC] 2009-08-26 13:43:40.309616 [DEBUG] switch_rtp.c:2222 RTP RECV DTMF *:2000 2009-08-26 13:43:40.970513 [DEBUG] switch_rtp.c:2222 RTP RECV DTMF 1:2000 2009-08-26 13:43:40.970513 [DEBUG] switch_ivr_async.c:1711 sofia/external/hemmeligt(a)129.142.224.250 Processing meta digit '1' [bridge::sofia/gateway/secretgw/8888] 2009-08-26 13:43:40.970513 [WARNING] switch_ivr_async.c:2310 Channel [sofia/external/hemmeligt@129.142.224.250][bridge::sofia/gateway/secretgw/8888] already broadcasting...broadcast aborted 2009-08-26 13:43:46.272706 [NOTICE] sofia.c:327 Hangup sofia/external/1234 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-08-26 13:43:46.272706 [DEBUG] switch_channel.c:1683 Send signal sofia/external/1234 [KILL] 2009-08-26 13:43:46.272706 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:371 sofia/external/1234 ending bridge by request from write function 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:426 sofia/external/hemmeligt(a)129.142.224.250 receive message [UNBRIDGE] 2009-08-26 13:43:46.284704 [DEBUG] switch_core_session.c:630 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/external/hemmeligt(a)129.142.224.250] 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:377 sofia/external/1234 ending bridge by request from read function 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/external/1234] 2009-08-26 13:43:46.284704 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:494 (sofia/external/1234) State EXCHANGE_MEDIA going to sleep 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_HANGUP 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:434 (sofia/external/1234) State HANGUP 2009-08-26 13:43:46.284704 [DEBUG] mod_sofia.c:338 Channel sofia/external/1234 hanging up, cause: NORMAL_CLEARING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:46 sofia/external/1234 Standard HANGUP, cause: NORMAL_CLEARING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:434 (sofia/external/1234) State HANGUP going to sleep 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:476 (sofia/external/1234) State Change CS_HANGUP -> CS_REPORTING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_session.c:932 Send signal sofia/external/1234 [BREAK] 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:398 (sofia/external/1234) Running State Change CS_REPORTING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:612 (sofia/external/1234) State REPORTING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:53 sofia/external/1234 Standard REPORTING, cause: NORMAL_CLEARING 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:612 (sofia/external/1234) State REPORTING going to sleep 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:411 (sofia/external/1234) State Change CS_REPORTING -> CS_DESTROY 2009-08-26 13:43:46.284704 [DEBUG] switch_core_session.c:1068 Session 5 (sofia/external/1234) Locked, Waiting on external entities 2009-08-26 13:43:46.284704 [NOTICE] switch_core_session.c:1086 Session 5 (sofia/external/1234) Ended 2009-08-26 13:43:46.284704 [NOTICE] switch_core_session.c:1088 Close Channel sofia/external/1234 [CS_DESTROY] 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:564 (sofia/external/1234) State DESTROY 2009-08-26 13:43:46.284704 [DEBUG] mod_sofia.c:255 sofia/external/1234 SOFIA DESTROY 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:60 sofia/external/1234 Standard DESTROY 2009-08-26 13:43:46.284704 [DEBUG] switch_core_state_machine.c:564 (sofia/external/1234) State DESTROY going to sleep 2009-08-26 13:44:46.746524 [NOTICE] sofia.c:327 Hangup sofia/external/hemmeligt(a)129.142.224.250 [CS_EXECUTE] [NORMAL_CLEARING] 2009-08-26 13:44:46.746524 [DEBUG] switch_channel.c:1683 Send signal sofia/external/hemmeligt(a)129.142.224.250 [KILL] 2009-08-26 13:44:46.746524 [DEBUG] switch_core_session.c:932 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:491 (sofia/external/hemmeligt(a)129.142.224.250) State EXECUTE going to sleep 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:398 (sofia/external/hemmeligt(a)129.142.224.250) Running State Change CS_HANGUP 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:434 (sofia/external/hemmeligt(a)129.142.224.250) State HANGUP 2009-08-26 13:44:46.747796 [DEBUG] mod_sofia.c:338 Channel sofia/external/hemmeligt(a)129.142.224.250 hanging up, cause: NORMAL_CLEARING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:46 sofia/external/hemmeligt(a)129.142.224.250 Standard HANGUP, cause: NORMAL_CLEARING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:434 (sofia/external/hemmeligt(a)129.142.224.250) State HANGUP going to sleep 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:476 (sofia/external/hemmeligt(a)129.142.224.250) State Change CS_HANGUP -> CS_REPORTING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_session.c:932 Send signal sofia/external/hemmeligt(a)129.142.224.250 [BREAK] 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:398 (sofia/external/hemmeligt(a)129.142.224.250) Running State Change CS_REPORTING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:612 (sofia/external/hemmeligt(a)129.142.224.250) State REPORTING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:53 sofia/external/hemmeligt(a)129.142.224.250 Standard REPORTING, cause: NORMAL_CLEARING 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:612 (sofia/external/hemmeligt(a)129.142.224.250) State REPORTING going to sleep 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:411 (sofia/external/hemmeligt(a)129.142.224.250) State Change CS_REPORTING -> CS_DESTROY 2009-08-26 13:44:46.747796 [DEBUG] switch_core_session.c:1068 Session 4 (sofia/external/hemmeligt(a)129.142.224.250) Locked, Waiting on external entities 2009-08-26 13:44:46.747796 [NOTICE] switch_core_session.c:1086 Session 4 (sofia/external/hemmeligt(a)129.142.224.250) Ended 2009-08-26 13:44:46.747796 [NOTICE] switch_core_session.c:1088 Close Channel sofia/external/hemmeligt(a)129.142.224.250 [CS_DESTROY] 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:564 (sofia/external/hemmeligt(a)129.142.224.250) State DESTROY 2009-08-26 13:44:46.747796 [DEBUG] mod_sofia.c:255 sofia/external/hemmeligt(a)129.142.224.250 SOFIA DESTROY 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:60 sofia/external/hemmeligt(a)129.142.224.250 Standard DESTROY 2009-08-26 13:44:46.747796 [DEBUG] switch_core_state_machine.c:564 (sofia/external/hemmeligt(a)129.142.224.250) State DESTROY going to sleep Of which I mainly find these interesting: EXECUTE sofia/external/hemmeligt(a)129.142.224.250 bind_meta_app(1 b a bridge::sofia/gateway/secretgw/8888) 2009-08-26 13:43:26.388737 [INFO] switch_ivr_async.c:1795 Bound B-Leg: 1 bridge::sofia/gateway/secretgw/8888 [...] 2009-08-26 13:43:40.309616 [DEBUG] switch_rtp.c:2222 RTP RECV DTMF *:2000 2009-08-26 13:43:40.970513 [DEBUG] switch_rtp.c:2222 RTP RECV DTMF 1:2000 2009-08-26 13:43:40.970513 [DEBUG] switch_ivr_async.c:1711 sofia/external/hemmeligt(a)129.142.224.250 Processing meta digit '1' [bridge::sofia/gateway/secretgw/8888] 2009-08-26 13:43:40.970513 [WARNING] switch_ivr_async.c:2310 Channel [sofia/external/hemmeligt@129.142.224.250][bridge::sofia/gateway/secretgw/8888] already broadcasting...broadcast aborted Can anybody explain what is going on here? It seems like the bind_meta_app is bound perfectly via event socket, but when it comes down, it doesn't work. Best regards, Harry

1 1

Newbie startup help. Tutorial? Learning path?
by Merle J. Ebbert 26 Aug '09

26 Aug '09

Hi, I'm trying to avoid taking up a lot of peoples valuable time. SIP & FS have brought some ideas for some commercial products but I need to know where to start. Having once written a proprietary DOS & helped with writing a RTOS, I consider myself capable of learning. I (we) just need to know where to start to come up to speed rapidly. Is there a FreeSWITCH tutorial available? Should someone new start with Asterisk and then possibly move to FS? Thanks, Merle

4 3

caller id on origination does not work
by Shameem Shiek 26 Aug '09

26 Aug '09

I am setting the caller id like this in my ESL script: @con.sendRecv("api originate {origination_caller_id_number=15103245678}sofia/gateway/junctionnetworks/1#{@number} &park()") And the caller id comes out as all zeroes. The sip trace shows the "from" as shown in the sofia status command. This is output of sofia gateway status and my gateway is under /usr/local/freeswitch/conf/sip_profiles/external/junctionnetworks.xml . Also, adding the gateway under /conf/directory/default did not work as the wiki suugests. What I need to do for the caller_id to come through? sofia status gateway junctionnetworks ================================================================================================= Name junctionnetworks Scheme Digest Realm jnctn.net Username username Password yes >From <sip:username@jnctn.net <sip%3Ausername(a)jnctn.net> ;transport=udp> Contact <sip:gw+junctionnetworks@24.87.80.12:5080;transport=udp> Exten 899xxxxxxx To sip:username@sip.jnctn.net <sip%3Ausername(a)sip.jnctn.net> Proxy sip:sip.jnctn.net Context public Expires 3600 Freq 3600 Ping 0 PingFreq 0 State REGED Status UP CallsIN 0 CallsOUT 1

3 3

how to avoid many "|" in bridge application?
by Max Ivanov 26 Aug '09

26 Aug '09

Nowdays I 'm forced to put multiple "|" to find first free gateway, ie sofia/gateway/panas111/1000|sofia/gateway/panas112/1000|sofia/gateway/panas113/1000 , the whole sting is tooo long, is there any shorter way to write same thing? Like "sofia/gateway/panas*/1000" will try all gateways matching the pattern.

6 13

Re: [Freeswitch-users] freeswitch as SBC and kamailio - no route
by Hristo Benev 26 Aug '09

26 Aug '09

I think that the problem is here: ------------------------- 2009-08-26 22:56:52.725878 [INFO] mod_dialplan_xml.c:315 Processing 1001->1000 in context ROUTING Dialplan: sofia/internal/1001(a)209.71.254.33 parsing [ROUTING->PEER_01] continue=false Dialplan: sofia/internal/1001(a)209.71.254.33 Regex (FAIL) [PEER_01] ${sip_h_X-ROUTE}(LOOKUP) =~ /PEER_01/ break=on-false 2009-08-26 22:56:52.728289 [INFO] switch_core_state_machine.c:136 No Route, Aborting -------------------------- Actually Regex FAIL I'm not familiar, but is this stating that ${sip_h_X-ROUTE} should be PEER_01 for success? Here is my default.xml: ---------------- <?xml version="1.0" encoding="utf-8"?>  <include> <context name="default"> <extension name="LOOKUP_ROUTE"> <condition field="destination_number" expression="(\d+)$"> <action application="set" data="hangup_after_bridge=true"/> <action application="set" data="continue_on_fail=true"/> <action application="export" data="sip_h_X-ROUTE=LOOKUP"/> <action application="bridge" data="sofia/internal/${destination_number}@127.0.0.1:5062"/> <action application="set" data="ROUTE_GW=${sip_redirect_contact_user_0}"/> <action application="set" data="AREA=${sip_redirect_contact_user_0}"/> <action application="transfer" data="${destination_number} XML ROUTING"/> </condition> </extension> </context> <context name="ROUTING"> <extension name="PEER_01"> <condition field="${sip_h_X-ROUTE}" expression="PEER_01"> <action application="set" data="hangup_after_bridge=true"/> <action application="set" data="continue_on_fail=NORMAL_TEMPORARY_FAILURE,TIMEOUT,NO_ROUTE_DESTINATION"/> <action application="set" data="PEER=1.1.1.1"/> <action application="bridge" data="sofia/external/${destination_number}(a)1.1.1.1"/> <action application="set" data="PEER=2.2.2.2"/> <action application="bridge" data="sofia/external/${destination_number}(a)2.2.2.2"/> <action application="set" data="PEER=3.3.3.3"/> <action application="bridge" data="sofia/external/${destination_number}(a)3.3.3.3"/> </condition> </extension> </context> </include> -------------------------- >-------- Оригинално писмо -------- >От: Brian West >Относно: Re: [Freeswitch-users] freeswitch as SBC and kamailio - no route >До: freeswitch-users(a)lists.freeswitch.org >Изпратено на: Сряда, 2009, Август 26 19:47:37 EEST >We do not blindly follow 302's as that is a dangerous thing to do. You have to process all 302's in the dialplan. Set this on your sofia profile You can set these variables sip_redirect_profile, sip_redirect_context, sip_redirect_dialplan, When a redirect happens you get these variables - sip_redirect_contact_%d, sip_redirected_to, sip_redirect_contact_user_%d, sip_redirect_contact_host_%d, sip_redirect_contact_params_%d, sip_redirect_dialstring_%d, sip_redirect_dialstring, sip_redirected_byThen its up to you to process the redirect in your dialplan, If you don't set the sip_redirect_context then it'll default to redirected context and XML as the dialplan./bOn Aug 26, 2009, at 11:37 AM, Hristo Benev wrote:HelloI followed the tutorialhttp://wiki.freeswitch.org/wiki/SBC_SetupI have following problem when I dial 1000 Kamalio reports 302, but freeswitch does not routeWhere to look for problems? >

1 0

Internal Profile sends RTP to external IP when using two network interfaces
by Alberto Escudero-Pascual (lists) 26 Aug '09

26 Aug '09

Hi, I have a FS box with two physical network interfaces. The internal interface is hosting several internal phones. I have binded the internal profile SIP/RTP/IP to the private interface. Phones registered correctly but with User: 1000(a)external.ip.address in sofia status profile internal When I place a call between two internal phones, RTP traffic is send to the external IP address of the FS box instead of the internal. The SIP/SDP messages send from FS carry the external IP instead of the internal. The result is that no RTP media arrives to any of the phones. /aep -- Stopping junk mailers is good for the environment

2 4

HOW-TO: being on "trunk" of FreePBX, starting from the ISO
by Giovanni Maruzzelli 26 Aug '09

26 Aug '09

Instructions for being on "trunk" of FreePBX, starting from the ISO (cut and paste to the ssh console after ISO install): /etc/init.d/httpd stop cd /var/www/html mv freepbx freepbx-originale svn co http://www.freepbx.org/v3/svn/trunk/ freepbx chown -R apache.apache freepbx ln -s freepbx freepbx-v3 cd freepbx-v3/ ln -s freepbx freepbx-v3 /etc/init.d/httpd start then browse to: http://192.168.0.12/freepbx-v3/index.php/installer it will work! :-) Sincerely, Giovanni Maruzzelli Cell : +39-347-2665618

2 1

freeswitch as SBC and kamailio - no route
by Hristo Benev 26 Aug '09

26 Aug '09

Hello I followed the tutorial http://wiki.freeswitch.org/wiki/SBC_Setup I have following problem when I dial 1000 Kamalio reports 302, but freeswitch does not route Where to look for problems? Here is the debug: 2009-08-26 20:21:42.332154 [INFO] mod_dialplan_xml.c:315 Processing 1001->1000 in context default Dialplan: sofia/internal/1001(a)10.10.10.10 parsing [default->LOOKUP_ROUTE] continue=false Dialplan: sofia/internal/1001(a)10.10.10.10 Regex (PASS) [LOOKUP_ROUTE] destination_number(1000) =~ /(\d+)$/ break=on-false Dialplan: sofia/internal/1001(a)10.10.10.10 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/1001(a)10.10.10.10 Action set(continue_on_fail=true) Dialplan: sofia/internal/1001(a)10.10.10.10 Action export(sip_h_X-ROUTE=LOOKUP) Dialplan: sofia/internal/1001(a)10.10.10.10 Action bridge(sofia/internal/${destination_number}@127.0.0.1:5062) Dialplan: sofia/internal/1001(a)10.10.10.10 Action set(ROUTE_GW=${sip_redirect_contact_user_0}) Dialplan: sofia/internal/1001(a)10.10.10.10 Action set(AREA=${sip_redirect_contact_user_0}) Dialplan: sofia/internal/1001(a)10.10.10.10 Action transfer(${destination_number} XML ROUTING) 2009-08-26 20:21:42.334579 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/1001(a)10.10.10.10) State Change CS_ROUTING -> CS_EXECUTE 2009-08-26 20:21:42.334579 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1001(a)10.10.10.10 [BREAK] 2009-08-26 20:21:42.334579 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1001(a)10.10.10.10) State ROUTING going to sleep 2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1001(a)10.10.10.10) Running State Change CS_EXECUTE 2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1001(a)10.10.10.10) State EXECUTE 2009-08-26 20:21:42.335292 [DEBUG] mod_sofia.c:173 sofia/internal/1001(a)10.10.10.10 SOFIA EXECUTE 2009-08-26 20:21:42.335292 [DEBUG] switch_core_state_machine.c:151 sofia/internal/1001(a)10.10.10.10 Standard EXECUTE EXECUTE sofia/internal/1001(a)10.10.10.10 set(hangup_after_bridge=true) 2009-08-26 20:21:42.336509 [DEBUG] mod_dptools.c:748 sofia/internal/1001(a)10.10.10.10 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/1001(a)10.10.10.10 set(continue_on_fail=true) 2009-08-26 20:21:42.337353 [DEBUG] mod_dptools.c:748 sofia/internal/1001(a)10.10.10.10 SET [continue_on_fail]=[true] EXECUTE sofia/internal/1001(a)10.10.10.10 export(sip_h_X-ROUTE=LOOKUP) 2009-08-26 20:21:42.338352 [DEBUG] mod_dptools.c:886 EXPORT [sip_h_X-ROUTE]=[LOOKUP] EXECUTE sofia/internal/1001(a)10.10.10.10 bridge(sofia/internal/1000@127.0.0.1:5062) 2009-08-26 20:21:42.339309 [NOTICE] switch_channel.c:602 New Channel sofia/internal/1000@127.0.0.1:5062 [8a588e6a-925c-11de-85dd-15dc0a06983f] 2009-08-26 20:21:42.339309 [DEBUG] mod_sofia.c:2811 (sofia/internal/1000@127.0.0.1:5062) State Change CS_NEW -> CS_INIT 2009-08-26 20:21:42.340376 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000@127.0.0.1:5062 [BREAK] 2009-08-26 20:21:42.341175 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000@127.0.0.1:5062) Running State Change CS_INIT 2009-08-26 20:21:42.341175 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/1000@127.0.0.1:5062) State INIT 2009-08-26 20:21:42.341175 [DEBUG] mod_sofia.c:83 sofia/internal/1000@127.0.0.1:5062 SOFIA INIT 2009-08-26 20:21:42.344378 [DEBUG] mod_sofia.c:111 (sofia/internal/1000@127.0.0.1:5062) State Change CS_INIT -> CS_ROUTING 2009-08-26 20:21:42.344378 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000@127.0.0.1:5062 [BREAK] 2009-08-26 20:21:42.344378 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/1000@127.0.0.1:5062) State INIT going to sleep 2009-08-26 20:21:42.344378 [DEBUG] sofia.c:3289 Channel sofia/internal/1000@127.0.0.1:5062 entering state [calling][0] 2009-08-26 20:21:42.344378 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000@127.0.0.1:5062) Running State Change CS_ROUTING 2009-08-26 20:21:42.345314 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1000@127.0.0.1:5062) State ROUTING 2009-08-26 20:21:42.345314 [DEBUG] mod_sofia.c:130 sofia/internal/1000@127.0.0.1:5062 SOFIA ROUTING 2009-08-26 20:21:42.345314 [DEBUG] switch_ivr_originate.c:63 (sofia/internal/1000@127.0.0.1:5062) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-08-26 20:21:42.345314 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000@127.0.0.1:5062 [BREAK] 2009-08-26 20:21:42.345314 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1000@127.0.0.1:5062) State ROUTING going to sleep 2009-08-26 20:21:42.346255 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000@127.0.0.1:5062) Running State Change CS_CONSUME_MEDIA 2009-08-26 20:21:42.346255 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/1000@127.0.0.1:5062) State CONSUME_MEDIA 2009-08-26 20:21:42.349173 [DEBUG] sofia.c:3289 Channel sofia/internal/1000@127.0.0.1:5062 entering state [calling][0] 2009-08-26 20:21:42.350242 [DEBUG] sofia.c:3289 Channel sofia/internal/1000@127.0.0.1:5062 entering state [terminated][503] 2009-08-26 20:21:42.350242 [NOTICE] sofia.c:3849 Hangup sofia/internal/1000@127.0.0.1:5062 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2009-08-26 20:21:42.351172 [DEBUG] switch_ivr_originate.c:2138 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2009-08-26 20:21:42.351172 [INFO] mod_dptools.c:2093 Originate Failed. Cause: NORMAL_TEMPORARY_FAILURE 2009-08-26 20:21:42.351172 [DEBUG] mod_dptools.c:2120 Continue on fail [true]: Cause: NORMAL_TEMPORARY_FAILURE EXECUTE sofia/internal/1001(a)10.10.10.10 set(ROUTE_GW=PEER-01) 2009-08-26 20:21:42.352234 [DEBUG] mod_dptools.c:748 sofia/internal/1001(a)10.10.10.10 SET [ROUTE_GW]=[PEER-01] 2009-08-26 20:21:42.352234 [DEBUG] switch_channel.c:1683 Send signal sofia/internal/1000@127.0.0.1:5062 [KILL] 2009-08-26 20:21:42.352234 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1000@127.0.0.1:5062 [BREAK] EXECUTE sofia/internal/1001(a)10.10.10.10 set(AREA=PEER-01) 2009-08-26 20:21:42.353179 [DEBUG] mod_dptools.c:748 sofia/internal/1001(a)10.10.10.10 SET [AREA]=[PEER-01] EXECUTE sofia/internal/1001(a)10.10.10.10 transfer(1000 XML ROUTING) 2009-08-26 20:21:42.355174 [DEBUG] switch_ivr.c:1343 (sofia/internal/1001(a)10.10.10.10) State Change CS_EXECUTE -> CS_ROUTING 2009-08-26 20:21:42.355174 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/1001(a)10.10.10.10 [BREAK] 2009-08-26 20:21:42.355174 [DEBUG] switch_ivr.c:1347 sofia/internal/1001(a)10.10.10.10 receive message [TRANSFER] 2009-08-26 20:21:42.355174 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/1001(a)10.10.10.10 [BREAK] 2009-08-26 20:21:42.355174 [NOTICE] switch_ivr.c:1349 Transfer sofia/internal/1001(a)10.10.10.10 to XML[1000@ROUTING] 2009-08-26 20:21:42.356405 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/1001(a)10.10.10.10) State EXECUTE going to sleep 2009-08-26 20:21:42.356405 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1001(a)10.10.10.10) Running State Change CS_ROUTING 2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/1000@127.0.0.1:5062) State CONSUME_MEDIA going to sleep 2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/1000@127.0.0.1:5062) Running State Change CS_HANGUP 2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/1001(a)10.10.10.10) State ROUTING 2009-08-26 20:21:42.357172 [DEBUG] mod_sofia.c:130 sofia/internal/1001(a)10.10.10.10 SOFIA ROUTING 2009-08-26 20:21:42.357172 [DEBUG] switch_core_state_machine.c:78 sofia/internal/1001(a)10.10.10.10 Standard ROUTING 2009-08-26 20:21:42.357172 [INFO] mod_dialplan_xml.c:315 Processing 1001->1000 in context ROUTING Dialplan: sofia/internal/1001(a)10.10.10.10 parsing [ROUTING->PEER_01] continue=false 2009-08-26 20:21:42.358179 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/1000@127.0.0.1:5062) State HANGUP Dialplan: sofia/internal/1001(a)10.10.10.10 Regex (FAIL) [PEER_01] ${sip_h_X-ROUTE}(LOOKUP) =~ /PEER_01/ break=on-false 2009-08-26 20:21:42.358179 [INFO] switch_core_state_machine.c:136 No Route, Aborting 2009-08-26 20:21:42.358179 [DEBUG] mod_sofia.c:306 sofia/internal/1000@127.0.0.1:5062 Overriding SIP cause 503 with 503 from the other leg 2009-08-26 20:21:42.359202 [NOTICE] switch_core_state_machine.c:137 Hangup sofia/internal/1001(a)10.10.10.10 [CS_ROUTING] [NO_ROUTE_DESTINATION]

2 1

Re: [Freeswitch-users] Moved Freeswitch to new subnet.
by Michael S Collins 26 Aug '09

26 Aug '09

> I have looked at that but I am confused on which files need to be > edited. Since I have already installed in Wanpipe mode with the > Sangoma card I skipped straight to the Wanpipe section. It mentions > setting the [span wanpie PRI_1] etc in the openzap.conf then further > down it mentions editing the autoload_configs/openzap.conf.xml with > a completely different format of config. > > > > Are these the same file? If not where does the first one mentioned > reside? > > These are two different files. The openzap.conf file is in the freeswitch/conf directory. It is for configuring OZ to talk to the Sangoma card. The openzap.conf.xml file is for configuring the actual openzap module in FreeSWITCH. The specs you listed on the T1 you have indicate that it's a PRI. However, you need to know which protocol, like NI2 or DMS. Have you ever done a PRI turn up? Who is the carrier? -MC

2 3