February 2011 - FreeSWITCH-users - FreeSWITCH Mailing Lists

FS HA
by Tihomir Culjaga 12 Apr '11

12 Apr '11

hello guys, i configured FS HA and looks like its trying to recover the call .. but the re-INVITE fails due to "wrong/missed" codec capability. freeswitch@internal> freeswitch@internal> sofia profile external siptrace on Enabled sip debugging on external freeswitch@internal> sofia profile internal siptrace on Enabled sip debugging on internal freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> sofia recover Recovered 1 call(s) 2011-01-24 21:32:31.667404 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error; FATAL: terminating connection due to administrator command ][176] freeswitch@internal> 2011-01-24 21:32:31.667404 [INFO] switch_odbc.c:210 The connection has been re-established 2011-01-24 21:32:32.726402 [NOTICE] switch_channel.c:669 New Channel sofia/external/385914392122(a)195.88.212.39[808b0a98-8928-4ca2-a320-8e026ba94018] *2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/385914392122(a)195.88.212.39 PCMA/8000 20 ms 160 samples* 2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/external/385914392122(a)195.88.212.39] 195.88.212.30 port 20092 -> 195.88.212.39 port 17750 codec: 8 ms: 20 2011-01-24 21:32:32.726402 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms 2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send payload to 101 2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf receive payload to 101 2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2790 Set comfort noise payload to 13 2011-01-24 21:32:32.779414 [DEBUG] sofia_glue.c:4246 (sofia/external/ 385914392122(a)195.88.212.39) State Change CS_NEW -> CS_INIT 2011-01-24 21:32:32.779414 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/385914392122(a)195.88.212.39 [BREAK] 2011-01-24 21:32:32.779414 [NOTICE] sofia_glue.c:4249 Resurrecting fallen channel sofia/external/385914392122(a)195.88.212.39 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:314 (sofia/external/385914392122(a)195.88.212.39) Running State Change CS_INIT 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:338 (sofia/external/385914392122(a)195.88.212.39) State INIT 2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:83 sofia/external/ 385914392122(a)195.88.212.39 SOFIA INIT 2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:114 (sofia/external/ 385914392122(a)195.88.212.39) State Change CS_INIT -> CS_EXECUTE 2011-01-24 21:32:32.780381 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/385914392122(a)195.88.212.39 [BREAK] 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:338 (sofia/external/385914392122(a)195.88.212.39) State INIT going to sleep 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:314 (sofia/external/385914392122(a)195.88.212.39) Running State Change CS_EXECUTE 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:348 (sofia/external/385914392122(a)195.88.212.39) State EXECUTE 2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:226 sofia/external/ 385914392122(a)195.88.212.39 SOFIA EXECUTE 2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:157 sofia/external/385914392122(a)195.88.212.39 Standard EXECUTE EXECUTE sofia/external/385914392122(a)195.88.212.39 set(playback_delimiter=!) send 1029 bytes to udp/[195.88.212.39]:62342 at 20:32:32.781241: ------------------------------------------------------------------------ INVITE sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39>SIP/2.0 Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j Route: <sip:385914392122@195.88.212.39:62342;lr> Max-Forwards: 69 From: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r To: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 CSeq: 7614512 INVITE Contact: <sip:385914392122@195.88.212.30:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.6-svn-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 168 X-FS-Support: update_display Remote-Party-ID: "38518880050" <sip:38518880050@195.88.212.30<sip%3A38518880050(a)195.88.212.30> >;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1295881060 1295881062 IN IP4 195.88.212.30 s=FreeSWITCH c=IN IP4 195.88.212.30 t=0 0* m=audio 20092 RTP/AVP 0 13* * a=rtpmap:13 CN/8000* a=ptime:20 ------------------------------------------------------------------------ 2011-01-24 21:32:32.781430 [DEBUG] sofia.c:4153 Channel sofia/external/ 385914392122(a)195.88.212.39 entering state [calling][0] recv 496 bytes from udp/[195.88.212.39]:5060 at 20:32:32.784077: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j From: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r To: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 Date: Mon, 24 Jan 2011 19:22:34 GMT Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 Server: Cisco-SIPGateway/IOS-12.x CSeq: 7614512 INVITE Allow-Events: telephone-event Remote-Party-ID: <sip:385914392122@195.88.212.39<sip%3A385914392122(a)195.88.212.39> >;party=called;screen=yes;privacy=off Content-Length: 0 ------------------------------------------------------------------------ recv 376 bytes from udp/[195.88.212.39]:5060 at 20:32:32.784195: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Media Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j From: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r To: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 Warning: 304 195.88.212.39 "Media Type(s) Unavailable" CSeq: 7614512 INVITE Content-Length: 0 ------------------------------------------------------------------------ send 395 bytes to udp/[195.88.212.39]:62342 at 20:32:32.784303: ------------------------------------------------------------------------ ACK sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39>SIP/2.0 Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j Route: <sip:385914392122@195.88.212.39:62342;lr> Max-Forwards: 69 From: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r To: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 CSeq: 7614512 ACK Content-Length: 0 ------------------------------------------------------------------------ 2011-01-24 21:32:32.784397 [DEBUG] sofia.c:4153 Channel sofia/external/ 385914392122(a)195.88.212.39 entering state [terminated][488] 2011-01-24 21:32:32.784397 [NOTICE] sofia.c:4789 Hangup sofia/external/ 385914392122(a)195.88.212.39 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION] 2011-01-24 21:32:32.784397 [DEBUG] switch_channel.c:2102 Send signal sofia/external/385914392122(a)195.88.212.39 [KILL] 2011-01-24 21:32:32.784397 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/385914392122(a)195.88.212.39 [BREAK] 2011-01-24 21:32:32.852411 [DEBUG] mod_dptools.c:816 sofia/external/ 385914392122(a)195.88.212.39 SET [playback_delimiter]=[!] 2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:348 (sofia/external/385914392122(a)195.88.212.39) State EXECUTE going to sleep 2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:314 (sofia/external/385914392122(a)195.88.212.39) Running State Change CS_HANGUP 2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:499 (sofia/external/385914392122(a)195.88.212.39) State HANGUP 2011-01-24 21:32:32.861414 [DEBUG] mod_sofia.c:408 sofia/external/ 385914392122(a)195.88.212.39 Overriding SIP cause 488 with 488 from the other leg 2011-01-24 21:32:32.861414 [DEBUG] mod_sofia.c:414 Channel sofia/external/ 385914392122(a)195.88.212.39 hanging up, cause: INCOMPATIBLE_DESTINATION 2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:46 sofia/external/385914392122(a)195.88.212.39 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:499 (sofia/external/385914392122(a)195.88.212.39) State HANGUP going to sleep 2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:333 (sofia/external/385914392122(a)195.88.212.39) State Change CS_HANGUP -> CS_REPORTING 2011-01-24 21:32:32.863454 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/385914392122(a)195.88.212.39 [BREAK] 2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:314 (sofia/external/385914392122(a)195.88.212.39) Running State Change CS_REPORTING 2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:590 (sofia/external/385914392122(a)195.88.212.39) State REPORTING 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:53 sofia/external/385914392122(a)195.88.212.39 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:590 (sofia/external/385914392122(a)195.88.212.39) State REPORTING going to sleep 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:327 (sofia/external/385914392122(a)195.88.212.39) State Change CS_REPORTING -> CS_DESTROY 2011-01-24 21:32:32.864328 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/385914392122(a)195.88.212.39 [BREAK] 2011-01-24 21:32:32.864328 [DEBUG] switch_core_session.c:1164 Session 1 (sofia/external/385914392122(a)195.88.212.39) Locked, Waiting on external entities 2011-01-24 21:32:32.864328 [NOTICE] switch_core_session.c:1182 Session 1 (sofia/external/385914392122(a)195.88.212.39) Ended 2011-01-24 21:32:32.864328 [NOTICE] switch_core_session.c:1184 Close Channel sofia/external/385914392122(a)195.88.212.39 [CS_DESTROY] 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:428 (sofia/external/385914392122(a)195.88.212.39) Running State Change CS_DESTROY 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:439 (sofia/external/385914392122(a)195.88.212.39) State DESTROY 2011-01-24 21:32:32.864328 [DEBUG] mod_sofia.c:341 sofia/external/ 385914392122(a)195.88.212.39 SOFIA DESTROY 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:60 sofia/external/385914392122(a)195.88.212.39 Standard DESTROY 2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:439 (sofia/external/385914392122(a)195.88.212.39) State DESTROY going to sleep freeswitch@internal> freeswitch@internal> freeswitch@internal> 2011-01-24 21:32:51.813359 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error; FATAL: terminating connection due to administrator command ][176] 2011-01-24 21:32:51.813359 [INFO] switch_odbc.c:210 The connection has been re-established 2011-01-24 21:32:52.810408 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error; FATAL: terminating connection due to administrator command ][176] 2011-01-24 21:32:52.810408 [INFO] switch_odbc.c:210 The connection has been re-established 2011-01-24 21:32:53.817405 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error; FATAL: terminating connection due to administrator command ][176] 2011-01-24 21:32:53.817405 [INFO] switch_odbc.c:210 The connection has been re-established recv 432 bytes from udp/[195.88.212.39]:62342 at 20:33:06.045642: ------------------------------------------------------------------------ BYE sip:385914392122@195.88.212.30:5080 SIP/2.0 Via: SIP/2.0/UDP 195.88.212.39:5060;branch=z9hG4bK240FA From: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 To: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r Date: Mon, 24 Jan 2011 19:22:34 GMT Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 User-Agent: Cisco-SIPGateway/IOS-12.x Max-Forwards: 70 Timestamp: 1295896987 CSeq: 102 BYE Content-Length: 0 ------------------------------------------------------------------------ send 522 bytes to udp/[195.88.212.39]:5060 at 20:33:06.045858: ------------------------------------------------------------------------ SIP/2.0 481 Call Does Not Exist Via: SIP/2.0/UDP 195.88.212.39:5060;branch=z9hG4bK240FA From: <sip:385914392122@195.88.212.39 <sip%3A385914392122(a)195.88.212.39> >;tag=C4B0BE6C-1B8 To: <sip:38518880050@195.88.212.30 <sip%3A38518880050(a)195.88.212.30> >;tag=DFejDg1B59y9r Call-ID: 1785B58B-272611E0-8909D88B-B59C2BD1(a)195.88.212.39 CSeq: 102 BYE Timestamp: 1295896987 0.000191 User-Agent: FreeSWITCH-mod_sofia/1.0.6-svn-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ freeswitch@internal> freeswitch@internal> my codec settings in vars.conf <X-PRE-PROCESS cmd="set" data="global_codec_prefs=PCMA,GSM"/> <X-PRE-PROCESS cmd="set" data="outbound_codec_prefs=PCMA,GSM"/> my codec settings in sip_profiles/* <param name="inbound-codec-prefs" value="$${global_codec_prefs}"/> <param name="outbound-codec-prefs" value="$${global_codec_prefs}"/> now, where the is FS picking up the codec list for the re-INVITE after "sofia recover" from the debug: 2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/385914392122(a)195.88.212.39 PCMA/8000 20 ms 160 samples i can see sofia knows what codec is being used, but somehow its not sending it in re-INVITE. BTW: yea, i know, i know .. the version is a bit outdated but we can still make it working .. :)

7 19

time_test on Centos 5.5
by Louis Huppenbauer 09 Apr '11

09 Apr '11

Hi, I am testing FreeSWITCH on a Centos 5.5. The hardware is a 32 bit AMD Opteron 275. On the system I recognize a avg of 2000 if I run the time_test 1000 application. Currently I have no audio problems but I want to make sure that the system works well. There are 4 possible options: - Try to update the kernel to a newer one - Try to use debian. - Try to use the kernel-rt (realtime kernel from Centos) - Set some nice kernel options. Which? What do you think is the better solution? Thanks in advance. Kind regards, Louis

12 18

New FreeSWITCH IVR coming, but need HELP!
by Zac Wolfe 08 Apr '11

08 Apr '11

Hi guys, First some good news: we're finally close to releasing our free IVR Development platform SafiServer/SafiWorkshop (www.safisystems.com) with FreeSWITCH support! It's happening much later than we originally anticipated as we've been unexpectedly busy with contracting opportunities but I think it will be worth the wait. Currently everything is working fine with one minor exception: if the user-created script (we call them Saflets) doesn't explicitly hang up the call, the call will remain parked until the caller hangs up. Some details: In Asterisk we invoke our server-side scripting applications via the extensions.conf using the following syntax: exten = 1111,1,Agi(agi:// 192.168.0.10:3573/safletEngine.agi?saflet=project1/callflow1) Here '192.168.0.10' is the IP address of the SafiServer and project1/callflow1 identifies the Saflet to be executed. Asterisk creates a socket connection to the SafiServer and, once the socket is disconnected, the call proceeds to the next entry in the dialplan (typically 'hangup'). For FreeSWITCH, the process is slightly different. Currently, rather than create a separate socket connection for each incoming call, we're invoking an event that informs the SafiServer that there is a new incoming call. The event contains the contextual information including the Saflet name. For example: <extension name="9194"> <condition field="destination_number" expression="9194$"> <action inline="true" application="set" data="saficall=true"/> <action application="event" data="Event-Subclass=saficall::incoming,Event-Name=CUSTOM,saflet_project=test,saflet=flow1,new_saficall=true"/>  <action application="park" /> </condition> </extension> So once the event is fired, the call is parked to prevent further execution within the dialplan. From there on, SafiServer is controlling the call via Inbound Mod event socket. So this works perfectly, except that if the invoked Saflet doesn't explicitly hang-up the call it will remain parked until the caller hangs up. My question is, is there a better way to do this? Is there some better alternative to park in this case? Ideally I'd like to initiate a 'session' of some kind when the SafiServer is "controlling" the call and then exit that session as soon as the Saflet is complete, at which point the call would continue on to the next entry in the dialplan. I understand I could use Outbound sockets to achieve this but, as I mentioned, I'd like to avoid the overhead of a separate socket connection for each incoming call. I actually have a mod_saficall.c app that does basically the the same thing as I described in the dialplan entry. Perhaps there's something more I could do in code that would allow me to be notified when the session is complete. Here's the relevant code I have so far: switch_channel_t *channel = NULL; switch_event_t *event; const char *safiCallFlag = NULL; channel = switch_core_session_get_channel(session); safiCallFlag = switch_channel_get_variable(channel, "saficall"); if (!safiCallFlag) switch_channel_set_variable(channel, "saficall", "true"); if (switch_event_create_subclass(&event, SWITCH_EVENT_CUSTOM, "saficall::incoming") == SWITCH_STATUS_SUCCESS) { switch_event_add_header_string(event, SWITCH_STACK_BOTTOM, "new_saficall", safiCallFlag ? "false" : "true"); switch_channel_event_set_data(channel, event); switch_event_fire(&event); switch_ivr_park(session, NULL); } Any ideas you might have on this are welcome. Thanks, Zac Wolfe Safi Systems LLC www.safisystems.com

3 3

Build on solaris fails in libs/esl
by Kim Culhan 01 Apr '11

01 Apr '11

Seeing this on Opensolaris b134 and on latest Solaris 11: making all mod_hash Creating mod_hash_la-mod_hash.lo mkdir .libs Compiling mod_hash.c ... "../../../../src/include/switch_types.h", line 563: warning: integer overflow detected: op "<<" (E_INTEGER_OVERFLOW_DETECTED) cc: Warning: Option -db passed to ld, if ld is invoked, ignored otherwise cc: Warning: Option -ffast-math passed to ld, if ld is invoked, ignored otherwise cc: -W option with unknown program all make[5]: *** [src/esl.o] Error 1 make[4]: *** [/usr/local/src/freeswitch/freeswitch/libs/esl/libesl.so] Error 2 make[3]: *** [mod_hash-all] Error 1 make[2]: *** [all-recursive] Error 1 make[1]: *** [all-recursive] Error 1 gmake: *** [all] Error 2 In freeswitch/libs/esl/Makefile: CXXFLAGS=$(BASE_FLAGS) -Wall -Werror -Wno-unused-variable With mod_hash commented in modules.conf all of fs compiles. Any help is greatly appreciated. -kim --

4 10

RTP Proxy mode, DTMF conversion problem
by Mac 31 Mar '11

31 Mar '11

Dear ALL, I am struggling for some time with DTMF issue. Let me introduce you my configuration. The main task is to configure RTP Proxy with full topology hiding - OPERATOR_1 ------- Freeswitch ------- OPERATOR_2 Here is a prt of my config: - sip profile. I have one new sip profile with two gateways (OPERATOR_1 and OPERATOR_2) <include> <gateway name="OP1"> <param name="username" value="X1.Y1.V1.Z1"/> <param name="password" value="09321234"/> <param name="proxy" value="X1.Y1.V1.Z1"/> <param name="from-domain" value="test.com"/> <param name="register" value="false"/> <param name="caller-id-in-from" value="true"/> <param name="extension" value="test"/> <param name="extension-in-contact" value="true"/> <param name="outbound-codec-prefs" value="$${outbound_codec_ prefs}"/> <param name="disable-transcoding" value="true"/> <param name="inbound-late-negotiation" value="true"/> </gateway> </include> - dialplan <include> <context name="public"> <extension name="term"> <condition field="network_addr" expression="^(X1\.Y1\.V1\.Z1|X2\.Y2\.V1\.Z1)$" break="on-false"/> <condition field="destination_number" expression="^46(\d{9})$"> <action application="set" data="proxy_media=true"/> <action application="set" data="bypass_media=false"/> <action application="start_dtmf"/> <action application="bridge" data="sofia/gateway/OP1/$0"/> </condition> </extension> </context> </include> Everything is fine, but i have problem with DTMF conversion from RFC2833 to inband. Refering to http://wiki.freeswitch.org/wiki/Misc._Dialplan_Tools_start_dtmf and http://wiki.freeswitch.org/wiki/Proxy_Media i have done all the necessary things. vars.xml <X-PRE-PROCESS cmd="set" data="global_codec_prefs=PCMA,G.729,PCMU,GSM"/> <X-PRE-PROCESS cmd="set" data="outbound_codec_prefs=PCMA,G.729,PCMU,GSM"/> The after-effect is following output: [ERR] switch_core_io.c:1055 Codec PROXY PASS-THROUGH encoder error! Placing <param name="dtmf-type" value="none"/> does not help anyway. Could sb point me where the problem is located? Thanks in advance, Mac

5 12

Error installing mod com g729
by Victor Chukalovskiy 31 Mar '11

31 Mar '11

11 32

Re: [Freeswitch-users] nibblebill sql requests
by Madovsky 29 Mar '11

29 Mar '11

I retried and it's the same in fact nibblebill makes sql request at channel destroy and normal clearing.... I don't know how to resolve it... ----- Original Message ----- From: Madovsky To: FreeSWITCH Users Help Sent: Tuesday, February 08, 2011 6:49 PM Subject: Re: [Freeswitch-users] nibblebill sql requests no 60s but maybe it could be I hanged up at the same time of the last second of 60 seconds but I'd like to be sure... I test again.. ----- Original Message ----- From: Avi Marcus To: FreeSWITCH Users Help Sent: Tuesday, February 08, 2011 6:30 PM Subject: Re: [Freeswitch-users] nibblebill sql requests I don't know why the first is billing so soon - is your heartbeat set to 1 second? But the second is billing again because the call hung up. -Avi On Wed, Feb 9, 2011 at 12:51 AM, Madovsky <infos(a)madovsky.org> wrote: Avi, look at the time, there are 2 identical select / update in the same second ----- Original Message ----- From: Avi Marcus To: FreeSWITCH Users Help Sent: Tuesday, February 08, 2011 2:18 PM Subject: Re: [Freeswitch-users] nibblebill sql requests I don't see why you think this is duplicate. nibblebill automatically bills every X as per the "heartbeat" variable. Also, since the timer is sometimes slightly off, it has to make up for it with a fraction at the end. Also, the constant "selects" is to enable the lowbal/nobal actions. That LAST select does seem suspect, however. If you only want POST-call billing, then set the heartbeat variable to 0 I think? or just something big? in the conf xml - <param name="global_heartbeat" value="300"> -Avi On Tue, Feb 8, 2011 at 6:11 PM, Madovsky <infos(a)madovsky.org> wrote: after hangup, nibblebill makes unnecessary duplicated SQL requests : 2011-02-08 11:06:36.894238 [DEBUG] mod_sofia.c:457 Channel sofia/internal/9999999999999(a)domain.com hanging up, cause: NORMAL_CLEARING 2011-02-08 11:06:37.018489 [DEBUG] mod_nibblebill.c:433 Attempting to bill at $0.03864 per minute to account 9999999999999 2011-02-08 11:06:37.018489 [INFO] mod_nibblebill.c:485 Beginning new billing on 461772290(a)12.34.56.78 2011-02-08 11:06:37.018489 [DEBUG] mod_nibblebill.c:491 1 seconds passed since last bill time of 2011-02-08 11:06:35 2011-02-08 11:06:37.018489 [DEBUG] mod_nibblebill.c:498 Billing $0.000874 to 9999999999999 (Call: 461772290(a)12.34.56.78 / 0.000000 so far) 2011-02-08 11:06:37.018489 [DEBUG] mod_nibblebill.c:321 Doing update query [UPDATE accounts SET cash=cash-0.000874 WHERE id='9999999999999'] 2011-02-08 11:06:37.074740 [DEBUG] mod_nibblebill.c:366 Doing lookup query [SELECT cash AS nibble_balance FROM accounts WHERE id='9999999999999'] 2011-02-08 11:06:37.082844 [DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999999999999 (balance = 9.579666) 2011-02-08 11:06:37.082844 [DEBUG] switch_core_state_machine.c:46 sofia/internal/9999999999999(a)domain.com Standard HANGUP, cause: NORMAL_CLEARING 2011-02-08 11:06:37.082844 [DEBUG] switch_core_state_machine.c:557 (sofia/internal/9999999999999(a)domain.com) State HANGUP going to sleep 2011-02-08 11:06:37.082844 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/9999999999999(a)domain.com) State Change CS_HANGUP -> CS_REPORTING 2011-02-08 11:06:37.082844 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/9999999999999(a)domain.com [BREAK] 2011-02-08 11:06:37.082844 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/9999999999999(a)domain.com) Running State Change CS_REPORTING 2011-02-08 11:06:37.082844 [DEBUG] switch_core_state_machine.c:617 (sofia/internal/9999999999999(a)domain.com) State REPORTING 2011-02-08 11:06:37.082844 [DEBUG] mod_nibblebill.c:433 Attempting to bill at $0.03864 per minute to account 9999999999999 2011-02-08 11:06:37.082844 [DEBUG] mod_nibblebill.c:491 0 seconds passed since last bill time of 2011-02-08 11:06:37 2011-02-08 11:06:37.082844 [DEBUG] mod_nibblebill.c:498 Billing $0.000041 to 9999999999999 (Call: 461772290(a)12.34.56.78 / 0.000874 so far) 2011-02-08 11:06:37.082844 [DEBUG] mod_nibblebill.c:321 Doing update query [UPDATE accounts SET cash=cash-0.000041 WHERE id='9999999999999'] 2011-02-08 11:06:37.133170 [DEBUG] mod_nibblebill.c:366 Doing lookup query [SELECT cash AS nibble_balance FROM accounts WHERE id='9999999999999'] 2011-02-08 11:06:37.201557 [DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999999999999 (balance = 9.579625) How to avoid duplicate SQL requests with nibblebill ? Thanks _______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org ------------------------------------------------------------------------ _______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org _______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org ---------------------------------------------------------------------------- _______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org

2 1

Invalid Application hash
by David Wafula 29 Mar '11

29 Mar '11

Hi all, Notice the last line in the log below when i dial 1001. This is from the latest git as of this morning. Any pointers? thanks. Dialplan: sofia/internal/1000(a)146.141.76.153 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(dialed_extension=1001) Dialplan: sofia/internal/1000(a)146.141.76.153 Action export(dialed_extension=1001) Dialplan: sofia/internal/1000(a)146.141.76.153 Action bind_meta_app(1 b s execute_extension::dx XML features) Dialplan: sofia/internal/1000(a)146.141.76.153 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) Dialplan: sofia/internal/1000(a)146.141.76.153 Action bind_meta_app(3 b s execute_extension::cf XML features) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(ringback=${us-ring}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(transfer_ringback=local_stream://moh) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(call_timeout=30) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(continue_on_fail=true) Dialplan: sofia/internal/1000(a)146.141.76.153 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action bridge(user/${dialed_extension}@${domain_name}) Dialplan: sofia/internal/1000(a)146.141.76.153 Action answer() Dialplan: sofia/internal/1000(a)146.141.76.153 Action sleep(1000) Dialplan: sofia/internal/1000(a)146.141.76.153 Action voicemail(default ${domain_name} ${dialed_extension}) 2010-10-25 10:47:47.193206 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/1000(a)146.141.76.153) State Change CS_ROUTING -> CS_EXECUTE 2010-10-25 10:47:47.193206 [DEBUG] switch_core_session.c:1057 Send signal sofia/internal/1000(a)146.141.76.153 [BREAK] 2010-10-25 10:47:47.193206 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/1000(a)146.141.76.153) State ROUTING going to sleep 2010-10-25 10:47:47.193206 [DEBUG] switch_core_state_machine.c:318 (sofia/internal/1000(a)146.141.76.153) Running State Change CS_EXECUTE 2010-10-25 10:47:47.193206 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/1000(a)146.141.76.153) State EXECUTE 2010-10-25 10:47:47.193206 [DEBUG] mod_sofia.c:239 sofia/internal/ 1000(a)146.141.76.153 SOFIA EXECUTE 2010-10-25 10:47:47.193206 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1000(a)146.141.76.153 Standard EXECUTE EXECUTE sofia/internal/1000(a)146.141.76.153 set(open=true) 2010-10-25 10:47:47.193206 [DEBUG] mod_dptools.c:1028 sofia/internal/ 1000(a)146.141.76.153 SET [open]=[true] 2010-10-25 10:47:47.193206 [ERR] switch_core_session.c:1807 Invalid Application hash -- David Wafula

3 3

Re: [Freeswitch-users] outgoing callerid freetdm TDM400
by François Legal 28 Mar '11

28 Mar '11

That looks like the problem I had with my FXS ports sending CID not understood by european phones. In which country are you / what kind of analog phones are you using ? François On Wed, 8 Sep 2010 13:16:08 +1000, Brian May <brian(a)microcomaustralia.com.au> wrote: > On 8 September 2010 11:21, Moises Silva <moises.silva(a)gmail.com> wrote: >> Pastebin a debug log for a call. Also, before placing the call, create the >> directory /var/log/freetdm and then execute this in FreeSWITCH CLI: >> ftdm trace /var/log/freetdm/test <span> <chan> > > Hopefully I got this right ;-) > > <http://pastebin.com/dEXkkNaK> > > To me everything looks good in this listing. > > Not sure what I can do with the freetdm trace files...

4 18

Question about mod_dingaling.
by 占平陈 24 Mar '11

24 Mar '11

Good everyone,I use Freeswitch 1.0.6 with mod_dingaling.Now i call out from mod_dingaling use google voice,this cann't call succeed. This is cli log: freeswitch@internal> 2011-02-11 13:27:39.558796 [DEBUG] sofia.c:5847 IP 121.28.15.8 Rejected by acl "domains". Falling back to Digest auth. 2011-02-11 13:27:39.834785 [DEBUG] sofia.c:5847 IP 121.28.15.8 Rejected by acl "domains". Falling back to Digest auth. 2011-02-11 13:27:39.838761 [NOTICE] switch_channel.c:669 New Channel sofia/internal/1000(a)184.105.153.247 [1df65ab4-0d7e-48e5-8c75-547ab51d73d7] 2011-02-11 13:27:39.838761 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_NEW 2011-02-11 13:27:39.838761 [DEBUG] switch_core_state_machine.c:320 ( sofia/internal/1000(a)184.105.153.247) State NEW 2011-02-11 13:27:39.850754 [DEBUG] sofia.c:4153 Channel sofia/internal/1000(a)184.105.153.247 entering state [received][100] 2011-02-11 13:27:39.850754 [DEBUG] sofia.c:4164 Remote SDP: v=0 o=- 7 2 IN IP4 61.235.150.11 s=CounterPath eyeBeam 1.5 c=IN IP4 61.235.150.11 t=0 0 m=audio 13410 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=alt:1 3 : xpehuJM9 ZEXWjImz 192.168.52.1 13410 a=alt:2 2 : 9AzLyh+Z Xgci2e96 192.168.153.1 13410 a=alt:3 1 : U0bonQcp 1CQ61b/S 61.235.150.11 13410 a=x-rtp-session-id:2BC4463425494741B4A9DA4DFD436DDF 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20] 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20] 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20] 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:3585 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20] 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:2354 Set Codec sofia/internal/1000(a)184.105.153.247 PCMU/8000 20 ms 160 samples 2011-02-11 13:27:39.850754 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf send/recv payload to 101 2011-02-11 13:27:39.850754 [DEBUG] sofia.c:4310 ( sofia/internal/1000(a)184.105.153.247) State Change CS_NEW -> CS_INIT 2011-02-11 13:27:39.850754 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_INIT 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:338 ( sofia/internal/1000(a)184.105.153.247) State INIT 2011-02-11 13:27:39.854744 [DEBUG] mod_sofia.c:83 sofia/internal/1000(a)184.105.153.247 SOFIA INIT 2011-02-11 13:27:39.854744 [DEBUG] mod_sofia.c:117 ( sofia/internal/1000(a)184.105.153.247) State Change CS_INIT -> CS_ROUTING 2011-02-11 13:27:39.854744 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:338 ( sofia/internal/1000(a)184.105.153.247) State INIT going to sleep 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_ROUTING 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:341 ( sofia/internal/1000(a)184.105.153.247) State ROUTING 2011-02-11 13:27:39.854744 [DEBUG] mod_sofia.c:140 sofia/internal/1000(a)184.105.153.247 SOFIA ROUTING 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:77 sofia/internal/1000(a)184.105.153.247 Standard ROUTING 2011-02-11 13:27:39.854744 [INFO] mod_dialplan_xml.c:418 Processing 1000->5018009993355 in context default Dialplan: sofia/internal/1000(a)184.105.153.247 parsing [default->gvoice_out] continue=false Dialplan: sofia/internal/1000(a)184.105.153.247 Regex (PASS) [gvoice_out] destination_number(5018009993355) =~ /^50(1\d{10})$/ break=on-false Dialplan: sofia/internal/1000(a)184.105.153.247 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/1000(a)184.105.153.247 Action bridge( dingaling/gv1/+18009993355(a)voice.google.com) 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:119 ( sofia/internal/1000(a)184.105.153.247) State Change CS_ROUTING -> CS_EXECUTE 2011-02-11 13:27:39.854744 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:341 ( sofia/internal/1000(a)184.105.153.247) State ROUTING going to sleep 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_EXECUTE 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:348 ( sofia/internal/1000(a)184.105.153.247) State EXECUTE 2011-02-11 13:27:39.854744 [DEBUG] mod_sofia.c:226 sofia/internal/1000(a)184.105.153.247 SOFIA EXECUTE 2011-02-11 13:27:39.854744 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1000(a)184.105.153.247 Standard EXECUTE EXECUTE sofia/internal/1000(a)184.105.153.247 set(hangup_after_bridge=true) 2011-02-11 13:27:39.854744 [DEBUG] mod_dptools.c:816 sofia/internal/1000(a)184.105.153.247 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/1000(a)184.105.153.247 bridge( dingaling/gv1/+18009993355(a)voice.google.com) 2011-02-11 13:27:39.894782 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence type="subscribe" to="+18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"> <status>Call Me!</status> </presence> 2011-02-11 13:27:39.894782 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence xmlns="jabber:client" type="probe" to=" +18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"></presence> 2011-02-11 13:27:40.050810 [INFO] libdingaling.c:1305 SecRECV: ------------------------------------------------------------------------------- <presence type="error" to="husodesar(a)gmail.com/gtalkAC94A6BE" from=" +18009993355(a)voice.google.com"> <error code="501" type="cancel"> <feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented> </error> </presence> 2011-02-11 13:27:44.595029 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence type="subscribe" to="+18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"> <status>Call Me!</status> </presence> 2011-02-11 13:27:44.595029 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence xmlns="jabber:client" type="probe" to=" +18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"></presence> 2011-02-11 13:27:44.751042 [INFO] libdingaling.c:1305 SecRECV: ------------------------------------------------------------------------------- <presence type="error" to="husodesar(a)gmail.com/gtalkAC94A6BE" from=" +18009993355(a)voice.google.com"> <error code="501" type="cancel"> <feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented> </error> </presence> 2011-02-11 13:27:49.595349 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence type="subscribe" to="+18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"> <status>Call Me!</status> </presence> 2011-02-11 13:27:49.595349 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence xmlns="jabber:client" type="probe" to=" +18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"></presence> 2011-02-11 13:27:49.751408 [INFO] libdingaling.c:1305 SecRECV: ------------------------------------------------------------------------------- <presence type="error" to="husodesar(a)gmail.com/gtalkAC94A6BE" from=" +18009993355(a)voice.google.com"> <error code="501" type="cancel"> <feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented> </error> </presence> 2011-02-11 13:27:54.595667 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence type="subscribe" to="+18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"> <status>Call Me!</status> </presence> 2011-02-11 13:27:54.595667 [NOTICE] libdingaling.c:1307 SecSEND: ------------------------------------------------------------------------------- <presence xmlns="jabber:client" type="probe" to=" +18009993355(a)voice.google.com" from=" husodesar(a)gmail.com/gtalkAC94A6BE"></presence> 2011-02-11 13:27:54.751666 [INFO] libdingaling.c:1305 SecRECV: ------------------------------------------------------------------------------- <presence type="error" to="husodesar(a)gmail.com/gtalkAC94A6BE" from=" +18009993355(a)voice.google.com"> <error code="501" type="cancel"> <feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented> </error> </presence> 2011-02-11 13:27:56.495776 [DEBUG] mod_dingaling.c:1715 Unknown Recipient! 2011-02-11 13:27:56.495776 [DEBUG] mod_dingaling.c:704 Terminate called from line 1716 state=CS_NEW 2011-02-11 13:27:56.495776 [NOTICE] mod_dingaling.c:715 Close Channel N/A [CS_NEW] 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:428 () Running State Change CS_DESTROY 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:439 (N/A) State DESTROY 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:439 (N/A) State DESTROY going to sleep 2011-02-11 13:27:56.495776 [ERR] switch_ivr_originate.c:2430 Cannot create outgoing channel of type [dingaling] cause: [NO_USER_RESPONSE] 2011-02-11 13:27:56.495776 [DEBUG] switch_ivr_originate.c:3228 Originate Resulted in Error Cause: 18 [NO_USER_RESPONSE] 2011-02-11 13:27:56.495776 [INFO] mod_dptools.c:2355 Originate Failed. Cause: NO_USER_RESPONSE 2011-02-11 13:27:56.495776 [NOTICE] switch_core_state_machine.c:185 sofia/internal/1000(a)184.105.153.247 has executed the last dialplan instruction, hanging up. 2011-02-11 13:27:56.495776 [NOTICE] switch_core_state_machine.c:187 Hangup sofia/internal/1000(a)184.105.153.247 [CS_EXECUTE] [NORMAL_CLEARING] 2011-02-11 13:27:56.495776 [DEBUG] switch_channel.c:2102 Send signal sofia/internal/1000(a)184.105.153.247 [KILL] 2011-02-11 13:27:56.495776 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:348 ( sofia/internal/1000(a)184.105.153.247) State EXECUTE going to sleep 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_HANGUP 2011-02-11 13:27:56.495776 [DEBUG] switch_core_state_machine.c:499 ( sofia/internal/1000(a)184.105.153.247) State HANGUP 2011-02-11 13:27:56.495776 [DEBUG] mod_sofia.c:414 Channel sofia/internal/1000(a)184.105.153.247 hanging up, cause: NORMAL_CLEARING 2011-02-11 13:27:56.511787 [DEBUG] mod_sofia.c:476 Responding to INVITE with: 480 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1000(a)184.105.153.247 Standard HANGUP, cause: NORMAL_CLEARING 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:499 ( sofia/internal/1000(a)184.105.153.247) State HANGUP going to sleep 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:333 ( sofia/internal/1000(a)184.105.153.247) State Change CS_HANGUP -> CS_REPORTING 2011-02-11 13:27:56.511787 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:314 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_REPORTING 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:590 ( sofia/internal/1000(a)184.105.153.247) State REPORTING 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1000(a)184.105.153.247 Standard REPORTING, cause: NORMAL_CLEARING 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:590 ( sofia/internal/1000(a)184.105.153.247) State REPORTING going to sleep 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:327 ( sofia/internal/1000(a)184.105.153.247) State Change CS_REPORTING -> CS_DESTROY 2011-02-11 13:27:56.511787 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1000(a)184.105.153.247 [BREAK] 2011-02-11 13:27:56.511787 [DEBUG] switch_core_session.c:1164 Session 12 ( sofia/internal/1000(a)184.105.153.247) Locked, Waiting on external entities 2011-02-11 13:27:56.511787 [NOTICE] switch_core_session.c:1182 Session 12 ( sofia/internal/1000(a)184.105.153.247) Ended 2011-02-11 13:27:56.511787 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1000(a)184.105.153.247 [CS_DESTROY] 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:428 ( sofia/internal/1000(a)184.105.153.247) Running State Change CS_DESTROY 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:439 ( sofia/internal/1000(a)184.105.153.247) State DESTROY 2011-02-11 13:27:56.511787 [DEBUG] mod_sofia.c:341 sofia/internal/1000(a)184.105.153.247 SOFIA DESTROY 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000(a)184.105.153.247 Standard DESTROY 2011-02-11 13:27:56.511787 [DEBUG] switch_core_state_machine.c:439 ( sofia/internal/1000(a)184.105.153.247) State DESTROY going to sleep What's wrong with this? Please help me, Thanks.

5 17