[Freeswitch-users] Freeswitch error: Cannot Blind Transfer 1 Legged calls

21 Mar 2014

      Hi,

I have installed FreeSwitch on a server and I would like to create a call
between two SIP accounts. I run on two computers FSComm Communicator and
specified 1001 and 1002 as accounts. I can see, they are registered. In FS
console I have a message:

freeswitch@internal> sofia status profile internal reg

Registrations:

=================================================================================================

Call-ID:        b273d725-aa58-44b6-9183-f3ac9770903e

User:           1001@192.168.1.164

Contact:        "" <sip:gw+1001@83.142.193.153:12345;transport=udp;gw=1001>

Agent:          FreeSWITCH/FSComm

Status:         Registered(UDP)(unknown) EXP(2014-03-21 14:00:47)
EXPSECS(108)

Host:           freeswitch

IP:             10.0.0.1

Port:           1073

Auth-User:      1001

Auth-Realm:     10.0.0.199

MWI-Account:    1001@192.168.1.164

Call-ID:        6dc6904a-787f-45c7-b67a-6c89ed7e44d6

User:           1002@192.168.1.164

Contact:        "" <sip:gw+1002@83.142.193.153:12345;transport=udp;gw=1002>

Agent:          FreeSWITCH/FSComm

Status:         Registered(UDP)(unknown) EXP(2014-03-21 14:00:47)
EXPSECS(108)

Host:           freeswitch

IP:             10.0.0.1

Port:           12345

Auth-User:      1002

Auth-Realm:     10.0.0.199

MWI-Account:    1002@192.168.1.164

Total items returned: 2

I am creating a call with command:

originate {ignore_early_media=true}sofia/internal/1001@192.168.1.164&bridge({ignore_early_media=true}sofia/internal/
1002@192.168.1.164)

But I can't see anything new in FSComm.

In console I have logs with error:

freeswitch@internal> originate {ignore_early_media=true}sofia/internal/
1001@192.168.1.164 &bridge({ignore_early_media=true}sofia/internal/
1002@192.168.1.164)

*-ERR NO_USER_RESPONSE*

2014-03-21 14:02:33.385597 [DEBUG] switch_ivr_originate.c:2070 Parsing
global variables

2014-03-21 14:02:33.385597 [DEBUG] switch_event.c:1680 Parsing variable
[ignore_early_media]=[true]

2014-03-21 14:02:33.385597 [NOTICE] switch_channel.c:1048 New Channel
sofia/internal/1001@192.168.1.164 [f8c7ad36-5b6a-4eed-aa32-7cc14427747b]

2014-03-21 14:02:33.385597 [DEBUG] mod_sofia.c:4426 (sofia/internal/
1001@192.168.1.164) State Change CS_NEW -> CS_INIT

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/1001@192.168.1.164) Running State Change CS_INIT

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:506
(sofia/internal/1001@192.168.1.164) State INIT

2014-03-21 14:02:33.385597 [DEBUG] mod_sofia.c:87 sofia/internal/
1001@192.168.1.164 SOFIA INIT

2014-03-21 14:02:33.385597 [DEBUG] sofia_glue.c:1225 Local SDP:

v=0

o=FreeSWITCH 1395374973 1395374974 IN IP4 192.168.1.164

s=FreeSWITCH

c=IN IP4 192.168.1.164

t=0 0

m=audio 31980 RTP/AVP 9 0 8 3 101 13

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

a=sendrecv

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [NOTICE] switch_channel.c:1048 New Channel
sofia/internal/0000000000@192.168.1.164[c45f5288-94d5-40c7-91fe-9059a0434581]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:40
sofia/internal/1001@192.168.1.164 Standard INIT

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:48
(sofia/internal/1001@192.168.1.164) State Change CS_INIT -> CS_ROUTING

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:506
(sofia/internal/1001@192.168.1.164) State INIT going to sleep

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/1001@192.168.1.164) Running State Change CS_ROUTING

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:522
(sofia/internal/1001@192.168.1.164) State ROUTING

2014-03-21 14:02:33.385597 [DEBUG] mod_sofia.c:123 sofia/internal/
1001@192.168.1.164 SOFIA ROUTING

2014-03-21 14:02:33.385597 [DEBUG] switch_ivr_originate.c:67
(sofia/internal/1001@192.168.1.164) State Change CS_ROUTING ->
CS_CONSUME_MEDIA

2014-03-21 14:02:33.385597 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:522
(sofia/internal/1001@192.168.1.164) State ROUTING going to sleep

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/1001@192.168.1.164) Running State Change CS_CONSUME_MEDIA

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:541
(sofia/internal/1001@192.168.1.164) State CONSUME_MEDIA

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:541
(sofia/internal/1001@192.168.1.164) State CONSUME_MEDIA going to sleep

2014-03-21 14:02:33.385597 [DEBUG] sofia.c:5863 Channel sofia/internal/
1001@192.168.1.164 entering state [calling][0]

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_NEW

2014-03-21 14:02:33.385597 [DEBUG] switch_core_state_machine.c:485
(sofia/internal/0000000000@192.168.1.164) State NEW

2014-03-21 14:02:33.425662 [DEBUG] sofia.c:8032 IP 192.168.1.164 Rejected
by acl "domains". Falling back to Digest auth.

2014-03-21 14:02:33.425662 [DEBUG] sofia.c:5863 Channel sofia/internal/
0000000000@192.168.1.164 entering state [received][100]

2014-03-21 14:02:33.425662 [DEBUG] sofia.c:5873 Remote SDP:

v=0

o=FreeSWITCH 1395374973 1395374974 IN IP4 192.168.1.164

s=FreeSWITCH

c=IN IP4 192.168.1.164

t=0 0

m=audio 31980 RTP/AVP 9 0 8 3 101 13

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20

2014-03-21 14:02:33.425662 [DEBUG] sofia.c:6085 (sofia/internal/
0000000000@192.168.1.164) State Change CS_NEW -> CS_INIT

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_INIT

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:506
(sofia/internal/0000000000@192.168.1.164) State INIT

2014-03-21 14:02:33.425662 [DEBUG] mod_sofia.c:87 sofia/internal/
0000000000@192.168.1.164 SOFIA INIT

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:40
sofia/internal/0000000000@192.168.1.164 Standard INIT

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:48
(sofia/internal/0000000000@192.168.1.164) State Change CS_INIT -> CS_ROUTING

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:506
(sofia/internal/0000000000@192.168.1.164) State INIT going to sleep

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_ROUTING

2014-03-21 14:02:33.425662 [DEBUG] switch_channel.c:2140 (sofia/internal/
0000000000@192.168.1.164) Callstate Change DOWN -> RINGING

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:522
(sofia/internal/0000000000@192.168.1.164) State ROUTING

2014-03-21 14:02:33.425662 [DEBUG] mod_sofia.c:123 sofia/internal/
0000000000@192.168.1.164 SOFIA ROUTING

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:164
sofia/internal/0000000000@192.168.1.164 Standard ROUTING

2014-03-21 14:02:33.425662 [INFO] mod_dialplan_xml.c:558 Processing
<0000000000>->1001
in context public

Dialplan: sofia/internal/0000000000@192.168.1.164 parsing [public->unloop]
continue=false

Dialplan: sofia/internal/0000000000@192.168.1.164 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false

Dialplan: sofia/internal/0000000000@192.168.1.164 Regex (PASS) [unloop]
${sip_looped_call}(true) =~ /^true$/ break=on-false

Dialplan: sofia/internal/0000000000@192.168.1.164 Action
deflect(${destination_number})

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:214
(sofia/internal/0000000000@192.168.1.164) State Change CS_ROUTING ->
CS_EXECUTE

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:522
(sofia/internal/0000000000@192.168.1.164) State ROUTING going to sleep

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_EXECUTE

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:529
(sofia/internal/0000000000@192.168.1.164) State EXECUTE

2014-03-21 14:02:33.425662 [DEBUG] mod_sofia.c:178 sofia/internal/
0000000000@192.168.1.164 SOFIA EXECUTE

2014-03-21 14:02:33.425662 [DEBUG] switch_core_state_machine.c:256
sofia/internal/0000000000@192.168.1.164 Standard EXECUTE

EXECUTE sofia/internal/0000000000@192.168.1.164 deflect(1001)

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] sofia.c:6896 Process REFER to [
1001@192.168.1.164]

*2014-03-21 14:02:33.425662 [ERR] sofia.c:7389 Cannot Blind Transfer 1
Legged calls*

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.425662 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1034 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [NOTICE] mod_sofia.c:1852 Hangup sofia/internal/
0000000000@192.168.1.164 [CS_EXECUTE] [BLIND_TRANSFER]

2014-03-21 14:02:33.525678 [DEBUG] switch_channel.c:3171 Send signal
sofia/internal/0000000000@192.168.1.164 [KILL]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:2838
sofia/internal/0000000000@192.168.1.164 skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:529
(sofia/internal/0000000000@192.168.1.164) State EXECUTE going to sleep

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_HANGUP

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:730
(sofia/internal/0000000000@192.168.1.164) State HANGUP

2014-03-21 14:02:33.525678 [DEBUG] mod_sofia.c:413 Channel sofia/internal/
0000000000@192.168.1.164 hanging up, cause: BLIND_TRANSFER

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:58
sofia/internal/0000000000@192.168.1.164 Standard HANGUP, cause:
BLIND_TRANSFER

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:730
(sofia/internal/0000000000@192.168.1.164) State HANGUP going to sleep

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:743
(sofia/internal/0000000000@192.168.1.164) Callstate Change RINGING -> HANGUP

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:498
(sofia/internal/0000000000@192.168.1.164) State Change CS_HANGUP ->
CS_REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:815
(sofia/internal/0000000000@192.168.1.164) State REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:102
sofia/internal/0000000000@192.168.1.164 Standard REPORTING, cause:
BLIND_TRANSFER

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:815
(sofia/internal/0000000000@192.168.1.164) State REPORTING going to sleep

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:492
(sofia/internal/0000000000@192.168.1.164) State Change CS_REPORTING ->
CS_DESTROY

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/0000000000@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1577 Session 355
(sofia/internal/0000000000@192.168.1.164) Locked, Waiting on external
entities

2014-03-21 14:02:33.525678 [NOTICE] switch_core_session.c:1595 Session 355
(sofia/internal/0000000000@192.168.1.164) Ended

2014-03-21 14:02:33.525678 [NOTICE] switch_core_session.c:1599 Close
Channel sofia/internal/0000000000@192.168.1.164 [CS_DESTROY]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:617
(sofia/internal/0000000000@192.168.1.164) Callstate Change HANGUP -> DOWN

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:620
(sofia/internal/0000000000@192.168.1.164) Running State Change CS_DESTROY

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:630
(sofia/internal/0000000000@192.168.1.164) State DESTROY

2014-03-21 14:02:33.525678 [DEBUG] mod_sofia.c:323 sofia/internal/
0000000000@192.168.1.164 SOFIA DESTROY

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:109
sofia/internal/0000000000@192.168.1.164 Standard DESTROY

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:630
(sofia/internal/0000000000@192.168.1.164) State DESTROY going to sleep

2014-03-21 14:02:33.525678 [DEBUG] sofia.c:5863 Channel sofia/internal/
1001@192.168.1.164 entering state [terminated][480]

2014-03-21 14:02:33.525678 [NOTICE] sofia.c:6671 Hangup sofia/internal/
1001@192.168.1.164 [CS_CONSUME_MEDIA] [NO_USER_RESPONSE]

2014-03-21 14:02:33.525678 [DEBUG] switch_channel.c:3171 Send signal
sofia/internal/1001@192.168.1.164 [KILL]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/1001@192.168.1.164) Running State Change CS_HANGUP

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:730
(sofia/internal/1001@192.168.1.164) State HANGUP

2014-03-21 14:02:33.525678 [DEBUG] mod_sofia.c:413 Channel sofia/internal/
1001@192.168.1.164 hanging up, cause: NO_USER_RESPONSE

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:58
sofia/internal/1001@192.168.1.164 Standard HANGUP, cause: NO_USER_RESPONSE

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:730
(sofia/internal/1001@192.168.1.164) State HANGUP going to sleep

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:743
(sofia/internal/1001@192.168.1.164) Callstate Change DOWN -> HANGUP

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:498
(sofia/internal/1001@192.168.1.164) State Change CS_HANGUP -> CS_REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:467
(sofia/internal/1001@192.168.1.164) Running State Change CS_REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:815
(sofia/internal/1001@192.168.1.164) State REPORTING

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:102
sofia/internal/1001@192.168.1.164 Standard REPORTING, cause:
NO_USER_RESPONSE

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:815
(sofia/internal/1001@192.168.1.164) State REPORTING going to sleep

2014-03-21 14:02:33.525678 [DEBUG] switch_core_state_machine.c:492
(sofia/internal/1001@192.168.1.164) State Change CS_REPORTING -> CS_DESTROY

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1369 Send signal
sofia/internal/1001@192.168.1.164 [BREAK]

2014-03-21 14:02:33.525678 [DEBUG] switch_core_session.c:1577 Session 354
(sofia/internal/1001@192.168.1.164) Locked, Waiting on external entities

2014-03-21 14:02:33.545687 [DEBUG] switch_ivr_originate.c:3670 Originate
Resulted in Error Cause: 18 [NO_USER_RESPONSE]

2014-03-21 14:02:33.545687 [NOTICE] switch_core_session.c:1595 Session 354
(sofia/internal/1001@192.168.1.164) Ended

2014-03-21 14:02:33.545687 [NOTICE] switch_core_session.c:1599 Close
Channel sofia/internal/1001@192.168.1.164 [CS_DESTROY]

2014-03-21 14:02:33.545687 [DEBUG] switch_core_state_machine.c:617
(sofia/internal/1001@192.168.1.164) Callstate Change HANGUP -> DOWN

2014-03-21 14:02:33.545687 [DEBUG] switch_core_state_machine.c:620
(sofia/internal/1001@192.168.1.164) Running State Change CS_DESTROY

2014-03-21 14:02:33.545687 [DEBUG] switch_core_state_machine.c:630
(sofia/internal/1001@192.168.1.164) State DESTROY

2014-03-21 14:02:33.545687 [DEBUG] mod_sofia.c:323 sofia/internal/
1001@192.168.1.164 SOFIA DESTROY

2014-03-21 14:02:33.545687 [DEBUG] switch_core_state_machine.c:109
sofia/internal/1001@192.168.1.164 Standard DESTROY

2014-03-21 14:02:33.545687 [DEBUG] switch_core_state_machine.c:630
(sofia/internal/1001@192.168.1.164) State DESTROY going to sleep

But If I create a new call in FSComm from 1001 to 1002, IVR is speaking
with message "Number is not available". The same message is when I call for
example 1003 number, which is not registered in FSComm on another computer.

What I am doing wrong?

Regards,

Karol Matuszewski