August 2012 - FreeSWITCH-users - FreeSWITCH Mailing Lists

RedHat 6.X Performance
by Daniel Eiland 25 Sep '12

25 Sep '12

Hi folks, We are currently running FreeSWITCH as a (sip/rtp) conference endpoint in our system and recently completed some latency testing. We found that audio passing through FreeSWITCH was taking approx 40-60ms to be mixed and sent out the other side. I did some looking around and as far as I can tell, we don't have FreeSWITCH configured with a jitter-buffer. The only other possible performance issue I could find was the 'tickless' issue ( http://wiki.freeswitch.org/wiki/Installation_Guide#Release.28es.29_6_and_La…) when running FreeSWITCH on a RedHat 6+ installation. However, its not clear to me if the 'tickless' setting could cause this type of latency when there are only two clients connected to FreeSWITCH. I was wondering if anybody else had experienced this type of problem or had any suggestions? For reference, we are using a RTP/PCMU payload with 10ms samples. Thanks, Daniel

13 23

Timezone with time conditions
by Spencer Thomason 24 Sep '12

24 Sep '12

Hello all, Has anyone found an elegant solution to using time conditions with multiple locations? For example suppose you have a multi tenant system with time conditions, is there any way to set the call time zone on a per call basis? Thanks, Spencer

5 9

CUSTOM event messages missing FreeSWITCH-Hostname
by James Bravo 24 Sep '12

24 Sep '12

Hi We've recently upgraded from version 1.0.head to 1.2.0-rc2 and noticed that certain events sent from php code using the ESP library have certain core headers missing, in particular 'FreeSWITCH-Hostname'. For example, using '/events plain all' in fs_cli, version 1.0.head would show RECV EVENT Event-Subclass: rmCTI::TRANSFER_ANSWER Core-UUID: 47527f1f-b6c3-4f74-9f16-32f565ee4123 FreeSWITCH-Hostname: s01pbx01.test.com FreeSWITCH-Switchname: s01pbx01.test.com FreeSWITCH-IPv4: 211.141.141.131 FreeSWITCH-IPv6: ::1 Event-Date-Local: 2012-08-03 10:43:06 Event-Date-GMT: Fri, 03 Aug 2012 09:43:06 GMT Event-Date-Timestamp: 1343986986238409 Event-Calling-File: mod_event_socket.c Event-Calling-Function: read_packet Event-Calling-Line-Number: 1188 Command: sendevent CUSTOM Event-Name: CUSTOM Channel-Name: sofia/internal1/1202(a)sip.test.com rmCTI-UUID: aebc98dc-22da-4ad1-8234-912487329123 rmCTI-Other-UUID: b24a8783-16b1-495e-9c4e-5ea52c362123 rmCTI-Info: Transfer to new b-leg answered where as version 1.2.0-rc2 it shows RECV EVENT Event-Subclass: rmCTI::TRANSFER_ANSWER Command: sendevent CUSTOM Event-Name: CUSTOM Channel-Name: sofia/internal1/1202(a)sip.test.com rmCTI-UUID: c3c7b331-0b8b-4ea5-834f-9d72fd446123 rmCTI-Other-UUID: db75c60b-a258-4b47-8d51-a1b7ac86f123 rmCTI-Info: Transfer to new b-leg answered Event-UUID: f47597b2-9e8c-4508-be57-3e1eed35c123 Is there any reason these core headers are missing? Is it something to do with CUSTOM events? Thanks in advance James Bravo

3 4

Asking a Question (a.k.a. Gabe must be getting old)
by Gabriel Gunderson 21 Sep '12

21 Sep '12

This should be required reading before posting questions to the list: http://www.catb.org/esr/faqs/smart-questions.html#intro Special thanks to those people on the list who take special care to ask *smart* questions and those who provide valuable answers. You know who you are ;) Gabe

7 13

freetdm and destination_number
by Tim Bock 18 Sep '12

18 Sep '12

Hello, I'm rather new with FreeSWITCH, migrating over from an asterisk setup. I have a Digium TDM card, with 3 fxo ports and 1 fxs port. I have everything mostly working, but I'm having an issue with incoming fxo calls. I've found examples where people have used destination_number in their condition, but this doesn't seem to work for me. I can successfully use > <condition field="${channel_name}" expression="^(FreeTDM/2:3/)"> but not > <condition field="destination_number" expression="(7832889)$"/> The issue seems to be that destination_number is set to 2 early on, somehow: > 012-08-23 20:40:43.213780 [DEBUG] ftmod_analog.c:646 [s2c3][1:3] > Executing stat > e handler on 2:3 for RING > 2012-08-23 20:40:43.213780 [DEBUG] mod_freetdm.c:1992 got FXO sig 2:3 > [START] > 2012-08-23 20:40:43.213780 [DEBUG] ftdm_io.c:3131 [s2c3][1:3] Enabled > software D > TMF detector > 2012-08-23 20:40:43.213780 [DEBUG] mod_freetdm.c:407 Set codec PCMU 20ms > 2012-08-23 20:40:43.213780 [DEBUG] mod_freetdm.c:1740 Connect inbound > channel Fr > eeTDM/2:3/2 so that later: > Dialplan: FreeTDM/2:3/2 Regex (FAIL) [fxo_incoming] > destination_number(2) =~ /(78328 > 89)$/ break=on-false Any ideas on what I am doing wrong? I can make it work using the channel_name variable, but using destination_number would be much more convenient. Thank you, Tim

2 1

directory variables not set on user channels when bridge does not succeed
by Babak Yakhchali 18 Sep '12

18 Sep '12

Hi To add some features to fs, I need to set some variables in xml directory user as: <include> <user id="1000"> <params> ... <variables> <variable name="profile_id" value="10"/> <variable name="dn_id" value="25"/> ..... <variable name="call_timeout" value="7"/> </variables> </user> </include> as documentation says these variables are set on every inbound or outbound channel for this user. when I try to call to user/1000 if call succeeds everything is ok but if call ends with results like no_answer, in xml_cdr that is sent to my cdr logger url, there is no sign of any variables set in directory as above. is this normal? because if I set these on bleg (user/1000) using export they appear in xml cdr. thanx

3 7

Problem with originated calls hanging in CS_SOFT_EXEC state
by Phillip Boles 15 Sep '12

15 Sep '12

var session = ManagedSession.OriginateHandleHangup(s, dialString, ts, (y) => { try { Log.WriteLine(LogLevel.Info, "Hanging UP: "+ y.GetUuid()); y.SetAutoHangup(true); y.destroy(); } catch( Exception ) { Log.WriteLine(LogLevel.Critical, "Exception While Trying to handup"); } }); 2012-08-28 10:49:12.156172 [INFO] switch_cpp.cpp:1227 Dialing 2012-08-28 10:49:12.156172 [DEBUG] switch_ivr_originate.c:1947 Parsing global variables 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [ignore_early_media]=[true] 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [origination_caller_id_number]=[2223334444] 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [origination_caller_id_name]=[Vocalspace] 2012-08-28 10:49:12.156172 [NOTICE] switch_channel.c:926 New Channel sofia/external/XXXXXXXXXXX [e315f2e8-1fa8-4fd9-849b-f687dad8aed5] 2012-08-28 10:49:12.156172 [DEBUG] mod_sofia.c:4709 (sofia/external/XXXXXXXXXXX) State Change CS_NEW -> CS_INIT 2012-08-28 10:49:12.156172 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.156172 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_INIT 2012-08-28 10:49:12.156172 [DEBUG] switch_core_state_machine.c:421 (sofia/external/XXXXXXXXXXX) State INIT 2012-08-28 10:49:12.156172 [DEBUG] mod_sofia.c:85 sofia/external/XXXXXXXXXXX SOFIA INIT 2012-08-28 10:49:12.216161 [DEBUG] switch_nat.c:511 mapped public port 19628 protocol UDP to localport 19628 2012-08-28 10:49:12.256159 [DEBUG] switch_nat.c:511 mapped public port 19629 protocol UDP to localport 19629 2012-08-28 10:49:12.256159 [DEBUG] mod_sofia.c:129 (sofia/external/XXXXXXXXXXX) State Change CS_INIT -> CS_ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:421 (sofia/external/XXXXXXXXXXX) State INIT going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_channel.c:1887 (sofia/external/XXXXXXXXXXX) Callstate Change DOWN -> RINGING 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:430 (sofia/external/XXXXXXXXXXX) State ROUTING 2012-08-28 10:49:12.256159 [DEBUG] mod_sofia.c:152 sofia/external/XXXXXXXXXXX SOFIA ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_ivr_originate.c:67 (sofia/external/XXXXXXXXXXX) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:430 (sofia/external/XXXXXXXXXXX) State ROUTING going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:449 (sofia/external/XXXXXXXXXXX) State CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:449 (sofia/external/XXXXXXXXXXX) State CONSUME_MEDIA going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [calling][0] 2012-08-28 10:49:12.636131 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.636131 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.636131 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:12.636131 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [proceeding][180] 2012-08-28 10:49:12.636131 [NOTICE] sofia.c:5769 Ring-Ready sofia/external/XXXXXXXXXXX! 2012-08-28 10:49:14.655990 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:14.655990 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:14.655990 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:14.655990 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [proceeding][183] 2012-08-28 10:49:14.655990 [DEBUG] sofia.c:5688 Remote SDP: v=0 o=FreeSWITCH 1346150407 1346150408 IN IP4 8.19.97.6 s=FreeSWITCH c=IN IP4 8.19.97.6 t=0 0 m=audio 18534 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:4911 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:2995 Set Codec sofia/external/XXXXXXXXXXX PCMU/8000 20 ms 160 samples 64000 bits 2012-08-28 10:49:14.655990 [DEBUG] switch_core_codec.c:111 sofia/external/XXXXXXXXXXX Original read codec set to PCMU:0 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:5025 Set 2833 dtmf send payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3244 AUDIO RTP [sofia/external/XXXXXXXXXXX] 10.10.80.33 port 19628 -> 8.19.97.6 port 18534 codec: 0 ms: 20 2012-08-28 10:49:14.655990 [DEBUG] switch_rtp.c:1676 Starting timer [soft] 160 bytes per 20ms 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3508 Set 2833 dtmf send payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3514 Set 2833 dtmf receive payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3541 sofia/external/XXXXXXXXXXX Set rtp dtmf delay to 40 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3547 Set comfort noise payload to 13 2012-08-28 10:49:14.655990 [NOTICE] sofia_glue.c:4052 Pre-Answer sofia/external/XXXXXXXXXXX! 2012-08-28 10:49:14.655990 [DEBUG] switch_channel.c:2986 (sofia/external/XXXXXXXXXXX) Callstate Change RINGING -> EARLY 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [completing][200] 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5685 Duplicate SDP v=0 o=FreeSWITCH 1346150407 1346150408 IN IP4 8.19.97.6 s=FreeSWITCH c=IN IP4 8.19.97.6 t=0 0 m=audio 18534 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [ready][200] 2012-08-28 10:49:22.136467 [DEBUG] switch_channel.c:3245 (sofia/external/XXXXXXXXXXX) Callstate Change EARLY -> ACTIVE 2012-08-28 10:49:22.136467 [NOTICE] sofia.c:6352 Channel [sofia/external/XXXXXXXXXXX] has been answered 2012-08-28 10:49:22.136467 [DEBUG] switch_ivr_originate.c:3330 Originate Resulted in Success: [sofia/external/XXXXXXXXXXX] 2012-08-28 10:49:22.136467 [DEBUG] switch_cpp.cpp:1083 (sofia/external/XXXXXXXXXXX) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:443 (sofia/external/XXXXXXXXXXX) State SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] mod_sofia.c:592 SOFIA SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:261 sofia/external/XXXXXXXXXXX Standard SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:443 (sofia/external/XXXXXXXXXXX) State SOFT_EXECUTE going to sleep 2012-08-28 10:49:22.136467 [DEBUG] switch_cpp.cpp:1172 CoreSession::seHangupHook, hangup_func: (nil) 2012-08-28 10:49:22.136467 [INFO] switch_cpp.cpp:1227 Connected 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Session Ready 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Call Initialized 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Trying To Run 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Running 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Checking for file /svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw 2012-08-28 10:49:23.160407 [ERR] mod_sndfile.c:197 Error Opening File [/svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw] [System error : No such file or directory.] 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Transcoding xfile /svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Sending MQ Message 2012-08-28 10:49:23.296392 [NOTICE] switch_cpp.cpp:1227 Creating a channel... 2012-08-28 10:49:23.296392 [NOTICE] switch_cpp.cpp:1227 Defining Exchange FS... 2012-08-28 10:49:23.316389 [NOTICE] switch_cpp.cpp:1227 Binding Exchange and Queue.. 2012-08-28 10:49:23.336389 [NOTICE] switch_cpp.cpp:1227 Sending message.. 2012-08-28 10:49:23.356388 [NOTICE] switch_cpp.cpp:1227 shout://www.product.com/files/media/e53bc5bf-4e00-ab8d-4e4d-b201699628d1.mp3 2012-08-28 10:49:25.916204 [DEBUG] mod_shout.c:472 Read Thread Done 2012-08-28 10:49:26.356173 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16@8000hz 1 channels 20ms 2012-08-28 10:49:26.596156 [DEBUG] switch_rtp.c:3252 Correct ip/port confirmed. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_channel.c:2849 (sofia/external/XXXXXXXXXXX) Callstate Change ACTIVE -> HANGUP 2012-08-28 10:49:27.296108 [NOTICE] sofia.c:694 Hangup sofia/external/XXXXXXXXXXX [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 2012-08-28 10:49:27.296108 [DEBUG] switch_channel.c:2872 Send signal sofia/external/XXXXXXXXXXX [KILL] 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1227 AppFunction is in hangupCallback. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_HANGUP 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:622 (sofia/external/XXXXXXXXXXX) State HANGUP 2012-08-28 10:49:27.296108 [DEBUG] mod_sofia.c:473 Channel sofia/external/XXXXXXXXXXX hanging up, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:47 sofia/external/XXXXXXXXXXX Standard HANGUP, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:622 (sofia/external/XXXXXXXXXXX) State HANGUP going to sleep 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:413 (sofia/external/XXXXXXXXXXX) State Change CS_HANGUP -> CS_REPORTING 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1227 AppFunction is in hangupCallback. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_REPORTING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:682 (sofia/external/XXXXXXXXXXX) State REPORTING 2012-08-28 10:49:27.296108 [INFO] switch_cpp.cpp:1227 Handing UP: e315f2e8-1fa8-4fd9-849b-f687dad8aed5 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1007 sofia/external/XXXXXXXXXXX destroy/unlink session from object 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:79 sofia/external/XXXXXXXXXXX Standard REPORTING, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:682 (sofia/external/XXXXXXXXXXX) State REPORTING going to sleep 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:407 (sofia/external/XXXXXXXXXXX) State Change CS_REPORTING -> CS_DESTROY 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1424 Session 4 (sofia/external/XXXXXXXXXXX) Locked, Waiting on external entities 2012-08-28 10:49:27.316110 [DEBUG] switch_ivr_play_say.c:1682 done playing file shout://www.product.com/files/media/e53bc5bf-4e00-ab8d-4e4d-b201699628d1.mp3 freeswitch(a)fs03.int.colo> show channels uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,application,application_data,dialplan,context,read_codec,read_rate,read_bit_rate,write_codec,write_rate,write_bit_rate,secure,hostname,presence_id,presence_data,callstate,callee_name,callee_num,callee_direction,call_uuid,sent_callee_name,sent_callee_num e315f2e8-1fa8-4fd9-849b-f687dad8aed5,outbound,2012-08-28 10:49:12,1346168952,sofia/external/XXXXXXXXXXX,CS_SOFT_EXECUTE,Vocalspace,2223334444,,XXXXXXXXXXX,,,,default,PCMU,8000,64000,PCMU,8000,64000,,fs03.int.colo,,,ACTIVE,Outbound Call,XXXXXXXXXXX,,,, 1 total. 2012-08-28 10:58:10.256585 [DEBUG] switch_nat.c:511 mapped public port 5060 protocol TCP to localport 5060 2012-08-28 10:58:10.296583 [DEBUG] switch_nat.c:511 mapped public port 5080 protocol UDP to localport 5080 2012-08-28 10:58:10.336580 [DEBUG] switch_nat.c:511 mapped public port 5080 protocol TCP to localport 5080 2012-08-28 10:58:10.396576 [DEBUG] switch_nat.c:511 mapped public port 19628 protocol UDP to localport 19628 2012-08-28 10:58:10.436573 [DEBUG] switch_nat.c:511 mapped public port 19629 protocol UDP to localport 19629 2012-08-28 10:58:10.476570 [DEBUG] switch_nat.c:511 mapped public port 22013 protocol UDP to localport 22013 2012-08-28 10:58:10.536566 [DEBUG] switch_nat.c:511 mapped public port 22191 protocol UDP to localport 22191 2012-08-28 10:58:10.576563 [DEBUG] switch_nat.c:511 mapped public port 25326 protocol UDP to localport 25326 2012-08-28 10:58:10.636559 [DEBUG] switch_nat.c:511 mapped public port 25327 protocol UDP to localport 25327 Loading /usr/lib64/freeswitch/mod/managed/Enyim.Caching.dll from domain Enyim.Caching.dll_63 2012-08-28 11:00:02.097780 [DEBUG] switch_cpp.cpp:1227 Assembly Enyim.Caching, Version=1.2.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Jint.dll from domain Jint.dll_64 2012-08-28 11:00:02.316760 [DEBUG] switch_cpp.cpp:1227 Assembly Jint, Version=0.9.2.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/log4net.dll from domain log4net.dll_65 2012-08-28 11:00:02.536745 [DEBUG] switch_cpp.cpp:1227 Assembly log4net, Version=1.2.10.0, Culture=neutral, PublicKeyToken=1b44e1d426115821 doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Antlr3.Runtime.dll from domain Antlr3.Runtime.dll_66 2012-08-28 11:00:02.756720 [DEBUG] switch_cpp.cpp:1227 Assembly Antlr3.Runtime, Version=3.3.1.7705, Culture=neutral, PublicKeyToken=eb42632606e9261f doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/RabbitMQ.Client.dll from domain RabbitMQ.Client.dll_67 2012-08-28 11:00:02.996708 [DEBUG] switch_cpp.cpp:1227 Assembly RabbitMQ.Client, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/ManagedEsl.dll from domain ManagedEsl.dll_68 2012-08-28 11:00:03.216689 [DEBUG] switch_cpp.cpp:1227 Assembly ManagedEsl, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/FreeSWITCH.Managed.dll from domain FreeSWITCH.Managed.dll_69 2012-08-28 11:00:03.436678 [DEBUG] switch_cpp.cpp:1227 Assembly FreeSWITCH.Managed, Version=1.0.5.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/FreeSwitch.EventSocket.dll from domain FreeSwitch.EventSocket.dll_70 2012-08-28 11:00:03.657671 [DEBUG] switch_cpp.cpp:1227 Assembly FreeSwitch.EventSocket, Version=1.0.0.0, Culture=neutral, PublicKeyToken=94d8a22cdb4b7713 doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Newtonsoft.Json.dll from domain Newtonsoft.Json.dll_71 2012-08-28 11:00:03.876655 [DEBUG] switch_cpp.cpp:1227 Assembly Newtonsoft.Json, Version=4.5.0.0, Culture=neutral, PublicKeyToken=30ad4fe6b2a6aeed doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Freeswitch.UUID.dll from domain Freeswitch.UUID.dll_72 2012-08-28 11:00:04.096628 [DEBUG] switch_cpp.cpp:1227 Assembly Freeswitch.UUID, Version=1.0.0.0, Culture=neutral, PublicKeyToken=f758751257b2cdaf doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Product.dll from domain Product.dll_73 freeswitch(a)fs03.int.colo> show channels 0 total. var session = ManagedSession.OriginateHandleHangup(s, dialString, ts, (y) => { try { Log.WriteLine(LogLevel.Info, "Hanging UP: "+ y.GetUuid()); y.SetAutoHangup(true); y.destroy(); } catch( Exception ) { Log.WriteLine(LogLevel.Critical, "Exception While Trying to handup"); } }); 2012-08-28 10:49:12.156172 [INFO] switch_cpp.cpp:1227 Dialing 2012-08-28 10:49:12.156172 [DEBUG] switch_ivr_originate.c:1947 Parsing global variables 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [ignore_early_media]=[true] 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [origination_caller_id_number]=[2223334444] 2012-08-28 10:49:12.156172 [DEBUG] switch_event.c:1527 Parsing variable [origination_caller_id_name]=[Vocalspace] 2012-08-28 10:49:12.156172 [NOTICE] switch_channel.c:926 New Channel sofia/external/XXXXXXXXXXX [e315f2e8-1fa8-4fd9-849b-f687dad8aed5] 2012-08-28 10:49:12.156172 [DEBUG] mod_sofia.c:4709 (sofia/external/XXXXXXXXXXX) State Change CS_NEW -> CS_INIT 2012-08-28 10:49:12.156172 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.156172 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_INIT 2012-08-28 10:49:12.156172 [DEBUG] switch_core_state_machine.c:421 (sofia/external/XXXXXXXXXXX) State INIT 2012-08-28 10:49:12.156172 [DEBUG] mod_sofia.c:85 sofia/external/XXXXXXXXXXX SOFIA INIT 2012-08-28 10:49:12.216161 [DEBUG] switch_nat.c:511 mapped public port 19628 protocol UDP to localport 19628 2012-08-28 10:49:12.256159 [DEBUG] switch_nat.c:511 mapped public port 19629 protocol UDP to localport 19629 2012-08-28 10:49:12.256159 [DEBUG] mod_sofia.c:129 (sofia/external/XXXXXXXXXXX) State Change CS_INIT -> CS_ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:421 (sofia/external/XXXXXXXXXXX) State INIT going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_channel.c:1887 (sofia/external/XXXXXXXXXXX) Callstate Change DOWN -> RINGING 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:430 (sofia/external/XXXXXXXXXXX) State ROUTING 2012-08-28 10:49:12.256159 [DEBUG] mod_sofia.c:152 sofia/external/XXXXXXXXXXX SOFIA ROUTING 2012-08-28 10:49:12.256159 [DEBUG] switch_ivr_originate.c:67 (sofia/external/XXXXXXXXXXX) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:430 (sofia/external/XXXXXXXXXXX) State ROUTING going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:449 (sofia/external/XXXXXXXXXXX) State CONSUME_MEDIA 2012-08-28 10:49:12.256159 [DEBUG] switch_core_state_machine.c:449 (sofia/external/XXXXXXXXXXX) State CONSUME_MEDIA going to sleep 2012-08-28 10:49:12.256159 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.256159 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [calling][0] 2012-08-28 10:49:12.636131 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.636131 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:12.636131 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:12.636131 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [proceeding][180] 2012-08-28 10:49:12.636131 [NOTICE] sofia.c:5769 Ring-Ready sofia/external/XXXXXXXXXXX! 2012-08-28 10:49:14.655990 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:14.655990 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:14.655990 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:14.655990 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [proceeding][183] 2012-08-28 10:49:14.655990 [DEBUG] sofia.c:5688 Remote SDP: v=0 o=FreeSWITCH 1346150407 1346150408 IN IP4 8.19.97.6 s=FreeSWITCH c=IN IP4 8.19.97.6 t=0 0 m=audio 18534 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:4911 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:2995 Set Codec sofia/external/XXXXXXXXXXX PCMU/8000 20 ms 160 samples 64000 bits 2012-08-28 10:49:14.655990 [DEBUG] switch_core_codec.c:111 sofia/external/XXXXXXXXXXX Original read codec set to PCMU:0 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:5025 Set 2833 dtmf send payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3244 AUDIO RTP [sofia/external/XXXXXXXXXXX] 10.10.80.33 port 19628 -> 8.19.97.6 port 18534 codec: 0 ms: 20 2012-08-28 10:49:14.655990 [DEBUG] switch_rtp.c:1676 Starting timer [soft] 160 bytes per 20ms 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3508 Set 2833 dtmf send payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3514 Set 2833 dtmf receive payload to 101 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3541 sofia/external/XXXXXXXXXXX Set rtp dtmf delay to 40 2012-08-28 10:49:14.655990 [DEBUG] sofia_glue.c:3547 Set comfort noise payload to 13 2012-08-28 10:49:14.655990 [NOTICE] sofia_glue.c:4052 Pre-Answer sofia/external/XXXXXXXXXXX! 2012-08-28 10:49:14.655990 [DEBUG] switch_channel.c:2986 (sofia/external/XXXXXXXXXXX) Callstate Change RINGING -> EARLY 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [INFO] sofia.c:890 sofia/external/XXXXXXXXXXX Update Callee ID to "Outbound Call" 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [completing][200] 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5685 Duplicate SDP v=0 o=FreeSWITCH 1346150407 1346150408 IN IP4 8.19.97.6 s=FreeSWITCH c=IN IP4 8.19.97.6 t=0 0 m=audio 18534 RTP/AVP 0 101 13 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] sofia.c:5677 Channel sofia/external/XXXXXXXXXXX entering state [ready][200] 2012-08-28 10:49:22.136467 [DEBUG] switch_channel.c:3245 (sofia/external/XXXXXXXXXXX) Callstate Change EARLY -> ACTIVE 2012-08-28 10:49:22.136467 [NOTICE] sofia.c:6352 Channel [sofia/external/XXXXXXXXXXX] has been answered 2012-08-28 10:49:22.136467 [DEBUG] switch_ivr_originate.c:3330 Originate Resulted in Success: [sofia/external/XXXXXXXXXXX] 2012-08-28 10:49:22.136467 [DEBUG] switch_cpp.cpp:1083 (sofia/external/XXXXXXXXXXX) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:443 (sofia/external/XXXXXXXXXXX) State SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] mod_sofia.c:592 SOFIA SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:261 sofia/external/XXXXXXXXXXX Standard SOFT_EXECUTE 2012-08-28 10:49:22.136467 [DEBUG] switch_core_state_machine.c:443 (sofia/external/XXXXXXXXXXX) State SOFT_EXECUTE going to sleep 2012-08-28 10:49:22.136467 [DEBUG] switch_cpp.cpp:1172 CoreSession::seHangupHook, hangup_func: (nil) 2012-08-28 10:49:22.136467 [INFO] switch_cpp.cpp:1227 Connected 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Session Ready 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Call Initialized 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Trying To Run 2012-08-28 10:49:23.136397 [INFO] switch_cpp.cpp:1227 Running 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Checking for file /svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw 2012-08-28 10:49:23.160407 [ERR] mod_sndfile.c:197 Error Opening File [/svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw] [System error : No such file or directory.] 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Transcoding xfile /svc/prod/product/2167/phone/2167.a74e3d86b1f93f0a1f2fc9ad4f6ef2f4.ulaw.ulaw 2012-08-28 10:49:23.160407 [NOTICE] switch_cpp.cpp:1227 Sending MQ Message 2012-08-28 10:49:23.296392 [NOTICE] switch_cpp.cpp:1227 Creating a channel... 2012-08-28 10:49:23.296392 [NOTICE] switch_cpp.cpp:1227 Defining Exchange FS... 2012-08-28 10:49:23.316389 [NOTICE] switch_cpp.cpp:1227 Binding Exchange and Queue.. 2012-08-28 10:49:23.336389 [NOTICE] switch_cpp.cpp:1227 Sending message.. 2012-08-28 10:49:23.356388 [NOTICE] switch_cpp.cpp:1227 shout://www.product.com/files/media/e53bc5bf-4e00-ab8d-4e4d-b201699628d1.mp3 2012-08-28 10:49:25.916204 [DEBUG] mod_shout.c:472 Read Thread Done 2012-08-28 10:49:26.356173 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16@8000hz 1 channels 20ms 2012-08-28 10:49:26.596156 [DEBUG] switch_rtp.c:3252 Correct ip/port confirmed. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:919 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_channel.c:2849 (sofia/external/XXXXXXXXXXX) Callstate Change ACTIVE -> HANGUP 2012-08-28 10:49:27.296108 [NOTICE] sofia.c:694 Hangup sofia/external/XXXXXXXXXXX [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 2012-08-28 10:49:27.296108 [DEBUG] switch_channel.c:2872 Send signal sofia/external/XXXXXXXXXXX [KILL] 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1227 AppFunction is in hangupCallback. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_HANGUP 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:622 (sofia/external/XXXXXXXXXXX) State HANGUP 2012-08-28 10:49:27.296108 [DEBUG] mod_sofia.c:473 Channel sofia/external/XXXXXXXXXXX hanging up, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:47 sofia/external/XXXXXXXXXXX Standard HANGUP, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:622 (sofia/external/XXXXXXXXXXX) State HANGUP going to sleep 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:413 (sofia/external/XXXXXXXXXXX) State Change CS_HANGUP -> CS_REPORTING 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1227 AppFunction is in hangupCallback. 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:382 (sofia/external/XXXXXXXXXXX) Running State Change CS_REPORTING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:682 (sofia/external/XXXXXXXXXXX) State REPORTING 2012-08-28 10:49:27.296108 [INFO] switch_cpp.cpp:1227 Handing UP: e315f2e8-1fa8-4fd9-849b-f687dad8aed5 2012-08-28 10:49:27.296108 [DEBUG] switch_cpp.cpp:1007 sofia/external/XXXXXXXXXXX destroy/unlink session from object 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:79 sofia/external/XXXXXXXXXXX Standard REPORTING, cause: NORMAL_CLEARING 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:682 (sofia/external/XXXXXXXXXXX) State REPORTING going to sleep 2012-08-28 10:49:27.296108 [DEBUG] switch_core_state_machine.c:407 (sofia/external/XXXXXXXXXXX) State Change CS_REPORTING -> CS_DESTROY 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1224 Send signal sofia/external/XXXXXXXXXXX [BREAK] 2012-08-28 10:49:27.296108 [DEBUG] switch_core_session.c:1424 Session 4 (sofia/external/XXXXXXXXXXX) Locked, Waiting on external entities 2012-08-28 10:49:27.316110 [DEBUG] switch_ivr_play_say.c:1682 done playing file shout://www.product.com/files/media/e53bc5bf-4e00-ab8d-4e4d-b201699628d1.mp3 freeswitch(a)fs03.int.colo> show channels uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,application,application_data,dialplan,context,read_codec,read_rate,read_bit_rate,write_codec,write_rate,write_bit_rate,secure,hostname,presence_id,presence_data,callstate,callee_name,callee_num,callee_direction,call_uuid,sent_callee_name,sent_callee_num e315f2e8-1fa8-4fd9-849b-f687dad8aed5,outbound,2012-08-28 10:49:12,1346168952,sofia/external/XXXXXXXXXXX,CS_SOFT_EXECUTE,Vocalspace,2223334444,,XXXXXXXXXXX,,,,default,PCMU,8000,64000,PCMU,8000,64000,,fs03.int.colo,,,ACTIVE,Outbound Call,XXXXXXXXXXX,,,, 1 total. 2012-08-28 10:58:10.256585 [DEBUG] switch_nat.c:511 mapped public port 5060 protocol TCP to localport 5060 2012-08-28 10:58:10.296583 [DEBUG] switch_nat.c:511 mapped public port 5080 protocol UDP to localport 5080 2012-08-28 10:58:10.336580 [DEBUG] switch_nat.c:511 mapped public port 5080 protocol TCP to localport 5080 2012-08-28 10:58:10.396576 [DEBUG] switch_nat.c:511 mapped public port 19628 protocol UDP to localport 19628 2012-08-28 10:58:10.436573 [DEBUG] switch_nat.c:511 mapped public port 19629 protocol UDP to localport 19629 2012-08-28 10:58:10.476570 [DEBUG] switch_nat.c:511 mapped public port 22013 protocol UDP to localport 22013 2012-08-28 10:58:10.536566 [DEBUG] switch_nat.c:511 mapped public port 22191 protocol UDP to localport 22191 2012-08-28 10:58:10.576563 [DEBUG] switch_nat.c:511 mapped public port 25326 protocol UDP to localport 25326 2012-08-28 10:58:10.636559 [DEBUG] switch_nat.c:511 mapped public port 25327 protocol UDP to localport 25327 Loading /usr/lib64/freeswitch/mod/managed/Enyim.Caching.dll from domain Enyim.Caching.dll_63 2012-08-28 11:00:02.097780 [DEBUG] switch_cpp.cpp:1227 Assembly Enyim.Caching, Version=1.2.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Jint.dll from domain Jint.dll_64 2012-08-28 11:00:02.316760 [DEBUG] switch_cpp.cpp:1227 Assembly Jint, Version=0.9.2.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/log4net.dll from domain log4net.dll_65 2012-08-28 11:00:02.536745 [DEBUG] switch_cpp.cpp:1227 Assembly log4net, Version=1.2.10.0, Culture=neutral, PublicKeyToken=1b44e1d426115821 doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Antlr3.Runtime.dll from domain Antlr3.Runtime.dll_66 2012-08-28 11:00:02.756720 [DEBUG] switch_cpp.cpp:1227 Assembly Antlr3.Runtime, Version=3.3.1.7705, Culture=neutral, PublicKeyToken=eb42632606e9261f doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/RabbitMQ.Client.dll from domain RabbitMQ.Client.dll_67 2012-08-28 11:00:02.996708 [DEBUG] switch_cpp.cpp:1227 Assembly RabbitMQ.Client, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/ManagedEsl.dll from domain ManagedEsl.dll_68 2012-08-28 11:00:03.216689 [DEBUG] switch_cpp.cpp:1227 Assembly ManagedEsl, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/FreeSWITCH.Managed.dll from domain FreeSWITCH.Managed.dll_69 2012-08-28 11:00:03.436678 [DEBUG] switch_cpp.cpp:1227 Assembly FreeSWITCH.Managed, Version=1.0.5.0, Culture=neutral, PublicKeyToken=null doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/FreeSwitch.EventSocket.dll from domain FreeSwitch.EventSocket.dll_70 2012-08-28 11:00:03.657671 [DEBUG] switch_cpp.cpp:1227 Assembly FreeSwitch.EventSocket, Version=1.0.0.0, Culture=neutral, PublicKeyToken=94d8a22cdb4b7713 doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Newtonsoft.Json.dll from domain Newtonsoft.Json.dll_71 2012-08-28 11:00:03.876655 [DEBUG] switch_cpp.cpp:1227 Assembly Newtonsoft.Json, Version=4.5.0.0, Culture=neutral, PublicKeyToken=30ad4fe6b2a6aeed doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Freeswitch.UUID.dll from domain Freeswitch.UUID.dll_72 2012-08-28 11:00:04.096628 [DEBUG] switch_cpp.cpp:1227 Assembly Freeswitch.UUID, Version=1.0.0.0, Culture=neutral, PublicKeyToken=f758751257b2cdaf doesn't reference FreeSWITCH.Managed, not loading. Loading /usr/lib64/freeswitch/mod/managed/Product.dll from domain Product.dll_73 freeswitch(a)fs03.int.colo> show channels 0 total.

6 23

B-Leg / A-Leg Race Condition
by Mike Burlingame 14 Sep '12

14 Sep '12

We are seeing some instances when we send a invite from the B-Leg back to FS and FS passes the invite to the A-Leg that the dialog on the A-Leg has not fully completed yet causing a return of a 491 from the A-Leg side causing the call to be disconnected. wanted to see if anyone else has seen something like this while running FS and if anyone had any suggestions on a fix? A-Leg Invite into Freeswitch 100 Trying back from FS to A-Leg 180 Ringing from FS to A-Leg 200 OK from FS to A-Leg at 15:08:14.638799 Invite from FS to A-Leg (From B-Leg below) at 15:08:14.749515 100 Giving a try form A-Leg to FS at 15:08:14.749757 491 From A-Leg to FS at 15:08:14.780968 ACK from FS to A-Leg at 15:08:14.781102 ACK from FS to A-Leg at 15:08:14.797143 BYE from A-LEG to FS B-Leg Invite from Freeswitch to B-Leg 100 Giving a try from B-Leg 180 Ringing from B-Leg 200 OK from B-Leg at 15:08:14.635670 ACK from FS to B-Leg at 15:08:14.637044 Invite from B-Leg to FS at 15:08:14.748623 100 Trying from FS to B-Leg at 15:08:14.748954 491 from FS to B-Leg at 15:08:14.782169 ACK from B-Leg to FS at 15:08:14.782372 BYE from FS to B-Leg

5 37

mod_dingaling picking wrong IP address / no audio?
by Mark Campbell-Smith 14 Sep '12

14 Sep '12

Hi! I am trying to call from my corporate network (firewalled) using Gtalk to Freeswitch. I am not getting any audio. In the logs I see that mod_dingaling is using my internal corporate IP address which is not publically addressable. 2009-06-23 22:46:52.140382 [DEBUG] mod_dingaling.c:2634 using Existing session for 4085152502 2009-06-23 22:46:52.140382 [DEBUG] mod_dingaling.c:2932 1 candidates 2009-06-23 22:46:52.140382 [DEBUG] mod_dingaling.c:2960 candidates 146.xx.xx.xx:50320 2009-06-23 22:46:52.140382 [DEBUG] mod_dingaling.c:2993 Acceptable Candidate 146.xx.xx.xx:50320 Further on in the log, I can see GTalk sending a new candidate IP address to use: 2009-06-23 22:46:52.940486 [DEBUG] libdingaling.c:503 New Candidate 1 name=rtp type=local protocol=udp username=e+JTkVHT1xEkqXGD password=fAxU6Pr1oF9Zq48U address=192.168.1.102 port=50322 pref=1.00 2009-06-23 22:46:52.940486 [DEBUG] mod_dingaling.c:2634 using Existing session for 4085152502 2009-06-23 22:46:52.940486 [DEBUG] mod_dingaling.c:2928 Already picked an IP [146.xx.xx.xx] and 2009-06-23 22:46:52.957753 [DEBUG] libdingaling.c:503 New Candidate 2 name=rtp type=stun protocol=udp username=RBqyF2XNMYLfJNoU password=DQMjon1fSVoJIRTp address=124.xxx.xxx.xxx port=50323 pref=0.90 2009-06-23 22:46:52.957753 [DEBUG] mod_dingaling.c:2634 using Existing session for 4085152502 2009-06-23 22:46:52.957753 [DEBUG] mod_dingaling.c:2928 Already picked an IP [146.xx.xx.xx] and 2009-06-23 22:46:53.788341 [DEBUG] libdingaling.c:503 New Candidate 3 name=rtp type=relay protocol=udp username=62L5zs2FHbcUdeCJ password=KxmNgkUmZsLfuX6S address=209.xx.xxx.xxx port=19295 pref=0.50 2009-06-23 22:46:53.788341 [DEBUG] mod_dingaling.c:2634 using Existing session for 4085152502 2009-06-23 22:46:53.788341 [DEBUG] mod_dingaling.c:2928 Already picked an IP [146.xx.xx.xx] Because of this, I never get audio. Any ideas how to fix this? Thanks!

5 5

mod_dingaling & inbound audio
by Locutis of Borg 14 Sep '12

14 Sep '12

Followed instructions on wiki. Enabled mod_dingaling to register over at GV. Set external rtp to right address. Outbound calling terminates, and outbound audio works, but seems that I can not get return audio. FS is on DMZ - same problem Moved to internal (behind NAT, port forwarding) - same problem Moved back to DMZ and killed iptables and router spi - same problem In and out calling on SIP to provider works fine in both cases. So my questions are: Can mod_dingaling be used for GV or not? Since it uses TLS, NAT shouldn't be an issue? and Does GV not like us FS users? Thank you for you help. "Resistance is Fruitful"

9 14