Re: CUPS, CVP and UCCE

since1976 · ‎09-15-2011

Hi,

Can anyone give me an idea why is CUPS needed to connect to agents (CallManager) and CVP

via static routes

Rgds,

KayLeong

david.macias · ‎09-15-2011

The easy answer is to think about CUPS or CUSP as a DNS server it gives the environment a way to translate destinations to IPs in order to route the call appropriately. Only needed if you're using SIP.

david

Blog

ATIF TANVEER · ‎09-16-2011

if u have cvp 7 or later then you can use srv.xml instead of cups.

Sent from Cisco Technical Support iPad App

geoff · ‎09-16-2011

I would not want a production CVP system without a SIP Proxy. If you have two proxies - which you would for production - you use the srv (originally through DNS but later with the srv.xml file) to find a proxy with resiliency.

Regards,

Geoff

Kris Lambrechts · ‎09-19-2011

There's two main reasons why it's a good idea to have a SIP proxy (either CUSP or CUPS) in your CVP design :

To keep your dialplan manageable in larger designs, image having to maintain a dialplan across 20 gateways, 5 Call Servers and a couple of CUCM nodes / clusters, it's pretty much impossible without a centralized SIP proxy.
Faster fail-over, while quite a few timers can be tweaked to achieve pretty much the same result, having a SIP proxy in place will help you in having a system that quickly works around one server i.e. CUCM node or CVP Call Server being down (for whatever reason).

In smaller setups consisiting of a few gateways, one CUCM cluster and 2 Call Servers, the added value of a SIP proxy may not justify the cost. If you're going to have a SIP proxy, you obviously need 2 at minimum or you're creating a single point of failure.

Cheers,

Kris

since1976 · ‎09-20-2011

Can we have both SIP and the srv.xml in place?

Where can I find the srv.xml in CVP?

geoff · ‎09-21-2011

That's exactly what you do. This is how it is used.

When CVP is notified that the call is to be given to a certain agent, it is given a label of their extension (let's say the extension is 71234 and all extensions are 5 digits starting with 7). It needs to find a Call Manager subscriber. There are three ways:

1. It can have a static route for 7>, (can have a pair of static routes)

2. It can be configured with a SIP proxy (but only 1) where the static routes for 7> live

3. It can be configured with no SIP proxy but with SRV to find the (2) SIP proxies where the static routes for 7> live.

In 3, the SRV resolution can be in DNS (old way) or you can create a srv.xml file (new way) and put it in the conf directory. CVP will read it on start up.

You create the srv.xml - see the guide for an example. Watch the trace as the Call Server starts - it's all there if you look.

As you can see, the progression from1 to 2 to 3 brings in greater fault tolerance and redundancy.

Regards,

Geoff

sandeep pokhriyal · ‎05-16-2012

Hi Geoff,

I am using the 3rd method mentioned by you. but it seems the CVP is not sending calls to the CUSP. the agent gets reserved but the call is not landing. the CVP has srv.xml file that has entry for two proxy servers.

the srv.xml file works fine if we have static routes for the CUCM as we discussed in this post.

https://supportforums.cisco.com/message/3636717

1. It can have a static route for 7>, (can have a pair of static routes)2. It can be configured with a SIP proxy (but only 1) where the static routes for 7> live
3. It can be configured with no SIP proxy but with SRV to find the (2) SIP proxies where the static routes for 7> live.

test(cusp-config)# show con

test(cusp-config)# show configuration acti

test(cusp-config)# show configuration active

Building CUSP configuration...

!

server-group sip global-load-balance call-id

server-group sip retry-after 0

server-group sip element-retries udp 2

server-group sip element-retries tls 1

server-group sip element-retries tcp 1

sip dns-srv

no enable

no naptr

end dns

!

no sip header-compaction

no sip logging

!

sip max-forwards 70

sip network CVP noicmp

no non-invite-provisional

allow-connections

retransmit-count invite-client-transaction 2

retransmit-count invite-server-transaction 9

retransmit-count non-invite-client-transaction 9

retransmit-timer T1 500

retransmit-timer T2 4000

retransmit-timer T4 5000

retransmit-timer TU1 5000

retransmit-timer TU2 32000

retransmit-timer clientTn 64000

retransmit-timer serverTn 64000

udp max-datagram-size 1500

end network

!

sip network enterprise standard

no non-invite-provisional

allow-connections

retransmit-count invite-client-transaction 2

retransmit-count invite-server-transaction 9

retransmit-count non-invite-client-transaction 9

retransmit-timer T1 500

retransmit-timer T2 4000

retransmit-timer T4 5000

retransmit-timer TU1 5000

retransmit-timer TU2 32000

retransmit-timer clientTn 64000

retransmit-timer serverTn 64000

udp max-datagram-size 1500

end network

!

sip network service-provider standard

no non-invite-provisional

allow-connections

retransmit-count invite-client-transaction 2

retransmit-count invite-server-transaction 9

retransmit-count non-invite-client-transaction 9

retransmit-timer T1 500

retransmit-timer T2 4000

retransmit-timer T4 5000

retransmit-timer TU1 5000

retransmit-timer TU2 32000

retransmit-timer clientTn 64000

retransmit-timer serverTn 64000

udp max-datagram-size 1500

end network

!

sip overload reject retry-after 0

!

no sip peg-counting

!

sip privacy service

sip queue message

drop-policy head

low-threshold 80

size 2000

thread-count 20

end queue

!

sip queue radius

drop-policy head

low-threshold 80

size 2000

thread-count 20

end queue

!

sip queue request

drop-policy head

low-threshold 80

size 2000

thread-count 20

end queue

!

sip queue response

drop-policy head

low-threshold 80

size 2000

thread-count 20

end queue

!

sip queue st-callback

drop-policy head

low-threshold 80

size 2000

thread-count 10

end queue

!

sip queue timer

drop-policy none

low-threshold 80

size 2500

thread-count 8

end queue

!

sip queue xcl

drop-policy head

low-threshold 80

size 2000

thread-count 2

end queue

!

route recursion

!

sip tcp connection-timeout 30

sip tcp max-connections 256

sip tls

sip tls trusted-peer 172.25.14.106

sip tls trusted-peer 172.25.27.165

!

trigger condition call-from-enterprise

sequence 1

in-network enterprise

end sequence

end trigger condition

!

trigger condition call-from-service-provider

sequence 1

in-network service-provider

end sequence

end trigger condition

!

trigger condition mid-dialog

sequence 1

mid-dialog

end sequence

end trigger condition

!

accounting

no enable

no client-side

no server-side

end accounting

!

server-group sip group cucm.test.com enterprise

element ip-address 172.25.7.11 5060 udp q-value 1 weight 25

element ip-address 172.25.27.10 5060 udp q-value 1 weight 25

failover-resp-codes 503

lbtype weight

ping

end server-group

!

server-group sip group cvp.test.com enterprise

element ip-address 172.25.10.25 5060 udp q-value 1 weight 25

failover-resp-codes 503

lbtype weight

ping

end server-group

!

server-group sip group gw.test.com enterprise

element ip-address 172.25.14.100 5060 udp q-value 1 weight 25

failover-resp-codes 503

lbtype weight

ping

end server-group

!

policy lookup enterprise

sequence 1 enterprise request-uri uri-component user

rule prefix

end sequence

end policy

!

trigger routing sequence 2 policy enterprise condition mid-dialog

trigger routing sequence 3 policy enterprise condition call-from-service-provider

trigger routing sequence 4 policy enterprise condition call-from-enterprise

!

no server-group sip global-ping

!

sip cac session-timeout 720

sip cac enterprise 172.25.10.25 5060 udp limit -1

sip cac enterprise 172.25.14.106 5060 udp limit -1

sip cac enterprise 172.25.27.10 5060 udp limit -1

sip cac enterprise 172.25.7.11 5060 udp limit -1

!

no sip cac

!

sip listen enterprise udp 172.25.11.95 5060

!

call-rate-limit 30

!

end

test(cusp-config)#

test(cusp-config)# show con

test(cusp-config)# show configuration ac

test(cusp-config)# show configuration active rou

test(cusp-config)# show configuration active route

Building CUSP configuration...

!

route table enterprise

key 2 target-destination cucm.test.com enterprise

key 77 target-destination cvp.test.com enterprise

key 8001234567 target-destination gw.test.com::udp enterprise

key 88 target-destination cvp.test.com CVP

key 91919191 target-destination gw.test.com enterprise

key 92929292 target-destination gw.test.com enterprise

end route table

test(cusp-config)#

geoff · ‎05-16-2012

two things.

You have not set the FQDN in CVP.

If the upstream element is using DNS SRV for routing to two CUSPs you must configure the FQDN on each CUSP using the "sip alias" command. Did you do that?

Regards,

Geoff

sandeep pokhriyal · ‎05-16-2012

I have defined the "sip alias" with cusp.test.com in both the proxy servers.

The FQDN option doesn't come if i set the outbound proxy to no. I am trying to use the static route to send calls to two proxies.

geoff · ‎05-16-2012

Turn on the proxy. Those buttons have been changed around a bit. It used to be one way - if you had chosen Outbound proxy you had to select from the drop down. So one did not do that but could enter the FQDN.

But now you can choose Outbound Proxy and nothing from the drop down and insert the FQDN

Regards,

Geoff

sandeep pokhriyal · ‎05-16-2012

Hi Geoff,

I have changed the configuration in FQDN.

geoff · ‎05-16-2012

sandeep pokhriyal wrote:

I have changed the configuration in FQDN.

OK. So what's the verdict? It's hard for you to test properly because it appears you really only have one CUSP.

Nevertheless, is the SIP routing working when an agent becomes available? Do you agree with the following?

1. The router chooses the agent and tells the PG

2. The PG sends the label for the device on the CVP routing client

3. There are no static routes to match because CVP is configured to use an outbound proxy

4. SRV is set and local, so it uses the in-memory copy it has of this file (SRV.xml) and chooses a proxy server based on past selections, weighting, priority - and if server group heartbeating is turned on, you get dynamic changes to the in-memory table if the server changes its response to the OPTION PING

5. Once the proxy server is chosen, CVP sends the invite to 2XXXX@cusp.test.com to CUSP.

6. CUSP has the sip alias cusp.test.com so it searches the route table for a pattern with 2

7. you have one - key 2 target-destination cucm.test.com enterprise - so that CUSP server group is chosen

8. This SG load balances

element ip-address 172.25.7.11 5060 udp q-value 1 weight 25

element ip-address 172.25.27.10 5060 udp q-value 1 weight 25

and a Sub is chosen.

9. You have a SIP trunk from the CUCM to the CUSPs (or 2 separate SIP trunks) so the SIP signaling is allowed. No MTP on the trunk. The CUCM SIP agent accepts the INVITE and the VXML leg is torn down and the leg extended to the SCCP phone.

Regards,

Geoff

sandeep pokhriyal · ‎05-16-2012

it is still not working, the call doesnt land on the phone. we jus hear the ringback from the gateway.

the call flow that you have mentioned is exactly what it should follow according to the config. in the CVP logs i can see that it is trying to send the call to CUSP.

%CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} Using Outbound Proxy for sip:2103@cusp.test.com

but the call never comes to CUSP.

delow is the debug from CVP for one call. sorry, i didn't find a way to attach this file.

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

Line 2029: 107: 172.25.10.25: May 17 2012 02:34:26.859 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DATAI.0} Found an incoming CISCO-GUID hdr : 0360795163-2665943521-2245263381-1673066776, Generated a new CVP-ID hdr : 15814C1B9EE711E185D4001563B8F918

Line 2030: 108: 172.25.10.25: May 17 2012 02:34:26.859 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DATAI.0} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Display Name [] Is Using Survivability [false] CallServer build CVP_8_5_1_0_0_0_312

Line 2031: 109: 172.25.10.25: May 17 2012 02:34:26.859 +0300: %CVP_8_5_SIP-7-PARAM: {Thrd=DATAI.0} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: ReqURI (DN) sip:8858@cvp.test.com FromURI sip:44251187@172.25.14.100 Video:false m_needs_postcallsurvey:false

Line 2032: 110: 172.25.10.25: May 17 2012 02:34:26.875 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DATAI.0} NEW CALL with guid=15814C1B9EE711E185D4001563B8F918 legid=1582846B-9EE711E1-BAD9C253-189A107E dn=8858 ani=44251187 uui= calldate=Thu May 17 02:34:26 GMT+03:00 2012 video=false cachecallcontext = false is_postcallsurvey = false RouterCallKey = null RouterCallKeyDay = null RouterCallKeySequenceNumber = null

Line 2033: 111: 172.25.10.25: May 17 2012 02:34:26.875 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DATAI.0} Sending msg:>>HEADERS: (JMSType)=MsgBus:NEW_CALL (JMSDestination)=Topic(CVP.SIP.CC.REQ) (JMSTimestamp)=1337211266875 >>BODY: replyto=true callguid=15814C1B9EE711E185D4001563B8F918 ani=44251187 dnis=8858 timezone=GMT+03:00 version=CVP_8_5 pstntrkgrpsrcip=172.25.14.100 calldate=Thu May 17 02:34:26 GMT+03:00 2012 calltypeid=4 localOffset=180 calllegid=1582846B-9EE711E1-BAD9C253-189A107E >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2034: 107: 172.25.10.25: May 17 2012 02:34:26.890 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-25-ICM-1} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = -1 [null] - Processing ,, [MsgBus:NEW_CALL], ssId=SYS_SIP5, mediaType=, location=, locationpkid=, locationsiteid=, srcaddr=172.25.14.100, pstntrunkgroupid=172.25.14.100 , pstntrunkgroupchannelnum=2147483647, sipheader=, rckey=, rcday=, rcseq=, uui=, calltypeid=4, CallContext:, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = null, DNIS = -1, ANI = -1

Line 2035: 108: 172.25.10.25: May 17 2012 02:34:26.890 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-25-ICM-1} CALLGUID = 15814C1B9EE711E185D4001563B8F918 - New call

Line 2036: 109: 172.25.10.25: May 17 2012 02:34:26.890 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-25-ICM-1} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Publishing ,, [ICM_NEW_CALL], dialogueId=1, sendSeqNo=1, trunkGroupId=100, trunkNumber=0, serviceId=1, dialedNumber=8858, uui=, callguid=15814C1B9EE711E185D4001563B8F918, rckey=, rcday=, rcseq=, location=, locationpkid=, pstntrunkgroupid=172.25.14.100 , pstntrunkgroupchannelnum=2147483647, sipheader=, CallContext:, user.media.id: 15814C1B9EE711E185D4001563B8F918, user.cvp_server_info: 172.25.10.25,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2037: 110: 172.25.10.25: May 17 2012 02:34:27.812 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-26-ICM-2} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Processing ,, [ICM_TEMPORARY_CONNECT], dialogueId=1, sendSeqNo=1, label=8001234567, correlationId=14136, callguid=15814C1B9EE711E185D4001563B8F918, rckey=3516, rcday=150251, rcseq=0, CallContext:, user.microapp.input_type: D, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2038: 111: 172.25.10.25: May 17 2012 02:34:27.812 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-26-ICM-2} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Publishing ,, [MsgBus:CONNECT], ssId=SYS_SIP5, labelType=, label=8001234567, correlationId=14136, command=null, rckey=3516, rcday=150251, rcseq=0, CallContext:, user.microapp.input_type: D, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2039: 112: 172.25.10.25: May 17 2012 02:34:27.828 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-27-SIP-22} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND] Handling CONNECT :>>HEADERS: (JMSType)=MsgBus:CONNECT (JMSDestination)=Topic(CVP.ICM.CC.RESP.SYS_SIP5) (JMSTimestamp)=1337211267812 (ServerID)=testcvp1.SYS_ICM5:ICM:ICM5:testcvp1.MsgBus005 >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 RouterCallKey=3516 floatingvars=>>HEADERS: (JMSType)=MsgBus:FLOATINGVARS >>BODY: callvars=[, , , , , , , , , ] user.microapp.app_media_lib=app_test user.microapp.media_server=172.25.10.158 user.media.id=15814C1B9EE711E185D4001563B8F918 user.microapp.FromExtVXML=[null, null, null, null] user.microapp.ToExtVXML=[null, null, null, null, null] user.microapp.locale=en-us user.microapp.input_type=D >>STATE: isTabular=false isWriteable=true cursor=-1 dnis=8858 correlationid=14136 timezone=GMT+03:00 RouterCallKeySequenceNumber=0 version=CVP_8_5 RouterCallKeyDay=15...

Line 2040: 113: 172.25.10.25: May 17 2012 02:34:27.828 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-27-SIP-22} 15814C1B9EE711E185D4001563B8F918 is correlated with other guid 15814C1B9EE711E185D4001563B8F918

Line 2043: 116: 172.25.10.25: May 17 2012 02:34:27.843 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-27-SIP-22} Found an incoming CISCO-GUID hdr : 0360795163-2665943521-2245263381-1673066776, Found an existing CVP-ID hdr: 15814C1B9EE711E185D4001563B8F918

Line 2044: 117: 172.25.10.25: May 17 2012 02:34:27.843 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-27-SIP-22} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: INVITE TO <800123456714136> FROM 44251187 <44251187> EXPIRES[60] 100REL[Unsupported]

Line 2045: 118: 172.25.10.25: May 17 2012 02:34:27.875 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Invitation proceeding 100

Line 2046: 119: 172.25.10.25: May 17 2012 02:34:28.015 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Invitation accepted - ack'ing

Line 2047: 120: 172.25.10.25: May 17 2012 02:34:28.015 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-28-SIP-23} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: with event type INV_ACCEPTED

Line 2048: 121: 172.25.10.25: May 17 2012 02:34:28.015 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-28-SIP-23} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Send the 200 OK back to caller with SDP of this dialog.

Line 2049: 122: 172.25.10.25: May 17 2012 02:34:28.015 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-28-SIP-23} Sending BUS MSG:>>HEADERS: (JMSType)=MsgBus:CALL_STATE_EVENT (JMSDestination)=Topic(CVP.SIP.CC.EVENT) (JMSTimestamp)=1337211268015 >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 RouterCallKey=3516 RouterCallKeySent=false causecode=0 timezone=GMT+03:00 RouterCallKeySequenceNumber=0 version=CVP_8_5 labeltype=-1 RouterCallKeyDay=150251 calldate=Thu May 17 02:34:28 GMT+03:00 2012 label=800123456714136 localOffset=180 eventid=4 calllegid=15814C1B9EE711E185D4001563B8F918-13372112678281 >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2050: 112: 172.25.10.25: May 17 2012 02:34:28.031 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-29-ICM-3} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = -1 [null] - Processing ,, [MsgBus:CALL_STATE_EVENT], ssId=SYS_SIP5, eventId=ANSWER, causeCode=NONE,, LEGID = null, DNIS = 8858, ANI = 44251187

Line 2051: 113: 172.25.10.25: May 17 2012 02:34:28.031 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-29-ICM-3} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Publishing ,, [ICM_EVENT_REPORT], dialogueId=1, sendSeqNo=2, eventId=ANSWER, causeCode=NONE,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2052: 63: 172.25.10.25: May 17 2012 02:34:28.093 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VBServlet:service: HTTP Request from 172.25.12.100: { CALL_ID=15814C1B9EE711E185D4001563B8F918, CLIENT_TYPE=IOS, MSG_TYPE=PING, RECOVERY_VXML=flash:recovery.vxml, CALL_DNIS=800123456714136, CALL_LEGID=15814C1B9EE711E185D4001563B8F918-13372112678281@172.25.10.25, CALL_UUI=, VERSION=CVP_8_5_1_0_0_0_312, ERROR_CODE=NONE(0), CALL_ANI=sip:44251187@172.25.10.25:5060 }

Line 2054: 65: 172.25.10.25: May 17 2012 02:34:28.093 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VXMLManager:generateVXML: CALLGUID=15814C1B9EE711E185D4001563B8F918 Generated VXML from template 'SubmitBack.template' for client: 172.25.12.100 clientType: IOS

Line 2055: 66: 172.25.10.25: May 17 2012 02:34:28.296 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VBServlet:service: HTTP Request from 172.25.12.100: { CALL_ID=15814C1B9EE711E185D4001563B8F918, CLIENT_TYPE=IOS, MSG_TYPE=CALL_NEW, RECOVERY_VXML=flash:recovery.vxml, CALL_DNIS=800123456714136, CALL_UUI=, ERROR_CODE=NONE(0), CALL_ANI=sip:44251187@172.25.10.25:5060 }

Line 2056: 67: 172.25.10.25: May 17 2012 02:34:28.312 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} CallSession:addCall: CALLGUID=15814C1B9EE711E185D4001563B8F918 Added IVRCall ( DNIS=800123456714136 client: 172.25.12.100) to CallSession at slot: 0

Line 2057: 68: 172.25.10.25: May 17 2012 02:34:28.312 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} CallMsgPublisher:sendNewCall: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 Publishing NewCall message: >>HEADERS: (JMSType)=MsgBus:NEW_CALL >>BODY: replyto=true uui= callguid=15814C1B9EE711E185D4001563B8F918 ani=sip:44251187@172.25.10.25:5060 dnis=800123456714136 timezone=GMT+03:00 mediatype=A version=CVP_8_5 calldate=Thu May 17 02:34:28 GMT+03:00 2012 location= calltypeid=5 localOffset=180 calllegid= >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2058: 114: 172.25.10.25: May 17 2012 02:34:28.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-30-ICM-4} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = -1 [null] - Processing ,, [MsgBus:NEW_CALL], ssId=SYS_IVR5, mediaType=A, location=, locationpkid=, locationsiteid=, srcaddr=, pstntrunkgroupid=, pstntrunkgroupchannelnum=2147483647, sipheader=, rckey=, rcday=, rcseq=, uui=, calltypeid=5, CallContext:, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = null, DNIS = 8858, ANI = 44251187

Line 2059: 115: 172.25.10.25: May 17 2012 02:34:28.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-30-ICM-4} CALLGUID = 15814C1B9EE711E185D4001563B8F918 - Correlation ID routed call

Line 2060: 116: 172.25.10.25: May 17 2012 02:34:28.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-30-ICM-4} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Publishing ,, [ICM_REQUEST_INSTRUCTION], dialogueId=2, sendSeqNo=1, trunkGroupId=200, trunkNumber=0, serviceId=2, uui=, correlationId=14136, location=, locationpkid=, pstntrunkgroupid=, pstntrunkgroupchannelnum=2147483647, sipheader=,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2061: 117: 172.25.10.25: May 17 2012 02:34:28.328 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-31-ICM-5} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Processing ,, [ICM_RUN_SCRIPT_REQ], dialogueId=2, sendSeqNo=1, invokedId=1, scriptId=PM,Holdline, scriptConfiguration=N, ced=, callguid=15814C1B9EE711E185D4001563B8F918, rckey=3516, rcday=150251, rcseq=1, CallContext:, user.microapp.input_type: D, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2062: 118: 172.25.10.25: May 17 2012 02:34:28.328 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-31-ICM-5} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Publishing ,, [MsgBus:RUN_SCRIPT_REQ], ssId=SYS_IVR5, requestId=1, scriptId=PM,Holdline, scriptConfig=N, ced=, rckey=3516, rcday=150251, rcseq=1, switch_leg_ani=44251187, switch_leg_dnis=8858, CallContext:, user.microapp.input_type: D, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2063: 69: 172.25.10.25: May 17 2012 02:34:28.328 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=SubscriptionMgr} CallMsgListener:onMsg: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 Received Call message : >>HEADERS: (JMSType)=MsgBus:RUN_SCRIPT_REQ (JMSDestination)=Topic(CVP.ICM.CC.REQ.SYS_IVR5) (JMSTimestamp)=1337211268328 (ServerID)=testcvp1.SYS_ICM5:ICM:ICM5:testcvp1.MsgBus005 >>BODY: ced= scriptconfiguration=N callguid=15814C1B9EE711E185D4001563B8F918 RouterCallKey=3516 ani=44251187 floatingvars=>>HEADERS: (JMSType)=MsgBus:FLOATINGVARS >>BODY: callvars=[, , , , , , , , , ] user.microapp.app_media_lib=app_test user.microapp.media_server=172.25.10.158 user.media.id=15814C1B9EE711E185D4001563B8F918 user.microapp.FromExtVXML=[null, null, null, null] user.microapp.ToExtVXML=[null, null, null, null, null] user.microapp.locale=en-us user.microapp.input_type=D >>STATE: isTabular=false isWriteable=true cursor=-1 dnis=800123456714136 timezone=GMT+03:00 RouterCallKeySequenceNumber=1 version=CVP_8_5...

Line 2064: 70: 172.25.10.25: May 17 2012 02:34:28.468 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VXMLManager:generateVXML: CALLGUID=15814C1B9EE711E185D4001563B8F918 Generated VXML from template 'PlayMediaIOS.template' for client: 172.25.12.100 clientType: IOS

Line 2065: 123: 172.25.10.25: May 17 2012 02:34:28.468 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: DsSipInviteDialog ack'ed

Line 2066: 124: 172.25.10.25: May 17 2012 02:34:28.468 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-33-SIP-24} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: with event type INV_ACKED

Line 2067: 125: 172.25.10.25: May 17 2012 02:34:28.468 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-33-SIP-24} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Acked the invite.

Line 2069: 71: 172.25.10.25: May 17 2012 02:34:37.296 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VBServlet:service: HTTP Request from 172.25.12.100: { CALL_ID=15814C1B9EE711E185D4001563B8F918, MSG_TYPE=CALL_RESULT, CALL_SEQ_NUM=1, ERROR_CODE=NONE(0) }

Line 2070: 72: 172.25.10.25: May 17 2012 02:34:37.296 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} CallMsgPublisher:sendRunScriptResult: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 Publishing RunScriptResult message: >>HEADERS: (JMSType)=MsgBus:SCRIPT_RESULT >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 floatingvars=>>HEADERS: (JMSType)=MsgBus:FLOATINGVARS >>BODY: user.microapp.error_code=0 >>STATE: isTabular=false isWriteable=true cursor=-1 dnis=800123456714136 resultcode=true timezone=GMT+03:00 version=CVP_8_5 calldate=Thu May 17 02:34:37 GMT+03:00 2012 localOffset=180 calllegid= requestid=1 >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2071: 119: 172.25.10.25: May 17 2012 02:34:37.296 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-35-ICM-6} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Processing ,, [MsgBus:SCRIPT_RESULT], ssId=SYS_IVR5, requestId=1, resultCode=true, ced=, CallContext:, user.microapp.error_code: 0,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2072: 120: 172.25.10.25: May 17 2012 02:34:37.296 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-35-ICM-6} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Publishing ,, [ICM_RUN_SCRIPT_RESULT], dialogueId=2, sendSeqNo=2, invokedId=1, resultCode=true, ced=, newTransaction=false, CallContext:, user.microapp.error_code: 0,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2073: 121: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-36-ICM-7} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Processing ,, [ICM_RELEASE], dialogueId=2, sendSeqNo=2, cause=NORMAL_CALL_CLEARING,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2074: 122: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-36-ICM-7} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Publishing ,, [MsgBus:DISCONNECT], ssId=SYS_IVR5, cause=NORMAL_CALL_CLEARING,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2075: 73: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=SubscriptionMgr} CallMsgListener:onMsg: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 Received Call message : >>HEADERS: (JMSType)=MsgBus:DISCONNECT (JMSDestination)=Topic(CVP.ICM.CC.EVENT.SYS_IVR5) (JMSTimestamp)=1337211277312 (ServerID)=testcvp1.SYS_ICM5:ICM:ICM5:testcvp1.MsgBus005 >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 dnis=800123456714136 causecode=0 timezone=GMT+03:00 version=CVP_8_5 localOffset=180 calllegid= >>STATE: isTabular=false isWriteable=false cursor=-1

Line 2076: 74: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=pool-1-thread-37-IVR-2} DisconnectTask:generateResponse: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 DisconnectResponse generated - CallReleaseCode: 0

Line 2077: 75: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VXMLManager:generateVXML: CALLGUID=15814C1B9EE711E185D4001563B8F918 Generated VXML from template 'Disconnect.template' for client: 172.25.12.100 clientType: IOS

Line 2078: 123: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-38-ICM-8} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Processing ,, [ICM_CONNECT], dialogueId=1, sendSeqNo=2, labelType=NORMAL, label=2103, transferHint=1, CallContext:, user.microapp.input_type: D, user.microapp.error_code: 0, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2079: 124: 172.25.10.25: May 17 2012 02:34:37.312 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-38-ICM-8} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Publishing ,, [MsgBus:CONNECT], ssId=SYS_SIP5, labelType=NORMAL, label=2103, correlationId=null, command=null, rckey=3516, rcday=150251, rcseq=0, CallContext:, user.microapp.input_type: D, user.microapp.error_code: 0, user.microapp.locale: en-us, user.microapp.app_media_lib: app_test, user.microapp.media_server: 172.25.10.158, user.media.id: 15814C1B9EE711E185D4001563B8F918,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2080: 126: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND] Handling CONNECT :>>HEADERS: (JMSType)=MsgBus:CONNECT (JMSDestination)=Topic(CVP.ICM.CC.RESP.SYS_SIP5) (JMSTimestamp)=1337211277312 (ServerID)=testcvp1.SYS_ICM5:ICM:ICM5:testcvp1.MsgBus005 >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 RouterCallKey=3516 floatingvars=>>HEADERS: (JMSType)=MsgBus:FLOATINGVARS >>BODY: callvars=[, , , , , , , , , ] user.microapp.app_media_lib=app_test user.microapp.media_server=172.25.10.158 user.media.id=15814C1B9EE711E185D4001563B8F918 user.microapp.FromExtVXML=[null, null, null, null] user.microapp.error_code=0 user.microapp.ToExtVXML=[null, null, null, null, null] user.microapp.locale=en-us user.microapp.input_type=D >>STATE: isTabular=false isWriteable=true cursor=-1 dnis=8858 timezone=GMT+03:00 RouterCallKeySequenceNumber=0 version=CVP_8_5 RouterCallKe...

Line 2081: 127: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} 15814C1B9EE711E185D4001563B8F918 is correlated with other guid 15814C1B9EE711E185D4001563B8F918

Line 2083: 129: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: with event type TERMINATED

Line 2086: 132: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-40-SIP-27} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND] DURATION (msecs) = 9500 - HANGUP with Call History

Line 2087: 133: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} Found an incoming CISCO-GUID hdr : 0360795163-2665943521-2245263381-1673066776, Found an existing CVP-ID hdr: 15814C1B9EE711E185D4001563B8F918

Line 2088: 134: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [OUTBOUND]: INVITE TO <91919191> FROM 44251187 <44251187> EXPIRES[10] 100REL[Unsupported]

Line 2089: 135: 172.25.10.25: May 17 2012 02:34:37.328 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-40-SIP-27} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112678281 - [OUTBOUND]: Terminated with Q.850;cause=16

Line 2090: 136: 172.25.10.25: May 17 2012 02:34:37.343 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Invitation accepted - ack'ing

Line 2091: 137: 172.25.10.25: May 17 2012 02:34:37.343 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-42-SIP-29} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: with event type INV_ACCEPTED

Line 2092: 138: 172.25.10.25: May 17 2012 02:34:37.343 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-42-SIP-29} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: reinviting caller.

Line 2093: 139: 172.25.10.25: May 17 2012 02:34:37.343 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-42-SIP-29} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Sending reinvite request. CSEQ:1

Line 2094: 140: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Reinvitation proceeding TRYING.

Line 2095: 141: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Reinvitation accepted. CSeq: 1 INVITE

Line 2096: 142: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-43-SIP-30} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: with event type REINV_ACCEPTED

Line 2097: 143: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-43-SIP-30} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Acked the invite.

Line 2098: 144: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-43-SIP-30} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Sent reinvite ack.

Line 2099: 145: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-43-SIP-30} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Notifying that ringtone is established 200 OK with caller.

Line 2100: 146: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: Called ring leg: CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: status code = 200: elapsed msecs = 31

Line 2101: 147: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} Found an incoming CISCO-GUID hdr : 0360795163-2665943521-2245263381-1673066776, Found an existing CVP-ID hdr: 15814C1B9EE711E185D4001563B8F918

Line 2102: 148: 172.25.10.25: May 17 2012 02:34:37.359 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-39-SIP-26} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773593 - [OUTBOUND]: INVITE TO <>2103@cusp.test.com;transport=tcp> FROM 44251187 <44251187> EXPIRES[60] 100REL[Unsupported]

Line 2103: 76: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VBServlet:service: HTTP Request from 172.25.12.100: { CALL_ID=15814C1B9EE711E185D4001563B8F918, MSG_TYPE=CALL_RESULT, CALL_SEQ_NUM=2, ERROR_CODE=HANGUP(1) }

Line 2104: 77: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} VXMLManager:generateAckVXML: CALLGUID=15814C1B9EE711E185D4001563B8F918 Generated VXML from template 'Ack.template' for client: 172.25.12.100 clientType: IOS

Line 2105: 78: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} CallSession:removeCallByDNIS: CALLGUID=15814C1B9EE711E185D4001563B8F918 Removed IVRCall from CallSession ( DNIS=800123456714136 eventId: 6 causeCode: 1). Removed from slot: 0

Line 2106: 79: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} CallMsgPublisher:sendCallStateEvent: CALLGUID=15814C1B9EE711E185D4001563B8F918 DNIS=800123456714136 Publishing CallStateEvent message: >>HEADERS: (JMSType)=MsgBus:CALL_STATE_EVENT >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 dnis=800123456714136 RouterCallKeySent=false causecode=1 timezone=GMT+03:00 version=CVP_8_5 calldate=Thu May 17 02:34:39 GMT+03:00 2012 localOffset=180 calllegid= eventid=6 >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2107: 80: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_IVR-7-CALL: {Thrd=http-8000-1} IVRSubSystem:checkInLicense: Released license for call: 15814C1B9EE711E185D4001563B8F918 - PortCheckinStatus=PORT_CHECKIN_OK_PORT_STILL_IN_USE

Line 2108: 125: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-44-ICM-9} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Processing ,, [MsgBus:CALL_STATE_EVENT], ssId=SYS_IVR5, eventId=DISCONNECT, causeCode=NORMAL_COMPLETION,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2109: 126: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-44-ICM-9} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Publishing ,, [ICM_EVENT_REPORT], dialogueId=2, sendSeqNo=3, eventId=DISCONNECT, causeCode=NORMAL_COMPLETION,, LEGID = , DNIS = 800123456714136, ANI = sip:44251187@172.25.10.25:5060

Line 2110: 127: 172.25.10.25: May 17 2012 02:34:39.171 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-44-ICM-9} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Deleted dialogue. Duration: 0 hrs, 0 mins, 12 secs, 281 msecs

Line 2128: 151: 172.25.10.25: May 17 2012 02:34:47.171 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND] DURATION (msecs) = 20328 - DIALOG TERMINATED. Reason: Q.850;cause=101

Line 2130: 152: 172.25.10.25: May 17 2012 02:34:47.171 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.3} Sending BUS MSG:>>HEADERS: (JMSType)=MsgBus:CALL_STATE_EVENT (JMSDestination)=Topic(CVP.SIP.CC.EVENT) (JMSTimestamp)=1337211287171 >>BODY: callguid=15814C1B9EE711E185D4001563B8F918 RouterCallKey=3516 RouterCallKeySent=true causecode=1 timezone=GMT+03:00 RouterCallKeySequenceNumber=0 version=CVP_8_5 labeltype=1 RouterCallKeyDay=150251 calldate=Thu May 17 02:34:47 GMT+03:00 2012 label=2103 localOffset=180 eventid=6 calllegid=1582846B-9EE711E1-BAD9C253-189A107E >>STATE: isTabular=false isWriteable=true cursor=-1

Line 2131: 153: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-45-SIP-31} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Updated by : CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 1582846B-9EE711E1-BAD9C253-189A107E - [INBOUND]: with event type TERMINATED

Line 2132: 154: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-46-SIP-32} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT] DURATION (msecs) = 9859 - HANGUP with Call History

Line 2133: 128: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-47-ICM-10} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Processing ,, [MsgBus:CALL_STATE_EVENT], ssId=SYS_SIP5, eventId=DISCONNECT, causeCode=NORMAL_COMPLETION,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2134: 129: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-47-ICM-10} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Publishing ,, [ICM_EVENT_REPORT], dialogueId=1, sendSeqNo=3, eventId=DISCONNECT, causeCode=NORMAL_COMPLETION,, LEGID = 1582846B-9EE711E1-BAD9C253-189A107E, DNIS = 8858, ANI = 44251187

Line 2135: 130: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-47-ICM-10} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Deleted dialogue. Duration: 0 hrs, 0 mins, 20 secs, 297 msecs

Line 2136: 131: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=pool-1-thread-47-ICM-10} CALLGUID = 15814C1B9EE711E185D4001563B8F918 - Deleted call. Duration: 0 hrs, 0 mins, 20 secs, 297 msecs

Line 2137: 155: 172.25.10.25: May 17 2012 02:34:47.187 +0300: %CVP_8_5_SIP-7-CALL: {Thrd=pool-1-thread-46-SIP-32} CALLGUID = 15814C1B9EE711E185D4001563B8F918 LEGID = 15814C1B9EE711E185D4001563B8F918-13372112773282 - [RING-OUT]: Terminated with Q.850;cause=16

Line 2139: 132: 172.25.10.25: May 17 2012 02:34:53.453 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=ICM Garbage Collector} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 2 [IVR_LEG] - Purged dialogue.

Line 2141: 133: 172.25.10.25: May 17 2012 02:35:03.453 +0300: %CVP_8_5_ICM-7-CALL: {Thrd=ICM Garbage Collector} CALLGUID = 15814C1B9EE711E185D4001563B8F918, DLGID = 1 [SIP_LEG] - Purged dialogue.

geoff · ‎05-16-2012

That trace is messed up. It has duplicated lines and line numbers. Next time, zip it and post it properly.

Now I can see "[OUTBOUND]: INVITE TO <>2103@cusp.test.com" in what you posted but I cannot see "Using Outbound Proxy" - but you should not see that. (EDIT: yes you should)

If you turn on debug on the dynamicsoft library - What you should see is

Resolver: Use the local SRV records

Resolver: SRV records found for cusp.test.com

Resolver: SRV record: /172.11.25.95

Resolver: SRV record: /172.21.25.95

(Sorry - I see you have two CUSP).

Did you restart the CVP Call Servers?

Regards,

Geoff