================================================================================================================================================================== VXML gateway logs ( I can see HTTP 404 "NOT found" response at the last line) Interestingly Its trying to connect to CVP on port 7000.. I am not sure where /how that is configured. In the working environment I see it as 8000. *May 31 19:06:31.457: //-1/xxxxxxxxxxxx/SIP/Error/sippmh_parse_callinfo_params: Unknown Purpose Value *May 31 19:06:31.465: //180//AFW_:/vtr_ev_gtd_object: rawmsg not found in event *May 31 19:06:31.465: //180/CD3D2B000000/SIP/Error/sipSPI_ipip_set_history_info_header: Not SIP2SIP mode SIP: (180) Group (a= group line) attribute, level 65535 instance 1 not found. SIP: Attribute mid, level 1 instance 1 not found. *May 31 19:06:31.517: //180/CD3D2B000000/SIP/Error/sipSPIProcessAckMedia: Could not modify QoS params for midcall INVITE *May 31 19:06:31.549: //-1//AFW_:/AFW_Module_ParseHandle: ERROR: Separator not found *May 31 19:06:31.589: //180//HTTPC:/httpc_get_doc: url: http://192.168.0.33:7000/cvp/VBServlet?MSG_TYPE=PING&CALL_DNIS=999030000049&CALL_ANI=sip:3101@192.168.0.33:5060&ERROR_CODE=0&RECOVERY_VXML=flash:recovery.vxml&CLIENT_TYPE=IOS&CALL_ID=CD3D2B0000010000000000125100A8C0&CALL_LEGID=CD3D2B0000010000000000125100A8C0-14962462311076@192.168.0.33&CALL_UUI=&VERSION=CVP_10_0_1_0_0_0_490 *May 31 19:06:31.589: //180//HTTPC:/httpc_get: url length=326 *May 31 19:06:31.589: //180//HTTPC:/httpc_get: url: http://192.168.0.33:7000/cvp/VBServlet?MSG_TYPE=PING&CALL_DNIS=999030000049&CALL_ANI=sip:3101@192.168.0.33:5060&ERROR_CODE=0&RECOVERY_VXML=flash:recovery.vxml&CLIENT_TYPE=IOS&CALL_ID=CD3D2B0000010000000000125100A8C0&CALL_LEGID=CD3D2B0000010000000000125100A8C0-14962462311076@192.168.0.33&CALL vxmlgw#_UUI=&VERSION=CVP_10_0_1_0_0_0_490 *May 31 19:06:31.589: //180//HTTPC:/httpc_send_ev: event sent to HTTP Client: *May 31 19:06:31.589: method (GET), url (http://192.168.0.33:7000/cvp/VBServlet?MSG_TYPE=PING&CALL_DNIS=999030000049&CALL_ANI=sip:3101@192.168.0.33:5060&ERROR_CODE=0&RECOVERY_VXML=flash:recovery.vxml&CLIENT_TYPE=IOS&CALL_ID=CD3D2B0000010000000000125100A8C0&CALL_LEGID=CD3D2B0000010000000000125100A8C0-14962462311076@192.168.0.33&CALL_UUI=&VERSION=CVP_10_0_1_0_0_0_490) *May 31 19:06:31.589: callback (41CAD754), argp (47B08C84), sid (0), timeout (7), maxage (-1), maxstale(-1) retries (0) *May 31 19:06:31.589: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 2 *May 31 19:06:31.589: //180//HTTPC:/httpc_msg_send: sid: 0, method: 2 *May 31 19:06:31.589: //180//HTTPC:/httpc_connect: No Connection found, opening socket... *May 31 19:06:31.589: //180//HTTPC:/httpc_connect: socket_open() OK fd=0 *May 31 19:06:31.589: //180//HTTPC:/httpc_connect: calling socket_connect() for fd 0 *May 31 19:06:31.593: //180//HTTPC:/httpc_connect: socket_connect() return errno=EWOULDBLOCK fd(0) state ---> HTTPC_SOCK_PENDING *May 31 19:06:31.593: //180//HTTPC:/httpc_enqueue_wmsg: sid: 0, method: 2 *May 31 19:06:31.593: //180//HTTPC:/httpc_process_read_ev: SOCK_PENDING --> SOCK_CONNECTED fd(0) port(7000) *May 31 19:06:31.593: //180//HTTPC:/httpc_process_read_ev: httpc_check_connect_secure() return HTTPC_ERR_SSLHS_NOT_NEEDED for fd 0 *May 31 19:06:31.593: //180//HTTPC:/httpc_process_write_queue: socket writeble fd: 0, process enqueued msg, sid: 0, method: 2 *May 31 19:06:31.593: //180//HTTPC:/httpc_msg_write: sid: 0, method: 2 *May 31 19:06:31.593: //180//HTTPC:/httpc_write_stream: malloc size=356 *May 31 19:06:31.593: //180//HTTPC:/httpc_write_stream: *May 31 19:06:31.685: //180//HTTPC:/httpc_socket_send: fd: 0 *May 31 19:06:31.685: //180//HTTPC:/httpc_socket_send: *May 31 19:06:31.685: about to send data to the socket 0 : first 400 bytes of data: GET /cvp/VBServlet?MSG_TYPE=PING&CALL_DNIS=999030000049&CALL_ANI=sip:3101@192.168.0.33:5060&ERROR_CODE=0&RECOVERY_VXML=flash:recovery.vxml&CLIENT_TYPE=IOS&CALL_ID=CD3D2B0000010000000000125100A8C0&CALL_LEGID=CD3D2B0000010000000000125100A8C0-14962462311076@192.168.0.33&CALL_UUI=&VERSION=CVP_10_0_1_0_0_0_490 HTTP/1.1 Host: 192.168.0.33:7000 Content-Type: application/x-www-form-urlencoded Connectio *May 31 19:06:31.685: //180//HTTPC:/httpc_socket_read: socket readable fd: 0 *May 31 19:06:31.693: //180//HTTPC:/httpc_socket_read: socket readable fd: 0 *May 31 19:06:31.693: //180//HTTPC:/httpc_socket_read: *May 31 19:06:31.693: read data from the socket 0 : first 400 bytes of data: HTTP/1.1 404 Not Found ================================================================================================================== ====================================================================================================================== CVP Combo server logs - Not working I see error - DIALOG TERMINATED. Reason: Q.850;cause=38 31: 192.168.0.33: Jun 01 2017 16:02:37.450 -0500: %CVP_11_5_IVR-7-CALL: {Thrd=Global-task-executor-group-2} CallServerStatsManager:[[CallServer Stats][SessionManager Stats:[Total Number of Active Sessions=0][Total Number of Deleted Sessions=0][Total Number of Pcs Sessions=0][[CallServerPoolManager Stats][SessionPool Stats:[Total Number of Active Sessions=0][Total Number of Idle Sessions=0][IvrConnectionPool Stats:[Total Number of Active IvrConnections=0][Total Number of Idle IvrConnections=0][IvrConnectionPool Stats:[Total Number of Active IcmConnections=0][Total Number of Idle IcmConnections=0][NewDialogHandlerPool Stats:[Total Number of Active NewDialogHandlers=0][Total Number of Idle NewDialogHandlers=0] [[QueueManager Stats][Name=CALLSERVER_TRANSPORT_MANAGER_QUEUE,Max Size=32768,Current Size=0[Name=EVENT_ROUTER_QUEUE,Max Size=15360,Current Size=0[Name=CALLSERVER_REPORTING_PROVIDER_QUEUE,Max Size=32768,Current Size=0[Name=SIP_MESSAGE_QUEUE,Max Size=32768,Current Size=0[[CallServer Worker Stats messages/second][TransportManager Reader Worker=0][EventRouter Worker=0][[CallServer Worker TimerManager stats]Total Active TImer=0 105: 192.168.0.33: Jun 01 2017 16:02:49.825 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} No Session ID found in this request. 106: 192.168.0.33: Jun 01 2017 16:02:49.841 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} Found an incoming CISCO-GUID hdr : 2851317760-0000065536-0000000045-1358997696, Generated a new CVP-ID hdr : A9F3A800000100000000002D5100A8C0 107: 192.168.0.33: Jun 01 2017 16:02:49.856 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Display Name [] Is Using Survivability [false] CallServer build CVP_11_5_1_0_1_0_349 108: 192.168.0.33: Jun 01 2017 16:02:49.856 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Incoming call already has a Location Call Info header. ;x-cisco-loc-id=29c5c1c4-8871-4d1e-8394-0b9181e8c54d;x-cisco-loc-name=Hub_None;x-cisco-fateshare-id=StandAloneCluster:28829754;x-cisco-video-traffic-class=VIDEO_UNSPECIFIED 109: 192.168.0.33: Jun 01 2017 16:02:49.856 -0500: %CVP_11_5_SIP-7-PARAM: {Thrd=DATAI.2} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: ReqURI (DN) sip:888050000094@192.168.0.33:5060 FromURI sip:3101@192.168.0.81 Video:false m_needs_postcallsurvey:false 110: 192.168.0.33: Jun 01 2017 16:02:49.872 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} getMySessionIDHeader: value=00000000000000000000000000000000;remote=6577cff11000015c33b5a7f2c137dfa5 111: 192.168.0.33: Jun 01 2017 16:02:49.872 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} NEW CALL with guid=A9F3A800000100000000002D5100A8C0 legid=a9f3a800-930180f9-2d-5100a8c0 dn=888050000094 ani=3101 uui=null calldate=Thu Jun 01 16:02:49 CDT 2017 video=false cachecallcontext = false is_postcallsurvey = false RouterCallKey = null RouterCallKeyDay = null RouterCallKeySequenceNumber = null 112: 192.168.0.33: Jun 01 2017 16:02:49.872 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} Sending msg:>>HEADERS: (JMSType)=MsgBus:NEW_CALL (JMSDestination)=Topic(CVP.SIP.CC.REQ) (JMSTimestamp)=1496350969872 >>BODY: replyto=true callguid=A9F3A800000100000000002D5100A8C0 ani=3101 dnis=888050000094 timezone=America/Chicago version=CVP_11_5 pstntrkgrpsrcip=192.168.0.81 location-pkid=29c5c1c4-8871-4d1e-8394-0b9181e8c54d calldate=Thu Jun 01 16:02:49 CDT 2017 location=Hub_None calltypeid=4 localOffset=-300 calllegid=a9f3a800-930180f9-2d-5100a8c0 >>STATE: isTabular=false isWriteable=true cursor=-1 113: 192.168.0.33: Jun 01 2017 16:02:49.872 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DATAI.2} Sending msg:ICMEvent, sessionId=A9F3A800000100000000002D5100A8C0 callId=a9f3a800-930180f9-2d-5100a8c0 dialogId=0 sourceId=a9f3a800-930180f9-2d-5100a8c0 systemType=SIP mediatype= dnis=888050000094 ani=3101 srcAddress=192.168.0.81 locationsiteid=sessionId=A9F3A800000100000000002D5100A8C0 callType=4 cacheCallContext=false postcallsurveycall=false icmMessage=NEW_CALL 32: 192.168.0.33: Jun 01 2017 16:02:49.903 -0500: %CVP_11_5_IVR-7-CALL: {Thrd=Core-router-group-0} --NEW SESSION CREATED--CALLGUID=A9F3A800000100000000002D5100A8C0 NUMBER OF ACTIVE SESSIONS= 1 33: 192.168.0.33: Jun 01 2017 16:02:49.903 -0500: %CVP_11_5_ICM-6-LOGMSG_ICM_SS_PROPERTY: Unexpected format of location received [Hub_None]. Expecting delimeter[--]. Ignoring invalid location info. [id:2011] 34: 192.168.0.33: Jun 01 2017 16:02:49.903 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID = A9F3A800000100000000002D5100A8C0 - Correlation ID routed call 35: 192.168.0.33: Jun 01 2017 16:02:49.934 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Processing from SIP_SS - [NEW_CALL] DialogID=0 SendSeqNo=0 DNIS=888050000094 ANI=3101 CED= rckey=0 rcday=0 rcseq=0 uui= callguid=A9F3A800000100000000002D5100A8C0 trunkGroupId=0 trunkNumber=0 serviceId=0 calledNumber= location=Hub_None locationpkid=29c5c1c4-8871-4d1e-8394-0b9181e8c54d pstntrunkgroupid= sipheader= ECC variables= ECC arrays= Peripheral Variables= 36: 192.168.0.33: Jun 01 2017 16:02:49.950 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Publishing to UCCE - [REQUEST_INSTRUCTION] DialogID=1 SendSeqNo=1 ANI=3101 DNIS=888050000094 correlationId=94 trunkGroupId=200 trunkNumber=0 serviceId=2 uui= calledNumber= location=Hub_None locationpkid= pstntrunkgroupid= pstntrunkgroupchannelnum=0 sipheader= 37: 192.168.0.33: Jun 01 2017 16:02:49.981 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Processing from UCCE - ,, [ICM_TEMPORARY_CONNECT], rckey=219, rcday=152092, DNIS=888050000094, ANI=3101 38: 192.168.0.33: Jun 01 2017 16:02:49.981 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Publishing to SIP_SS - [CONNECT] DialogID=1 SendSeqNo=0 Label=9990300000 TxrHint=false rckey=152092 rcday=152092 rcseq=1 location=Hub_None locationpkid= sipheader= uui= whisperAnnounce=null callguid=A9F3A800000100000000002D5100A8C0 ECC variables=user.microapp.input_type: D,user.microapp.app_media_lib: sys,user.microapp.media_server: http://192.168.0.33,user.cvp_server_info: N, ECC arrays= Peripheral Variables=CallVariable10=thankyou.wav, 114: 192.168.0.33: Jun 01 2017 16:02:49.981 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND] Handling CONNECT :[CONNECT] DialogID=1 SendSeqNo=0 Label=9990300000 TxrHint=false rckey=152092 rcday=152092 rcseq=1 location=Hub_None locationpkid= sipheader= uui= whisperAnnounce=null callguid=A9F3A800000100000000002D5100A8C0 ECC variables=user.microapp.input_type: D,user.microapp.app_media_lib: sys,user.microapp.media_server: http://192.168.0.33,user.cvp_server_info: N, ECC arrays= Peripheral Variables=CallVariable10=thankyou.wav, 115: 192.168.0.33: Jun 01 2017 16:02:49.981 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} Static route matched 999030000095 to StaticRoute: patterns like 999030> will route to 192.168.0.85 116: 192.168.0.33: Jun 01 2017 16:02:49.997 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} Using Local Static Route for sip:999030000095@192.168.0.85 117: 192.168.0.33: Jun 01 2017 16:02:49.997 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} Inside updateSessionIDForPassThrough 118: 192.168.0.33: Jun 01 2017 16:02:49.997 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} No Session ID found in this request. 119: 192.168.0.33: Jun 01 2017 16:02:49.997 -0500: %CVP_11_5_SIP-7-LOW: {Thrd=pool-1-thread-7-SIP-4} Setting uuid to: 6577d08d1000015c133e05c4bd2efb4b 120: 192.168.0.33: Jun 01 2017 16:02:50.013 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} Found an incoming CISCO-GUID hdr : 2851317760-0000065536-0000000045-1358997696, Found an existing CVP-ID hdr: A9F3A800000100000000002D5100A8C0 121: 192.168.0.33: Jun 01 2017 16:02:50.013 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: INVITE TO FROM 3101 EXPIRES[60] 100REL[Unsupported] 122: 192.168.0.33: Jun 01 2017 16:02:50.013 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-7-SIP-4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]Trying to set headers in INVITE with these operations: 123: 192.168.0.33: Jun 01 2017 16:02:50.059 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: Invitation proceeding 100 124: 192.168.0.33: Jun 01 2017 16:02:50.059 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} No Session ID found in this request. 125: 192.168.0.33: Jun 01 2017 16:02:50.075 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: Invitation accepted - ack'ing 126: 192.168.0.33: Jun 01 2017 16:02:50.075 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: NO P_Asserted_Identity. 127: 192.168.0.33: Jun 01 2017 16:02:50.091 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: CountObserver() is >0 and sending Re-Invite to caller. 128: 192.168.0.33: Jun 01 2017 16:02:50.106 -0500: %CVP_11_5_SIP-7-LOW: {Thrd=pool-1-thread-8-SIP-5} Setting uuid to: 6577d0cb1000015cecec8a68e496d051 129: 192.168.0.33: Jun 01 2017 16:02:50.106 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Updated by : CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: with event type INV_ACCEPTED 130: 192.168.0.33: Jun 01 2017 16:02:50.106 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND] added location header from initial invite. 131: 192.168.0.33: Jun 01 2017 16:02:50.106 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Send the 200 OK back to caller with SDP of this dialog. 132: 192.168.0.33: Jun 01 2017 16:02:50.106 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} getMySessionIDHeader: value=6577d0cb1000015cecec8a68e496d051;remote=6577cff11000015c33b5a7f2c137dfa5 39: 192.168.0.33: Jun 01 2017 16:02:50.122 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Processing from SIP_SS - [EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=ANSWER CauseCode=CAUSE_NONE 133: 192.168.0.33: Jun 01 2017 16:02:50.122 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} Sending BUS MSG for Event Reportnew CSS:[EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=ANSWER CauseCode=CAUSE_NONE 40: 192.168.0.33: Jun 01 2017 16:02:50.122 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Publishing to UCCE - [EVENT_REPORT] DialogID=1 SendSeqNo=2 EventID=ANSWER CauseCode=NONE 134: 192.168.0.33: Jun 01 2017 16:02:50.122 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-8-SIP-5} Sending BUS MSG:>>HEADERS: (JMSType)=MsgBus:CALL_STATE_EVENT (JMSDestination)=Topic(CVP.SIP.CC.EVENT) (JMSTimestamp)=1496350970106 (ServerID)=CVPCMB.SYS_SIP1:SIP:SIP1:CVPCMB.MsgBus001 >>BODY: callguid=A9F3A800000100000000002D5100A8C0 RouterCallKey=219 RouterCallKeySent=false causecode=0 timezone=America/Chicago RouterCallKeySequenceNumber=1 version=CVP_11_5 labeltype=-1 RouterCallKeyDay=152092 calldate=Thu Jun 01 16:02:50 CDT 2017 label=999030000095 localOffset=-300 eventid=4 calllegid=A9F3A800000100000000002D5100A8C0-14963509699971 >>STATE: isTabular=false isWriteable=false cursor=-1 135: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: DsSipInviteDialog ack'ed 136: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} Inside updateSessionIDForPassThrough 137: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-LOW: {Thrd=pool-1-thread-9-SIP-6} Setting uuid to: 6577cff11000015c33b5a7f2c137dfa5 138: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-9-SIP-6} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: Updated by : CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: with event type INV_ACKED 139: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-9-SIP-6} Inside sendAckOnInvite 140: 192.168.0.33: Jun 01 2017 16:02:50.138 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-9-SIP-6} getMySessionIDHeader: value=6577cff11000015c33b5a7f2c137dfa5;remote=6577d0cb1000015cecec8a68e496d051 141: 192.168.0.33: Jun 01 2017 16:02:50.153 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-9-SIP-6} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: Acked the invite. 142: 192.168.0.33: Jun 01 2017 16:02:50.684 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} Inside updateSessionIDForPassThrough 143: 192.168.0.33: Jun 01 2017 16:02:50.684 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND] DURATION (msecs) = 687 - DIALOG TERMINATED. Reason: Q.850;cause=38 144: 192.168.0.33: Jun 01 2017 16:02:50.684 -0500: %CVP_11_5_SIP-7-LOW: {Thrd=pool-1-thread-10-SIP-7} Setting uuid to: 6577d0cb1000015cecec8a68e496d051 145: 192.168.0.33: Jun 01 2017 16:02:50.684 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-10-SIP-7} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Updated by : CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = A9F3A800000100000000002D5100A8C0-14963509699971 - [OUTBOUND]: with event type TERMINATED 146: 192.168.0.33: Jun 01 2017 16:02:50.700 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-11-SIP-8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Waiting 2000 millisecs before terminating. 147: 192.168.0.33: Jun 01 2017 16:02:50.700 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-11-SIP-8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Registering Timer 2000 millisecs before terminating. 148: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} Sending BUS MSG for Event Reportnew CSS:[EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=DISCONNECT CauseCode=UNKNOWN 149: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} Sending BUS MSG:>>HEADERS: (JMSType)=MsgBus:CALL_STATE_EVENT (JMSDestination)=Topic(CVP.SIP.CC.EVENT) (JMSTimestamp)=1496350972731 >>BODY: callguid=A9F3A800000100000000002D5100A8C0 RouterCallKey=219 RouterCallKeySent=true causecode=22 timezone=America/Chicago RouterCallKeySequenceNumber=1 version=CVP_11_5 labeltype=-1 RouterCallKeyDay=152092 calldate=Thu Jun 01 16:02:52 CDT 2017 label=999030000095 localOffset=-300 eventid=6 calllegid=a9f3a800-930180f9-2d-5100a8c0 >>STATE: isTabular=false isWriteable=true cursor=-1 150: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND] DURATION (msecs) = 2906 - HANGUP with Call History999030000095|-1; 41: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Processing from SIP_SS - [EVENT_REPORT] DialogID=0 SendSeqNo=0 EventID=DISCONNECT CauseCode=UNKNOWN 151: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-3-SIP_CALL_ERROR: CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND] - ABNORMALLY ENDING - SIP code [200], Reason Hdr [Q.850;cause=38] Q850 Reason [38 - Network out of order, typically a TCL script abnormal disconnect.], GW call using SURV TCL flag [false], NON NORMAL flag [true], DNIS [888050000094], ANI [3101] with AGE (msecs) 2906 and Call History : 999030000095|-1; [id:5004] 42: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_ICM-7-CALL: {Thrd=Event-router-group-0} CALLGUID=A9F3A800000100000000002D5100A8C0 DialogId=1 [SIP_CORRID_LEG_DIALOGUE] - Publishing to UCCE - [EVENT_REPORT] DialogID=1 SendSeqNo=3 EventID=DISCONNECT CauseCode=CAUSE_CALLED_PARTY_DISCONNECTED 152: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} Static route matched 92929292 to StaticRoute: patterns like 92> will route to 192.168.0.85 153: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} Using Local Static Route for sip:92929292@192.168.0.85 154: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: REFERRING TO: 155: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} getMySessionIDHeader: value=6577d0cb1000015cecec8a68e496d051;remote=6577cff11000015c33b5a7f2c137dfa5 156: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-12-SIP-9} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]Trying to set headers in REFER request with these operations: 33: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} CallSession:removeConnectionByDialogId: CALLGUID=A9F3A800000100000000002D5100A8C0--REMOVING CONNECTION-- dialogId=1 34: 192.168.0.33: Jun 01 2017 16:02:52.731 -0500: %CVP_11_5_IVR-7-CALL: {Thrd=Event-router-group-0} -- SESSION DESTROYED--CALLGUID=A9F3A800000100000000002D5100A8C0 NUMBER OF ACTIVE SESSIONS= 0 157: 192.168.0.33: Jun 01 2017 16:02:52.762 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Dialog Reinvitation received - 104 158: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} Inside updateSessionIDForPassThrough 159: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} getMySessionIDHeader: value=6577d0cb1000015cecec8a68e496d051;remote=6577cff11000015c33b5a7f2c137dfa5 160: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: could not reinvite so we sent a inactive media 200OK response on the other legs reinvite. 161: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: DsSipOutgoingReferInterface accepted with ds628d37ce,82~f5988258-8088-40ca-8461-ea246d8f7004-28829756,a9f3a800-930180f9-2d-5100a8c0@192.168.0.81,refer,SUBSCRIBER1 162: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Received a NOTIFY: subscription = refer 163: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.4} Inside updateSessionIDForPassThrough 164: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Subscription terminated subscription = refer 165: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} Inside updateSessionIDForPassThrough 166: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.9} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Reinvitation ack'ed. 104 167: 192.168.0.33: Jun 01 2017 16:02:52.778 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.9} Inside updateSessionIDForPassThrough 168: 192.168.0.33: Jun 01 2017 16:02:52.794 -0500: %CVP_11_5_SIP-3-SIP_CALL_ERROR: CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Refer failed with 404 - Not Found. May be a problem with Routing Configuration or Gateway Dial-Peer. [id:5004] 169: 192.168.0.33: Jun 01 2017 16:02:52.794 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.9} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Resetting m_inbound_reinvitation 170: 192.168.0.33: Jun 01 2017 16:02:52.794 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: handleReferNotifyEvent,ReasonCode=404Corresponding Cause Code=null 171: 192.168.0.33: Jun 01 2017 16:02:52.794 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-18-SIP-13} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Waiting 2000 millisecs before terminating. 172: 192.168.0.33: Jun 01 2017 16:02:52.794 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=pool-1-thread-18-SIP-13} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Registering Timer 2000 millisecs before terminating. 173: 192.168.0.33: Jun 01 2017 16:02:54.419 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} Inside updateSessionIDForPassThrough 174: 192.168.0.33: Jun 01 2017 16:02:54.419 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND] DURATION (msecs) = 4594 - DIALOG TERMINATED. Reason: Q.850;cause=16 175: 192.168.0.33: Jun 01 2017 16:02:54.419 -0500: %CVP_11_5_SIP-7-CALL: {Thrd=DIALOG_CALLBACK.8} CALLGUID = A9F3A800000100000000002D5100A8C0 LEGID = a9f3a800-930180f9-2d-5100a8c0 - [INBOUND]: Disconnect received when cleanup of this leg is in progress 35: 192.168.0.33: Jun 01 2017 16:02:54.825 -0500: %CVP_11_5_IVR-7-CALL: {Thrd=Core-router-group-0} EventRouter:executeEvent: Session not found or destroyed for the event:TimerEvent, startTime=1496350972794 delay=2000 event=sessionId=A9F3A800000100000000002D5100A8C0 callId=a9f3a800-930180f9-2d-5100a8c0 dialogId=0 sourceId=null systemType=null mediatype= dnis= ani= srcAddress= locationsiteid=sessionId=A9F3A800000100000000002D5100A8C0 176: 192.168.0.33: Jun 01 2017 16:02:54.825 -0500: %CVP_11_5_SIP-6-SIP_INFO: SIP Leg not found for Timer message, sending EVT RPT back to ICM - TimerEvent, startTime=1496350972794 delay=2000 event=sessionId=A9F3A800000100000000002D5100A8C0 callId=a9f3a800-930180f9-2d-5100a8c0 dialogId=0 sourceId=null systemType=null mediatype= dnis= ani= srcAddress= locationsiteid=sessionId=A9F3A800000100000000002D5100A8C0 [id:5000] ===================================================================================================================================================