[Freeswitch-users] originate -ERR NORMAL_TEMPORARY_FAILURE

Aqs Younas aqsyounas at gmail.com
Thu Dec 10 17:02:26 MSK 2015


Hi,

I have just installed FreeSWITCH Version
1.7.0+git~20151210T010223Z~85f8bca628~64bit (git 85f8bca 2015-12-10
01:02:23Z 64bit) and registered two default user without changing anything
on my local server.

freeswitch at internal> sofia status profile internal reg

Registrations:
=================================================================================================
Call-ID:        OfA_p_gR_cgODJYoIsvSNg..
User:           1010 at 192.168.10.41
Contact:        "" <sip:1010 at 182.176.103.109:39618
;rinstance=c2197b164b54c39f;transport=UDP>
Agent:          Z 3.7.30891 r30851
Status:         Registered(UDP)(unknown) EXP(2015-12-09 22:28:28)
EXPSECS(2770)
Ping-Status:    Reachable
Ping-Time:      0.00
Host:           debian
IP:             192.168.10.37
Port:           39618
Auth-User:      1010
Auth-Realm:     192.168.10.41
MWI-Account:    1010 at 192.168.10.41

Call-ID:        II5QsfQj_hgDlJI8jdEmEA..
User:           1015 at 192.168.10.41
Contact:        "" <sip:1015 at 182.176.103.109:39618
;rinstance=18c203322f8c75d5;transport=TCP>
Agent:          Z 3.7.30891 r30851
Status:         Registered(TCP)(unknown) EXP(2015-12-09 22:30:03)
EXPSECS(2865)
Ping-Status:    Reachable
Ping-Time:      0.00
Host:           debian
IP:             192.168.10.37
Port:           58530
Auth-User:      1015
Auth-Realm:     192.168.10.41
MWI-Account:    1015 at 192.168.10.41

Total items returned: 2
=================================================================================================


But when i try to call user 1015 using originate and connect with extension
8000.

freeswitch at internal> originate sofia/internal/1010%192.168.10.41 8000
-ERR NORMAL_TEMPORARY_FAILURE

Also

freeswitch at internal> originate user/1010 8000
-ERR NORMAL_TEMPORARY_FAILURE



2015-12-09 21:45:04.194431 [DEBUG] switch_ivr_originate.c:2128 Parsing
global variables
2015-12-09 21:45:04.194431 [DEBUG] switch_ivr_originate.c:2128 Parsing
global variables
2015-12-09 21:45:04.194431 [NOTICE] switch_channel.c:1091 New Channel
sofia/internal/1010 at 182.176.103.109:39618
[196521b6-3287-430e-a560-80cb2799bc48]
2015-12-09 21:45:04.194431 [DEBUG] mod_sofia.c:4775 (sofia/internal/
1010 at 182.176.103.109:39618) State Change CS_NEW -> CS_INIT
2015-12-09 21:45:04.194431 [NOTICE] switch_ivr_originate.c:2763 Cannot
create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
2015-12-09 21:45:04.194431 [DEBUG] switch_core_state_machine.c:473
(sofia/internal/1010 at 182.176.103.109:39618) Running State Change CS_INIT
2015-12-09 21:45:04.194431 [DEBUG] switch_core_state_machine.c:516
(sofia/internal/1010 at 182.176.103.109:39618) State INIT
2015-12-09 21:45:04.194431 [DEBUG] mod_sofia.c:88 sofia/internal/
1010 at 182.176.103.109:39618 SOFIA INIT
2015-12-09 21:45:04.513945 [DEBUG] switch_nat.c:508 mapped public port
17470 protocol UDP to localport 17470
2015-12-09 21:45:04.539548 [DEBUG] sofia_glue.c:1257 sofia/internal/
1010 at 182.176.103.109:39618 sending invite version: 1.7.0 git 85f8bca
2015-12-10 01:02:23Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1449698034 1449698035 IN IP4 182.176.103.109
s=FreeSWITCH
c=IN IP4 182.176.103.109
t=0 0
m=audio 17470 RTP/AVP 9 0 8 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

send 1306 bytes to udp/[182.176.103.109]:39618 at 21:45:04.540029:
   ------------------------------------------------------------------------
   INVITE sip:1010 at 182.176.103.109:39618;rinstance=c2197b164b54c39f;transport=UDP
SIP/2.0
   Via: SIP/2.0/UDP 182.176.103.109;rport;branch=z9hG4bKcBm8r50ZcZ9ma
   Max-Forwards: 70
   From: "" <sip:0000000000 at 192.168.10.41>;tag=aaD54F6Xm2Q2K
   To: <sip:1010 at 182.176.103.109:39618
;rinstance=c2197b164b54c39f;transport=UDP>
   Call-ID: db9b9b73-198a-1234-408f-0800274aea4c
   CSeq: 84521688 INVITE
   Contact: <sip:mod_sofia at 182.176.103.109:5060>
   User-Agent:
FreeSWITCH-mod_sofia/1.7.0+git~20151210T010223Z~85f8bca628~64bit
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, presence, as-feature-event,
dialog, line-seize, call-info, sla, include-session-description,
presence.winfo, message-summary, refer
   Content-Type: application/sdp

 Content-Disposition: session
   Content-Length: 274
   X-FS-Support: update_display,send_info
   Remote-Party-ID: <sip:0000000000 at 192.168.10.41
>;party=calling;screen=yes;privacy=off

   v=0
   o=FreeSWITCH 1449698034 1449698035 IN IP4 182.176.103.109
   s=FreeSWITCH
   c=IN IP4 182.176.103.109
   t=0 0
   m=audio 17470 RTP/AVP 9 0 8 101
   a=rtpmap:9 G722/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=ptime:20
   ------------------------------------------------------------------------
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:40
sofia/internal/1010 at 182.176.103.109:39618 Standard INIT
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:48
(sofia/internal/1010 at 182.176.103.109:39618) State Change CS_INIT ->
CS_ROUTING
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:516
(sofia/internal/1010 at 182.176.103.109:39618) State INIT going to sleep
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:473
(sofia/internal/1010 at 182.176.103.109:39618) Running State Change CS_ROUTING
2015-12-09 21:45:04.539548 [DEBUG] sofia.c:6755 Channel sofia/internal/
1010 at 182.176.103.109:39618 entering state [calling][0]
2015-12-09 21:45:04.539548 [DEBUG] sofia.c:6755 Channel sofia/internal/
1010 at 182.176.103.109:39618 entering state [terminated][503]
2015-12-09 21:45:04.539548 [NOTICE] sofia.c:7774 Hangup sofia/internal/
1010 at 182.176.103.109:39618 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:532
(sofia/internal/1010 at 182.176.103.109:39618) State ROUTING
2015-12-09 21:45:04.539548 [DEBUG] mod_sofia.c:141 sofia/internal/
1010 at 182.176.103.109:39618 SOFIA ROUTING
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:532
(sofia/internal/1010 at 182.176.103.109:39618) State ROUTING going to sleep
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:473
(sofia/internal/1010 at 182.176.103.109:39618) Running State Change CS_HANGUP
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:739
(sofia/internal/1010 at 182.176.103.109:39618) Callstate Change DOWN -> HANGUP
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:741
(sofia/internal/1010 at 182.176.103.109:39618) State HANGUP
2015-12-09 21:45:04.539548 [DEBUG] mod_sofia.c:431 Channel sofia/internal/
1010 at 182.176.103.109:39618 hanging up, cause: NORMAL_TEMPORARY_FAILURE
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1010 at 182.176.103.109:39618 Standard HANGUP, cause:
NORMAL_TEMPORARY_FAILURE
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:741
(sofia/internal/1010 at 182.176.103.109:39618) State HANGUP going to sleep
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:508
(sofia/internal/1010 at 182.176.103.109:39618) State Change CS_HANGUP ->
CS_REPORTING
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:473
(sofia/internal/1010 at 182.176.103.109:39618) Running State Change
CS_REPORTING
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:827
(sofia/internal/1010 at 182.176.103.109:39618) State REPORTING
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:104
sofia/internal/1010 at 182.176.103.109:39618 Standard REPORTING, cause:
NORMAL_TEMPORARY_FAILURE
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:827
(sofia/internal/1010 at 182.176.103.109:39618) State REPORTING going to sleep
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:499
(sofia/internal/1010 at 182.176.103.109:39618) State Change CS_REPORTING ->
CS_DESTROY
2015-12-09 21:45:04.539548 [DEBUG] switch_core_session.c:1646 Session 12
(sofia/internal/1010 at 182.176.103.109:39618) Locked, Waiting on external
entities
2015-12-09 21:45:04.539548 [DEBUG] switch_ivr_originate.c:3751 Originate
Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2015-12-09 21:45:04.539548 [NOTICE] switch_ivr_originate.c:2763 Cannot
create outgoing channel of type [user] cause: [NORMAL_TEMPORARY_FAILURE]
2015-12-09 21:45:04.539548 [DEBUG] switch_ivr_originate.c:3751 Originate
Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2015-12-09 21:45:04.539548 [NOTICE] switch_core_session.c:1664 Session 12
(sofia/internal/1010 at 182.176.103.109:39618) Ended
2015-12-09 21:45:04.539548 [NOTICE] switch_core_session.c:1668 Close
Channel sofia/internal/1010 at 182.176.103.109:39618 [CS_DESTROY]
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:630
(sofia/internal/1010 at 182.176.103.109:39618) Running State Change CS_DESTROY
2015-12-09 21:45:04.539548 [DEBUG] switch_core_state_machine.c:640
(sofia/internal/1010 at 182.176.103.109:39618) State DESTROY
2015-12-09 21:45:04.539548 [DEBUG] mod_sofia.c:341 sofia/internal/
1010 at 182.176.103.109:39618 SOFIA DESTROY
2015-12-09 21:45:04.894497 [DEBUG] switch_nat.c:568 unmapped public port
17470 protocol UDP to localport 17470
2015-12-09 21:45:05.094227 [DEBUG] switch_core_state_machine.c:111
sofia/internal/1010 at 182.176.103.109:39618 Standard DESTROY
2015-12-09 21:45:05.094227 [DEBUG] switch_core_state_machine.c:640
(sofia/internal/1010 at 182.176.103.109:39618) State DESTROY going to sleep



Why it is failing or what I am doing wrong. Any pointer would be much
appreciated.
Thanks.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20151210/97b5a556/attachment-0001.html 


Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list