"pvt_type
": "resource"
,
"name
": "test carrier softswitch"
,
"enabled
": true
,
"flags
": [
]
,
"weight_cost
": 30
,
"rules
": [
"^\\1(\\d{10})$"
]
,
"gateways
": [
{
"server
": "10.16.19.201"
,
"realm
": "10.16.19.201"
,
"prefix
": "9"
,
"codecs
": [
"PCMU"
]
,
"enabled
": true
}
]
,
"grace_period
": 5
,
"formatters
": {
"request
": [
{
"regex
": "^\\?1?\\d{6}(\\d{4})$"
,
"prefix
": ""
,
"suffix
": ""
}
]
}
}
But when i try to make a call heres what i get back from the /var/log/freeswitch/debug.log
recv 1162 bytes from udp/[10.16.18.23]:5060 at 16:06:06.992990:
------------------------------------------------------------------------
INVITE sip:18474...@sip.kazoo01.ccs.local SIP/2.0
Record-Route: <sip:10.16.18.23;lr=on;ftag=ee75625e>
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKe4b1.7fb84e12aee6441e5029c40d5f9223b8.0
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---05e4765577c5490e;rport=61577
Max-Forwards: 50
Contact: <sip:user_ud...@10.16.18.73:61577;rinstance=78c6bf07d4d7fc55>
To: <sip:18474241500@sip.kazoo01.ccs.local>
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 1 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, MESSAGE, OPTIONS
Content-Type: application/sdp
Supported: replaces
User-Agent: X-Lite release 4.9.1 stamp 78728
Content-Length: 332
X-AUTH-IP: 10.16.18.73
X-AUTH-PORT: 61577
v=0
o=- 13093740366826934 1 IN IP4 10.16.18.73
s=X-Lite release 4.9.1 stamp 78728
c=IN IP4 10.16.18.73
t=0 0
m=audio 59492 RTP/AVP 9 8 120 0 84 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 494 bytes to udp/[10.16.18.23]:5060 at 16:06:06.993478:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKe4b1.7fb84e12aee6441e5029c40d5f9223b8.0
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---05e4765577c5490e;rport=61577
Record-Route: <sip:10.16.18.23;lr=on;ftag=ee75625e>
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
To: <sip:18474241500@sip.kazoo01.ccs.local>
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 1 INVITE
User-Agent: 2600hz
Content-Length: 0
------------------------------------------------------------------------
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [NOTICE] switch_channel.c:1055 New Channel sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_NEW
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] sofia.c:8955 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local receiving invite from 10.16.18.23:5060 version: 1.4.15 64bit
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:9077 1 acls to check for proxy
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:9082 checking 10.16.18.23 against acl authoritative
2015-12-04 16:06:06.992965 [INFO] sofia.c:9084 10.16.18.23 is a proxy according to the authoritative acl
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:9094 network ip is a proxy
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:9098 found auth ip [X-AUTH-IP] header of [10.16.18.73]
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:9122 IP 10.16.18.23 Rejected by acl "trusted". Falling back to Digest auth.
2015-12-04 16:06:06.992965 [WARNING] sofia_reg.c:1742 SIP auth challenge (INVITE) on sofia profile 'sipinterface_1' for [18474...@sip.kazoo01.ccs.local] from ip 10.16.18.23
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_state_machine.c:491 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State NEW
send 946 bytes to udp/[10.16.18.23]:5060 at 16:06:06.994729:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKe4b1.7fb84e12aee6441e5029c40d5f9223b8.0
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---05e4765577c5490e;rport=61577
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
To: <sip:18474241500@sip.kazoo01.ccs.local>;tag=yK2FgNBKy2DDK
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 1 INVITE
User-Agent: 2600hz
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: 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
Proxy-Authenticate: Digest realm="sip.kazoo01.ccs.local", nonce="37d1cd88-9ad3-11e5-9cde-d3c66f2aa541", algorithm=MD5, qop="auth"
Content-Length: 0
------------------------------------------------------------------------
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:2067 detaching session 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
recv 373 bytes from udp/[10.16.18.23]:5060 at 16:06:06.995046:
------------------------------------------------------------------------
ACK sip:18474...@sip.kazoo01.ccs.local SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKe4b1.7fb84e12aee6441e5029c40d5f9223b8.0
Max-Forwards: 50
To: <sip:18474...@sip.kazoo01.ccs.local>;tag=yK2FgNBKy2DDK
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 1 ACK
Content-Length: 0
------------------------------------------------------------------------
recv 1458 bytes from udp/[10.16.18.23]:5060 at 16:06:07.000425:
------------------------------------------------------------------------
INVITE sip:18474...@sip.kazoo01.ccs.local SIP/2.0
Record-Route: <sip:10.16.18.23;lr=on;ftag=ee75625e>
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKb4b1.c6fa7b69fe9bb810990b964ff8b32f32.0
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---8d00357a86d86f2b;rport=61577
Max-Forwards: 50
Contact: <sip:user_ud...@10.16.18.73:61577;rinstance=78c6bf07d4d7fc55>
To: <sip:18474241500@sip.kazoo01.ccs.local>
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 2 INVITE
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, MESSAGE, OPTIONS
Content-Type: application/sdp
Proxy-Authorization: *HIDDEN*
Supported: replaces
User-Agent: X-Lite release 4.9.1 stamp 78728
Content-Length: 332
X-AUTH-IP: 10.16.18.73
X-AUTH-PORT: 61577
v=0
o=- 13093740366826934 1 IN IP4 10.16.18.73
s=X-Lite release 4.9.1 stamp 78728
c=IN IP4 10.16.18.73
t=0 0
m=audio 59492 RTP/AVP 9 8 120 0 84 101
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
a=rtpmap:84 speex/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
------------------------------------------------------------------------
send 494 bytes to udp/[10.16.18.23]:5060 at 16:06:07.000706:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKb4b1.c6fa7b69fe9bb810990b964ff8b32f32.0
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---8d00357a86d86f2b;rport=61577
Record-Route: <sip:10.16.18.23;lr=on;ftag=ee75625e>
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
To: <sip:18474241500@sip.kazoo01.ccs.local>
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 2 INVITE
User-Agent: 2600hz
Content-Length: 0
------------------------------------------------------------------------
2015-12-04 16:06:06.992965 [DEBUG] sofia.c:2175 Re-attaching to session 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:06.992965 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.012981 [DEBUG] sofia.c:8955 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local receiving invite from 10.16.18.23:5060 version: 1.4.15 64bit
2015-12-04 16:06:07.012981 [DEBUG] sofia.c:9077 1 acls to check for proxy
2015-12-04 16:06:07.012981 [DEBUG] sofia.c:9082 checking 10.16.18.23 against acl authoritative
2015-12-04 16:06:07.012981 [INFO] sofia.c:9084 10.16.18.23 is a proxy according to the authoritative acl
2015-12-04 16:06:07.012981 [DEBUG] sofia.c:9094 network ip is a proxy
2015-12-04 16:06:07.012981 [DEBUG] sofia.c:9098 found auth ip [X-AUTH-IP] header of [10.16.18.73]
2015-12-04 16:06:07.012981 [DEBUG] sofia.c:9122 IP 10.16.18.23 Rejected by acl "trusted". Falling back to Digest auth.
2015-12-04 16:06:07.012981 [DEBUG] switch_xml.c:2008 Cache Info
Time Now: 1449266767012981
Expires: 1449266025925704
2015-12-04 16:06:07.012981 [DEBUG] switch_xml.c:2010 Cache expired for user_ud...@sip.kazoo01.ccs.local, doing fresh lookup
2015-12-04 16:06:07.012981 [DEBUG] kazoo_fetch_agent.c:216 Sending directory XML request (37d5002a-9ad3-11e5-9cdf-d3c66f2aa541) to ecal...@kazoo01.ccs.local <3.1193.0>
2015-12-04 16:06:07.112984 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecal...@kazoo01.ccs.local <3.1193.0>
2015-12-04 16:06:07.112984 [DEBUG] kazoo_fetch_agent.c:277 Received directory XML (37d5002a-9ad3-11e5-9cdf-d3c66f2aa541) after 100ms: <document type="freeswitch/xml"><section name="directory"><domain name="sip.kazoo01.ccs.local"><user id="user_udmnnvv3u6" cacheable="3600000"><variables><variable name="ecallmgr_Username" value="user_udmnnvv3u6"/><variable name="ecallmgr_Realm" value="sip.kazoo01.ccs.local"/><variable name="ecallmgr_Account-ID" value="b497bdab67c60087a0d26984cb66a8b3"/><variable name="ecallmgr_Authorizing-ID" value="406deb6fcdcdc047e312e01a9f6f87ac"/><variable name="ecallmgr_Authorizing-Type" value="device"/><variable name="ecallmgr_Owner-ID" value="8896d274428bb52d71834b5f00315e65"/><variable name="ecallmgr_Account-Realm" value="sip.kazoo01.ccs.local"/><variable name="ecallmgr_Account-Name" value=""/><variable name="ecallmgr_Suppress-Unregister-Notifications" value="false"/><variable name="ecallmgr_Register-Overwrite-Notify" value="false"/></variables><params><param name="password" value="z8upzm4mg793"/></params></user></domain></section></document>
2015-12-04 16:06:07.112984 [DEBUG] switch_xml.c:2088 caching lookup for user user_ud...@sip.kazoo01.ccs.local for 3600000 milliseconds
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local entering state [received][100]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] sofia.c:6624 Remote SDP:
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU v=0
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU o=- 13093740366826934 1 IN IP4 10.16.18.73
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU s=X-Lite release 4.9.1 stamp 78728
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU c=IN IP4 10.16.18.73
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU t=0 0
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU m=audio 59492 RTP/AVP 9 8 120 0 84 101
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU a=rtpmap:120 opus/48000/2
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU a=rtpmap:84 speex/16000
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU a=rtpmap:101 telephone-event/8000
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU a=fmtp:101 0-15
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] sofia.c:6891 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State Change CS_NEW -> CS_INIT
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_INIT
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:512 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State INIT
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local SOFIA INIT
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local Standard INIT
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State Change CS_INIT -> CS_ROUTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:512 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State INIT going to sleep
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_ROUTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_channel.c:2184 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Callstate Change DOWN -> RINGING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State ROUTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local SOFIA ROUTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [DEBUG] switch_core_state_machine.c:166 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local Standard ROUTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:07.112984 [INFO] mod_dialplan_xml.c:635 Processing user_udmnnvv3u6 <user_udmnnvv3u6>->18474241500 in context context_2
2015-12-04 16:06:07.112984 [DEBUG] kazoo_fetch_agent.c:216 Sending dialplan XML request (37e4df5e-9ad3-11e5-9ce1-d3c66f2aa541) to ecal...@kazoo01.ccs.local <3.1194.0>
2015-12-04 16:06:07.313001 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecal...@kazoo01.ccs.local <3.1194.0>
2015-12-04 16:06:10.212996 [NOTICE] kazoo_fetch_agent.c:286 Request for dialplan XML (37e4df5e-9ad3-11e5-9ce1-d3c66f2aa541) timed-out after 3100ms
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [WARNING] mod_dialplan_xml.c:665 Context context_2 not found
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [INFO] switch_core_state_machine.c:241 No Route, Aborting
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [NOTICE] switch_core_state_machine.c:242 Hangup sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [CS_ROUTING] [NO_ROUTE_DESTINATION]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_channel.c:3222 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [KILL]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State ROUTING going to sleep
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_HANGUP
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Callstate Change RINGING -> HANGUP
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State HANGUP
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local hanging up, cause: NO_ROUTE_DESTINATION
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] mod_sofia.c:551 Responding to INVITE with: 404
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local Standard HANGUP, cause: NO_ROUTE_DESTINATION
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State HANGUP going to sleep
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State Change CS_HANGUP -> CS_REPORTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_REPORTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State REPORTING
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:104 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local Standard REPORTING, cause: NO_ROUTE_DESTINATION
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State REPORTING going to sleep
send 972 bytes to udp/[10.16.18.23]:5060 at 16:06:10.221296:
------------------------------------------------------------------------
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:498 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State Change CS_REPORTING -> CS_DESTROY
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKb4b1.c6fa7b69fe9bb810990b964ff8b32f32.078728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [BREAK]
Via: SIP/2.0/UDP 10.16.18.73:61577;received=10.16.18.73;branch=z9hG4bK-524287-1---8d00357a86d86f2b;rport=61577
Max-Forwards: 50
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_session.c:1615 Session 9 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Locked, Waiting on external entities
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
To: <sip:18474241500@sip.kazoo01.ccs.local>;tag=ZvU8HgvpUB4Ze
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 2 INVITE
User-Agent: 2600hz
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [NOTICE] switch_core_session.c:1633 Session 9 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Ended
Supported: 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
Reason: Q.850;cause=3;text="NO_ROUTE_DESTINATION"
Content-Length: 078728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [NOTICE] switch_core_session.c:1637 Close Channel sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local [CS_DESTROY]
Remote-Party-ID: "18474241500" <sip:18474241500@sip.kazoo01.ccs.local>;party=calling;privacy=off;screen=no
------------------------------------------------------------------------
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:626 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) Running State Change CS_DESTROY
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State DESTROY
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local SOFIA DESTROY
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:111 sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local Standard DESTROY
78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU 2015-12-04 16:06:10.212996 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/user_ud...@sip.kazoo01.ccs.local) State DESTROY going to sleep
recv 373 bytes from udp/[10.16.18.23]:5060 at 16:06:10.221555:
------------------------------------------------------------------------
ACK sip:18474...@sip.kazoo01.ccs.local SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23;branch=z9hG4bKb4b1.c6fa7b69fe9bb810990b964ff8b32f32.0
Max-Forwards: 50
To: <sip:18474...@sip.kazoo01.ccs.local>;tag=ZvU8HgvpUB4Ze
From: <sip:user_ud...@sip.kazoo01.ccs.local>;tag=ee75625e
Call-ID: 78728ODNkMGRkZjVhY2M3NjMwNDEwYjFlN2ZiYTBjYzRhZmU
CSeq: 2 ACK
Content-Length: 0
Good deal. Any idea where the old ui is on github, and an install guide for it?
--
You received this message because you are subscribed to a topic in the Google Groups "2600hz-dev" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/2600hz-dev/YTT7AGJ575Y/unsubscribe.
To unsubscribe from this group and all its topics, send an email to 2600hz-dev+...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
/bin/sed
-i s
#https://api.zswitch.net:8443/v1#http://$HOST_IP:8000/v1#g /var/www/html/kazoo-ui/config/config.js
--
This is a test environment, hence the "carrier" in quotes :)
There is no firewall, it's only a layer 3 switch, so definitely nothing being blocked.
Iptables is off on the kazoo server.
Will get a packet capture tomorrow, just wanted to see if you had experienced this and had any thoughts on it.
As always thanks for all the replies and help, much appreciated.
I should also add that I can telnet on port 11000 from the 10.16.19.201 IP to the kazoo freeswitch server at 10.16.18.23, so it's not a layer 4 port blocking issue, the ports are open.
The carrier is using Avaya? Strange, that usually a CPE. Incoming traffic is most likely being dropped by a firewall ruleset somewhere. There are some SIP stacks that might report their sending a response to a specific port (I have run into this with both Genband and Oracle under certain conditions). Everything is suspect until a packet capture is obtained. If you can't acquire one from in front of your firewall then I would just check for iptables rules that might be blocking traffic (on the freeswitch/media servers) and any other firewalls that might be under your control through which the traffic may traverse.-BC
On Tue, Dec 8, 2015 at 7:26 PM, jallen202020 <jallen...@gmail.com> wrote:
So, using the carrier setup in kazoo-ui, i was able to set up an outbound carrier, and calls are now routing out from kazoo to my test "carrier".I'm getting progress timeouts at this point....very strange. what is happening, is freeswitch is sending the invite with a "via" specifying port 11000 as you would expect, and then my "carrier" replies back witha trying to port 11000, but fee switch never gets the message. Does this ring a bell of something you have run into before?FROM FREESWITCH (after proxy auth completes successfully with my softphone):
send 1196 bytes to udp/[10.16.19.201]:5060 at 18:21:04.153663:------------------------------------------------------------------------
INVITE sip:9184...@10.16.19.201 SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23:11000;rport;branch=z9hG4bKaXcrKUcgg93BpMax-Forwards: 49From: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;tag=NBSDH5rDgp6XK
To: <sip:9184...@10.16.19.201>
INVITE sip:9184...@10.16.19.201 SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23:11000;rport;branch=z9hG4bKB65gNpXKDjtyHMax-Forwards: 49From: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;tag=7D0BHc8cvpQem
To: <sip:9184...@10.16.19.201>
FREESWITCH NEVER SEES THE RESPONSE BACK AND KEEPS SENDING INVITE MESSAGES UNTIL TIMEOUT IS REACHED
INVITE sip:9184...@10.16.19.201 SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23:11000;rport;branch=z9hG4bKcFZ9pHeQaUgHDMax-Forwards: 49From: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;tag=tS02je8F5ZyHg
To: <sip:9184...@10.16.19.201>
Call-ID: 4ba74294-9e0b-11e5-b9e0-8b7af99f9725CSeq: 84474288 INVITEContact: <sip:mod_...@10.16.18.23:11000>User-Agent: 2600hzAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBESupported: path, replacesAllow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, referContent-Type: application/sdpContent-Disposition: sessionContent-Length: 221X-AUTH-IP: 10.16.18.73
X-AUTH-PORT: 638264ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/9184742415...@10.16.19.201) Running State Change CS_ROUTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/9184742415...@10.16.19.201) State ROUTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/9184742415...@10.16.19.201 SOFIA ROUTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_1/9184742415...@10.16.19.201) State Change CS_ROUTING -> CS_CONSUME_MEDIA4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [BREAK]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/9184742415...@10.16.19.201) State ROUTING going to sleep4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/9184742415...@10.16.19.201) Running State Change CS_CONSUME_MEDIA4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:547 (sofia/sipinterface_1/9184742415...@10.16.19.201) State CONSUME_MEDIA4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_state_machine.c:547 (sofia/sipinterface_1/9184742415...@10.16.19.201) State CONSUME_MEDIA going to sleep
X-FS-Support: update_display,send_infoRemote-Party-ID: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;party=calling;screen=yes;privacy=offv=0o=FreeSWITCH 1449602719 1449602720 IN IP4 10.16.18.23s=FreeSWITCHc=IN IP4 10.16.18.23t=0 0m=audio 17986 RTP/AVP 0 101 13a=rtpmap:0 PCMU/8000a=rtpmap:101 telephone-event/8000a=fmtp:101 0-16a=ptime:20------------------------------------------------------------------------
4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [BREAK]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:05.563854 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/9184742415...@10.16.19.201 entering state [calling][0]
2015-12-08 18:25:05.563854 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecal...@kazoo01.ccs.local <1.10889.0>2015-12-08 18:25:05.563854 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(4ba74294-9e0b-11e5-b9e0-8b7af99f9725 ecallmgr_Channel-Authorized=true;ecallmgr_Reseller-ID=b497bdab67c60087a0d26984cb66a8b3;ecallmgr_Account-ID=3303b40d216be330b46ec7fbaee5dd62)2015-12-08 18:25:05.763845 [DEBUG] kazoo_node.c:1100 Sent erlang message to ecal...@kazoo01.ccs.local <1.10935.0>send 1196 bytes to udp/[10.16.19.201]:5060 at 18:25:06.577412:------------------------------------------------------------------------
INVITE sip:9184...@10.16.19.201 SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23:11000;rport;branch=z9hG4bKcFZ9pHeQaUgHDMax-Forwards: 49From: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;tag=tS02je8F5ZyHg
To: <sip:9184...@10.16.19.201>
INVITE sip:9184...@10.16.19.201 SIP/2.0
Via: SIP/2.0/UDP 10.16.18.23:11000;rport;branch=z9hG4bKcFZ9pHeQaUgHDMax-Forwards: 49From: "Account Admin" <sip:+15554...@sip.kazoo01.ccs.local>;tag=tS02je8F5ZyHg
To: <sip:9184...@10.16.19.201>
4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [NOTICE] switch_ivr_originate.c:324 Hangup sofia/sipinterface_1/9184742415...@10.16.19.201 [CS_CONSUME_MEDIA] [PROGRESS_TIMEOUT]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_channel.c:3222 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [KILL]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [BREAK]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/9184742415...@10.16.19.201) Running State Change CS_HANGUP4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/9184742415...@10.16.19.201) Callstate Change DOWN -> HANGUP4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/9184742415...@10.16.19.201) State HANGUP4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/9184742415...@10.16.19.201 hanging up, cause: PROGRESS_TIMEOUT4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] mod_sofia.c:478 Sending CANCEL to sofia/sipinterface_1/9184742415...@10.16.19.2014ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/9184742415...@10.16.19.201 Standard HANGUP, cause: PROGRESS_TIMEOUT4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/9184742415...@10.16.19.201) State HANGUP going to sleep4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_1/9184742415...@10.16.19.201) State Change CS_HANGUP -> CS_REPORTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [BREAK]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/9184742415...@10.16.19.201) Running State Change CS_REPORTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/9184742415...@10.16.19.201) State REPORTING4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:104 sofia/sipinterface_1/9184742415...@10.16.19.201 Standard REPORTING, cause: PROGRESS_TIMEOUT4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/9184742415...@10.16.19.201) State REPORTING going to sleep4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_state_machine.c:498 (sofia/sipinterface_1/9184742415...@10.16.19.201) State Change CS_REPORTING -> CS_DESTROY4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/9184742415...@10.16.19.201 [BREAK]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.003841 [DEBUG] switch_core_session.c:1615 Session 57 (sofia/sipinterface_1/9184742415...@10.16.19.201) Locked, Waiting on external entities
78728YmNkZjgzYjM3NTA4MGI0NGU0OGJkZjc2ZmI3MjVmYTM 2015-12-08 18:25:12.023846 [DEBUG] switch_ivr_originate.c:3723 Originate Resulted in Error Cause: 607 [PROGRESS_TIMEOUT]
4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [NOTICE] switch_core_session.c:1633 Session 57 (sofia/sipinterface_1/9184742415...@10.16.19.201) Ended4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [NOTICE] switch_core_session.c:1637 Close Channel sofia/sipinterface_1/9184742415...@10.16.19.201 [CS_DESTROY]4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [DEBUG] switch_core_state_machine.c:626 (sofia/sipinterface_1/9184742415...@10.16.19.201) Running State Change CS_DESTROY4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/9184742415...@10.16.19.201) State DESTROY4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/9184742415...@10.16.19.201 SOFIA DESTROY4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [DEBUG] switch_core_state_machine.c:111 sofia/sipinterface_1/9184742415...@10.16.19.201 Standard DESTROY4ba74294-9e0b-11e5-b9e0-8b7af99f9725 2015-12-08 18:25:12.023846 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/9184742415...@10.16.19.201) State DESTROY going to sleep
78728YmNkZjgzYjM3NTA4MGI0NGU0OGJkZjc2ZmI3MjVmYTM 2015-12-08 18:25:12.023846 [INFO] mod_dptools.c:3262 Originate Failed. Cause: PROGRESS_TIMEOUT