[1308638454,NET] NOTIFY sip:1192@10.96.16.11:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 10.96.215.44:49496;branch=z9hG4bK29fe8ebd To: ;tag=969231743 From: ;tag=94d4692b8663008766006097-7e8ccdeb Call-ID: 4d353e00-ebd11455-143944f-b10600a@10.96.16.11 Session-ID: 38dd42ba00105000a00094d4692b8663;remote=00000000000000000000000000000000 Date: Thu, 14 May 2020 09:50:18 GMT CSeq: 1001 NOTIFY Event: kpml Subscription-State: active; expires=7200 Max-Forwards: 70 Contact: ;+u.sip!devicename.ccm.cisco.com="SEP94D4692B8663" Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE Content-Length: 215 Content-Type: application/kpml-response+xml Content-Disposition: session;handling=required 88116881.003 |12:50:18.507 |AppInfo |SIPTcp - SignalCounter = 444790262 88116882.000 |12:50:18.507 |SdlSig |SIPSPISignal |wait |SIPHandler(7,100,80,1) |SIPTcp(7,100,72,1) |7,100,14,2537834.191^10.96.215.44^* |*TraceFlagOverrode 88116882.001 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 88116882.002 |12:50:18.507 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 88116882.003 |12:50:18.507 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xe99c00a8, addr=10.96.215.44, port=49496, connid=2272164, transport=TCP 88116882.004 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 49496 connId 2272164 88116882.005 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : 4d353e00-ebd11455-143944f-b10600a@10.96.16.11969231743 88116882.006 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_event: Event [SIPSPI_EV_NEW_MSG] received in State [SUBSCRIBE_STATE_ACTIVE] 88116882.007 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0xa493fe10/sipGenCheckCseq: Difference in CSEQ numbers: 1 88116882.008 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xa50ef1a0 88116882.009 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBServerMarker: Server TCB's Marker Updated to: 1001NOTIFYz9hG4bK29fe8ebd 88116882.010 |12:50:18.507 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBResponseInfo: Dialog Transaction Address 10.96.215.44,Port 49496, Transport 2, SentBy Port 88116882.011 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBParent: TCB 0xa50ef1a0's parent is 0xa493fe10 88116882.012 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBLastRequest: TCB(0xa50ef1a0) has claimed possesion of lastReq (0xa4e94308) 88116882.013 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTCBonEDB: Q'd TCB(0xa50ef1a0) on EDB(0xa5b76ff0) 88116882.014 |12:50:18.507 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=442260264/findDevicePID: Routed to SIPD by ccbId/scbId 88116882.015 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0xa493fe10/sipSPIHandleNotifyOnExistingDialog: ccsip_api_notify_ind returned: SIP_SUCCESS 88116883.000 |12:50:18.507 |SdlSig |SIPNotifyInd |wait |SIPStationD(7,100,74,221790) |SIPHandler(7,100,80,1) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbID=442260264 --TransType=1 --TransSecurity=0 PeerAddr = 10.96.215.44:49496 88116884.000 |12:50:18.507 |SdlSig |SNFNotifyInd |active |Subscriber(7,100,62,978654) |SIPStationD(7,100,74,221790) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] SNFSubscriptionId = 0|0|0, SNFNotifyMsg: state = 1, reason = 0, retryAfter = -1, subscriptionType = KPML , content = no content , mProtocolData is not null 88116884.001 |12:50:18.507 |AppInfo |SIPKpmlEventPkg::subscriberNotifyInd tcbIndex = 0XA50EF1A0 state = 1 reason = 0 88116884.002 |12:50:18.507 |AppInfo |SIPEventPkg::sendSIPNotResponse 200 88116884.003 |12:50:18.507 |AppInfo |SIPKpmlDigitCollectionEventPkg::forwardNotifiedDigits ENTER 88116884.004 |12:50:18.507 |AppInfo |forwardNotifiedDigits: code: responseCode:[423] responseDigits:[-317250132] 88116885.000 |12:50:18.507 |SdlSig |StationT302 |overlap_sending2 |SIPStationCdfc(7,100,75,1776830) |Subscriber(7,100,62,978654) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] 88116886.000 |12:50:18.507 |SdlSig |CcT302ToInd |restart0 |LineControl(7,100,176,132670) |SIPStationCdfc(7,100,75,1776830) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 88116887.000 |12:50:18.507 |SdlSig |CcT302ToInd |call_initiated1 |LineCdpc(7,100,177,1546180) |LineControl(7,100,176,132670) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 88116888.000 |12:50:18.507 |SdlSig |CcT302ToInd |wait |Cc(7,100,223,1) |LineCdpc(7,100,177,1546180) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 88116889.000 |12:50:18.507 |SdlSig |CcT302ToInd |tcc_await_digits1 |Cdcc(7,100,222,1029691) |Cc(7,100,223,1) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 88116890.000 |12:50:18.507 |SdlSig |SIPNotifyResp |wait |SIPHandler(7,100,80,1) |Subscriber(7,100,62,978654) |7,100,14,2537834.191^10.96.215.44^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbID=442260264 --TransType=1 --TransSecurity=0 PeerAddr= 10.96.215.44:49496 respCode=200 88116890.001 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 206 (UNDEFINED) 88116890.002 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_NOTIFY_RESP] received in State [SUBSCRIBE_STATE_ACTIVE] 88116890.003 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBMethod: TCB 0xa50ef1a0's method is 111 88116890.004 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0xa493fe10/sipSPITCBSendPackage: Sending Package on tcb: 0xa50ef1a0 and parent: 0xa493fe10, parent context: 0xa607af80 88116890.005 |12:50:18.507 |AppInfo |//SIP/Stack/Transport/0x0xa493fe10/sipSPITransportSendMessage: msg=0xa5f8d1a0, addr=10.96.215.44, port=49496, sentBy_port=49496, is_req=0, tran 88116890.006 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0xa607af80/_sipSPIFreeOneTCB: Invoking SIP TCB destructor 88116890.007 |12:50:18.507 |AppInfo |//SIP/Stack/Info/0x0xa607af80/_sipSPIFreeOneTCB: Invoking nullify package 88116890.008 |12:50:18.508 |AppInfo |//SIP/Stack/Info/0x0xa607af80/_sipSPIFreeOneTCB: Invoking nullify last_request 88116889.001 |12:50:18.508 |AppInfo |processCCMFeatureData: operationIeIdd=0 88116889.002 |12:50:18.508 |AppInfo |findUnfiredInterceptOnPattern numOfPatterns = 0 88116891.000 |12:50:18.508 |SdlSig |SdlDataInd |wait |StationInit(7,100,63,1) |SdlTCPConnection(7,100,14,34) |7,100,14,34.577744^10.242.66.202^TWR-xcode2 |*TraceFlagOverrode 88116891.001 |12:50:18.508 |AppInfo |InboundStim - KeepAliveMessage - Send KeepAlive to Device Controller. DeviceName=TWR-xcode2, TCPPid = [7.100.14.34], IPAddr=10.242.66.202, Port=38416, Device Controller=[7,139,2] 88116890.009 |12:50:18.508 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: gcb=0xa493fe10 is already on connection=0xa4c31b78 context_list 88116890.010 |12:50:18.508 |AppInfo |//SIP/Stack/Transport/0x0xa493fe10/sipTransportLogicSendMsg: Connection obtained...sending msg=0xa5f8d1a0 88116892.000 |12:50:18.508 |SdlSig |CcProceedReq |restart0 |LineControl(7,100,176,132670) |Cdcc(7,100,222,1029691) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 lPart=ef367da7-5694-de16-114f-89fe5477b75f lPatt=1188 lModNum=tn=0npi=0ti=1nd=1188pi=1si1 lName=locale: 1 Name: test UnicodeName: test pi: 1 cName=locale: 1 Name: UnicodeName: pi: 0 cn:tn=0npi=0ti=1nd=1192pi=1si1 cVMbox= localPatternUsage=2 connectedPatternUsage=2 lCnPart=ef367da7-5694-de16-114f-89fe5477b75f lCnPatt=1192 rn:tn=0npi=0ti=1nd=1192pi=0si1 lLRPart=ef367da7-5694-de16-114f-89fe5477b75f lLRPatt=1192 lOCdpnPart=ef367da7-5694-de16-114f-89fe5477b75f lOCdpnPatt=1192 oCdpn:tn=0npi=0ti=1nd=1192pi=0si1 oRFR =0 lBridgePartID= lCnBridgePartID= DevCEPN=74225a40-d5fb-ceb9-7844-3be669e3f04d lineCEPN=a188d368-0e5f-0803-a981-afbe547663d6 CnDevCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 cnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lrnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 oCdpnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lHPMemCEPN= cHPMemCEPN= ctiActive=F ctiFarEndDev=0 ctiCCMId=0 isAnnMohResourceInsertedAtParty =F media=1 isOffNetDev=F cmDeviceType=4 locPkid= locName= deductBW=F fateShareId= videoTrafficClass=UnspecifiedSupp DTMF=0DTMF Cfg=1DTMF Payload=0 Orig DD=tn=0npi=1ti=1nd=1192User=1192Host=10.96.16.11Port=5060PassWord=Madder=Transport=4mDisplayName=RawUrl=sip:1192@10.96.16.11;user=phoneOrigPort=0pi=0si1TransparentData=null CanSupportSIPTandN=false TransId=0 AllowBitMask=0x0 UserAgentOrServer= OrigDDName=locale: 1 Name: UnicodeName: pi: 0 mCallerId= mCallerName=bibEnabled=0 isCgpnNonPreemptable=F isCdpnNonPreemptable=F 88116892.001 |12:50:18.508 |AppInfo |LineControl - restert0_CcProceedReq updated precedence of CI=122435292 to 5 88116890.011 |12:50:18.508 |AppInfo |//SIP/Stack/Transport/0x0xa493fe10/sipTransportPostSendMessage: Posting send for msg=0xa5f8d1a0, addr=10.96.215.44, port=49496, connId=2272164 for 88116893.000 |12:50:18.508 |SdlSig |PolicyAndCACAssociateReq |wait |ReservationMgr(7,100,111,1) |Cdcc(7,100,222,1029691) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] CI= 122435292 aCI=122435292 bCI=122435293 isASerCI=F isBSerCI=F sendResp=T mcNodeId=0 sideAnp=F sideBnp=F 88116894.000 |12:50:18.508 |SdlSig |CcProceedReq |call_initiated1 |LineCdpc(7,100,177,1546180) |LineControl(7,100,176,132670) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:3,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 lPart=ef367da7-5694-de16-114f-89fe5477b75f lPatt=1188 lModNum=tn=0npi=0ti=1nd=1188pi=1si1 lName=locale: 1 Name: test UnicodeName: test pi: 1 cName=locale: 1 Name: UnicodeName: pi: 0 cn:tn=0npi=0ti=1nd=1192pi=1si1 cVMbox= localPatternUsage=2 connectedPatternUsage=2 lCnPart=ef367da7-5694-de16-114f-89fe5477b75f lCnPatt=1192 rn:tn=0npi=0ti=1nd=1192pi=0si1 lLRPart=ef367da7-5694-de16-114f-89fe5477b75f lLRPatt=1192 lOCdpnPart=ef367da7-5694-de16-114f-89fe5477b75f lOCdpnPatt=1192 oCdpn:tn=0npi=0ti=1nd=1192pi=0si1 oRFR =0 lBridgePartID= lCnBridgePartID= DevCEPN=74225a40-d5fb-ceb9-7844-3be669e3f04d lineCEPN=a188d368-0e5f-0803-a981-afbe547663d6 CnDevCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 cnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lrnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 oCdpnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lHPMemCEPN= cHPMemCEPN= ctiActive=F ctiFarEndDev=0 ctiCCMId=0 isAnnMohResourceInsertedAtParty =F media=1 isOffNetDev=F cmDeviceType=4 locPkid= locName= deductBW=F fateShareId= videoTrafficClass=UnspecifiedSupp DTMF=0DTMF Cfg=1DTMF Payload=0 Orig DD=tn=0npi=1ti=1nd=1192User=1192Host=10.96.16.11Port=5060PassWord=Madder=Transport=4mDisplayName=RawUrl=sip:1192@10.96.16.11;user=phoneOrigPort=0pi=0si1TransparentData=null CanSupportSIPTandN=false TransId=0 AllowBitMask=0x0 UserAgentOrServer= OrigDDName=locale: 1 Name: UnicodeName: pi: 0 mCallerId= mCallerName=bibEnabled=0 isCgpnNonPreemptable=F isCdpnNonPreemptable=F 88116894.001 |12:50:18.508 |AppInfo |LineCdpc(1546180): -dispatchToAllDevices-, sigName=CcProceedReq, device=SEP94D4692B8663 88116895.000 |12:50:18.508 |SdlSig |CACAssociateReq |await_associate |RSVPSession(7,100,108,1029646) |ReservationMgr(7,100,111,1) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] CI= 122435292 aCI=122435292 bCI=122435293 isASerCI=F isBSerCI=F sendResp=F mcNodeId=0 sideAnp=F sideBnp=F 88116890.012 |12:50:18.508 |AppInfo |//SIP/Stack/Info/0x0xa50ef1a0/sipSPIFreeOneTCB: Invoking sipSPIFreeOneTCB - Ref count: 9 88116896.000 |12:50:18.508 |SdlSig |PolicyAndCACAssociateRes |wait |Cc(7,100,223,1) |ReservationMgr(7,100,111,1) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] CI= 122435292 aCI=122435292 bCI=122435293 isASerCI=F isBSerCI=F sendResp=F mcNodeId=0 sideAnp=F sideBnp=F 88116897.000 |12:50:18.508 |SdlSig |CcProceedReq |wait |SIPStationD(7,100,74,221790) |LineCdpc(7,100,177,1546180) |7,100,14,2537834.191^10.96.215.44^* |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] CI=122435292 CI.branch=0 lPart=ef367da7-5694-de16-114f-89fe5477b75f lPatt=1188 lModNum=tn=0npi=0ti=1nd=1188pi=1si1 lName=locale: 1 Name: test UnicodeName: test pi: 1 cName=locale: 1 Name: UnicodeName: pi: 0 cn:tn=0npi=0ti=1nd=1192pi=1si1 cVMbox= localPatternUsage=2 connectedPatternUsage=2 lCnPart=ef367da7-5694-de16-114f-89fe5477b75f lCnPatt=1192 rn:tn=0npi=0ti=1nd=1192pi=0si1 lLRPart=ef367da7-5694-de16-114f-89fe5477b75f lLRPatt=1192 lOCdpnPart=ef367da7-5694-de16-114f-89fe5477b75f lOCdpnPatt=1192 oCdpn:tn=0npi=0ti=1nd=1192pi=0si1 oRFR =0 lBridgePartID= lCnBridgePartID= DevCEPN=74225a40-d5fb-ceb9-7844-3be669e3f04d lineCEPN=a188d368-0e5f-0803-a981-afbe547663d6 CnDevCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 cnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lrnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 oCdpnCEPN=b3cb1ca7-59c3-368f-7ce8-0082879effb3 lHPMemCEPN= cHPMemCEPN= ctiActive=F ctiFarEndDev=0 ctiCCMId=0 isAnnMohResourceInsertedAtParty =F media=1 isOffNetDev=F cmDeviceType=4 locPkid= locName= deductBW=F fateShareId= videoTrafficClass=UnspecifiedSupp DTMF=0DTMF Cfg=1DTMF Payload=0 Orig DD=tn=0npi=1ti=1nd=1192User=1192Host=10.96.16.11Port=5060PassWord=Madder=Transport=4mDisplayName=RawUrl=sip:1192@10.96.16.11;user=phoneOrigPort=0pi=0si1TransparentData=null CanSupportSIPTandN=false TransId=0 AllowBitMask=0x0 UserAgentOrServer= OrigDDName=locale: 1 Name: UnicodeName: pi: 0 mCallerId= mCallerName=bibEnabled=0 isCgpnNonPreemptable=F isCdpnNonPreemptable=F 88116898.000 |12:50:18.508 |SdlSig |CACAssociateReq |active |LBMInterface(7,100,178,1) |ReservationMgr(7,100,111,1) |7,100,14,2537834.191^10.96.215.44^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] CI= 122435292 aCI=122435292 bCI=122435293 isASerCI=F isBSerCI=F sendResp=F mcNodeId=0 sideAnp=F sideBnp=F 88116898.001 |12:50:18.508 |AppInfo |LBMIF: CI: 122435292 ASSOC 122435293 88116898.002 |12:50:18.508 |AppInfo |LBMIF: CI: 122435293 ASSOC' 122435292 88116899.000 |12:50:18.508 |SdlSig |SIPSPISignal |wait |SIPTcp(7,100,72,1) |SIPHandler(7,100,80,1) |7,100,14,2537834.191^10.96.215.44^* |*TraceFlagOverrode 88116899.001 |12:50:18.508 |AppInfo |SIPTcp - wait_SdlSPISignal: Outgoing SIP TCP message to 10.96.215.44 on port 49496 index 2272164 [1308638455,NET] SIP/2.0 200 OK Via: SIP/2.0/TCP 10.96.215.44:49496;branch=z9hG4bK29fe8ebd From: ;tag=94d4692b8663008766006097-7e8ccdeb To: ;tag=969231743 Date: Thu, 14 May 2020 09:50:18 GMT Call-ID: 4d353e00-ebd11455-143944f-b10600a@10.96.16.11 CSeq: 1001 NOTIFY Server: Cisco-CUCM11.5 Content-Length: 0