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