[Freeswitch-users] Originating a call from a confeence is not working ?

Sun Jun 12 19:32:53 MSD 2011

Hi,

I am trying to use the dial-out feature of the conference application of
freeswitch.
I am using freeswtich version 1.0.6.

As i understand the conference gets created when the first user joins. Hence
i made one user with number 1004 join the conference.

freeswitch at internal> conference list
Conference 3001-10.10.10.16 (1 member rate: 8000)
6;sofia/internal/1004 at 10.10.10.16
;08178796-a06e-4ee8-81fb-1f0cc53f8fb5;1004;1004;hear|speak|floor;0;0;300

Now i dail out another user 1005 using the command :
freeswitch at internal> conference 3001-10.10.10.16 dial
{originate_timeout=30}sofia/internal/1005 at 10.10.10.16<%7Boriginate_timeout=30%7Dsofia/internal/1005 at 135.254.210.166>1234567890
FreeSWITCH_Conference
This does not work. I observe the following errors in the fs_cli logs:

*2011-06-12 10:57:57.621014 [ERR] sofia.c:5366 Cannot Blind Transfer 1
Legged calls*
can anyone tell what could be going wrong ?

thanks
--naren

The complete logs are given below:

#############################################################
freeswitch at internal> conference 3001-10.10.10.16 dial
{originate_timeout=30}sofia/internal/1005 at 10.10.10.16 1234567890
FreeSWITCH_Conference
Call Requested: result: [NO_USER_RESPONSE]
2011-06-12 10:57:57.606002 [DEBUG] switch_ivr_originate.c:1885 variable
string 0 = [ignore_early_media=true]
freeswitch at internal> 2011-06-12 10:57:57.606002 [DEBUG]
switch_ivr_originate.c:1885 variable string 1 = [originate_timeout=30]
2011-06-12 10:57:57.606002 [NOTICE] switch_channel.c:669 New Channel
sofia/internal/1005 at 10.10.10.16 [a77137ad-ad1a-45e9-ac27-88a996cd865d]
2011-06-12 10:57:57.606002 [DEBUG] mod_sofia.c:3384 (
sofia/internal/1005 at 10.10.10.16) State Change CS_NEW -> CS_INIT
2011-06-12 10:57:57.606002 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_INIT
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:338 (
sofia/internal/1005 at 10.10.10.16) State INIT
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:83
sofia/internal/1005 at 10.10.10.16 SOFIA INIT
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:117 (
sofia/internal/1005 at 10.10.10.16) State Change CS_INIT -> CS_ROUTING
2011-06-12 10:57:57.607036 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:338 (
sofia/internal/1005 at 10.10.10.16) State INIT going to sleep
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_ROUTING
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:341 (
sofia/internal/1005 at 10.10.10.16) State ROUTING
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:140
sofia/internal/1005 at 10.10.10.16 SOFIA ROUTING
2011-06-12 10:57:57.607036 [DEBUG] switch_ivr_originate.c:66 (
sofia/internal/1005 at 10.10.10.16) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2011-06-12 10:57:57.607036 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:341 (
sofia/internal/1005 at 10.10.10.16) State ROUTING going to sleep
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_CONSUME_MEDIA
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:360 (
sofia/internal/1005 at 10.10.10.16) State CONSUME_MEDIA
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:360 (
sofia/internal/1005 at 10.10.10.16) State CONSUME_MEDIA going to sleep
2011-06-12 10:57:57.608051 [DEBUG] sofia.c:4153 Channel
sofia/internal/1005 at 10.10.10.16 entering state [calling][0]
2011-06-12 10:57:57.608051 [DEBUG] sofia.c:5847 IP 10.10.10.16 Rejected by
acl "domains". Falling back to Digest auth.
2011-06-12 10:57:57.608051 [NOTICE] switch_channel.c:669 New Channel
sofia/internal/1234567890 at 10.10.10.16 [86e5b4a9-8c63-4d22-b087-94c4b95d1abb]
2011-06-12 10:57:57.609139 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_NEW
2011-06-12 10:57:57.609139 [DEBUG] switch_core_state_machine.c:320 (
sofia/internal/1234567890 at 10.10.10.16) State NEW
2011-06-12 10:57:57.616761 [DEBUG] sofia.c:4153 Channel
sofia/internal/1234567890 at 10.10.10.16 entering state [received][100]
2011-06-12 10:57:57.616761 [DEBUG] sofia.c:4164 Remote SDP:
v=0
o=FreeSWITCH 1307870225 1307870226 IN IP4 10.10.10.16
s=FreeSWITCH
c=IN IP4 10.10.10.16
t=0 0
m=audio 20452 RTP/AVP 115 107 9 0 8 3 101 13
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:107 G7221/16000
a=fmtp:107 bitrate=32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
2011-06-12 10:57:57.616761 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
[G7221:115:32000:20]/[G7221:115:32000:20]
2011-06-12 10:57:57.616761 [DEBUG] sofia_glue.c:2354 Set Codec
sofia/internal/1234567890 at 10.10.10.16 G7221/32000 20 ms 640 samples
2011-06-12 10:57:57.617820 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf send/recv
payload to 101
2011-06-12 10:57:57.617820 [DEBUG] sofia.c:4310 (
sofia/internal/1234567890 at 10.10.10.16) State Change CS_NEW -> CS_INIT
2011-06-12 10:57:57.617820 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_INIT
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:338 (
sofia/internal/1234567890 at 10.10.10.16) State INIT
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:83
sofia/internal/1234567890 at 10.10.10.16 SOFIA INIT
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:117 (
sofia/internal/1234567890 at 10.10.10.16) State Change CS_INIT -> CS_ROUTING
2011-06-12 10:57:57.618925 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:338 (
sofia/internal/1234567890 at 10.10.10.16) State INIT going to sleep
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_ROUTING
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:341 (
sofia/internal/1234567890 at 10.10.10.16) State ROUTING
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:140
sofia/internal/1234567890 at 10.10.10.16 SOFIA ROUTING
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1234567890 at 10.10.10.16 Standard ROUTING
2011-06-12 10:57:57.618925 [INFO] mod_dialplan_xml.c:418 Processing
FreeSWITCH_Conference->1005 in context public
Dialplan: sofia/internal/1234567890 at 10.10.10.16 parsing [public->unloop]
continue=false
Dialplan: sofia/internal/1234567890 at 10.10.10.16 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1234567890 at 10.10.10.16 Regex (PASS) [unloop]
${sip_looped_call}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1234567890 at 10.10.10.16 Action
deflect(${destination_number})
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:119 (
sofia/internal/1234567890 at 10.10.10.16) State Change CS_ROUTING -> CS_EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:341 (
sofia/internal/1234567890 at 10.10.10.16) State ROUTING going to sleep
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:348 (
sofia/internal/1234567890 at 10.10.10.16) State EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] mod_sofia.c:226
sofia/internal/1234567890 at 10.10.10.16 SOFIA EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1234567890 at 10.10.10.16 Standard EXECUTE
EXECUTE sofia/internal/1234567890 at 10.10.10.16 deflect(1005)
2011-06-12 10:57:57.621014 [DEBUG] sofia.c:5004 Process REFER to [
1005 at 10.10.10.16]
*2011-06-12 10:57:57.621014 [ERR] sofia.c:5366 Cannot Blind Transfer 1
Legged calls*
2011-06-12 10:57:57.622041 [DEBUG] sofia.c:4153 Channel
sofia/internal/1005 at 10.10.10.16 entering state [terminated][480]
2011-06-12 10:57:57.622041 [NOTICE] sofia.c:4789 Hangup
sofia/internal/1005 at 10.10.10.16 [CS_CONSUME_MEDIA] [NO_USER_RESPONSE]
2011-06-12 10:57:57.622041 [DEBUG] switch_channel.c:2102 Send signal
sofia/internal/1005 at 10.10.10.16 [KILL]
2011-06-12 10:57:57.622041 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.622041 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_HANGUP
2011-06-12 10:57:57.623126 [DEBUG] switch_core_state_machine.c:499 (
sofia/internal/1005 at 10.10.10.16) State HANGUP
2011-06-12 10:57:57.623126 [DEBUG] mod_sofia.c:408
sofia/internal/1005 at 10.10.10.16 Overriding SIP cause 408 with 480 from the
other leg
2011-06-12 10:57:57.623126 [DEBUG] mod_sofia.c:414 Channel
sofia/internal/1005 at 10.10.10.16 hanging up, cause: NO_USER_RESPONSE
2011-06-12 10:57:57.623126 [DEBUG] switch_ivr_originate.c:3228 Originate
Resulted in Error Cause: 18 [NO_USER_RESPONSE]
2011-06-12 10:57:57.636515 [DEBUG] switch_core_state_machine.c:46
sofia/internal/1005 at 10.10.10.16 Standard HANGUP, cause: NO_USER_RESPONSE
2011-06-12 10:57:57.636515 [DEBUG] switch_core_state_machine.c:499 (
sofia/internal/1005 at 10.10.10.16) State HANGUP going to sleep
2011-06-12 10:57:57.636515 [DEBUG] switch_core_state_machine.c:333 (
sofia/internal/1005 at 10.10.10.16) State Change CS_HANGUP -> CS_REPORTING
2011-06-12 10:57:57.636515 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_REPORTING
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:590 (
sofia/internal/1005 at 10.10.10.16) State REPORTING
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1005 at 10.10.10.16 Standard REPORTING, cause: NO_USER_RESPONSE
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:590 (
sofia/internal/1005 at 10.10.10.16) State REPORTING going to sleep
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:327 (
sofia/internal/1005 at 10.10.10.16) State Change CS_REPORTING -> CS_DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1005 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_session.c:1164 Session 24 (
sofia/internal/1005 at 10.10.10.16) Locked, Waiting on external entities
2011-06-12 10:57:57.637437 [NOTICE] switch_core_session.c:1182 Session 24 (
sofia/internal/1005 at 10.10.10.16) Ended
2011-06-12 10:57:57.637437 [NOTICE] switch_core_session.c:1184 Close Channel
sofia/internal/1005 at 10.10.10.16 [CS_DESTROY]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:428 (
sofia/internal/1005 at 10.10.10.16) Running State Change CS_DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:439 (
sofia/internal/1005 at 10.10.10.16) State DESTROY
2011-06-12 10:57:57.637437 [DEBUG] mod_sofia.c:341
sofia/internal/1005 at 10.10.10.16 SOFIA DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1005 at 10.10.10.16 Standard DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:439 (
sofia/internal/1005 at 10.10.10.16) State DESTROY going to sleep
2011-06-12 10:57:57.720295 [DEBUG] switch_core_session.c:641 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.721283 [NOTICE] switch_core_state_machine.c:185
sofia/internal/1234567890 at 10.10.10.16 has executed the last dialplan
instruction, hanging up.
2011-06-12 10:57:57.721283 [NOTICE] switch_core_state_machine.c:187 Hangup
sofia/internal/1234567890 at 10.10.10.16 [CS_EXECUTE] [NORMAL_CLEARING]
2011-06-12 10:57:57.721283 [DEBUG] switch_channel.c:2102 Send signal
sofia/internal/1234567890 at 10.10.10.16 [KILL]
2011-06-12 10:57:57.722210 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.722210 [DEBUG] switch_core_state_machine.c:348 (
sofia/internal/1234567890 at 10.10.10.16) State EXECUTE going to sleep
2011-06-12 10:57:57.722210 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_HANGUP
2011-06-12 10:57:57.722210 [DEBUG] switch_core_state_machine.c:499 (
sofia/internal/1234567890 at 10.10.10.16) State HANGUP
2011-06-12 10:57:57.722210 [DEBUG] mod_sofia.c:414 Channel
sofia/internal/1234567890 at 10.10.10.16 hanging up, cause: NORMAL_CLEARING
2011-06-12 10:57:57.722210 [DEBUG] mod_sofia.c:476 Responding to INVITE
with: 480
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:46
sofia/internal/1234567890 at 10.10.10.16 Standard HANGUP, cause:
NORMAL_CLEARING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:499 (
sofia/internal/1234567890 at 10.10.10.16) State HANGUP going to sleep
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:333 (
sofia/internal/1234567890 at 10.10.10.16) State Change CS_HANGUP ->
CS_REPORTING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:314 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_REPORTING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:590 (
sofia/internal/1234567890 at 10.10.10.16) State REPORTING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1234567890 at 10.10.10.16 Standard REPORTING, cause:
NORMAL_CLEARING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:590 (
sofia/internal/1234567890 at 10.10.10.16) State REPORTING going to sleep
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:327 (
sofia/internal/1234567890 at 10.10.10.16) State Change CS_REPORTING ->
CS_DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_session.c:1021 Send signal
sofia/internal/1234567890 at 10.10.10.16 [BREAK]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_session.c:1164 Session 25 (
sofia/internal/1234567890 at 10.10.10.16) Locked, Waiting on external entities
2011-06-12 10:57:57.723321 [NOTICE] switch_core_session.c:1182 Session 25 (
sofia/internal/1234567890 at 10.10.10.16) Ended
2011-06-12 10:57:57.723321 [NOTICE] switch_core_session.c:1184 Close Channel
sofia/internal/1234567890 at 10.10.10.16 [CS_DESTROY]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:428 (
sofia/internal/1234567890 at 10.10.10.16) Running State Change CS_DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:439 (
sofia/internal/1234567890 at 10.10.10.16) State DESTROY
2011-06-12 10:57:57.723321 [DEBUG] mod_sofia.c:341
sofia/internal/1234567890 at 10.10.10.16 SOFIA DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1234567890 at 10.10.10.16 Standard DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:439 (
sofia/internal/1234567890 at 10.10.10.16) State DESTROY going to sleep
##################################################################################
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20110612/938b9650/attachment-0001.html 