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@internal> conference list

Conference 3001-10.10.10.16 (1 member rate: 8000)
6;sofia/internal/1004@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@internal> conference 3001-10.10.10.16 dial {originate_timeout=30}sofia/internal/1005@10.10.10.16 1234567890 FreeSWITCH_Conference

This does not work. I observe the following errors in the fs_cli logs:

 

can anyone tell what could be going wrong ?

 

thanks

--naren

 

The complete logs are given below:

 

#############################################################

freeswitch@internal> conference 3001-10.10.10.16 dial {originate_timeout=30}sofia/internal/1005@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@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@10.10.10.16 [a77137ad-ad1a-45e9-ac27-88a996cd865d]
2011-06-12 10:57:57.606002 [DEBUG] mod_sofia.c:3384 (sofia/internal/1005@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1005@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@10.10.10.16) State INIT
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:83 sofia/internal/1005@10.10.10.16 SOFIA INIT
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:117 (sofia/internal/1005@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1005@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@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@10.10.10.16) State ROUTING
2011-06-12 10:57:57.607036 [DEBUG] mod_sofia.c:140 sofia/internal/1005@10.10.10.16 SOFIA ROUTING
2011-06-12 10:57:57.607036 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/1005@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1005@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@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@10.10.10.16) State CONSUME_MEDIA
2011-06-12 10:57:57.607036 [DEBUG] switch_core_state_machine.c:360 (sofia/internal/1005@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@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@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@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@10.10.10.16) State NEW
2011-06-12 10:57:57.616761 [DEBUG] sofia.c:4153 Channel sofia/internal/1234567890@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@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1234567890@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@10.10.10.16) State INIT
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:83 sofia/internal/1234567890@10.10.10.16 SOFIA INIT
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:117 (sofia/internal/1234567890@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1234567890@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@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@10.10.10.16) State ROUTING
2011-06-12 10:57:57.618925 [DEBUG] mod_sofia.c:140 sofia/internal/1234567890@10.10.10.16 SOFIA ROUTING
2011-06-12 10:57:57.618925 [DEBUG] switch_core_state_machine.c:77 sofia/internal/1234567890@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@10.10.10.16 parsing [public->unloop] continue=false
Dialplan: sofia/internal/1234567890@10.10.10.16 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1234567890@10.10.10.16 Regex (PASS) [unloop] ${sip_looped_call}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1234567890@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1234567890@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@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@10.10.10.16) State EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] mod_sofia.c:226 sofia/internal/1234567890@10.10.10.16 SOFIA EXECUTE
2011-06-12 10:57:57.620002 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1234567890@10.10.10.16 Standard EXECUTE
EXECUTE sofia/internal/1234567890@10.10.10.16 deflect(1005)
2011-06-12 10:57:57.621014 [DEBUG] sofia.c:5004 Process REFER to [1005@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@10.10.10.16 entering state [terminated][480]
2011-06-12 10:57:57.622041 [NOTICE] sofia.c:4789 Hangup sofia/internal/1005@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@10.10.10.16 [KILL]
2011-06-12 10:57:57.622041 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1005@10.10.10.16 [BREAK]
2011-06-12 10:57:57.622041 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1005@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@10.10.10.16) State HANGUP
2011-06-12 10:57:57.623126 [DEBUG] mod_sofia.c:408 sofia/internal/1005@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@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@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@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1005@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@10.10.10.16) State REPORTING
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1005@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@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_session.c:1164 Session 24 (sofia/internal/1005@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@10.10.10.16) Ended
2011-06-12 10:57:57.637437 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1005@10.10.10.16 [CS_DESTROY]
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:428 (sofia/internal/1005@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@10.10.10.16) State DESTROY
2011-06-12 10:57:57.637437 [DEBUG] mod_sofia.c:341 sofia/internal/1005@10.10.10.16 SOFIA DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1005@10.10.10.16 Standard DESTROY
2011-06-12 10:57:57.637437 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1005@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.721283 [NOTICE] switch_core_state_machine.c:185 sofia/internal/1234567890@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@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@10.10.10.16 [KILL]
2011-06-12 10:57:57.722210 [DEBUG] switch_core_session.c:1021 Send signal sofia/internal/1234567890@10.10.10.16 [BREAK]
2011-06-12 10:57:57.722210 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1234567890@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@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@10.10.10.16) State HANGUP
2011-06-12 10:57:57.722210 [DEBUG] mod_sofia.c:414 Channel sofia/internal/1234567890@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@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@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1234567890@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@10.10.10.16) State REPORTING
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1234567890@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@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@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@10.10.10.16 [BREAK]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_session.c:1164 Session 25 (sofia/internal/1234567890@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@10.10.10.16) Ended
2011-06-12 10:57:57.723321 [NOTICE] switch_core_session.c:1184 Close Channel sofia/internal/1234567890@10.10.10.16 [CS_DESTROY]
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:428 (sofia/internal/1234567890@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@10.10.10.16) State DESTROY
2011-06-12 10:57:57.723321 [DEBUG] mod_sofia.c:341 sofia/internal/1234567890@10.10.10.16 SOFIA DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1234567890@10.10.10.16 Standard DESTROY
2011-06-12 10:57:57.723321 [DEBUG] switch_core_state_machine.c:439 (sofia/internal/1234567890@10.10.10.16) State DESTROY going to sleep
##################################################################################