January 2009 - FreeSWITCH-users - FreeSWITCH Mailing Lists

ipauth - directory
by Apostolos Pantsiopoulos 18 Feb '09

18 Feb '09

I noticed an "ip=" setting in the brian.xml sample file. The comments state that this is used for ipauth (IP based authentication?) What exactly is this setting. I cannot find anything in the wiki about it. Does it replace the use of the <param name="auth-calls" value="false"/> + ACL mechanism for IP authentication? -- ------------------------------------------- Apostolos Pantsiopoulos Kinetix Tele.com R & D email: regs(a)kinetix.gr -------------------------------------------

4 6

Freeswitch freezes on increasing call traffic
by shehzad p 06 Feb '09

06 Feb '09

Hi all, Yesterday my Freeswitch server faced a problem when call traffic increased to more than 100. When I start Freeswitch, it works fine and then after some time (approximately 15 to 20 minutes) it stops functioning (means no call is being processed, no CLI command is working and it just freezes) until I restart the freeswitch. I am using Freeswitch 1.0.1. Debug (gdb) trace as on wiki page http://wiki.freeswitch.org/wiki/Debugging_Freeswitch#fs_debug.sh is attached http://www.nabble.com/file/p21701744/fs_debgu.txt fs_debgu.txt -- View this message in context: http://www.nabble.com/Freeswitch-freezes-on-increasing-call-traffic-tp21701… Sent from the Freeswitch-users mailing list archive at Nabble.com.

6 27

spidermonkey problems
by Jonas Gauffin 05 Feb '09

05 Feb '09

Hello I got problems with hanging spidermonkey sessions and need some advice on how to debug them. I've made a javascript queue application that uses mod_spidermonkey_socket. It works fine for a while, but after some calls I noticed that calls didnt get transferred to agents. The reason was that earlier calls had not been terminated properly. freeswitch@test1> hupall 2009-01-15 12:15:04 [CRIT] switch_core_session.c:147 switch_core_session_hupall() Giving up with 8 sessions remaining API CALL [hupall()] output: +OK hangup all channels with cause MANAGER_REQUEST freeswitch@test1> show calls API CALL [show(calls)] output: 0 total. As you can see, 8 sessions are alive, but none of them are listed as calls. What kind of logs should I turn on to see what is happening with those sessions? Thanks, Jonas

5 7

Q931 decoding
by Helmut Kuper 05 Feb '09

05 Feb '09

Hello, if anyone is interessted in a Q931 decoder for FS, I found a way to get FS's Q931 debug hexdumps decoded in wireshark. It's quite simple and mainly about encapsulating each Q931 dump into a TPKT paket and that one in a TCP/IP paket and to put all TCP/IP pakets into a pcap file. regards helmut

11 47

Conference dialing and uuid
by Sias Mey 03 Feb '09

03 Feb '09

Hi, Im trying to build a web based conference control system. Got most of it sorted with some help from the list but I seem to have run into some strangeness. I use a conference dial call to pull extra users into the conference. I couldent find a way of setting channel variables or executing javascript directly on the conference dial since it expects and endpoint and the {} syntax produced an error. So now I am using the Loopback inteface to register some values. One of the functions these script fullfill is to register the uuid of the new channel in the database, however after thinking for a while all of this is working fine upon further testing I found that going through loopback generated 3 channels. I was saving the uuid of the a leg of the call to loopback into the database. However to manipulate the call in the conference I need the uuid of the leg bridgeing to the conference. I have tried some queries against the core database in via javascript however there seems to be some delay as to when the needed leg gets inserted into the channels table. I have tried with execute_on_ring and execute_on_answer. But I suspect that the call only gets added after it is actually answered. Is there some way for me to find the uuid of this call? I can use api calls via conference list to find all the calls in the conference, however if two people get added to the same conference in rapid succesion it will be quite circumstantial as to which one is which since I dont have a way of directly relating the call back to the channels that originally spawned it without a lot of costly text comparisons. Any help from someone who understands this beter than I do will be greatly appreciated. Thanks in advance, Sias

4 14

Strange Performance when using as SBC
by rod 03 Feb '09

03 Feb '09

Dear list, I've been playing with freeswitch for some time (2 months) and the fact is that I'm very pleased with the functionnalities of this software. I'd like to use FS as a SBC handling media and I'm doing some tests with sipp to load the machine but I'm unable to bridge more than 60 calls without seeing the CPU being loaded at 100%. I'm sure something is going wrong with my setup but I'm unable to see what. The test machine has the following specs: Athlon XP 3500+ with 2GB of memory (I know this is not a high end machine :p) Freeswitch:/opt/freeswitch/log# cat /proc/cpuinfo processor : 0 vendor_id : AuthenticAMD cpu family : 15 model : 95 model name : AMD Athlon(tm) 64 Processor 3500+ stepping : 2 cpu MHz : 2199.973 cache size : 512 KB fpu : yes fpu_exception : yes cpuid level : 1 wp : yes flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx mmxext fxsr_opt rdtscp lm 3dnowext 3dnow up rep_good pni cx16 lahf_lm svm extapic cr8_legacy bogomips : 4402.97 TLB size : 1024 4K pages clflush size : 64 cache_alignment : 64 address sizes : 40 bits physical, 48 bits virtual power management: ts fid vid ttp tm stc I installed FS on a fresh debian 64: Linux Freeswitch 2.6.26-1-amd64 #1 SMP Sat Jan 10 17:57:00 UTC 2009 x86_64 GNU/Linux I set the ulimit parameters like those on the website: freeswitch@internal> ... Freeswitch:/opt/free-svn/bin# ulimit -a core file size (blocks, -c) unlimited data seg size (kbytes, -d) unlimited scheduling priority (-e) 0 file size (blocks, -f) unlimited pending signals (-i) unlimited max locked memory (kbytes, -l) unlimited max memory size (kbytes, -m) unlimited open files (-n) 999999 pipe size (512 bytes, -p) 8 POSIX message queues (bytes, -q) unlimited real-time priority (-r) 0 stack size (kbytes, -s) 244 cpu time (seconds, -t) unlimited max user processes (-u) unlimited virtual memory (kbytes, -v) unlimited file locks (-x) unlimited My network setup is the following: SIPP machine (10.10.10.1/24)----------------vlan 55 ----------(10.10.10.254/24) FS (10.10.20.254/24)-------------- vlan56 -------------------(10.10.20.100/24) OTHER STOCK FS I launched sipp with: sipp -sn uac_pcap -s 9999 -r 10 -l 80 -d 60000 -mi 10.10.10.1 -i 10.10.10.1 -mp 25000 10.10.10.254:5060 The dialplan on FS is very simple: <?xml version="1.0" encoding="utf-8"?>  <include> <context name="default"> <extension name="hold_music_bridge"> <condition field="destination_number" expression="^9999$"> <action application="answer"/> <action application="bridge" data="sofia/external/9999(a)10.10.20.100"/> </condition> </extension> </context> </include> FreeSWITCH Version 1.0.trunk (11560M) Started. Crash Protection [Disabled] Max Sessions[1000] Session Rate[100] SQL [Enabled] The test is very simple: sipp dial 9999 that matches in my FS dialplan and this is bridged to an other FS machine playing music on hold. When I launch "top" I see after 30 to 40 s that FS consumes all the CPU ressources (with a mean of 50-60 % before), with 80 calls. When I set 70 calls, I have to wait 70-80 s before seeing the same issue. Presence is set to false on the 2 profile. I have the same issue with FS 1.0.2 that' s why I tried FS 11560. When I use the FS machine as a router to test the packet per second performance, I'm reaching 100Mbps with 8000pps in each direction (from vlan 55 to vlan56) with less than 12% CPU. So that I don't think there's an issue with the network. Here is an "mpstat -P ALL 1" to show you what's happening suddenly with 70 bridge calls: 12:31:26 CPU %user %nice %sys %iowait %irq %soft %steal %idle intr/s 12:31:27 all 3,00 0,00 3,00 0,00 1,00 4,00 0,00 89,00 6241,00 12:31:27 0 3,00 0,00 3,00 0,00 1,00 4,00 0,00 89,00 6241,00 12:31:27 CPU %user %nice %sys %iowait %irq %soft %steal %idle intr/s 12:31:28 all 14,14 0,00 56,57 0,00 2,02 5,05 0,00 22,22 6035,35 12:31:28 0 14,14 0,00 56,57 0,00 2,02 5,05 0,00 22,22 6035,35 12:31:28 CPU %user %nice %sys %iowait %irq %soft %steal %idle intr/s 12:31:29 all 24,75 0,00 67,33 0,00 0,99 6,93 0,00 0,00 5483,17 12:31:29 0 24,75 0,00 67,33 0,00 0,99 6,93 0,00 0,00 5483,17 The CPU is going from 89% idle to 0% in less than 2 seconds. I know that I don't have to expect too much from this kind of hardware, but it seems strange that the CPU power vanished so suddenly. Thanks a lot for the guys that have read this long mail :p kind regards, rod

9 27

LDAP Integration
by Vinicius Kobashi 02 Feb '09

02 Feb '09

7 16

Outbound Proxy configuration
by Boris Lansky 02 Feb '09

02 Feb '09

What way should I configure Freeswitch to get out all the outbound calls through a specific proxy server? Regards, Boris Lansky Unified Communications Telephony Team AT&T Unified Communications Phone: +972.3.976.7604 Fax: +972.3.976.7712 blansky(a)interwise.com <mailto:Simon.shaw@att.com>

5 9

TDM400 FXO can dialout only once
by Nandy Dagondon 01 Feb '09

01 Feb '09

hi everybody, i installed FS 1.0.2 to an Atom mobo (Intel D945GCLF2). it's working using IP phones, softphones and digium FXS port. but there's a problem in dialing out to PSTN using digium tdm400 fxo - it works fine on the first attempt (after starting FS) but it fails on the subsequent attempts. i tested to call using the FXS port and IP phone. same problem. before i place any call, i checked >oz dump 2 1 (show current state = DOWN, last state = DOWN) in the first call, there's this message: [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled type for channel 2:1 but then i hangup. checked >oz dump 21 (show current state=DOWN, last state=HANGUP) in the 2nd (and subsequent) attempts, the fxo just goes off-hook but doesn't send the dtmf tones. >oz dump 2 1 (shows current state = DIALING, last state = DOWN) has anyone encountered this problem before? i appreciate for any help to correct this problem. tks, nandy Environment: ================== kernel 2.6.18-92.1.22.el5 FS 1.0.2 zaptel 1.4.11 oslec digium TDM400P Rev. I (1-FXS, 2-FXO,3-4 vacant) zaptel.conf ======== loadzone = us defaultzone=us channels=1-2 alaw=1-4 fxsks=2 fxoks=1 openzap.conf.xml: =============== <configuration name="openzap.conf" description="OpenZAP Configuration"> <settings> <param name="debug" value="9"/>    </settings>  <analog_spans> <span id="1">    <param name="tonegroup" value="us"/> <param name="digit-timeout" value="2000"/> <param name="max-digits" value="15"/> <param name="dialplan" value="XML"/> <param name="context" value="default"/> <param name="enable-callerid" value="false"/>     </span> <span id="2">    <param name="tonegroup" value="us"/> <param name="digit-timeout" value="2000"/> <param name="max-digits" value="11"/> <param name="dialplan" value="XML"/> <param name="context" value="default"/> <param name="enable-callerid" value=""false/>     </span> </analog_spans> </configuration> openzap.conf ========== [span zt] name => OpenZAP FXS number => 1 fxs-channel => 1 [span zt] name => OpenZAP FXO number => 2 fxo-channel => 2 tones.conf (the dialtone and ring tone is set to Philipping tones) ======== [us] generate-dial => v=-7;%(1000,0,425) detect-dial => 425 generate-ring => v=-7;%(1000,4000,425,480) detect-ring => 425,480 generate-busy => v=-7;%(500,500,480,620) detect-busy => 480,620 generate-attn => v=0;%(200,300,1400,1800) detect-attn => 1400,1800 generate-callwaiting-sas => v=0;%(300,10000,440) detect-callwaiting-sas => 440 generate-callwaiting-cas => v=0;%(80,0,2750,2130) detect-callwaiting-cas => 2750,2130 detect-fail1 => 913.8 detect-fail2 => 1370.6 detect-fail3 => 776.7 LOG OF FIRST CALL (OK) ==================== 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute bridge(openzap/2/1/3400534) 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU 20ms 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel() Connect outbound channel OpenZAP/2:1/3400534 2009-01-25 10:35:58 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel OpenZAP/2:1/3400534 [e5f12114-ea88-11dd-9f5c-290fb4a527a4] 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel() (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call() Changing state on 2:1 from DOWN to DIALING 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run() ANALOG CHANNEL thread starting. 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT 2009-01-25 10:35:58 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for DIALING 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (OpenZAP/2:1/3400534) State INIT 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:364 channel_on_init() (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_ROUTING 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING 2009-01-25 10:35:58 [DEBUG] mod_openzap.c:387 channel_on_routing() OpenZAP/2:1/3400534 CHANNEL ROUTING 2009-01-25 10:35:58 [DEBUG] switch_ivr_originate.c:58 originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-01-25 10:35:58 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_CONSUME_MEDIA 2009-01-25 10:35:58 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA 2009-01-25 10:35:59 [DEBUG] ozmod_analog.c:615 zap_analog_channel_run() Detected tone DIAL on 2:1 2009-01-25 10:35:59 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig 2:1 [TONE_DETECTED] 2009-01-25 10:35:59 [WARNING] mod_openzap.c:1196 on_fxo_signal() Unhandled type for channel 2:1 2009-01-25 10:35:59 [DEBUG] zap_io.c:1179 zchan_activate_dtmf_buffer() Created DTMF Buffer! 2009-01-25 10:35:59 [DEBUG] zap_io.c:1715 handle_dtmf() 2:1 GENERATE DTMF [3400534] 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:302 zap_analog_channel_run() Changing state on 2:1 from DIALING to UP 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for UP 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig 2:1 [UP] 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1710 switch_channel_perform_mark_answered() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:1180 on_fxo_signal() Channel [OpenZAP/2:1/3400534] has been answered 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC] 2009-01-25 10:36:03 [DEBUG] switch_channel.c:1768 switch_channel_perform_answer() OpenZAP/1:1/93400534 receive message [ANSWER] 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:813 channel_receive_message_fxs() Changing state on 1:1 from IDLE to UP 2009-01-25 10:36:03 [NOTICE] mod_openzap.c:814 channel_receive_message_fxs() Channel [OpenZAP/1:1/93400534] has been answered 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message [AUDIO_SYNC] 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511 switch_core_session_perform_receive_message() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:36:03 [DEBUG] switch_ivr_originate.c:1627 switch_ivr_originate() Originate Resulted in Success: [OpenZAP/2:1/3400534] 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() OpenZAP/2:1/3400534 receive message [AUDIO_SYNC] 2009-01-25 10:36:03 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() OpenZAP/1:1/93400534 receive message [AUDIO_SYNC] 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:862 switch_ivr_multi_threaded_bridge() OpenZAP/2:1/3400534 receive message [BRIDGE] 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511 switch_core_session_perform_receive_message() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:869 switch_ivr_multi_threaded_bridge() OpenZAP/1:1/93400534 receive message [BRIDGE] 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:511 switch_core_session_perform_receive_message() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:36:03 [DEBUG] switch_ivr_bridge.c:913 switch_ivr_multi_threaded_bridge() (OpenZAP/2:1/3400534) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-01-25 10:36:03 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:03 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 1:1 for UP 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig [UP] 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to sleep 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_EXCHANGE_MEDIA 2009-01-25 10:36:03 [DEBUG] switch_core_state_machine.c:457 switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA 2009-01-25 10:36:03 [DEBUG] mod_openzap.c:511 channel_on_exchange_media() CHANNEL EXCHANGE_MEDIA 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:744 process_event() EVENT [ONHOOK][1:1] STATE [UP] 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:780 process_event() Changing state on 1:1 from UP to DOWN 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 1:1 for DOWN 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig [STOP] 2009-01-25 10:36:16 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING] 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL] 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:36:16 [DEBUG] zap_io.c:1125 zap_channel_done() channel done 1:1 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run() ANALOG CHANNEL 1:1 thread ended. 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:360 audio_bridge_thread() OpenZAP/1:1/93400534 ending bridge by request from read function 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread() BRIDGE THREAD DONE [OpenZAP/1:1/93400534] 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:354 audio_bridge_thread() OpenZAP/1:1/93400534 ending bridge by request from write function 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:409 audio_bridge_thread() OpenZAP/2:1/3400534 receive message [UNBRIDGE] 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:511 switch_core_session_perform_receive_message() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread() BRIDGE THREAD DONE [OpenZAP/2:1/3400534] 2009-01-25 10:36:16 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:36:16 [NOTICE] switch_ivr_bridge.c:470 audio_bridge_on_exchange_media() Hangup OpenZAP/2:1/3400534 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-01-25 10:36:16 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL] 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:457 switch_core_session_run() (OpenZAP/2:1/3400534) State EXCHANGE_MEDIA going to sleep 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_HANGUP 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing state on 2:1 from UP to HANGUP 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup() OpenZAP/2:1/3400534 CHANNEL HANGUP 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause: NORMAL_CLEARING 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Locked, Waiting on external entities 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to sleep 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 2 (OpenZAP/2:1/3400534) Ended 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP] 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change CS_HANGUP 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP 2009-01-25 10:36:16 [DEBUG] mod_openzap.c:472 channel_on_hangup() OpenZAP/1:1/93400534 CHANNEL HANGUP 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP, cause: NORMAL_CLEARING 2009-01-25 10:36:16 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep 2009-01-25 10:36:16 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Locked, Waiting on external entities 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 1 (OpenZAP/1:1/93400534) Ended 2009-01-25 10:36:16 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP] 2009-01-25 10:36:16 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for HANGUP 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run() Changing state on 2:1 from HANGUP to DOWN 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for DOWN 2009-01-25 10:36:17 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig 2:1 [STOP] 2009-01-25 10:36:17 [DEBUG] zap_io.c:1125 zap_channel_done() channel done 2:1 2009-01-25 10:36:17 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run() ANALOG CHANNEL 2:1 thread ended. LOG OF FAILED CALLS ================== 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() OpenZAP/1:1/93400534 Execute bridge(openzap/2/1/3400534) 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:340 tech_init() Set codec PCMU 20ms 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1017 channel_outgoing_channel() Connect outbound channel OpenZAP/2:1/3400534 2009-01-25 10:36:55 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel OpenZAP/2:1/3400534 [079f5420-ea89-11dd-9f5c-290fb4a527a4] 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:1026 channel_outgoing_channel() (OpenZAP/2:1/3400534) State Change CS_NEW -> CS_INIT 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:52 analog_fxo_outgoing_call() Changing state on 2:1 from DOWN to DIALING 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:239 zap_analog_channel_run() ANALOG CHANNEL thread starting. 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_INIT 2009-01-25 10:36:55 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for DIALING 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (OpenZAP/2:1/3400534) State INIT 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:364 channel_on_init() (OpenZAP/2:1/3400534) State Change CS_INIT -> CS_ROUTING 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (OpenZAP/2:1/3400534) State INIT going to sleep 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_ROUTING 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING 2009-01-25 10:36:55 [DEBUG] mod_openzap.c:387 channel_on_routing() OpenZAP/2:1/3400534 CHANNEL ROUTING 2009-01-25 10:36:55 [DEBUG] switch_ivr_originate.c:58 originate_on_routing() (OpenZAP/2:1/3400534) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-01-25 10:36:55 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (OpenZAP/2:1/3400534) State ROUTING going to sleep 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_CONSUME_MEDIA 2009-01-25 10:36:55 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:744 process_event() EVENT [ONHOOK][1:1] STATE [IDLE] 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:780 process_event() Changing state on 1:1 from IDLE to DOWN 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 1:1 for DOWN 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:1212 on_fxs_signal() got FXS sig [STOP] 2009-01-25 10:37:08 [NOTICE] mod_openzap.c:1300 on_fxs_signal() Hangup OpenZAP/1:1/93400534 [CS_EXECUTE] [NORMAL_CLEARING] 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal OpenZAP/1:1/93400534 [KILL] 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/1:1/93400534 [BREAK] 2009-01-25 10:37:08 [DEBUG] zap_io.c:1125 zap_channel_done() channel done 1:1 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run() ANALOG CHANNEL 1:1 thread ended. 2009-01-25 10:37:08 [NOTICE] switch_ivr_originate.c:1566 switch_ivr_originate() Hangup OpenZAP/2:1/3400534 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2009-01-25 10:37:08 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal OpenZAP/2:1/3400534 [KILL] 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal OpenZAP/2:1/3400534 [BREAK] 2009-01-25 10:37:08 [DEBUG] switch_ivr_originate.c:1691 switch_ivr_originate() Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2009-01-25 10:37:08 [INFO] mod_dptools.c:1909 audio_bridge_function() Originate Failed. Cause: ORIGINATOR_CANCEL 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (OpenZAP/1:1/93400534) State EXECUTE going to sleep 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/1:1/93400534) Running State Change CS_HANGUP 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup() OpenZAP/1:1/93400534 CHANNEL HANGUP 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() OpenZAP/1:1/93400534 Standard HANGUP, cause: NORMAL_CLEARING 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/1:1/93400534) State HANGUP going to sleep 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Locked, Waiting on external entities 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 3 (OpenZAP/1:1/93400534) Ended 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (OpenZAP/2:1/3400534) State CONSUME_MEDIA going to sleep 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel OpenZAP/1:1/93400534 [CS_HANGUP] 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (OpenZAP/2:1/3400534) Running State Change CS_HANGUP 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:429 channel_on_hangup() Changing state on 2:1 from DIALING to HANGUP 2009-01-25 10:37:08 [DEBUG] mod_openzap.c:472 channel_on_hangup() OpenZAP/2:1/3400534 CHANNEL HANGUP 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() OpenZAP/2:1/3400534 Standard HANGUP, cause: ORIGINATOR_CANCEL 2009-01-25 10:37:08 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (OpenZAP/2:1/3400534) State HANGUP going to sleep 2009-01-25 10:37:08 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Locked, Waiting on external entities 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 4 (OpenZAP/2:1/3400534) Ended 2009-01-25 10:37:08 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel OpenZAP/2:1/3400534 [CS_HANGUP] 2009-01-25 10:37:08 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for HANGUP 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:351 zap_analog_channel_run() Changing state on 2:1 from HANGUP to DOWN 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:410 zap_analog_channel_run() Executing state handler on 2:1 for DOWN 2009-01-25 10:37:09 [DEBUG] mod_openzap.c:1153 on_fxo_signal() got FXO sig 2:1 [STOP] 2009-01-25 10:37:09 [DEBUG] zap_io.c:1125 zap_channel_done() channel done 2:1 2009-01-25 10:37:09 [DEBUG] ozmod_analog.c:726 zap_analog_channel_run() ANALOG CHANNEL 2:1 thread ended.

4 10

Can't get debug to stop
by Ron McCarthy 31 Jan '09

31 Jan '09

Hi, I enabled debug and SIP trace via: export SOFIA_DEBUG=9 export NUA_DEBUG=9 export NTA_DEBUG=9 export TPORT_DEBUG=9 export TPORT_LOG=1 I then tried to unset the vars, and also export SOFIA_DEBUG=0 and so on. Still the SIP traces are on the screen. I exitied screen and tried again still no go. What am I doing wrong? Im running bash on FreeBSD. Any help would be great, id have to reboot this box just cause I cant figure this out! Thanks

2 4