[Freeswitch-users] Call Hanging up on receiving 302 from SIP endpoint

Tue Feb 25 23:26:01 MSK 2014

The scenario is 14087184373 calling DID which terminates at exten 1108,
which has set Call Forward to 14084935591 ..

Here is the relevant log

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

2014-02-24 09:15:35.963293 [DEBUG] switch_core_state_machine.c:325
(sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060) Running State Change
CS_CONSUME_MEDIA
2014-02-24 09:15:35.963293 [DEBUG] switch_core_state_machine.c:383
(sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060) State CONSUME_MEDIA
2014-02-24 09:15:35.963293 [DEBUG] switch_core_state_machine.c:383
(sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060) State CONSUME_MEDIA
going to sleep
2014-02-24 09:15:36.257932 [DEBUG] sofia.c:4366 Redirect: Transfering to
14084935591 XML redirected
2014-02-24 09:15:36.257932 [DEBUG] switch_ivr.c:1600
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State Change
CS_EXECUTE -> CS_ROUTING
2014-02-24 09:15:36.257932 [DEBUG] switch_core_session.c:1116 Send signal
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [BREAK]
2014-02-24 09:15:36.257932 [DEBUG] switch_core_session.c:709 Send signal
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [BREAK]
2014-02-24 09:15:36.257932 [NOTICE] switch_ivr.c:1606 Transfer
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 to
XML[14084935591 at redirected]
2014-02-24 09:15:36.257932 [DEBUG] switch_channel.c:2563
(sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060) Callstate Change RINGING
-> HANGUP
2014-02-24 09:15:36.257932 [NOTICE] sofia.c:4369 Hangup
sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060 [CS_CONSUME_MEDIA]
[REDIRECTION_TO_NEW_DESTINATION]
2014-02-24 09:15:36.257932 [DEBUG] switch_channel.c:2579 Send signal
sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060 [KILL]
2014-02-24 09:15:36.257932 [DEBUG] switch_core_session.c:1116 Send signal
sofia/sipinterface_4/sip:1108 at 192.168.99.8:5060 [BREAK]
2014-02-24 09:15:36.257932 [DEBUG] switch_ivr_originate.c:3293 Originate
Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2014-02-24 09:15:36.257932 [ERR] switch_ivr_originate.c:2447 Cannot create
outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
2014-02-24 09:15:36.257932 [DEBUG] switch_ivr_originate.c:3299 Originate
Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
2014-02-24 09:15:36.257932 [INFO] mod_dptools.c:2647 Originate Failed. 
Cause: ORIGINATOR_CANCEL
2014-02-24 09:15:36.257932 [DEBUG] switch_core_state_machine.c:371
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State EXECUTE going
to sleep
2014-02-24 09:15:36.257932 [DEBUG] switch_core_state_machine.c:325
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) Running State
Change CS_ROUTING
2014-02-24 09:15:36.257932 [DEBUG] switch_core_state_machine.c:364
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State ROUTING
2014-02-24 09:15:36.257932 [DEBUG] mod_sofia.c:147
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 SOFIA ROUTING
2014-02-24 09:15:36.257932 [DEBUG] switch_core_state_machine.c:77
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 Standard ROUTING
2014-02-24 09:15:36.257932 [INFO] mod_dialplan_xml.c:331 Processing
+14087184373 <+14087184373>->14084935591 in context redirected
Dialplan: sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 parsing
[redirected->redirected] continue=false
Dialplan: sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 Regex
(PASS) [redirected] destination_number(14084935591) =~
/^(1[2-9][0-8][0-9][2-9][0-9]{6})$/ break=never
Dialplan: sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 Action
set(sip_redirect_profile=sipinterface_4)
Dialplan: sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 Action
bridge(sofia/internal/${sip_redirect_contact_0}@127.0.0.1:5062)
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:119
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State Change
CS_ROUTING -> CS_EXECUTE
2014-02-24 09:15:36.259255 [DEBUG] switch_core_session.c:1116 Send signal
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [BREAK]
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:364
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State ROUTING going
to sleep
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:325
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) Running State
Change CS_EXECUTE
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:371
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) State EXECUTE
2014-02-24 09:15:36.259255 [DEBUG] mod_sofia.c:240
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 SOFIA EXECUTE
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:157
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 Standard EXECUTE

2014-02-24 09:15:36.259255 [DEBUG] mod_dptools.c:1060
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 SET
[sip_redirect_profile]=[sipinterface_4]
EXECUTE sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060
bridge(sofia/internal/"14084935591"
<sip:14084935591 at orion.switch1.telrouter.com>@127.0.0.1:5062)
2014-02-24 09:15:36.259255 [DEBUG] switch_channel.c:918
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060
EXPORTING[export_vars] [sip_callee_id_name]=[1108] to event
2014-02-24 09:15:36.259255 [DEBUG] switch_channel.c:918
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060
EXPORTING[export_vars] [sip_callee_id_number]=[14087973449] to event
2014-02-24 09:15:36.259255 [DEBUG] switch_ivr_originate.c:1873 Parsing
global variables
2014-02-24 09:15:36.259255 [ERR] mod_sofia.c:4167 Invalid Profile
2014-02-24 09:15:36.259255 [NOTICE] mod_sofia.c:4402 Close Channel N/A
[CS_NEW]
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:457 ()
Running State Change CS_DESTROY
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:467 (N/A)
State DESTROY
2014-02-24 09:15:36.259255 [DEBUG] mod_sofia.c:362 N/A SOFIA DESTROY
2014-02-24 09:15:36.259255 [DEBUG] switch_core_state_machine.c:467 (N/A)
State DESTROY going to sleep
2014-02-24 09:15:36.259255 [ERR] switch_ivr_originate.c:2447 Cannot create
outgoing channel of type [sofia] cause: [DESTINATION_OUT_OF_ORDER]
2014-02-24 09:15:36.259255 [DEBUG] switch_ivr_originate.c:3299 Originate
Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]
2014-02-24 09:15:36.259255 [INFO] mod_dptools.c:2647 Originate Failed. 
Cause: DESTINATION_OUT_OF_ORDER
2014-02-24 09:15:36.259255 [DEBUG] switch_channel.c:2563
(sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060) Callstate Change
RINGING -> HANGUP
2014-02-24 09:15:36.259255 [NOTICE] mod_dptools.c:2761 Hangup
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [CS_EXECUTE]
[DESTINATION_OUT_OF_ORDER]
2014-02-24 09:15:36.261841 [DEBUG] switch_channel.c:2579 Send signal
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [KILL]
2014-02-24 09:15:36.261841 [DEBUG] switch_core_session.c:1116 Send signal
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 [BREAK]
2014-02-24 09:15:36.261841 [DEBUG] switch_core_session.c:2060
sofia/sipinterface_5/+14087184373 at 65.98.234.222:5060 skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)

==========================================
sipinterface_4 is where all authentication happens ie for extensions ..
sipinterface_5 is reserved for all trunk accounts which do not require any
authentication.

The error shows that "Invalid Profile" .. I

Regards,
Akhil

> Hello everyone,
>
> Looks like I have a strange problem at hand.
>
> A SIP user/ extension has forwarded his phone to his mobile number.
>
> 1. When another user( ie extension) calls the SIP user, the call is
> forwarded to forwarded number after getting 302 redirect.
>
> 2. However, if I call from PSTN into the SIP user, the call hangs up after
> getting the 302 message.
>
> Not sure whats going on. I have the  <param name="manual-redirect"
> value="true"/> in the SIP profile for the extension.
> NB: I have 2 profiles, one for extensions and one for SIP trunk. Just
> wanted to separate the ports. Nothing else. But the SIP profile for trunk
> does not have the manual-redirect set to true.
>
>
> Regards,
> Akhil
>
>
>
>