September 2010 - freeswitch-dev@lists.freeswitch.org

[Freeswitch-dev] tone2wav compile error
by François Delawarde 16 Sep '10

16 Sep '10

Hello, This is when trying to compile the latest git: `if test -z "1" ; then echo /bin/sh /home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/quiet_libtool ;else echo /home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/libtool; fi;` --tag=CC --mode=link x86_64-linux-gnu-gcc -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/src/include -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/src/include -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/libs/libteletone/src -fPIC -Werror -fvisibility=hidden -DSWITCH_API_VISIBILITY=1 -DHAVE_VISIBILITY=1 -g -ggdb -Wall -std=c99 -pedantic -Wdeclaration-after-statement -ggdb -lm -lz -lm -o tone2wav tone2wav-tone2wav.o libfreeswitch.la -lssl -lcrypto -lncurses x86_64-linux-gnu-gcc -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/src/include -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/src/include -I/home/francois/svn/cm/packages/trunk/src/freeswitch/freeswitch-trunk/libs/libteletone/src -fPIC -Werror -fvisibility=hidden -DSWITCH_API_VISIBILITY=1 -DHAVE_VISIBILITY=1 -g -ggdb -Wall -std=c99 -pedantic -Wdeclaration-after-statement -ggdb -o .libs/tone2wav tone2wav-tone2wav.o -lz -lm ./.libs/libfreeswitch.so -lssl -lcrypto -lncurses -Wl,--rpath -Wl,/opt/freeswitch/lib ./.libs/libfreeswitch.so: undefined reference to `clock_getres' ./.libs/libfreeswitch.so: undefined reference to `clock_nanosleep' ./.libs/libfreeswitch.so: undefined reference to `crypt_r' ./.libs/libfreeswitch.so: undefined reference to `clock_gettime' ./.libs/libfreeswitch.so: undefined reference to `uuid_generate' collect2: ld returned 1 exit status make[2]: *** [tone2wav] Error 1 François.

5 7

[Freeswitch-dev] Please help with session recording
by Farhan Husain 16 Sep '10

16 Sep '10

Hello, I am trying to write a module which would record all the media sessions. What I did so far is as following: 1. Subscribed to channel events 2. On SWITCH_EVENT_CHANNEL_CREATE I install a callback function using the "switch_core_media_bug_add" function. At this point, my callback method is being called. I can see my debug printout when I leave a voicemail for an offline user. I want to write the media data to a file. The callback method signature is like this: switch_bool_t my_callback(switch_media_bug_t *bug, void *user_data, switch_abc_type_t type) However, I tried to find what "switch_media_bug_t" and "switch_abc_type_t" are in the API reference doc and found their definition. But it seems hard to understand what the fields mean, specially for the first struct. Can anyone help me by describing these two data types? Thanks, Farhan

3 2

[Freeswitch-dev] Strange bug...
by Oleg Khovayko 16 Sep '10

16 Sep '10

Hi, Just for information: Time to time, very seldom, I see strange bug with my FS-machine. FreeSwitch starts to use ~60% of CPU, and eat more and more memory. When it reach ~500MB, system kill this task. Restart does not help. Reboot also. After reboot, FS, when starts, continues to eat CPU and memory. When I try if from terminal, and use command "shutdown", system not exit completely, and freeze, just after print about destroying all connections to SIP-providers. When I tried to debug it, looks like problem in the thread #5. And, last time, I seen following message, after which FS starts working normally: Program received signal SIGSEGV, Segmentation fault. [Switching to Thread 0x28802250 (LWP 100075)] sqlite3HashInsert (pH=0x0, pKey=0x297e7d78, nKey=15, data=0x28c5a080) at ./src/hash.c:347 347 xHash = hashFunction(pH->keyClass); (gdb)

2 1

[Freeswitch-dev] Hundreds of filters with mod_event_socket
by Juan Jose Comellas 15 Sep '10

15 Sep '10

I am using mod_event_socket in inbound mode to receive events and send actions to FreeSWITCH and mod_xml_curl to return dialplans over HTTP. I have multiple FS servers and multiple servers running my application. Currently I have a 1-to-1 mapping between each FS server and a server hosting my application. I want to remove the 1-to-1 mapping and have my application on one server take over the mod_event_socket connection to FS in another server if the application crashes. The plan is to have multiple app instances connected to multiple FS instances where every instance of my app would be connected to every FS server in its group. In order to do this, it looks like I would have to use filters to avoid receiving the events for all the channels on all my app instances all the time. So, when starting the mod _event_socket connection I'd have to do something like this on all my app instances: events plain all filter Event-Name CHANNEL_CREATE And then when a channel is created and all my app instances receive the CHANNEL_CREATE event, have one of them "win" and assign the channel to it. That instance will then issue the following action over mod_event_socket: filter Unique-ID <channel_id> The problem I see with this scheme is that I would probably end up with hundreds of filters per mod_event_socket connection. From what I've seen in the code, the filters are kept as a linked-list. This means that for each event fired, a list of hundreds of elements will have to be evaluated every time, which is obviously undesirable. Does anybody know of a better way to do this? Thanks, Juanjo

1 0

[Freeswitch-dev] FreeSWITCH Conference Call Today - Discussing Cookbook Recipes, etc.
by Michael Collins 15 Sep '10

15 Sep '10

Okay all, here's the agenda: http://wiki.freeswitch.org/wiki/FS_weekly_2010_09_15 Talk to you soon! -MC

1 0

[Freeswitch-dev] FreeSWITCH Cookbook Ideas - Submit Them ASAP!
by Michael Collins 13 Sep '10

13 Sep '10

Hello all, We are still collecting recipe ideas for the FreeSWITCH cookbook. We need your input if this thing is going to happen. Please visit http://wiki.freeswitch.org/wiki/cookbook and add your ideas, or at least comment on what's there. Even random, incoherent input from the community is better than silence! :) We'd like to have a preliminary list of recipe groups (i.e. chapters) by the end of the conference call on Wednesday. Thanks everyone! -Michael

2 4

[Freeswitch-dev] Google Voice incoming calls
by Oleg Khovayko 08 Sep '10

08 Sep '10

Hi, I very excited with your new feature: support GV in the FreeSWITCH. This is real and valuable asset, and I definitely plan to use it. Yesterday, Sep 4, I fetch new FS tree by GIT, build and install. Outgoing calls through GV works OK. But, I faced 2 problems with incoming calls. 1. When calls comes in, phone rings. But, when I pick up handset on recipient's phone, I hear nothing. In the caller's handset,I continue hear long beeps. And, thereafter, in ~30s, GVs answering machine activated. Looks like GV doesn't understand, when I pick up handset on recepient's phone. I tried couple times, with 2 phones: X-lite softphone and VM1188T SIP phone. Result is same. Can you help me to resolve this problem? Log following. 2. When call comes from GV, on the phone, in the CID line, i see message "Google Voice". In the logs, I see Caller's Number in the line: 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3066 Creating an identity for SIP1182451466(a)10.218.25.84 Google Voice <+12404760839> 1002 This meaning, CID sending correct. Problem in the both my phones, they shows CID Name only. I have fixed it, by adding into dialplan "public" following XML code: <extension name="Fix CID" continue="true"> <condition field="caller_id_name" expression="^Google Voice"> <action application="set" data="effective_caller_id_name=${caller_id_number}"/> </condition> </extension> RESUME: Please, help me with [1], and add [2] onto WIKI about GV call, if you found it interesting. Following - log of the unsuccessful call through GV->dingaling 2010-09-05 09:26:48.363159 [DEBUG] sofia_reg.c:1583 Changing expire time to 60 by request of proxy sip:callcentric.com 2010-09-05 09:26:48.455464 [DEBUG] sofia_reg.c:1583 Changing expire time to 60 by request of proxy sip:callcentric.com 2010-09-05 09:27:15.336517 [DEBUG] sofia_reg.c:1583 Changing expire time to 61 by request of proxy sip:callcentric.com 2010-09-05 09:27:20.332705 [DEBUG] sofia_reg.c:1583 Changing expire time to 61 by request of proxy sip:callcentric.com 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:1002 Send Candidate 192.168.1.5:31596 [OfpLNJrFNLpxHUnK] 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3066 Creating an identity for SIP1182451466(a)10.218.25.84 Google Voice <+12404760839> 1002 2010-09-05 09:27:32.554417 [NOTICE] switch_channel.c:779 New Channel dingaling/1002 [dc89b256-f1b8-df11-8091-005004c3cb7e] 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3094 Creating a session for SIP1182451466(a)10.218.25.84 2010-09-05 09:27:32.554417 [NOTICE] switch_channel.c:777 Rename Channel dingaling/1002->DingaLing/new [dc89b256-f1b8-df11-8091-005004c3cb7e] 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3098 (DingaLing/new) State Change CS_NEW -> CS_INIT 2010-09-05 09:27:32.554417 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3200 2 payloads 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3202 Available Payload PCMU 0 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3210 compare PCMU 0/8000 to PCMU 0/8000 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:3221 Choosing Payload index 0 PCMU 0 2010-09-05 09:27:32.554417 [DEBUG] mod_dingaling.c:1077 Send Describe [PCMU@8000] 2010-09-05 09:27:32.562847 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_INIT 2010-09-05 09:27:32.562847 [DEBUG] switch_core_state_machine.c:338 (DingaLing/new) State INIT 2010-09-05 09:27:32.562847 [NOTICE] mod_dingaling.c:1104 Ring-Ready DingaLing/new! 2010-09-05 09:27:32.795806 [DEBUG] mod_dingaling.c:2934 using Existing session for SIP1182451466(a)10.218.25.84 2010-09-05 09:27:32.795806 [DEBUG] mod_dingaling.c:3272 3 candidates 2010-09-05 09:27:32.795806 [DEBUG] mod_dingaling.c:3292 candidate 74.125.93.126:19295 PASS ACL wan.auto 2010-09-05 09:27:32.795806 [DEBUG] mod_dingaling.c:3344 Acceptable Candidate 74.125.93.126:19295 2010-09-05 09:27:32.800152 [DEBUG] mod_dingaling.c:859 Set Read Codec to PCMU@8000 2010-09-05 09:27:32.800152 [DEBUG] mod_dingaling.c:874 Set Write Codec to PCMU@8000 2010-09-05 09:27:35.151687 [DEBUG] switch_nat.c:500 mapped public port 31596 protocol UDP to localport 31596 2010-09-05 09:27:35.151687 [DEBUG] mod_dingaling.c:886 SETUP RTP 192.168.1.5:31596 -> 74.125.93.126:19295 2010-09-05 09:27:35.151687 [DEBUG] switch_rtp.c:1413 Starting timer [soft] 160 bytes per 20ms 2010-09-05 09:27:35.156967 [DEBUG] switch_rtp.c:3545 Activate VAD codec PCMU 20ms 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1199 (DingaLing/new) State Change CS_INIT -> CS_ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:338 (DingaLing/new) State INIT going to sleep 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_channel.c:1512 (DingaLing/new) Callstate Change DOWN -> RINGING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:341 (DingaLing/new) State ROUTING 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1213 DingaLing/new CHANNEL ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:77 DingaLing/new Standard ROUTING 2010-09-05 09:27:35.156967 [INFO] mod_dialplan_xml.c:331 Processing Google Voice <+12404760839>->1002 in context public 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:119 (DingaLing/new) State Change CS_ROUTING -> CS_EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:341 (DingaLing/new) State ROUTING going to sleep 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:348 (DingaLing/new) State EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1230 DingaLing/new CHANNEL EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:157 DingaLing/new Standard EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [outside_call]=[true] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [bypass_media]=[false] 2010-09-05 09:27:35.156967 [DEBUG] switch_ivr.c:1470 (DingaLing/new) State Change CS_EXECUTE -> CS_ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:35.156967 [DEBUG] switch_core_session.c:658 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:35.156967 [NOTICE] switch_ivr.c:1476 Transfer DingaLing/new to XML[1002@default] 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:348 (DingaLing/new) State EXECUTE going to sleep 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:341 (DingaLing/new) State ROUTING 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1213 DingaLing/new CHANNEL ROUTING 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:77 DingaLing/new Standard ROUTING 2010-09-05 09:27:35.156967 [INFO] mod_dialplan_xml.c:331 Processing Google Voice <+12404760839>->1002 in context default 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:119 (DingaLing/new) State Change CS_ROUTING -> CS_EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:341 (DingaLing/new) State ROUTING going to sleep 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:348 (DingaLing/new) State EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] mod_dingaling.c:1230 DingaLing/new CHANNEL EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] switch_core_state_machine.c:157 DingaLing/new Standard EXECUTE 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [dialed_extension]=[1002] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:938 EXPORT [dialed_extension]=[1002] 2010-09-05 09:27:35.156967 [INFO] switch_ivr_async.c:2464 Bound B-Leg: *1 execute_extension::dx XML features EXECUTE DingaLing/new bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/+12404760839.2010-09-05-09-27-35.wav) 2010-09-05 09:27:35.156967 [INFO] switch_ivr_async.c:2464 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/+12404760839.2010-09-05-09-27-35.wav 2010-09-05 09:27:35.156967 [INFO] switch_ivr_async.c:2464 Bound B-Leg: *3 execute_extension::cf XML features 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [ringback]=[%(2000,4000,440.0,480.0)] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [transfer_ringback]=[local_stream://moh] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [call_timeout]=[30] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [hangup_after_bridge]=[true] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [continue_on_fail]=[true] 2010-09-05 09:27:35.156967 [DEBUG] mod_dptools.c:854 DingaLing/new SET [called_party_callgroup]=[techsupport] 2010-09-05 09:27:35.266981 [DEBUG] switch_ivr_originate.c:1979 variable string 0 = [presence_id=1002(a)192.168.1.5] 2010-09-05 09:27:35.266981 [NOTICE] switch_channel.c:779 New Channel sofia/internal/sip:1002@173.79.240.220:42889 [6de15b58-f1b8-df11-8091-005004c3cb7e] 2010-09-05 09:27:35.266981 [DEBUG] mod_sofia.c:3899 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_NEW -> CS_INIT 2010-09-05 09:27:35.266981 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:35.840736 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_INIT 2010-09-05 09:27:35.840736 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/sip:1002@173.79.240.220:42889) State INIT 2010-09-05 09:27:35.840736 [DEBUG] mod_sofia.c:83 sofia/internal/sip:1002@173.79.240.220:42889 SOFIA INIT 2010-09-05 09:27:38.271136 [DEBUG] switch_nat.c:500 mapped public port 26562 protocol UDP to localport 26562 2010-09-05 09:27:40.682970 [DEBUG] switch_nat.c:500 mapped public port 26563 protocol UDP to localport 26563 2010-09-05 09:27:40.682970 [DEBUG] sofia_glue.c:2036 sip:1002@173.79.240.220:42889;rinstance=67bf5b5a98c827e0 Setting proxy route to sofia/internal/sip:1002@173.79.240.220:42889 2010-09-05 09:27:40.692198 [DEBUG] mod_sofia.c:119 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_INIT -> CS_ROUTING 2010-09-05 09:27:40.692198 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/sip:1002@173.79.240.220:42889) State INIT going to sleep 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_ROUTING 2010-09-05 09:27:40.692198 [DEBUG] switch_channel.c:1512 (sofia/internal/sip:1002@173.79.240.220:42889) Callstate Change DOWN -> RINGING 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/sip:1002@173.79.240.220:42889) State ROUTING 2010-09-05 09:27:40.692198 [DEBUG] mod_sofia.c:142 sofia/internal/sip:1002@173.79.240.220:42889 SOFIA ROUTING 2010-09-05 09:27:40.692198 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2010-09-05 09:27:40.692198 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/sip:1002@173.79.240.220:42889) State ROUTING going to sleep 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_CONSUME_MEDIA 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:360 (sofia/internal/sip:1002@173.79.240.220:42889) State CONSUME_MEDIA 2010-09-05 09:27:40.692198 [DEBUG] switch_core_state_machine.c:360 (sofia/internal/sip:1002@173.79.240.220:42889) State CONSUME_MEDIA going to sleep 2010-09-05 09:27:40.702461 [DEBUG] sofia.c:4405 Channel sofia/internal/sip:1002@173.79.240.220:42889 entering state [calling][0] 2010-09-05 09:27:40.767237 [INFO] sofia.c:709 sofia/internal/sip:1002@173.79.240.220:42889 Update Callee ID to "1002" <1002> 2010-09-05 09:27:40.841074 [DEBUG] sofia.c:4405 Channel sofia/internal/sip:1002@173.79.240.220:42889 entering state [proceeding][180] 2010-09-05 09:27:40.841074 [NOTICE] sofia.c:4477 Ring-Ready sofia/internal/sip:1002@173.79.240.220:42889! 2010-09-05 09:27:40.852851 [DEBUG] switch_core_session.c:658 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:40.852851 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:40.852851 [NOTICE] switch_ivr_originate.c:1079 Pre-Answer DingaLing/new! 2010-09-05 09:27:40.852851 [DEBUG] switch_channel.c:2409 (DingaLing/new) Callstate Change RINGING -> EARLY 2010-09-05 09:27:40.852851 [DEBUG] switch_ivr_originate.c:1128 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2010-09-05 09:27:40.852851 [DEBUG] switch_core_codec.c:116 DingaLing/new Push codec L16:10 2010-09-05 09:27:40.852851 [DEBUG] switch_ivr_originate.c:1193 Play Ringback Tone [%(2000,4000,440.0,480.0)] 2010-09-05 09:27:40.942585 [DEBUG] switch_rtp.c:2527 Correct ip/port confirmed. 2010-09-05 09:27:46.853579 [DEBUG] sofia.c:4405 Channel sofia/internal/sip:1002@173.79.240.220:42889 entering state [completing][200] 2010-09-05 09:27:46.853579 [DEBUG] sofia.c:4416 Remote SDP: 2010-09-05 09:27:46.860768 [DEBUG] sofia.c:4405 Channel sofia/internal/sip:1002@173.79.240.220:42889 entering state [ready][200] 2010-09-05 09:27:46.860768 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20] 2010-09-05 09:27:46.860768 [DEBUG] sofia_glue.c:2446 Set Codec sofia/internal/sip:1002@173.79.240.220:42889 PCMU/8000 20 ms 160 samples 2010-09-05 09:27:46.860768 [DEBUG] sofia_glue.c:3941 Set 2833 dtmf send payload to 101 2010-09-05 09:27:46.860768 [DEBUG] sofia_glue.c:2686 AUDIO RTP [sofia/internal/sip:1002@173.79.240.220:42889] 192.168.1.5 port 26562 -> 192.168.1.8 port 43976 codec: 0 ms: 20 2010-09-05 09:27:46.860768 [DEBUG] switch_rtp.c:1413 Starting timer [soft] 160 bytes per 20ms 2010-09-05 09:27:46.879728 [DEBUG] sofia_glue.c:2896 Set 2833 dtmf send payload to 101 2010-09-05 09:27:46.879728 [DEBUG] sofia_glue.c:2901 Set 2833 dtmf receive payload to 101 2010-09-05 09:27:46.879728 [DEBUG] switch_channel.c:2555 (sofia/internal/sip:1002@173.79.240.220:42889) Callstate Change RINGING -> ACTIVE 2010-09-05 09:27:46.879728 [DEBUG] switch_channel.c:2567 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:46.879728 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:46.879728 [NOTICE] sofia.c:4963 Channel [sofia/internal/sip:1002@173.79.240.220:42889] has been answered 2010-09-05 09:27:46.929612 [DEBUG] switch_core_codec.c:140 DingaLing/new Restore previous codec PCMU:0. 2010-09-05 09:27:46.929612 [DEBUG] switch_core_session.c:658 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:46.929612 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:46.929612 [DEBUG] switch_channel.c:2555 (DingaLing/new) Callstate Change EARLY -> ACTIVE 2010-09-05 09:27:46.929612 [NOTICE] switch_ivr_originate.c:3315 Channel [DingaLing/new] has been answered 2010-09-05 09:27:46.929612 [DEBUG] switch_ivr_originate.c:3360 Originate Resulted in Success: [sofia/internal/sip:1002@173.79.240.220:42889] 2010-09-05 09:27:46.929612 [DEBUG] switch_ivr_originate.c:3360 Originate Resulted in Success: [sofia/internal/sip:1002@173.79.240.220:42889] 2010-09-05 09:27:46.929612 [DEBUG] switch_core_session.c:658 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:46.929612 [DEBUG] switch_core_session.c:658 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:46.929612 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:46.929612 [DEBUG] switch_ivr_bridge.c:1186 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2010-09-05 09:27:46.929612 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:46.977702 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_EXCHANGE_MEDIA 2010-09-05 09:27:46.977702 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:1002@173.79.240.220:42889) State EXCHANGE_MEDIA 2010-09-05 09:27:46.977702 [DEBUG] mod_sofia.c:548 SOFIA EXCHANGE_MEDIA 2010-09-05 09:27:46.977702 [DEBUG] switch_core_session.c:720 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:46.977702 [DEBUG] switch_core_session.c:720 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:46.977702 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.241475 [INFO] switch_rtp.c:2510 Auto Changing port from 192.168.1.8:43976 to 173.79.240.220:43976 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:2934 using Existing session for SIP1182451466(a)10.218.25.84 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:3404 hungup DingaLing/new 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:704 Terminate called from line 3405 state=CS_EXECUTE 2010-09-05 09:27:47.261722 [DEBUG] switch_channel.c:2322 (DingaLing/new) Callstate Change ACTIVE -> HANGUP 2010-09-05 09:27:47.261722 [NOTICE] mod_dingaling.c:725 Hangup DingaLing/new [CS_EXECUTE] [NORMAL_CLEARING] 2010-09-05 09:27:47.261722 [DEBUG] switch_channel.c:2338 Send signal DingaLing/new [KILL] 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.261722 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.261722 [DEBUG] mod_dingaling.c:3406 End Call 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:473 DingaLing/new ending bridge by request from write function 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:560 BRIDGE THREAD DONE [sofia/internal/sip:1002@173.79.240.220:42889] 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:580 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:47.295949 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.295949 [DEBUG] switch_channel.c:2322 (sofia/internal/sip:1002@173.79.240.220:42889) Callstate Change ACTIVE -> HANGUP 2010-09-05 09:27:47.295949 [NOTICE] switch_ivr_bridge.c:632 Hangup sofia/internal/sip:1002@173.79.240.220:42889 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2010-09-05 09:27:47.295949 [DEBUG] switch_channel.c:2338 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [KILL] 2010-09-05 09:27:47.295949 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:1002@173.79.240.220:42889) State EXCHANGE_MEDIA going to sleep 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_HANGUP 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/sip:1002@173.79.240.220:42889) State HANGUP 2010-09-05 09:27:47.295949 [DEBUG] mod_sofia.c:453 Channel sofia/internal/sip:1002@173.79.240.220:42889 hanging up, cause: NORMAL_CLEARING 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:479 DingaLing/new ending bridge by request from read function 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:560 BRIDGE THREAD DONE [DingaLing/new] 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:580 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:1257 sofia/internal/sip:1002@173.79.240.220:42889 skip receive message [UNBRIDGE] (channel is hungup already) 2010-09-05 09:27:47.295949 [DEBUG] switch_ivr_bridge.c:1260 DingaLing/new skip receive message [UNBRIDGE] (channel is hungup already) 2010-09-05 09:27:47.295949 [DEBUG] switch_core_session.c:1909 DingaLing/new skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:348 (DingaLing/new) State EXECUTE going to sleep 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_HANGUP 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:535 (DingaLing/new) State HANGUP 2010-09-05 09:27:47.295949 [DEBUG] mod_dingaling.c:1310 DingaLing/new CHANNEL HANGUP 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:46 DingaLing/new Standard HANGUP, cause: NORMAL_CLEARING 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:535 (DingaLing/new) State HANGUP going to sleep 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:333 (DingaLing/new) State Change CS_HANGUP -> CS_REPORTING 2010-09-05 09:27:47.295949 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:47.295949 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:314 (DingaLing/new) Running State Change CS_REPORTING 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:595 (DingaLing/new) State REPORTING 2010-09-05 09:27:47.295949 [DEBUG] mod_sofia.c:496 Sending BYE to sofia/internal/sip:1002@173.79.240.220:42889 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:53 DingaLing/new Standard REPORTING, cause: NORMAL_CLEARING 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:595 (DingaLing/new) State REPORTING going to sleep 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:327 (DingaLing/new) State Change CS_REPORTING -> CS_DESTROY 2010-09-05 09:27:47.295949 [DEBUG] switch_core_session.c:1039 Send signal DingaLing/new [BREAK] 2010-09-05 09:27:47.295949 [DEBUG] mod_dingaling.c:1341 DingaLing/new CHANNEL KILL 2010-09-05 09:27:47.295949 [DEBUG] switch_core_session.c:1202 Session 1 (DingaLing/new) Locked, Waiting on external entities 2010-09-05 09:27:47.295949 [NOTICE] switch_core_session.c:1220 Session 1 (DingaLing/new) Ended 2010-09-05 09:27:47.295949 [NOTICE] switch_core_session.c:1222 Close Channel DingaLing/new [CS_DESTROY] 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:427 (DingaLing/new) Callstate Change HANGUP -> DOWN 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:430 (DingaLing/new) Running State Change CS_DESTROY 2010-09-05 09:27:47.295949 [DEBUG] switch_core_state_machine.c:440 (DingaLing/new) State DESTROY 2010-09-05 09:27:47.295949 [DEBUG] mod_dingaling.c:1246 NUKE RTP 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:1002@173.79.240.220:42889 Standard HANGUP, cause: NORMAL_CLEARING 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/sip:1002@173.79.240.220:42889) State HANGUP going to sleep 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_HANGUP -> CS_REPORTING 2010-09-05 09:27:47.412489 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_REPORTING 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/sip:1002@173.79.240.220:42889) State REPORTING 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:1002@173.79.240.220:42889 Standard REPORTING, cause: NORMAL_CLEARING 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/sip:1002@173.79.240.220:42889) State REPORTING going to sleep 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/sip:1002@173.79.240.220:42889) State Change CS_REPORTING -> CS_DESTROY 2010-09-05 09:27:47.412489 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/sip:1002@173.79.240.220:42889 [BREAK] 2010-09-05 09:27:47.412489 [DEBUG] switch_core_session.c:1202 Session 2 (sofia/internal/sip:1002@173.79.240.220:42889) Locked, Waiting on external entities 2010-09-05 09:27:47.412489 [NOTICE] switch_core_session.c:1220 Session 2 (sofia/internal/sip:1002@173.79.240.220:42889) Ended 2010-09-05 09:27:47.412489 [NOTICE] switch_core_session.c:1222 Close Channel sofia/internal/sip:1002@173.79.240.220:42889 [CS_DESTROY] 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:427 (sofia/internal/sip:1002@173.79.240.220:42889) Callstate Change HANGUP -> DOWN 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:430 (sofia/internal/sip:1002@173.79.240.220:42889) Running State Change CS_DESTROY 2010-09-05 09:27:47.412489 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/sip:1002@173.79.240.220:42889) State DESTROY 2010-09-05 09:27:47.412489 [DEBUG] mod_sofia.c:358 sofia/internal/sip:1002@173.79.240.220:42889 SOFIA DESTROY 2010-09-05 09:27:47.533113 [DEBUG] switch_nat.c:560 unmapped public port 31596 protocol UDP to localport 31596 2010-09-05 09:27:47.533113 [DEBUG] switch_core_state_machine.c:60 DingaLing/new Standard DESTROY 2010-09-05 09:27:47.533113 [DEBUG] switch_core_state_machine.c:440 (DingaLing/new) State DESTROY going to sleep 2010-09-05 09:27:47.533113 [DEBUG] switch_nat.c:560 unmapped public port 26562 protocol UDP to localport 26562 2010-09-05 09:27:47.642883 [DEBUG] switch_nat.c:560 unmapped public port 26563 protocol UDP to localport 26563 2010-09-05 09:27:47.642883 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:1002@173.79.240.220:42889 Standard DESTROY 2010-09-05 09:27:47.642883 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/sip:1002@173.79.240.220:42889) State DESTROY going to sleep 2010-09-05 09:27:48.361715 [DEBUG] sofia_reg.c:1583 Changing expire time to 62 by request of proxy sip:callcentric.com

4 9

[Freeswitch-dev] FreeSWITCH Conference Call Today
by Michael Collins 08 Sep '10

08 Sep '10

Hello all, Today's agenda is here: http://wiki.freeswitch.org/wiki/FS_weekly_2010_09_08 I don't know if Raymond is available today to speak on mod_xml_curl, so if he isn't then maybe we can have a community powwow about the new Google Voice features in mod_dingaling and we can possibly crowd-source the docs. Talk to you in a few hours! -Michael

1 0

[Freeswitch-dev] On high CPU usage and NONBLOCK-ed sockets
by Mathieu Parent 06 Sep '10

06 Sep '10

Hi, I have made some investigations on high CPU usage (Methodology below). In the most CPU-consuming threads, there is: * Several threads related to queues handling (Can't we use interrupt here?) * The time thread * Several threads related to opened sockets. i think there can be some improvement here: Example: Thread 3 (Thread 0xb626fb70 (LWP 5922)): #0 0xb7693f6a in clock_nanosleep (clock_id=-1215964116, flags=0, req=0xb626d928, rem=0x0) at ../sysdeps/unix/sysv/linux/clock_nanosleep.c:49 #1 0xb7764faa in do_sleep (t=882) at src/switch_time.c:165 #2 0xb7765971 in switch_cond_next () at src/switch_time.c:428 #3 0xb6bb553d in read_packet (listener=0x966c418, event=0xb626ecd4, timeout=0) at /home/mathieu/apps/freeswitch/freeswitch.git/src/mod/event_handlers/mod_event_socket/mod_event_socket.c:1316 #4 0xb6bb95a6 in listener_run (thread=0x965b0f0, obj=0x966c418) at /home/mathieu/apps/freeswitch/freeswitch.git/src/mod/event_handlers/mod_event_socket/mod_event_socket.c:2468 #5 0xb77933f5 in dummy_worker (opaque=0x965b0f0) at threadproc/unix/thread.c:138 #6 0xb7645955 in start_thread (arg=0xb626fb70) at pthread_create.c:300 #7 0xb73e910e in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:130 Modules concerned are mod_event_socket, mod_sofia, mod_skinny and probably all endpoints modules. Currently, FS try to read from the socket and if there is no data in the buffer, some checks are made then the thread sleeps for some time (by using do_sleep() or switch_cond_next()). Most of the time this is not needed to do those extra checks that cost CPU. I propose to move to BLOCK-ed sockets with timeout. Also, ajusting the requested length will reduce execution time. Most of the job will be done on the kernel side which is more efficient. Maybe I'm missing some corner-cases, but for mod_skinny, the move improves greatly the performance. This protocol was easy to handle because we first should to read the header then we read the remaining data whose length is in the header. Mathieu Parent Methodology: - Started FS - Connecting various kind of clients (event_socket, SIP, Skinny) - getting list of high CPU thread: ps -eLf | egrep '(freeswitch|CMD)' | sort -n -k 5 - debugging freeswitch with gdb to show backtraces on all thread: "thread apply all bt" - analyse

4 5

[Freeswitch-dev] Another problem with GV
by Oleg Khovayko 06 Sep '10

06 Sep '10

Previos time I reported about problem with receiving calls from GV by dingaling module. I found another problem , with outgoing call. When I originate outgoing call to cell phone by GV, and cancel it on the originator's side -- 1. Bell on recipient's side continues for ~1 min, despite call is canceled. 2. After stop bell (I assume, answering machine is ON on the cell provider's system), freeswitch crashed to core. See log and gdb output in there: http://olegh.ath.cx/fs/fsgv-core.txt Looks like passed NULL pointer to inet_pton() instead of dot-separated string...

1 0