Troubleshoot CVP Call Flow Analysis with VVB Server

Available Languages

Download Options

  • PDF     (52.6 KB)
    View with Adobe Reader on a variety of devices
  • ePub     (76.8 KB)
    View in various apps on iPhone, iPad, Android, Sony Reader, or Windows Phone
  • Mobi (Kindle)     (78.8 KB)
    View on Kindle device or Kindle app on multiple devices
Updated:October 11, 2018
Document ID:213796

Bias-Free Language

The documentation set for this product strives to use bias-free language. For the purposes of this documentation set, bias-free is defined as language that does not imply discrimination based on age, disability, gender, racial identity, ethnic identity, sexual orientation, socioeconomic status, and intersectionality. Exceptions may be present in the documentation due to language that is hardcoded in the user interfaces of the product software, language used based on RFP documentation, or language that is used by a referenced third-party product. Learn more about how Cisco is using Inclusive Language.

    Introduction

    This document describes trace analysis of Cisco Customer Voice Portal (CVP) with Cisco Virtual Voice Browser (VVB) as a VXML browser.

    Prerequisites

    Requirements

    Cisco recommends that you have knowledge of these topics:

    • CVP with Cisco Unified Contact Center Enterprise (UCCE)
    • Cisco VVB integrated with CVP
    • CVP Comprehensive Call Flow

    Components Used

    The information in this document is based on these software and hardware versions:

    • CVP 11.5
    • UCCE 11.5
    • VVB 11.5

    The information in this document was created from the devices in a specific lab environment. All of the devices used in this document started with a cleared (default) configuration. If your network is live, ensure that you understand the potential impact of any command.

    Background Information

    With the introduction of Cisco VVB as a VXML browser, here is a sample trace analysis in order to track signals between CVP, UCCE and VVB.

    In this anaysis, call is routed to CVP and after Interactive Voice Respose (IVR) treatment, CVP routes the call to an available agent.

    CVP and VVB log snippets can be identified by MIVR and CVP keywords in the traces.

    CVP with VVB Call Flow Trace Analysis

    New Call-Switch Leg

    1937: 192.0.2.11: May 10 2017 11:39:37.880 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.1} NEW CALL with guid=0F46E580000100000000007A0851430A legid=56A1AA52-345511E7-ABF1C227-5C85A942 dn=9876 ani=12345 uui=null calldate=Wed May 10 11:39:37 EST 2017 video=false cachecallcontext = false is_postcallsurvey = false RouterCallKey = null RouterCallKeyDay = null RouterCallKeySequenceNumber = null


     Number of active sessions

    44241: 192.0.2.11: May 10 2017 11:39:37.880 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Core-router-group-0} --NEW SESSION CREATED--CALLGUID=0F46E580000100000000007A0851430A NUMBER OF ACTIVE SESSIONS= 1


    New call on SIP SS

    4281: 192.0.2.11: May 10 2017 11:39:37.880 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Processing from SIP_SS - [NEW_CALL] DialogID=0 SendSeqNo=0 DNIS=9876 ANI=12345 CED= rckey=0 rcday=0 rcseq=0 uui= callguid=0F46E580000100000000007A0851430A trunkGroupId=0 trunkNumber=0 serviceId=0 calledNumber= location= locationpkid= pstntrunkgroupid= sipheader= ECC variables= ECC arrays= Peripheral Variables=


    New CALL- publishing to UCCE

    4282: 192.0.2.11: May 10 2017 11:39:37.880 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Publishing to UCCE - [NEW_CALL] DialogID=46 SendSeqNo=1 DNIS=9876 ANI=12345 CED= rckey=0 rcday=0 rcseq=0 uui= callguid=0F46E580000100000000007A0851430A trunkGroupId=100 trunkNumber=0 serviceId=1 calledNumber= location= locationpkid= pstntrunkgroupid= sipheader= ECC variables=user.media.id: 0F46E580000100000000007A0851430A, ECC arrays= Peripheral Variables=


    Temporary connect -processing from UCCE

    4283: 192.0.2.11: May 10 2017 11:39:37.896 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Processing from UCCE - ,, [ICM_TEMPORARY_CONNECT], rckey=201, rcday=152070, DNIS=9876, ANI=12345


    Temporary connect -publishing to sip SS

    4284: 192.0.2.11: May 10 2017 11:39:37.896 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Publishing to SIP_SS - [CONNECT] DialogID=46 SendSeqNo=0 Label=8888111111 TxrHint=false rckey=152070 rcday=152070 rcseq=0 location= locationpkid= sipheader= uui= whisperAnnounce=null callguid=0F46E580000100000000007A0851430A ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    Route matching for VRU label

    1941: 192.0.2.11: May 10 2017 11:39:37.896 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-120-SIP-19461} Static route matched 888811111112 to StaticRoute: patterns like 88881111> will route to 192.0.2.12
1942: 192.0.2.11: May 10 2017 11:39:37.896 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-120-SIP-19461} Using Local Static Route for sip:888811111112@192.0.2.12


    Invite to VXML GW-VRU Leg

    1947: 192.0.2.11: May 10 2017 11:39:37.896 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-120-SIP-19461} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438037789633 - [OUTBOUND]: INVITE TO <sip:888811111112@192.0.2.12;transport=tcp> FROM "PSTN PHONE--CVP_11_5_1_0_1_0_344" <sip:12345@192.0.2.11:5060> EXPIRES[60] 100REL[Unsupported]


    New call to VVB VRU Leg

    195208: May 10 11:21:27.305 AEST %MIVR-JASMIN-7-UNK:[CALLID=0F46E580000100000000007A0851430A-149438037789633] NEW_CALL, GUCID=0F46E580000100000000007A0851430A; GUID=0256304512-0000065536-0000000122-0139543306; CallId=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11; URI=sip:888811111112@192.0.2.12;transport=tcp

### CALL_NEW () from VVB to VXML server
189323: May 02 16:10:49.778 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370661959432] Browser.makeRequest(): got encoded uri=http://192.0.2.11:7000/CVP/Server?MSG_TYPE=CALL_NEW&CALL_DNIS=8888111111&CALL_ANI=sip%3A60006099%40..

    .


    Invitation accepted-VRU Leg

    1951: 192.0.2.11: May 10 2017 11:39:38.067 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.5} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438037789633 - [OUTBOUND]: Invitation accepted - ack'ing

    Answer

    1958: 192.0.2.11: May 10 2017 11:39:38.083 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-121-SIP-19462} Sending BUS MSG for Event Reportnew CSS:[EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=ANSWER CauseCode=CAUSE_NONE

    Answer -processing from SIP SS

    4285: 192.0.2.11: May 10 2017 11:39:38.083 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Processing from SIP_SS - [EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=ANSWER CauseCode=CAUSE_NONE


    Answer -publishing to UCCE

    4286: 192.0.2.11: May 10 2017 11:39:38.083 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Publishing to UCCE - [EVENT_REPORT] DialogID=46 SendSeqNo=2 EventID=ANSWER CauseCode=NONE


    New Call [IVR] - processing from VXMLServer - New call with VRU + Correlation ID

    44242: 192.0.2.11: May 10 2017 11:39:38.364 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A [IVR] - processing from VXMLServer - [NEW_CALL] ClientNewCallEvent,sessionId=0F46E580000100000000007A0851430A ani=sip:12345@192.0.2.11:5060 dnis=888811111112 uui= callId=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 seqNo=1 invokationId=0 message=null ccbServletUrl= ccbServletReqTimeout=0


    New Call-Publishing to ICM_SS

    44243: 192.0.2.11: May 10 2017 11:39:38.364 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 [IVR] [Publishing to ICM_SS] [NEW_CALL] DialogID=0 SendSeqNo=0 DNIS=888811111112 ANI=12345 CED= rckey=0 rcday=0 rcseq=0 uui= callguid=0F46E580000100000000007A0851430A trunkGroupId=0 trunkNumber=0 serviceId=0 calledNumber= location= locationpkid= pstntrunkgroupid= sipheader= ECC variables= ECC arrays= Peripheral Variables=


    Correlation ID Routed Call

    4287: 192.0.2.11: May 10 2017 11:39:38.364 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID = 0F46E580000100000000007A0851430A - Correlation ID routed call

    [IVR-VRU_LEG_DIALOGUE]-processing from IVR_SS-[NEW_call]-VRU Leg Dialogue ID

    4288: 192.0.2.11: May 10 2017 11:39:38.364 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Processing from IVR_SS - [NEW_CALL] DialogID=0 SendSeqNo=0 DNIS=888811111112 ANI=12345 CED= rckey=0 rcday=0 rcseq=0 uui= callguid=0F46E580000100000000007A0851430A trunkGroupId=0 trunkNumber=0 serviceId=0 calledNumber= location= locationpkid= pstntrunkgroupid= sipheader= ECC variables= ECC arrays= Peripheral Variables=


    [Request_Instruction]-publishing to UCCE

    4289: 192.0.2.11: May 10 2017 11:39:38.364 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Publishing to UCCE - [REQUEST_INSTRUCTION] DialogID=47 SendSeqNo=1 ANI=12345 DNIS=888811111112 correlationId=12 trunkGroupId=200 trunkNumber=0 serviceId=2 uui= calledNumber= location= locationpkid= pstntrunkgroupid= pstntrunkgroupchannelnum=0 sipheader=

    [RUN_SCRIPT_REQ]-[IVR_VRU_LEG_DIALOGUE]-processing from UCCE

    4294: 192.0.2.11: May 10 2017 11:39:47.521 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Processing from UCCE - [RUN_SCRIPT_REQ] DialogID=47 SendSeqNo=2 InvokeID=2 scriptId=GS,Server,V scriptConfiguration= ANI=12345 ced=complete callguid=0F46E580000100000000007A0851430A rckey=201 rcday=152070 rcseq=1 uui= featureType=0 featureParam1= _featureParam2= ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    [RUN_SCRIPT_REQ]-[IVR_VRU_LEG_DIALOGUE] - Publishing to IVR_SS

    4295: 192.0.2.11: May 10 2017 11:39:47.536 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Publishing to IVR_SS - [RUN_SCRIPT_REQ] DialogID=47 SendSeqNo=2 InvokeID=2 scriptId=GS,Server,V scriptConfiguration= ANI=12345 ced=complete callguid=0F46E580000100000000007A0851430A rckey=201 rcday=152070 rcseq=1 uui= featureType=0 featureParam1= _featureParam2= ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    [RUN_SCRIPT_REQ]-[IVR] processing from ICM_SS

    44250: 192.0.2.11: May 10 2017 11:39:47.536 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 [IVR] [processing from ICM_SS] [RUN_SCRIPT_REQ] DialogID=47 SendSeqNo=2 InvokeID=2 scriptId=GS,Server,V scriptConfiguration= ANI=12345 ced=complete callguid=0F46E580000100000000007A0851430A rckey=201 rcday=152070 rcseq=1 uui= featureType=0 featureParam1= _featureParam2= ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    [RUN_SCRIPT_REQ]-[IVR]- Publishing to VXML server

    44251: 192.0.2.11: May 10 2017 11:39:47.536 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 [IVR] - Publishing to VXMLServer - [RUN_SCRIPT_REQ] DialogID=47 SendSeqNo=2 InvokeID=2 scriptId=GS,Server,V scriptConfiguration= ANI=12345 ced=complete callguid=0F46E580000100000000007A0851430A rckey=201 rcday=152070 rcseq=1 uui= featureType=0 featureParam1= _featureParam2= ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    VVB AUDIUM_Root(AUDIUM_VXML)

    178107: May 02 16:07:47.410 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] application (Root document): /CVP/Server?audium_root=true&calling_into=HelloWorld


    VVB ROOT Document

    178424: May 02 16:07:56.480 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] VoiceBrowser.invokeRootDocument: [URI=http://192.0.2.11:7000/CVP/Server?audium_root=true&calling_into=HelloWorld fragment=null]


    VVB loadbAndParse

    178430: May 02 16:07:56.481 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] VXMLDocumnet.loadbAndParse(): fetching (http://192.0.2.11:7000/CVP/Server?audium_root=true&calling_into=HelloWorld) timeout attribute=0

    VVB browser make request

    178435: May 02 16:07:56.481 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Browser.makeRequest(): got encoded uri=http://192.0.2.11:7000/CVP/Server?audium_root=true&calling_into=HelloWorld


    VVB Fetch VXML doc

    178444: May 02 16:07:56.489 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Browser.fetchVxml(): got doc from new VxmlDocStream(type: 1, cnt: com.cisco.wf.cache.http.CachedHttpURLConnection:http://192.0.2.11:7000/CVP/Server?audium_root=true&calling_into=HelloWorld, cachable: true, isFromCache: false, lastModified: 0)

###VVB successfully fetched
178445: May 02 16:07:56.489 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Successfully fetched bytes: 0, duration(s): 0.008, URI: http://192.0.2.11:7000/CVP/Server?audium_root=true&calling_into=HelloWorld

### VXML Content
Content of VXML Document
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0">
.....

    VVB SUBMIT POST ()

    178480: May 02 16:07:56.494 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] GotoRequest.handleSubmitElement(): goto req=http://192.0.2.11:7000/CVP/Server
178481: May 02 16:07:56.494 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] GotoRequest.handleSubmitElement(): req's method=post


    Result of the SUBMIT post

    178506: May 02 16:07:56.500 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Fetch: HTTP 200 OK
178507: May 02 16:07:56.500 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Browser.fetchVxml(): got doc from new VxmlDocStream(type: 1, cnt: com.cisco.net.www.protocol.http.HttpURLTimeoutConnection:http://192.0.2.11:7000/CVP/Server, cachable: false, isFromCache: false, lastModified: 0)
178508: May 02 16:07:56.500 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Successfully fetched bytes: 0, duration(s): 0.004, URI: http://192.0.2.11:7000/CVP/Server


    Handle Element

    178603: May 02 16:07:56.509 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] GotoRequest.handleSubmitElement(): goto req=http://192.0.2.11:7000/CVP/Server
178604: May 02 16:07:56.509 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] GotoRequest.handleSubmitElement(): req's method=post


    Creates new Query

    178510: May 02 16:07:56.500 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Content of POST query:
audium_vxmlLog=
178511: May 02 16:07:56.500 AEST %MIVR-SS_VB-7-UNK:Content of VXML Document:
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" application="/CVP/Server?audium_root=true&amp;calling_into=HelloWorld">
<form id="audium_start_form">
<block>


    Result of post (Audio Load)

    178579: May 02 16:07:56.503 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] <audio> src:/CVP/audio/helloworld_audio.wav expr:
178580: May 02 16:07:56.503 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] checkWriteOnDiskForVVB: Setting Disk write to true for VVB

    [RUN_SCRIPT_RESULT][IVR]- processing from VXMLServer

    44257: 192.0.2.11: May 10 2017 11:39:50.927 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 [IVR] - processing from VXMLServer - [RUN_SCRIPT_RESULT] DialogID=0 SendSeqNo=0 InvokeID=0 Result=1 CED= newTransaction=0 ECC variables= ECC arrays= Peripheral Variables=

    [RUN_SCRIPT_RESULT][IVR]-publishing to ICM_SS

    44248: 192.0.2.11: May 10 2017 11:39:47.521 +1000: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A CALLID=0F46E580000100000000007A0851430A-149438037789633@192.0.2.11 [IVR] [Publishing to ICM_SS] [RUN_SCRIPT_RESULT] DialogID=0 SendSeqNo=0 InvokeID=1 Result=1 CED=complete newTransaction=0 ECC variables=user.microapp.error_code: 0,user.microapp.metadata: N|000|01|00|00|009141|GS,Server,V,user.microapp.caller_input: complete, ECC arrays= Peripheral Variables=


    [RUN_SCRIPT_RESULT][IVR_VRU_LEG_DIALOGUE]-Processing from IVR_SS- Result=1

    4292: 192.0.2.11: May 10 2017 11:39:47.521 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Processing from IVR_SS - [RUN_SCRIPT_RESULT] DialogID=0 SendSeqNo=0 InvokeID=1 Result=1 CED=complete newTransaction=0 ECC variables=user.microapp.error_code: 0,user.microapp.metadata: N|000|01|00|00|009141|GS,Server,V,user.microapp.caller_input: complete, ECC arrays= Peripheral Variables=


    [RUN_SCRIPT_RESULT][IVR_VRU_LEG_DIALOGUE]-Publishing to UCCE

    4293: 192.0.2.11: May 10 2017 11:39:47.521 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Publishing to UCCE - [RUN_SCRIPT_RESULT] DialogID=47 SendSeqNo=2 InvokeID=1 Result=1 CED=complete newTransaction=0 ECC variables=user.microapp.error_code: 0,user.microapp.metadata: N|000|01|00|00|009141|GS,Server,V,user.microapp.caller_input: complete, ECC arrays= Peripheral Variables=

    Agent becomes available and ICM cancel the VXML operation

    4296: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} 47CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE]sending ICMEvent, sessionId=0F46E580000100000000007A0851430A callId=null dialogId=47 sourceId=null systemType=ICM mediatype= dnis= ani= srcAddress= locationsiteid=sessionId=0F46E580000100000000007A0851430A callType=0 cacheCallContext=false postcallsurveycall=false icmMessage=DIALOGUE_FAILURE_CONF

    [Dialogue_failure_conf]-[IVR_VRU_LEG_DIALOGUE publishing to UCCE]

    4297: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Publishing to UCCE - [DIALOGUE_FAILURE_CONF] DialogID=47 SendSeqNo=3 InvokeId=2 ErrorCode=E_OPERATION_CANCELLED

    [CANCEL]-Processing from UCCE

    4298: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Processing from UCCE - [CANCEL] DialogID=47 SendSeqNo=3 InvokeID=3 ReqID=2

    [Connect] processing from UCCE

    4300: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Processing from UCCE - [CONNECT] DialogID=46 SendSeqNo=2 Label=4001 TxrHint=false rckey=152070 rcday=152070 rcseq=0 location= locationpkid= sipheader= uui= whisperAnnounce= callguid=0F46E580000100000000007A0851430A ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=

    [Connect] publishing to SIP_SS-label 4001

    4301: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=46 [SIP_SWITCH_LEG_DIALOGUE] - Publishing to SIP_SS - [CONNECT] DialogID=46 SendSeqNo=2 Label=4001 TxrHint=false rckey=152070 rcday=152070 rcseq=0 location= locationpkid= sipheader= uui= whisperAnnounce= callguid=0F46E580000100000000007A0851430A ECC variables=user.media.id: 0F46E580000100000000007A0851430A,user.microapp.error_code: 0,user.microapp.app_media_lib: ..,user.microapp.media_server: http://192.0.2.11:7000/CVP,user.microapp.caller_input: complete,user.microapp.UseVXMLParams: N, ECC arrays=USER_MICROAPP_TOEXTVXML[0]:application=HelloWorld;callid=0F46E580000100000000007A085143, Peripheral Variables=


    Route Matching-agent Leg

    1968: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} Static route matched 4001 to StaticRoute: patterns like 400> will route to 10.66.75.51
1969: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} Using Local Static Route for sip:4001@10.66.75.51


    VRU LEG disconnects

    4302: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Processing from UCCE - [RELEASE] DialogID=47 SendSeqNo=4 Cause=E_NO_ERROR

    [Disconnect] publishing [disconnect]

    4303: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID = 0F46E580000100000000007A0851430A, DLGID = 47 [IVR_VRU_LEG_DIALOGUE] - Publishing [DISCONNECT] DialogID=47 SendSeqNo=4 Cause=E_NO_ERROR, LEGID = 0F46E580000100000000007A0851430A-149438037789633@192.0.2.11, DNIS = 888811111112, ANI = 12345


    [Disconnect] publishing to IVR_SS

    4304: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=0F46E580000100000000007A0851430A DialogId=47 [IVR_VRU_LEG_DIALOGUE] - Publishing to IVR_SS - [DISCONNECT] DialogID=47 SendSeqNo=4 Cause=E_NO_ERROR


    VVB Disconnect

    178207: May 02 16:07:56.190 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] Content of POST query:
audium_vxmlLog=
178208: May 02 16:07:56.191 AEST %MIVR-SS_VB-7-UNK:Content of VXML Document:
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0">
<catch event="vxml.session.error">
<return event="vxml.session.error" />
</catch>
<catch event="telephone.disconnect.hangup">

188680: May 02 16:10:49.431 AEST %MIVR-SS_VB-7-UNK:[CALLID=43A31C0000010000000000790851430A-149370359030630] traverseDialog: id=Disconnect
196326: May 10 11:21:40.066 AEST %MIVR-JASMIN-7-UNK:[CALLID=0F46E580000100000000007A0851430A-149438037789633] SIP-ltp6-88881111*, State=CONNECTED: setDisconnected processTerminated, cause=NORMAL


    Ring Back Leg

    1972: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} Static route matched 91919191 to StaticRoute: patterns like 91919191> will route to 192.0.2.12
1973: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} Using Local Static Route for sip:91919191@192.0.2.12


    Invite to VXML GW

    1978: 192.0.2.11: May 10 2017 11:39:50.661 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438039066134 - [OUTBOUND]: INVITE TO <sip:91919191@192.0.2.12;transport=tcp> FROM "PSTN PHONE--CVP_11_5_1_0_1_0_344" <sip:12345@192.0.2.11:5060> EXPIRES[10] 100REL[Unsupported]

Sending Message (NB): INVITE sip:91919191@192.0.2.12;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 192.0.2.11:5060;branch=z9hG4bKKIR8MEBv53bzAj6MuGd1vw~~132
Max-Forwards: 67
To: <sip:91919191@192.0.2.12;transport=tcp>
From: "PSTN PHONE--CVP_11_5_1_0_1_0_344" <sip:12345@192.0.2.11:5060>;tag=ds789c1a64
Call-ID: 0F46E580000100000000007A0851430A-149438039066134@192.0.2.11
CSeq: 1 INVITE
Content-Length: 0
Contact: <sip:12345@192.0.2.11:5060;transport=tcp>
Expires: 10
User-Agent: CVP 11.5 (1) ES-1 Build-344
Call-Info: <sip:10.66.74.114:5060>;purpose=x-cisco-origIP
Remote-Party-ID: "PSTN PHONE--CVP_11_5_1_0_1_0_344" <sip:12345@192.0.2.14>;party=calling;screen=yes;privacy=off
Date: Wed, 10 May 2017 01:17:58 GMT
Min-SE: 1800
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
X-Cisco-CCBProbe: id:192.0.2.14;loc:donnielab;trunks:10
Session-Expires: 1800
Cisco-Gucid: 0F46E580000100000000007A0851430A
Supported: timer
Supported: resource-priority
Supported: replaces
Supported: sdp-anat
App-Info: <192.0.2.11:7000:7443>;ringtone=ringback.wav


    Ringback tone is established with caller

    2007: 192.0.2.11: May 10 2017 11:39:50.771 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-128-SIP-19468} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438039066134 - [RING-OUT]: Notifying that ringtone is established 200 OK with caller.

    Agent Leg Invite to CUCM

    2013: 192.0.2.11: May 10 2017 11:39:50.771 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-123-SIP-19464} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438039077135 - [OUTBOUND]: INVITE TO <sip:4001@10.66.75.51;transport=tcp> FROM "PSTN PHONE--CVP_11_5_1_0_1_0_344" <sip:12345@192.0.2.11:5060> EXPIRES[60] 100REL[Unsupported]

    Call connected

    2049: 192.0.2.11: May 10 2017 11:39:58.817 +1000: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-130-SIP-19471} CALLGUID = 0F46E580000100000000007A0851430A LEGID = 0F46E580000100000000007A0851430A-149438039077135 - [OUTBOUND]: Acked the invite.

    Related Information

    TAC Authored

    Contributed by Cisco Engineers

    • Sahar Modares
      Cisco TAC Engineer

    Was this Document Helpful?

    FeedbackFeedback

    Contact Cisco

    This Document Applies to These Products