INVITE sip:8015408813@10.10.10.1:5060 SIP/2.0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348268 Via: SIP/2.0/UDP 10.2.21.11:5060;branch=z9hG4bKf67dc174b47a4 10.10.10.1 Jul 07 09:37:04 syslog debug 100348269 From: ;tag=2181784~b81a956d-b4eb-4321-807f-845e8fa3a514-41576574 10.10.10.1 Jul 07 09:37:04 syslog debug 100348270 To: ;tag=D805110D-248E 10.10.10.1 Jul 07 09:37:04 syslog debug 100348271 Date: Thu, 07 Jul 2016 15:37:04 GMT 10.10.10.1 Jul 07 09:37:04 syslog debug 100348272 Call-ID: 75D30FAD-438F11E6-B1779F34-3186D190@10.10.10.1 10.10.10.1 Jul 07 09:37:04 syslog debug 100348273 Supported: 100rel,timer,resource-priority,replaces 10.10.10.1 Jul 07 09:37:04 syslog debug 100348274 Min-SE: 1800 10.10.10.1 Jul 07 09:37:04 syslog debug 100348275 Cisco-Guid: 1976526373-1133449702-2977013556-0830919056 10.10.10.1 Jul 07 09:37:04 syslog debug 100348276 User-Agent: Cisco-CUCM9.1 10.10.10.1 Jul 07 09:37:04 syslog debug 100348277 Allow: INVITE, 10.10.10.1 Jul 07 09:37:04 syslog debug 100348278 OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY 10.10.10.1 Jul 07 09:37:04 syslog debug 100348279 CSeq: 107 INVITE 10.10.10.1 Jul 07 09:37:04 syslog debug 100348280 Max-Forwards: 70 10.10.10.1 Jul 07 09:37:04 syslog debug 100348281 Expires: 180 10.10.10.1 Jul 07 09:37:04 syslog debug 100348282 Allow-Events: presence 10.10.10.1 Jul 07 09:37:04 syslog debug 100348283 Supported: X-cisco-srtp-fallback 10.10.10.1 Jul 07 09:37:04 syslog debug 100348284 Supported: Geolocation 10.10.10.1 Jul 07 09:37:04 syslog debug 100348285 Session-Expires: 1800;refresher=uac 10.10.10.1 Jul 07 09:37:04 syslog debug 100348286 P-Asserted-Identity: "ZIJA Contact Center" 10.10.10.1 Jul 07 09:37:04 syslog debug 100348287 Remote-Party-ID: "ZIJA Contact Center" ;party=calling;screen=yes;privacy=off 10.10.10.1 Jul 07 09:37:04 syslog debug 100348288 Contact: 10.10.10.1 Jul 07 09:37:04 syslog debug 100348289 Content-Length: 0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348290 10.10.10.1 Jul 07 09:37:04 syslog debug 100348291 52516909: Jul 7 09:37:04.170: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog 10.10.10.1 Jul 07 09:37:04 syslog debug 100348292 52516910: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/4096/sipSPICheckFromToRequest: Found matching CB 7FBC44FD56E8 10.10.10.1 Jul 07 09:37:04 syslog debug 100348293 52516911: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/4096/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table 10.10.10.1 Jul 07 09:37:04 syslog debug 100348294 52516912: Jul 7 09:37:04.170: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC3753B0F0) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:04 syslog debug 100348295 52516913: Jul 7 09:37:04.170: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348296 52516914: Jul 7 09:37:04.170: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:04 syslog debug 100348297 52516915: Jul 7 09:37:04.170: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348298 52516916: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/4096/sact_active_new_message_request: Case of Mid-Call INVITE/UPDATE in ACTIVE STATE 10.10.10.1 Jul 07 09:37:04 syslog debug 100348299 52516917: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/36864/sipSPIUpdateIdentity: MF: Free is previous PAI.. 10.10.10.1 Jul 07 09:37:04 syslog debug 100348300 52516918: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/36864/sipSPIUpdateIdentity: MF: PAI url is present & make a copy.. 10.10.10.1 Jul 07 09:37:04 syslog debug 100348301 52516919: Jul 7 09:37:04.170: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Name to ZIJA Contact Center 10.10.10.1 Jul 07 09:37:04 syslog debug 100348302 52516920: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: PAI genUrl is present.. 10.10.10.1 Jul 07 09:37:04 syslog debug 100348303 52516921: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Number to 4432 10.10.10.1 Jul 07 09:37:04 syslog debug 100348304 52516922: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: PAI host = 10.2.21.11 10.10.10.1 Jul 07 09:37:04 syslog debug 100348305 52516923: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 10.2.21.11 10.10.10.1 Jul 07 09:37:04 syslog debug 100348306 52516924: Jul 7 09:37:04.171: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC370E8008 10.10.10.1 Jul 07 09:37:04 syslog debug 100348307 52516925: Jul 7 09:37:04.171: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC3753B0F0) counter, current msg->refCount = 3 10.10.10.1 Jul 07 09:37:04 syslog debug 100348308 52516926: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x7FBC44FD56E8)->last_request = 0x7FBC3753B0F0, refCount = 3 10.10.10.1 Jul 07 09:37:04 syslog debug 100348309 52516927: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIHandleIncomingMidCallInvite: Delayed Media in MidCall Invite 10.10.10.1 Jul 07 09:37:04 syslog debug 100348310 52516928: Jul 7 09:37:04.171: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container 10.10.10.1 Jul 07 09:37:04 syslog debug 100348311 52516929: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header not present - create uuid 10.10.10.1 Jul 07 09:37:04 syslog debug 100348312 52516930: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/8192/sipSPICreateAndPopulateSessionID: Establish dialog 10.10.10.1 Jul 07 09:37:04 syslog debug 100348313 52516931: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 70815cbf9370581ea949dd2ab733e0c8remoteUUID - 0ca83f6b40745d6a9af5170b46d2e6fa 10.10.10.1 Jul 07 09:37:04 syslog debug 100348314 52516932: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/8192/sipSPI_Add_SessionIDtoTDContainer: Add sessionid to TD container 10.10.10.1 Jul 07 09:37:04 syslog debug 100348315 52516933: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_api_request_offer: Setting passthru/copylist event to 14 10.10.10.1 Jul 07 09:37:04 syslog debug 100348316 52516934: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/verbose/6144/sipSPI_ipip_read_peer_config_info: Mid-call Config info for peer leg is 0x2 10.10.10.1 Jul 07 09:37:04 syslog debug 100348317 52516935: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/verbose/6144/sipSPI_ipip_read_peer_config_info: Config info for peer leg is 0x0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348318 52516936: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: sipAuthInfo Initialized 10.10.10.1 Jul 07 09:37:04 syslog debug 100348319 52516937: Jul 7 09:37:04.171: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: Set Authentication Tag to Container 10.10.10.1 Jul 07 09:37:04 syslog debug 100348320 52516938: Jul 7 09:37:04.171: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC3737C550 10.10.10.1 Jul 07 09:37:04 syslog debug 100348321 52516939: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348322 52516940: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/critical/10240/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue 10.10.10.1 Jul 07 09:37:04 syslog debug 100348323 52516941: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/critical/8192/sipSPI_ipip_IsContentPassthruEnabled: - 0 10.10.10.1 Jul 07 09:37:04 syslog debug 100348324 52516942: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled 10.10.10.1 Jul 07 09:37:04 syslog debug 100348325 52516943: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/critical/4096/ccsip_api_request_offer: Unable to add passthru hdrs to container 10.10.10.1 Jul 07 09:37:04 syslog debug 100348326 52516944: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_REQ 10.10.10.1 Jul 07 09:37:04 syslog debug 100348327 52516945: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: dir:2, method:102, resp_code:0, container:7FBC474EFBF8 10.10.10.1 Jul 07 09:37:04 syslog debug 100348328 52516946: Jul 7 09:37:04.172: //176886/75CF6625B171/SIP/Info/info/8192/Session-Timer/sipSTSLExtractSessionExpiresHdr: 10.10.10.1 Jul 07 09:37:05 syslog debug 100349228 52517566: Jul 7 09:37:04.215: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7FBC47B394D0) counter, current msg->refCount = 1 10.10.10.1 Jul 07 09:37:05 syslog debug 100349229 52517567: Jul 7 09:37:05.364: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIHandleServerTCBTimerPop: Die timer Popped on TCB(0x7FBC373443D0) 10.10.10.1 Jul 07 09:37:05 syslog debug 100349230 52517568: Jul 7 09:37:05.365: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC372E2CA8 10.10.10.1 Jul 07 09:37:05 syslog debug 100349231 52517569: Jul 7 09:37:05.365: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC370FD378 10.10.10.1 Jul 07 09:37:05 syslog debug 100349232 52517570: Jul 7 09:37:05.365: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: Sizeof tcbQ: 5 10.10.10.1 Jul 07 09:37:05 syslog debug 100349233 52517571: Jul 7 09:37:05.365: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: DeQ'd TCB(0x7FBC373443D0) from EDB(0x7FBC47798E00) 10.10.10.1 Jul 07 09:37:05 syslog debug 100349234 52517572: Jul 7 09:37:05.365: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: EnQing TCB(0x7FBC373443D0) for reuse 10.10.10.1 Jul 07 09:37:06 syslog info 100349235 52517573: Jul 7 09:37:05.853: %FMANFP-6-IPACCESSLOGDP:fman_fp_image: list ACL-OUT-IN denied icmp X.X.X.X -> X.X.X.X (11/0), 15 packets 10.10.10.1 Jul 07 09:37:06 syslog info 100349236 52517574: Jul 7 09:37:05.853: %FMANFP-6-IPACCESSLOGDP:fman_fp_image: list ACL-OUT-IN denied icmp X.X.X.X -> X.X.X.X (11/0), 12 packets 10.10.10.1 Jul 07 09:37:07 syslog debug 100349237 52517575: Jul 7 09:37:07.446: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIHandleServerTCBTimerPop: Die timer Popped on TCB(0x7FBC37599480) 10.10.10.1 Jul 07 09:37:07 syslog debug 100349238 52517576: Jul 7 09:37:07.446: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC37346278 10.10.10.1 Jul 07 09:37:07 syslog debug 100349239 52517577: Jul 7 09:37:07.446: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC375C2990 10.10.10.1 Jul 07 09:37:07 syslog debug 100349240 52517578: Jul 7 09:37:07.446: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: Sizeof tcbQ: 4 10.10.10.1 Jul 07 09:37:07 syslog debug 100349241 52517579: Jul 7 09:37:07.447: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: DeQ'd TCB(0x7FBC37599480) from EDB(0x7FBC47798E00) 10.10.10.1 Jul 07 09:37:08 syslog debug 100349242 52517580: Jul 7 09:37:07.447: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: EnQing TCB(0x7FBC37599480) for reuse 10.10.10.1 Jul 07 09:37:08 syslog debug 100349243 52517581: Jul 7 09:37:08.093: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE42 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349244 52517582: Jul 7 09:37:08.093: << Pt:100 Evt:2 Pkt:09 00 A0 10.10.10.1 Jul 07 09:37:08 syslog debug 100349245 52517583: Jul 7 09:37:08.093: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349246 52517584: Jul 7 09:37:08.093: Pt:100 Evt:2 Pkt:09 00 A0 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349247 52517585: Jul 7 09:37:08.113: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE44 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349248 52517586: Jul 7 09:37:08.113: << Pt:100 Evt:2 Pkt:09 01 40 10.10.10.1 Jul 07 09:37:08 syslog debug 100349249 52517587: Jul 7 09:37:08.113: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349250 52517588: Jul 7 09:37:08.113: Pt:100 Evt:2 Pkt:09 01 40 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349251 52517589: Jul 7 09:37:08.133: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE46 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349252 52517590: Jul 7 09:37:08.133: << Pt:100 Evt:2 Pkt:09 01 E0 10.10.10.1 Jul 07 09:37:08 syslog debug 100349253 52517591: Jul 7 09:37:08.133: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349254 52517592: Jul 7 09:37:08.133: Pt:100 Evt:2 Pkt:09 01 E0 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349255 52517593: Jul 7 09:37:08.153: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE48 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349256 52517594: Jul 7 09:37:08.153: << Pt:100 Evt:2 Pkt:09 02 80 10.10.10.1 Jul 07 09:37:08 syslog debug 100349257 52517595: Jul 7 09:37:08.153: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349258 52517596: Jul 7 09:37:08.153: Pt:100 Evt:2 Pkt:09 02 80 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349259 52517597: Jul 7 09:37:08.173: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE4A timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349260 52517598: Jul 7 09:37:08.173: << Pt:100 Evt:2 Pkt:09 03 20 10.10.10.1 Jul 07 09:37:08 syslog debug 100349261 52517599: Jul 7 09:37:08.173: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349262 52517600: Jul 7 09:37:08.173: Pt:100 Evt:2 Pkt:09 03 20 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349263 52517601: Jul 7 09:37:08.193: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE4C timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349264 52517602: Jul 7 09:37:08.193: << Pt:100 Evt:2 Pkt:09 03 C0 10.10.10.1 Jul 07 09:37:08 syslog debug 100349265 52517603: Jul 7 09:37:08.193: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349266 52517604: Jul 7 09:37:08.193: Pt:100 Evt:2 Pkt:09 03 C0 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349267 52517605: Jul 7 09:37:08.213: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE4E timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349268 52517606: Jul 7 09:37:08.213: << Pt:100 Evt:2 Pkt:09 04 60 10.10.10.1 Jul 07 09:37:08 syslog debug 100349269 52517607: Jul 7 09:37:08.213: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349270 52517608: Jul 7 09:37:08.213: Pt:100 Evt:2 Pkt:09 04 60 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349271 52517609: Jul 7 09:37:08.233: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE50 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349272 52517610: Jul 7 09:37:08.233: << Pt:100 Evt:2 Pkt:09 05 00 10.10.10.1 Jul 07 09:37:08 syslog debug 100349273 52517611: Jul 7 09:37:08.233: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349274 52517612: Jul 7 09:37:08.233: Pt:100 Evt:2 Pkt:09 05 00 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349275 52517613: Jul 7 09:37:08.253: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE52 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349276 52517614: Jul 7 09:37:08.253: << Pt:100 Evt:2 Pkt:09 05 A0 10.10.10.1 Jul 07 09:37:08 syslog debug 100349277 52517615: Jul 7 09:37:08.253: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349278 52517616: Jul 7 09:37:08.253: Pt:100 Evt:2 Pkt:09 05 A0 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349279 52517617: Jul 7 09:37:08.273: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE54 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349280 52517618: Jul 7 09:37:08.273: << Pt:100 Evt:2 Pkt:09 06 40 10.10.10.1 Jul 07 09:37:08 syslog debug 100349281 52517619: Jul 7 09:37:08.273: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349282 52517620: Jul 7 09:37:08.273: Pt:100 Evt:2 Pkt:09 06 40 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349283 52517621: Jul 7 09:37:08.293: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE56 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349284 52517622: Jul 7 09:37:08.293: << Pt:100 Evt:2 Pkt:09 06 E0 10.10.10.1 Jul 07 09:37:08 syslog debug 100349285 52517623: Jul 7 09:37:08.293: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349286 52517624: Jul 7 09:37:08.293: Pt:100 Evt:2 Pkt:09 06 E0 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349287 52517625: Jul 7 09:37:08.313: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE58 timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349288 52517626: Jul 7 09:37:08.313: << Pt:100 Evt:2 Pkt:89 07 08 10.10.10.1 Jul 07 09:37:08 syslog debug 100349289 52517627: Jul 7 09:37:08.313: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349290 52517628: Jul 7 09:37:08.313: Pt:100 Evt:2 Pkt:89 07 08 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349291 52517629: Jul 7 09:37:08.313: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 16 10.10.10.1 Jul 07 09:37:08 syslog debug 100349292 52517630: Jul 7 09:37:08.333: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE5A timestamp 0xBFE1BE7 10.10.10.1 Jul 07 09:37:08 syslog debug 100349293 52517631: Jul 7 09:37:08.333: << Pt:100 Evt:2 Pkt:89 07 08 10.10.10.1 Jul 07 09:37:08 syslog debug 100349294 52517632: Jul 7 09:37:08.333: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:08 syslog debug 100349295 52517633: Jul 7 09:37:08.333: Pt:100 Evt:2 Pkt:89 07 08 >> 10.10.10.1 Jul 07 09:37:08 syslog debug 100349296 52517634: Jul 7 09:37:08.333: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xE5B timestamp 0xBFE1BE7 10.12.11.20 Jul 07 09:37:08 syslog debug 2250528 1230780: Jul 7 09:37:07.907: ISDN Se0/0/0:15 Q921: User RX <- RRp sapi=0 tei=0 nr=122 10.12.11.20 Jul 07 09:37:08 syslog debug 2250529 1230781: Jul 7 09:37:07.907: ISDN Se0/0/0:15 Q921: User TX -> RRf sapi=0 tei=0 nr=82 10.10.10.1 Jul 07 09:37:09 syslog debug 100349297 52517635: Jul 7 09:37:08.333: << Pt:100 Evt:2 Pkt:89 07 08 10.10.10.1 Jul 07 09:37:09 syslog debug 100349298 52517636: Jul 7 09:37:08.333: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:09 syslog debug 100349299 52517637: Jul 7 09:37:08.333: Pt:100 Evt:2 Pkt:89 07 08 >> 10.10.10.1 Jul 07 09:37:09 syslog debug 100349300 52517638: Jul 7 09:37:09.953: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xEAD timestamp 0xBFE5607 10.10.10.1 Jul 07 09:37:09 syslog debug 100349301 52517639: Jul 7 09:37:09.953: << Pt:100 Evt:2 Pkt:09 00 A0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349302 52517640: Jul 7 09:37:09.953: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:09 syslog debug 100349303 52517641: Jul 7 09:37:09.953: Pt:100 Evt:2 Pkt:09 00 A0 >> 10.10.10.1 Jul 07 09:37:09 syslog debug 100349304 52517642: Jul 7 09:37:09.953: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 16 10.10.10.1 Jul 07 09:37:09 syslog debug 100349305 52517643: Jul 7 09:37:09.973: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xEAF timestamp 0xBFE5607 10.10.10.1 Jul 07 09:37:09 syslog debug 100349306 52517644: Jul 7 09:37:09.973: << Pt:100 Evt:2 Pkt:09 01 40 10.10.10.1 Jul 07 09:37:09 syslog debug 100349307 52517645: Jul 7 09:37:09.973: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:09 syslog debug 100349308 52517646: Jul 7 09:37:09.973: Pt:100 Evt:2 Pkt:09 01 40 >> 10.10.10.1 Jul 07 09:37:09 syslog debug 100349309 52517647: Jul 7 09:37:09.993: s=VoIP d=DSP payload 0x64 ssrc 0x5A7ED5B4 sequence 0xEB1 timestamp 0xBFE5607 10.10.10.1 Jul 07 09:37:09 syslog debug 100349310 52517648: Jul 7 09:37:09.993: << Pt:100 Evt:2 Pkt:09 01 E0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349311 52517649: Jul 7 09:37:09.993: s=DSP d=VoIP payload 0x64 ssrc 0xBC7F0000 sequence 0x0 timestamp 0xD8DD4931 10.10.10.1 Jul 07 09:37:09 syslog debug 100349312 52517650: Jul 7 09:37:09.993: Pt:100 Evt:2 Pkt:09 01 E0 >> 10.10.10.1 Jul 07 09:37:09 syslog debug 100349313 52517651: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode: encode_data=0x7FBC44CA76F0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349314 52517652: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode_context_create: chunk_size=2k, max_allowed=16k 10.10.10.1 Jul 07 09:37:09 syslog debug 100349315 52517653: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode_context_create: context=0x7FBC477E2380, mp=0x7FBC37617400 10.10.10.1 Jul 07 09:37:09 syslog debug 100349316 52517654: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_build_response: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349317 52517655: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode: malloc xml_buf=0x7FBC40404CC0, length=112 10.10.10.1 Jul 07 09:37:09 syslog debug 100349318 52517656: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_build_response: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349319 52517657: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_build_response: length=73, buffp=0x7FBC40404D2F 10.10.10.1 Jul 07 09:37:09 syslog debug 100349320 52517658: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode: rc=0, encoded str= 10.10.10.1 Jul 07 09:37:09 syslog debug 100349321 52517659: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/KPML/Builder/kpml_encode_context_free: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349322 52517660: Jul 7 09:37:09.993: kpml_encode_context_free:mem_mgr_mempool_free: mem_refcnt(7FBC37617400)=0 - mempool cleanup 10.10.10.1 Jul 07 09:37:09 syslog debug 100349323 52517661: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/SIP/Event/ccsip_call_notify_event: Queued event from SIP SPI : SIPSPI_EV_CC_NOTIFY 10.10.10.1 Jul 07 09:37:09 syslog debug 100349324 52517662: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 for event 204 10.10.10.1 Jul 07 09:37:09 syslog debug 100349325 52517663: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_NOTIFY] received in State [SUBSCRIBE_STATE_ACTIVE] 10.10.10.1 Jul 07 09:37:09 syslog debug 100349326 52517664: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_tcb: Created TCB: 0x7FBC37216DB8 10.10.10.1 Jul 07 09:37:09 syslog debug 100349327 52517665: Jul 7 09:37:09.993: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/act_dialog_active_notify_event: TCB 0x7FBC37216DB8's parent is 0x7FBC49874D48 10.10.10.1 Jul 07 09:37:09 syslog debug 100349328 52517666: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateTCBRequestInfo: Dialog Transaction Address 10.2.21.11,Port 5060, Port Present: TRUE, Transport 1, FQDN 94643462899744 10.10.10.1 Jul 07 09:37:09 syslog debug 100349329 52517667: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC375C2990 with refCount = 1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349330 52517668: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:09 syslog debug 100349331 52517669: Jul 7 09:37:09.994: //0/000000000000/SIP/Info/info/4096/sipSPICreateEventHdrBuffer: event name kpml 10.10.10.1 Jul 07 09:37:09 syslog debug 100349332 52517670: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetDialogParamStr: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349333 Invalid parameters 10.10.10.1 Jul 07 09:37:09 syslog debug 100349334 52517671: Jul 7 09:37:09.994: //0/000000000000/SIP/Info/notify/4096/sipSPICreateNotify: Event 11, content_type application/kpml-response+xml 10.10.10.1 Jul 07 09:37:09 syslog debug 100349335 52517672: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 113 10.10.10.1 Jul 07 09:37:09 syslog debug 100349336 52517673: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/act_dialog_active_notify_event: TCB 0x7FBC37216DB8's method is 111 10.10.10.1 Jul 07 09:37:09 syslog debug 100349337 52517674: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIUpdateTCBPackage: Current tcb(0x7FBC37216DB8)->package = 0x7FBC375C2990, refCount = 1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349338 52517675: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bK7C605157E 10.10.10.1 Jul 07 09:37:09 syslog debug 100349339 52517676: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIQueTcb: Q'd TCB(0x7FBC37216DB8) on EDB(0x7FBC48792B08) 10.10.10.1 Jul 07 09:37:09 syslog debug 100349340 52517677: Jul 7 09:37:09.994: //0/000000000000/SIP/Info/verbose/4096/sipSPIQueTcb: There are 5 TCB's on tcbQ 10.10.10.1 Jul 07 09:37:09 syslog debug 100349341 52517678: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPITCBSendPackage: Sending Package on tcb: 0x7FBC37216DB8 and parent: 0x7FBC49874D48 10.10.10.1 Jul 07 09:37:09 syslog debug 100349342 52517679: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC375C2990) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:09 syslog debug 100349343 52517680: Jul 7 09:37:09.994: //0/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x7FBC375C2990, addr=10.2.21.11, port=5060, sentBy_port=0, local_addr=10.10.10.1, is_req=1, transport=1, switch=0, callBack=0x5613E20578E0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349344 52517681: Jul 7 09:37:09.994: //0/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349345 52517682: Jul 7 09:37:09.994: //0/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349346 52517683: Jul 7 09:37:09.994: //0/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 10.10.10.1 Jul 07 09:37:09 syslog debug 100349347 52517684: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.2.21.11, rport:5060 with laddr:10.10.10.1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349348 52517685: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x7FBC49874D48 is already on connection=0x7FBC3C4E7F40 context_list 10.10.10.1 Jul 07 09:37:09 syslog debug 100349349 52517686: Jul 7 09:37:09.994: //0/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x7FBC375C2990 10.10.10.1 Jul 07 09:37:09 syslog debug 100349350 52517687: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7FBC375C2990, addr=10.2.21.11, port=5060, local_addr=10.10.10.1, connId=6 vrfid=0 for UDP 10.10.10.1 Jul 07 09:37:09 syslog debug 100349351 52517688: Jul 7 09:37:09.994: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIKickoffTcbReTxTimer: Kicking off ReTx Timer for Client TCB (0x7FBC37216DB8) with 10 retries and 500 milliseconds initial retx 10.10.10.1 Jul 07 09:37:09 syslog debug 100349352 52517689: Jul 7 09:37:09.995: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_new_timer_context: Created timer context(0x7FBC4771AC40) 10.10.10.1 Jul 07 09:37:09 syslog debug 100349353 52517690: Jul 7 09:37:09.995: //0/000000000000/SIP/Msg/ccsipDisplayMsg: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349354 Sent: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349355 NOTIFY sip:10.2.21.11:5060 SIP/2.0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349356 Via: SIP/2.0/UDP 10.10.10.1:5060;branch=z9hG4bK7C605157E 10.10.10.1 Jul 07 09:37:09 syslog debug 100349357 From: ;tag=D805110D-248E 10.10.10.1 Jul 07 09:37:09 syslog debug 100349358 To: ;tag=2181784~b81a956d-b4eb-4321-807f-845e8fa3a514-41576574 10.10.10.1 Jul 07 09:37:09 syslog debug 100349359 Call-ID: 75D30FAD-438F11E6-B1779F34-3186D190@10.10.10.1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349360 CSeq: 108 NOTIFY 10.10.10.1 Jul 07 09:37:09 syslog debug 100349361 Max-Forwards: 70 10.10.10.1 Jul 07 09:37:09 syslog debug 100349362 Date: Thu, 07 Jul 2016 15:37:09 GMT 10.10.10.1 Jul 07 09:37:09 syslog debug 100349363 User-Agent: Cisco-SIPGateway/IOS-Denali 10.10.10.1 Jul 07 09:37:09 syslog debug 100349364 Event: kpml 10.10.10.1 Jul 07 09:37:09 syslog debug 100349737 Nego. Codec payload : 0 (tx), 0 (rx) 10.10.10.1 Jul 07 09:37:09 syslog debug 100349738 Negotiated DTMF relay : inband-voice 10.10.10.1 Jul 07 09:37:09 syslog debug 100349739 Negotiated NTE payload : 0 (tx), 0 (rx) 10.10.10.1 Jul 07 09:37:09 syslog debug 100349740 Negotiated CN payload : 0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349741 Media Srce Addr/Port : [10.10.10.1]:42968 10.10.10.1 Jul 07 09:37:09 syslog debug 100349742 Media Dest Addr/Port : [0.0.0.0]:26490 10.10.10.1 Jul 07 09:37:09 syslog debug 100349743 52517936: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/info/1/is_call_held: Remote media IP addr: 0.0.0.0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349744 52517937: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Media/ccsip_api_request_offer: Call has been put on hold 10.10.10.1 Jul 07 09:37:09 syslog debug 100349745 52517938: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIProcessMediaChanges: sipSPIProcessMediaChanges 10.10.10.1 Jul 07 09:37:09 syslog debug 100349746 52517939: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIProcessMediaChanges: DSPDNLD Reqd is true. Call DoCapsInd 10.10.10.1 Jul 07 09:37:09 syslog debug 100349747 52517940: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_iwf_handle_generic_event: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349748 52517941: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_SENT_SDP, event:E_SIP_IWF_EV_LOCAL_CAPS_IND 10.10.10.1 Jul 07 09:37:09 syslog debug 100349749 52517942: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/info/4096/sip_iwf_common_ignore_hdlr: 10.10.10.1 Jul 07 09:37:09 syslog debug 100349750 52517943: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 10.10.10.1 Jul 07 09:37:09 syslog debug 100349751 52517944: Jul 7 09:37:10.022: //176886/75CF6625B171/SIP/Info/info/1/sipSPIIncomingCallSDP: SDP already there use old SDP and update media if needed 10.10.10.1 Jul 07 09:37:09 syslog debug 100349752 52517945: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIUpdateSrcSdpFixedPart: RTP port already reserved for stream 1, src_port=42968 10.10.10.1 Jul 07 09:37:09 syslog debug 100349753 52517946: Jul 7 09:37:10.023: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/sipSPISetMediaDirectionForStream: Setting Media direction INACTIVE for stream 1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349754 52517947: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/info/1/sipSPIUpdateSrcSdpVariablePart: Setting stream 1 portnum to 42968 10.10.10.1 Jul 07 09:37:09 syslog debug 100349755 52517948: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/1/sipSPIUpdateSrcSdpVariablePart: SIP update src sdp, negoitated codec 5, payload type 0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349756 52517949: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/131072/ccsip_api_request_offer: Deffering QoS reservation for MID-CALL Invite 10.10.10.1 Jul 07 09:37:09 syslog debug 100349757 52517950: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/State/sipSPIChangeState: 0x7FBC44FD56E8 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_MIDCALL_LOCAL_RESP_PENDING, SUBSTATE_NONE) 10.10.10.1 Jul 07 09:37:09 syslog debug 100349758 52517951: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPIHandleIncomingMidCallInvite: ccsip_api_request_offer returned: SIP_SUCCESS 10.10.10.1 Jul 07 09:37:09 syslog debug 100349759 52517952: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [2] 10.10.10.1 Jul 07 09:37:09 syslog debug 100349760 52517953: Jul 7 09:37:10.023: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC377982C8 with refCount = 1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349761 52517954: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIGetTDSessionID: Get peer leg session-ID local uuid - 0ca83f6b40745d6a9af5170b46d2e6fa remote uuid - 70815cbf9370581ea949dd2ab733e0c8 10.10.10.1 Jul 07 09:37:09 syslog debug 100349762 52517955: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 0ca83f6b40745d6a9af5170b46d2e6fa remoteUUID - 70815cbf9370581ea949dd2ab733e0c8 10.10.10.1 Jul 07 09:37:09 syslog debug 100349763 52517956: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 70815cbf9370581ea949dd2ab733e0c8;remote=0ca83f6b40745d6a9af5170b46d2e6fa 10.10.10.1 Jul 07 09:37:09 syslog debug 100349764 52517957: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP 10.10.10.1 Jul 07 09:37:09 syslog debug 100349765 52517958: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:100, container:7FBC474E4388 10.10.10.1 Jul 07 09:37:09 syslog debug 100349766 52517959: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/critical/8192/Session-Timer/sipSTSLValidateSessRefreshMsg: Ignoring 1xx response for session timer processing 10.10.10.1 Jul 07 09:37:09 syslog debug 100349767 52517960: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/8192/sipSPIPresendProcessing: Presend Processing called for 3 event 10.10.10.1 Jul 07 09:37:09 syslog debug 100349768 52517961: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container 10.10.10.1 Jul 07 09:37:09 syslog debug 100349769 52517962: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/notify/4096/sipSPI_ipip_GetPassthruCopyListDataFromTdContainer: Could not get any elements from TD Container 10.10.10.1 Jul 07 09:37:09 syslog debug 100349770 52517963: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPIPushSubsqTransMsgContainerIntoHolder: Response Container Holder is above threshold...trimming 10.10.10.1 Jul 07 09:37:09 syslog debug 100349771 52517964: Jul 7 09:37:10.023: //176886/75CF6625B171/SIP/Info/verbose/1024/sipSPIAddWarningHeader: For 100 Trying skip adding warning header. 10.10.10.1 Jul 07 09:37:09 syslog debug 100349772 52517965: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Info/info/4096/sipSPISendInviteResponse: Associated container=0x7FBC474E4388 to Invite Response 100 10.10.10.1 Jul 07 09:37:09 syslog debug 100349773 52517966: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPIAppHandleContainerBody: sipSPIAppHandleContainerBody len 0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349774 52517967: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Transport/sipSPITransportSendMessage: msg=0x7FBC377982C8, addr=10.2.21.11, port=5060, sentBy_port=5060, local_addr=10.10.10.1, is_req=0, transport=1, switch=0, callBack=0x0 10.10.10.1 Jul 07 09:37:09 syslog debug 100349775 52517968: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349776 52517969: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:09 syslog debug 100349777 52517970: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 10.10.10.1 Jul 07 09:37:09 syslog debug 100349778 52517971: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Transport/sipTransportLogicSendMsg: Trying to send resp=0x7FBC377982C8 to default port=5060 10.10.10.1 Jul 07 09:37:10 syslog debug 100349779 52517972: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.2.21.11, rport:5060 with laddr:10.10.10.1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349780 52517973: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x7FBC44FD56E8 is already on connection=0x7FBC3C4E7F40 context_list 10.10.10.1 Jul 07 09:37:10 syslog debug 100349781 52517974: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Transport/sipTransportLogicSendMsg: Connection obtained...sending msg=0x7FBC377982C8 10.10.10.1 Jul 07 09:37:10 syslog debug 100349782 52517975: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7FBC377982C8, addr=10.2.21.11, port=5060, local_addr=10.10.10.1, connId=6 vrfid=0 for UDP 10.10.10.1 Jul 07 09:37:10 syslog debug 100349783 52517976: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Info/verbose/4608/sipSPIProcessContactInfo: Previous Hop 10.2.21.11:5060 10.10.10.1 Jul 07 09:37:10 syslog debug 100349784 52517977: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7FBC375C2990) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:10 syslog debug 100349785 52517978: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 13 for event 70 10.10.10.1 Jul 07 09:37:10 syslog debug 100349786 52517979: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_SDP_RCVD_AWAIT_SENT_SDP, event:E_SIP_IWF_EV_UPDATE_STREAM_CONTEXT 10.10.10.1 Jul 07 09:37:10 syslog debug 100349787 52517980: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:new_container:sip_iwf_main_container 10.10.10.1 Jul 07 09:37:10 syslog debug 100349788 52517981: Jul 7 09:37:10.024: //176886/75CF6625B171/SIP/Info/verbose/4096/ccsip_iwf_process_event: IWF - cnfsm ret 2 10.10.10.1 Jul 07 09:37:10 syslog debug 100349789 52517982: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 for event 205 10.10.10.1 Jul 07 09:37:10 syslog debug 100349790 52517983: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_SUBSCRIBE] received in State [SUBSCRIBE_STATE_ACTIVE] 10.10.10.1 Jul 07 09:37:10 syslog debug 100349791 52517984: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_tcb: Created TCB: 0x7FBC37704F58 10.10.10.1 Jul 07 09:37:10 syslog debug 100349792 52517985: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateTCBRequestInfo: Dialog Transaction Address 10.2.21.11,Port 5060, Port Present: TRUE, Transport 1, FQDN 94643462899744 10.10.10.1 Jul 07 09:37:10 syslog debug 100349793 52517986: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/act_dialog_active_subscribe_event: TCB 0x7FBC37704F58's parent is 0x7FBC49874D48 10.10.10.1 Jul 07 09:37:10 syslog debug 100349794 52517987: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC370FD378 with refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349795 52517988: Jul 7 09:37:10.024: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:10 syslog debug 100349796 52517989: Jul 7 09:37:10.025: //0/000000000000/SIP/Info/info/4096/sipSPICreateEventHdrBuffer: event name kpml 10.10.10.1 Jul 07 09:37:10 syslog debug 100349797 52517990: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetDialogParamStr: 10.10.10.1 Jul 07 09:37:10 syslog debug 100349798 Invalid parameters 10.10.10.1 Jul 07 09:37:10 syslog debug 100349799 52517991: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/act_dialog_active_subscribe_event: TCB 0x7FBC37704F58's method is 112 10.10.10.1 Jul 07 09:37:10 syslog debug 100349800 52517992: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIUpdateTCBPackage: Current tcb(0x7FBC37704F58)->package = 0x7FBC370FD378, refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349801 52517993: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bK7C606C6 10.10.10.1 Jul 07 09:37:10 syslog debug 100349802 52517994: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIQueTcb: Q'd TCB(0x7FBC37704F58) on EDB(0x7FBC48792B08) 10.10.10.1 Jul 07 09:37:10 syslog debug 100349803 52517995: Jul 7 09:37:10.025: //0/000000000000/SIP/Info/verbose/4096/sipSPIQueTcb: There are 5 TCB's on tcbQ 10.10.10.1 Jul 07 09:37:10 syslog debug 100349804 52517996: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPITCBSendPackage: Sending Package on tcb: 0x7FBC37704F58 and parent: 0x7FBC49874D48 10.10.10.1 Jul 07 09:37:10 syslog debug 100349805 52517997: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC370FD378) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:10 syslog debug 100349806 52517998: Jul 7 09:37:10.025: //0/000000000000/SIP/Transport/sipSPITransportSendMessage: msg=0x7FBC370FD378, addr=10.2.21.11, port=5060, sentBy_port=0, local_addr=10.10.10.1, is_req=1, transport=1, switch=0, callBack=0x5613E20578E0 10.10.10.1 Jul 07 09:37:10 syslog debug 100349807 52517999: Jul 7 09:37:10.025: //0/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349808 52518000: Jul 7 09:37:10.025: //0/000000000000/SIP/Info/info/2048/sipSPIGetExtensionCfg: SIP extension config:1, check sys cfg:1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349809 52518001: Jul 7 09:37:10.025: //0/000000000000/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediately 10.10.10.1 Jul 07 09:37:10 syslog debug 100349810 52518002: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerGetConnection: connection required for raddr:10.2.21.11, rport:5060 with laddr:10.10.10.1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349811 52518003: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Transport/sipInstanceGetConnectionId: gcb=0x7FBC49874D48 is already on connection=0x7FBC3C4E7F40 context_list 10.10.10.1 Jul 07 09:37:10 syslog debug 100349812 52518004: Jul 7 09:37:10.025: //0/000000000000/SIP/Transport/sipTransportLogicSendMsg: Set to send the msg=0x7FBC370FD378 10.10.10.1 Jul 07 09:37:10 syslog debug 100349813 52518005: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x7FBC370FD378, addr=10.2.21.11, port=5060, local_addr=10.10.10.1, connId=6 vrfid=0 for UDP 10.10.10.1 Jul 07 09:37:10 syslog debug 100349814 52518006: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/sipSPIKickoffTcbReTxTimer: Kicking off ReTx Timer for Client TCB (0x7FBC37704F58) with 6 retries and 500 milliseconds initial retx 10.10.10.1 Jul 07 09:37:10 syslog debug 100349815 52518007: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_new_timer_context: Created timer context(0x7FBC47715240) 10.10.10.1 Jul 07 09:37:10 syslog debug 100349816 52518008: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 for event 29 10.10.10.1 Jul 07 09:37:10 syslog debug 100349817 52518009: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/info/4096/act_handle_app_media_event: method = 102 state = 16 10.10.10.1 Jul 07 09:37:10 syslog debug 100349818 52518010: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/info/4096/act_handle_app_media_event: Received media sip event SIP_RESPONSE_ANSWER 10.10.10.1 Jul 07 09:37:10 syslog debug 100349819 52518011: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/info/1/sipSPIProcessHoldTimerForCall: Media IP Addr 0.0.0.0, RTCP Type 4 10.10.10.1 Jul 07 09:37:10 syslog debug 100349820 52518012: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/info/4096/sipSPISetHoldTimer: Starting hold timer at 2880 minutes 10.10.10.1 Jul 07 09:37:10 syslog debug 100349821 52518013: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPISendInviteResponse: Dialog State: [2] 10.10.10.1 Jul 07 09:37:10 syslog debug 100349822 52518014: Jul 7 09:37:10.025: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC37346278 with refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100349823 52518015: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIGetTDSessionID: Get peer leg session-ID local uuid - 0ca83f6b40745d6a9af5170b46d2e6fa remote uuid - 70815cbf9370581ea949dd2ab733e0c8 10.10.10.1 Jul 07 09:37:10 syslog debug 100349824 52518016: Jul 7 09:37:10.025: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPIAddSessionID: localUUID - 0ca83f6b40745d6a9af5170b46d2e6fa remoteUUID - 70815cbf9370581ea949dd2ab733e0c8 10.10.10.1 Jul 07 09:37:10 syslog debug 100349825 52518017: Jul 7 09:37:10.026: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPIAddSessionID: Session-ID header 0ca83f6b40745d6a9af5170b46d2e6fa;remote=70815cbf9370581ea949dd2ab733e0c8 10.10.10.1 Jul 07 09:37:10 syslog debug 100349826 52518018: Jul 7 09:37:10.026: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: Event: E_STSL_SESSION_REFRESH_RESP 10.10.10.1 Jul 07 09:37:10 syslog debug 100349827 52518019: Jul 7 09:37:10.026: //176886/75CF6625B171/SIP/Event/Session-Timer/sipSTSLMain: dir:1, method:102, resp_code:200, container:7FBC474E9AE0 10.10.10.1 Jul 07 09:37:10 syslog debug 100349828 52518020: Jul 7 09:37:10.026: //176886/75CF6625B171/SIP/Info/info/8192/Session-Timer/sipSTSLSRRespSend: Session expires header is received in the request, starting the session timer 10.10.10.1 Jul 07 09:37:10 syslog debug 100349829 52518021: Jul 7 09:37:10.026: //176886/75CF6625B171/SIP/Info/info/8192/Session-Timer/sipSTSLStartSessionTimer: Started Session Expiry Timer with duration:1768000 10.10.10.1 Jul 07 09:37:10 syslog debug 100351048 52518827: Jul 7 09:37:10.088: //176886/75CF6625B171/SIP/Media/sipSPIUpdateRtpSession: Stun packet reporting for ICE disabled in sipSPIUpdateRtpSession 10.10.10.1 Jul 07 09:37:10 syslog debug 100351049 52518828: Jul 7 09:37:10.088: //176886/75CF6625B171/SIP/Media/sipSPIUpdateRtpSession: stun is disabled for stream:7FBC37CCE408 10.10.10.1 Jul 07 09:37:10 syslog debug 100351050 52518829: Jul 7 09:37:10.088: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:next_state:CNFSM_NO_STATE_CHANGE 10.10.10.1 Jul 07 09:37:10 syslog debug 100351051 52518830: Jul 7 09:37:11.063: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC3737C550 with refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351052 52518831: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC37016FE8 with refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351053 52518832: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [10.2.21.11]:5060, local_address:[ - ] 10.10.10.1 Jul 07 09:37:10 syslog debug 100351054 52518833: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC3737C550 10.10.10.1 Jul 07 09:37:10 syslog debug 100351055 52518834: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351056 52518835: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351057 52518836: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: 10.10.10.1 Jul 07 09:37:10 syslog debug 100351058 Received: 10.10.10.1 Jul 07 09:37:10 syslog debug 100351059 INVITE sip:8015408813@10.10.10.1:5060 SIP/2.0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351060 Via: SIP/2.0/UDP 10.2.21.11:5060;branch=z9hG4bKf67e649035e33 10.10.10.1 Jul 07 09:37:10 syslog debug 100351061 From: ;tag=2181784~b81a956d-b4eb-4321-807f-845e8fa3a514-41576574 10.10.10.1 Jul 07 09:37:10 syslog debug 100351062 To: ;tag=D805110D-248E 10.10.10.1 Jul 07 09:37:10 syslog debug 100351063 Date: Thu, 07 Jul 2016 15:37:11 GMT 10.10.10.1 Jul 07 09:37:10 syslog debug 100351064 Call-ID: 75D30FAD-438F11E6-B1779F34-3186D190@10.10.10.1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351065 Supported: 100rel,timer,resource-priority,replaces 10.10.10.1 Jul 07 09:37:10 syslog debug 100351066 Min-SE: 1800 10.10.10.1 Jul 07 09:37:10 syslog debug 100351067 Cisco-Guid: 1976526373-1133449702-2977013556-0830919056 10.10.10.1 Jul 07 09:37:10 syslog debug 100351068 User-Agent: Cisco-CUCM9.1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351069 Allow: INVITE, 10.10.10.1 Jul 07 09:37:10 syslog debug 100351070 OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY 10.10.10.1 Jul 07 09:37:10 syslog debug 100351071 CSeq: 114 INVITE 10.10.10.1 Jul 07 09:37:10 syslog debug 100351072 Max-Forwards: 70 10.10.10.1 Jul 07 09:37:10 syslog debug 100351073 Expires: 180 10.10.10.1 Jul 07 09:37:10 syslog debug 100351074 Allow-Events: presence 10.10.10.1 Jul 07 09:37:10 syslog debug 100351075 Supported: X-cisco-srtp-fallback 10.10.10.1 Jul 07 09:37:10 syslog debug 100351076 Supported: Geolocation 10.10.10.1 Jul 07 09:37:10 syslog debug 100351077 Session-Expires: 1800;refresher=uac 10.10.10.1 Jul 07 09:37:10 syslog debug 100351078 P-Asserted-Identity: "ZIJA Contact Center" 10.10.10.1 Jul 07 09:37:10 syslog debug 100351079 Remote-Party-ID: "ZIJA Contact Center" ;party=calling;screen=yes;privacy=off 10.10.10.1 Jul 07 09:37:10 syslog debug 100351080 Contact: 10.10.10.1 Jul 07 09:37:10 syslog debug 100351081 Content-Length: 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351082 10.10.10.1 Jul 07 09:37:10 syslog debug 100351083 52518837: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog 10.10.10.1 Jul 07 09:37:10 syslog debug 100351084 52518838: Jul 7 09:37:11.064: //176886/75CF6625B171/SIP/Info/info/4096/sipSPICheckFromToRequest: Found matching CB 7FBC44FD56E8 10.10.10.1 Jul 07 09:37:10 syslog debug 100351085 52518839: Jul 7 09:37:11.064: //176886/75CF6625B171/SIP/Info/info/4096/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table 10.10.10.1 Jul 07 09:37:10 syslog debug 100351086 52518840: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC37016FE8) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:10 syslog debug 100351087 52518841: Jul 7 09:37:11.064: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351088 52518842: Jul 7 09:37:11.065: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:10 syslog debug 100351089 52518843: Jul 7 09:37:11.065: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351090 52518844: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sact_active_new_message_request: Case of Mid-Call INVITE/UPDATE in ACTIVE STATE 10.10.10.1 Jul 07 09:37:10 syslog debug 100351091 52518845: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/36864/sipSPIUpdateIdentity: MF: Free is previous PAI.. 10.10.10.1 Jul 07 09:37:10 syslog debug 100351092 52518846: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/36864/sipSPIUpdateIdentity: MF: PAI url is present & make a copy.. 10.10.10.1 Jul 07 09:37:10 syslog debug 100351093 52518847: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Name to ZIJA Contact Center 10.10.10.1 Jul 07 09:37:10 syslog debug 100351094 52518848: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: PAI genUrl is present.. 10.10.10.1 Jul 07 09:37:10 syslog debug 100351095 52518849: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: SIP->PSTN: Setting Calling Number to 4428 10.10.10.1 Jul 07 09:37:10 syslog debug 100351096 52518850: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIUpdateIdentity: PAI host = 10.2.21.11 10.10.10.1 Jul 07 09:37:10 syslog debug 100351097 52518851: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/4096/sipSPISetCallingInfo: calling host comes from From: 10.2.21.11 10.10.10.1 Jul 07 09:37:10 syslog debug 100351098 52518852: Jul 7 09:37:11.065: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC377982C8 10.10.10.1 Jul 07 09:37:10 syslog debug 100351099 52518853: Jul 7 09:37:11.065: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC37016FE8) counter, current msg->refCount = 3 10.10.10.1 Jul 07 09:37:10 syslog debug 100351100 52518854: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x7FBC44FD56E8)->last_request = 0x7FBC37016FE8, refCount = 3 10.10.10.1 Jul 07 09:37:10 syslog debug 100351101 52518855: Jul 7 09:37:11.065: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIHandleIncomingMidCallInvite: Delayed Media in MidCall Invite 10.10.10.1 Jul 07 09:37:10 syslog debug 100351102 52518856: Jul 7 09:37:11.066: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container 10.10.10.1 Jul 07 09:37:10 syslog debug 100351103 52518857: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/info/8192/sipSPIProcessSessionID: SessionID header not present - create uuid 10.10.10.1 Jul 07 09:37:10 syslog debug 100351104 52518858: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/info/8192/sipSPICreateAndPopulateSessionID: Establish dialog 10.10.10.1 Jul 07 09:37:10 syslog debug 100351105 52518859: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPI_Add_SessionIDtoTDContainer: localUUID - 70815cbf9370581ea949dd2ab733e0c8remoteUUID - 0ca83f6b40745d6a9af5170b46d2e6fa 10.10.10.1 Jul 07 09:37:10 syslog debug 100351106 52518860: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/info/8192/sipSPI_Add_SessionIDtoTDContainer: Add sessionid to TD container 10.10.10.1 Jul 07 09:37:10 syslog debug 100351107 52518861: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_api_request_offer: Setting passthru/copylist event to 14 10.10.10.1 Jul 07 09:37:10 syslog debug 100351108 52518862: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/verbose/6144/sipSPI_ipip_read_peer_config_info: Mid-call Config info for peer leg is 0x2 10.10.10.1 Jul 07 09:37:10 syslog debug 100351109 52518863: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/verbose/6144/sipSPI_ipip_read_peer_config_info: Config info for peer leg is 0x0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351110 52518864: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: sipAuthInfo Initialized 10.10.10.1 Jul 07 09:37:10 syslog debug 100351111 52518865: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPICopyAuthenticationHeadersToContainer: Set Authentication Tag to Container 10.10.10.1 Jul 07 09:37:10 syslog debug 100351112 52518866: Jul 7 09:37:11.066: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC370E8008 10.10.10.1 Jul 07 09:37:10 syslog debug 100351113 52518867: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351114 52518868: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/critical/10240/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue 10.10.10.1 Jul 07 09:37:10 syslog debug 100351115 52518869: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/critical/8192/sipSPI_ipip_IsContentPassthruEnabled: - 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351116 52518870: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/notify/8192/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled 10.10.10.1 Jul 07 09:37:10 syslog debug 100351117 52518871: Jul 7 09:37:11.066: //176886/75CF6625B171/SIP/Info/critical/4096/ccsip_api_request_offer: Unable to add passthru hdrs to container 10.10.10.1 Jul 07 09:37:10 syslog debug 100351332 Content-Type: application/sdp 10.10.10.1 Jul 07 09:37:10 syslog debug 100351333 Content-Length: 178 10.10.10.1 Jul 07 09:37:10 syslog debug 100351334 10.10.10.1 Jul 07 09:37:10 syslog debug 100351335 v=0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351336 o=CiscoSystemsCCM-SIP 2181784 7 IN IP4 10.2.21.11 10.10.10.1 Jul 07 09:37:10 syslog debug 100351337 s=SIP Call 10.10.10.1 Jul 07 09:37:10 syslog debug 100351338 c=IN IP4 10.2.21.14 10.10.10.1 Jul 07 09:37:10 syslog debug 100351339 b=TIAS:64000 10.10.10.1 Jul 07 09:37:10 syslog debug 100351340 b=AS:64 10.10.10.1 Jul 07 09:37:10 syslog debug 100351341 t=0 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351342 m=audio 26492 RTP/AVP 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351343 a=rtpmap:0 PCMU/8000 10.10.10.1 Jul 07 09:37:10 syslog debug 100351344 a=ptime:20 10.10.10.1 Jul 07 09:37:10 syslog debug 100351345 52519008: Jul 7 09:37:11.079: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog 10.10.10.1 Jul 07 09:37:10 syslog debug 100351346 52519009: Jul 7 09:37:11.079: //176886/75CF6625B171/SIP/Info/info/4096/sipSPICheckFromToRequest: Found matching CB 7FBC44FD56E8 10.10.10.1 Jul 07 09:37:10 syslog debug 100351347 52519010: Jul 7 09:37:11.079: //176886/75CF6625B171/SIP/Info/info/4096/sipSPILocateInviteDialogCCB: ****Found CCB in UAC table 10.10.10.1 Jul 07 09:37:10 syslog debug 100351348 52519011: Jul 7 09:37:11.079: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC377982C8) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:10 syslog debug 100351349 52519012: Jul 7 09:37:11.079: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351350 52519013: Jul 7 09:37:11.079: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:10 syslog debug 100351351 52519014: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address 10.2.21.11,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351352 52519015: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIProcessIncomingAckForMidCall: Processing MidCall ACK 10.10.10.1 Jul 07 09:37:10 syslog debug 100351353 52519016: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/State/sipSPIChangeState: 0x7FBC44FD56E8 : State change from (STATE_MIDCALL_LOCAL_RESP_PENDING, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_NONE) 10.10.10.1 Jul 07 09:37:10 syslog debug 100351354 52519017: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/4096/sipSPIProcessIncomingAckForMidCall: Transaction Complete. Lock on Facilities released.. 10.10.10.1 Jul 07 09:37:10 syslog debug 100351355 52519018: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7FBC37016FE8) counter, current msg->refCount = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351356 52519019: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/9216/sipSPIProcessNotifyCallInfoHeader: Processing Unsolicited Notifyfrom ACK SDP even though Call-Info header not there in ACK 10.10.10.1 Jul 07 09:37:10 syslog debug 100351357 52519020: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIDoMediaNegotiation: Number of m-lines = 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351358 52519021: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIValidateConnectionAddress: Dest port = 26492 10.10.10.1 Jul 07 09:37:10 syslog debug 100351359 SIP: Attribute mid, level 1 instance 1 not found. 10.10.10.1 Jul 07 09:37:10 syslog debug 100351360 52519022: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351361 52519023: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 10.10.10.1 Jul 07 09:37:10 syslog debug 100351362 52519024: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.10.10.1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351363 52519025: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec (g711ulaw) Negotiation Successful on Static Payload for m-line 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351364 52519026: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/verbose/1/sipSPIDoPtimeNegotiation: One ptime attribute found - value:20 10.10.10.1 Jul 07 09:37:10 syslog debug 100351365 52519027: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_ptime_to_codec_bytes: Values :Codec: g711ulaw ptime :20, codecbytes: 160 10.10.10.1 Jul 07 09:37:10 syslog debug 100351366 52519028: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Info/notify/1/convert_codec_bytes_to_ptime: Values :Codec: g711ulaw codecbytes :160, ptime: 20 10.10.10.1 Jul 07 09:37:10 syslog debug 100351367 52519029: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Media/sipSPIDoPtimeNegotiation: Offered ptime:20, Negotiated ptime:20 Negotiated codec bytes: 160 for codec g711ulaw 10.10.10.1 Jul 07 09:37:10 syslog debug 100351368 52519030: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/info/8/sipSPISetFaxFlags: FAX_PASSTHROUGH = 0, END_FAX_PASSTHROUGH = 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351369 52519031: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/info/1/sipSPIDoDTMFRelayNegotiation: m-line index 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351370 52519032: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Info/info/8193/sip_sdp_get_modem_relay_cap_params: ptype is 0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351371 52519033: Jul 7 09:37:11.080: //-1/xxxxxxxxxxxx/SIP/Info/info/8209/sip_sdp_get_modem_relay_cap_params: V150 NSE payload = 0, SSE payload = 0, SPRT payload=0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351372 52519034: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/critical/8193/sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay 10.10.10.1 Jul 07 09:37:10 syslog debug 100351373 52519035: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/info/1/sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0 10.10.10.1 Jul 07 09:37:10 syslog debug 100351374 52519036: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/Info/notify/1/sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1 10.10.10.1 Jul 07 09:37:10 syslog debug 100351375 payload_type=0, codec_bytes=160, codec=g711ulaw, dtmf_relay=sip-kpml 10.10.10.1 Jul 07 09:37:10 syslog debug 100351376 stream_type=voice-only (0), dest_ip_address=10.2.21.14, dest_port=26492 10.10.10.1 Jul 07 09:37:10 syslog debug 100351377 52519037: Jul 7 09:37:11.080: //176886/75CF6625B171/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_DEAD) to (STREAM_ADDING) 10.10.10.1 Jul 07 09:37:11 syslog debug 100351378 52519038: Jul 7 09:37:11.081: //-1/xxxxxxxxxxxx/SIP/Info/sipSPI_Check_If_ICE_Needed: CANDIDATE attribute, level 1not found. 10.10.10.1 Jul 07 09:37:11 syslog debug 100351379 52519039: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPIRSVPCompareSDP: Port Change Identified 10.10.10.1 Jul 07 09:37:11 syslog debug 100351380 52519040: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPIRSVPCompareSDP: Media IP Address Change Identified 10.10.10.1 Jul 07 09:37:11 syslog debug 100351381 52519041: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPIRSVPCompareSDP: DTMF Change Identified 10.10.10.1 Jul 07 09:37:11 syslog debug 100351382 52519042: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/State/sipSPIChangeStreamState: Stream (callid = -1) State changed from (STREAM_ADDING) to (STREAM_CHANGING) 10.10.10.1 Jul 07 09:37:11 syslog debug 100351383 52519043: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPICompareStreams: Old Dest Addr is different from New Dest Addr 10.10.10.1 Jul 07 09:37:11 syslog debug 100351384 52519044: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPICompareStreams: stream 1 dest_port: old=26550 new=26492 10.10.10.1 Jul 07 09:37:11 syslog debug 100351385 52519045: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPIGetNewLocalMediaDirection: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351386 New Remote Media Direction = SENDRECV 10.10.10.1 Jul 07 09:37:11 syslog debug 100351387 Present Local Media Direction = SENDRECV 10.10.10.1 Jul 07 09:37:11 syslog debug 100351388 New Local Media Direction = SENDRECV 10.10.10.1 Jul 07 09:37:11 syslog debug 100351389 retVal = 0 10.10.10.1 Jul 07 09:37:11 syslog debug 100351390 52519046: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPICompareStreams: negotiated dtmf relay changed from inband-voice to sip-kpml 10.10.10.1 Jul 07 09:37:11 syslog debug 100351391 52519047: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPICompareStreams: Flags set for stream 1: RTP_CHANGE=Yes CAPS_CHANGE=Yes RSVP_ADDR_CHANGE=Yes RSVP_MEDIA_CHANGE=No 10.10.10.1 Jul 07 09:37:11 syslog debug 100351392 52519048: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPICompareSDP: Flags set for call: NEW_MEDIA=Yes DSPDNLD_REQD=Yes IPIP_MEDIA=Yes 10.10.10.1 Jul 07 09:37:11 syslog debug 100351393 52519049: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/131073/sipSPIDoQoSNegotiationWithMediaLine: QOS negotiation for mline_index 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351394 52519050: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/notify/131072/sipSPIDoStreamQoSNegotiation: Best effort 10.10.10.1 Jul 07 09:37:11 syslog debug 100351395 52519051: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/1/is_call_held: Remote media IP addr: 10.2.21.14 10.10.10.1 Jul 07 09:37:11 syslog debug 100351396 52519052: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/1/is_call_held: Media IP Addr 10.2.21.10, RTCP Type 3 10.10.10.1 Jul 07 09:37:11 syslog debug 100351397 52519053: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/1/sipSPIProcessHoldTimerForCall: Media IP Addr 10.2.21.10, RTCP Type 3 10.10.10.1 Jul 07 09:37:11 syslog debug 100351398 52519054: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/sipSPIStopHoldTimer: Stopping hold timer 10.10.10.1 Jul 07 09:37:11 syslog debug 100351399 52519055: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPIUpdCallWithSdpInfo: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351400 Preferred Codec : g711ulaw, bytes :160 10.10.10.1 Jul 07 09:37:11 syslog debug 100351401 Preferred DTMF relay : rtp-nte 10.10.10.1 Jul 07 09:37:11 syslog debug 100351402 Preferred NTE payload : 100 10.10.10.1 Jul 07 09:37:11 syslog debug 100351403 Early Media : No 10.10.10.1 Jul 07 09:37:11 syslog debug 100351404 Delayed Media : Yes 10.10.10.1 Jul 07 09:37:11 syslog debug 100351405 Bridge Done : Yes 10.10.10.1 Jul 07 09:37:11 syslog debug 100351406 New Media : Yes 10.10.10.1 Jul 07 09:37:11 syslog debug 100351407 DSP DNLD Reqd : Yes 10.10.10.1 Jul 07 09:37:11 syslog debug 100351408 52519056: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/notify/6/sipSPIValidateStreamAddrType: stream:1, Mode : 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351409 52519057: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/verbose/513/resolve_media_ip_address_to_bind: Media already bound, use existing source_media_ip_addr 10.10.10.1 Jul 07 09:37:11 syslog debug 100351410 52519058: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Media/sipSPISetMediaSrcAddr: Media src addr for stream 1 = 10.10.10.1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351411 52519059: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_offer_ans_handle_rcvd_sdp: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351412 52519060: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: OA:cur_container:ccsip_offer_ans_main_container, cur_state:S_SIP_MID_DIALOG_OFFER_SENT, event:E_SIP_ACK_SDP_RCVD 10.10.10.1 Jul 07 09:37:11 syslog debug 100351413 52519061: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/verbose/4096/ccsip_offer_ans_is_incoming_ack_answer_valid_for_do2do: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351414 52519062: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_offer_ans_is_incoming_ack_answer_valid: TRUE 10.10.10.1 Jul 07 09:37:11 syslog debug 100351415 52519063: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/verbose/4096/ccsip_offer_ans_is_incoming_ack_answer_valid_for_do2do: - 0 10.10.10.1 Jul 07 09:37:11 syslog debug 100351416 52519064: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_offer_ans_is_incoming_ack_answer_valid: TRUE 10.10.10.1 Jul 07 09:37:11 syslog debug 100351417 52519065: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_offer_ans_common_answer_rcvd_hdlr: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351418 52519066: Jul 7 09:37:11.081: //176886/75CF6625B171/SIP/Info/info/4096/ccsip_iwf_handle_network_event: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351419 52519067: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IWF:cur_container:sip_iwf_sip_mid_dialog_container, cur_state:S_SIP_IWF_LOCAL_SDP_SENT_AWAIT_SDP, event:E_SIP_IWF_EV_RCVD_SDP 10.10.10.1 Jul 07 09:37:11 syslog debug 100351420 52519068: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Media/sipSPIDisplayStreamInfo: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351421 Stream type : voice-only 10.10.10.1 Jul 07 09:37:11 syslog debug 100351422 Media line : 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351423 State : STREAM_CHANGING (4) 10.10.10.1 Jul 07 09:37:11 syslog debug 100351424 Stream address type : 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351425 Callid : -1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351426 Peer Callid : 176885 10.10.10.1 Jul 07 09:37:11 syslog debug 100351427 RTP/SRTP Negotiated : 8 10.10.10.1 Jul 07 09:37:11 syslog debug 100351428 Negotiated Codec : g711ulaw, bytes :160 10.10.10.1 Jul 07 09:37:11 syslog debug 100351429 Nego. Codec payload : 0 (tx), 0 (rx) 10.10.10.1 Jul 07 09:37:11 syslog debug 100351430 Negotiated DTMF relay : sip-kpml 10.10.10.1 Jul 07 09:37:11 syslog debug 100351431 Negotiated NTE payload : 0 (tx), 0 (rx) 10.10.10.1 Jul 07 09:37:11 syslog debug 100351432 Negotiated CN payload : 0 10.10.10.1 Jul 07 09:37:11 syslog debug 100351433 Media Srce Addr/Port : [10.10.10.1]:42968 10.10.10.1 Jul 07 09:37:11 syslog debug 100351434 Media Dest Addr/Port : [10.2.21.14]:26492 10.10.10.1 Jul 07 09:37:11 syslog debug 100351435 52519069: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/verbose/4097/sipSPI_ipip_calculate_channel_count: Number of streams to report = 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351436 52519070: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/verbose/4097/sipSPI_ipip_is_rcvd_escalation_sdp: local channels = 1, peer channels = 1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351437 52519071: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/4097/is_midcall_signaling_passthru_media_change: TRUE 10.10.10.1 Jul 07 09:37:11 syslog debug 100351438 52519072: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/1/is_midcall_answer_sdp_change_indicate_to_peer: Masking the bitmasks. sdp_change = 0x106, indicate = 0x8 10.10.10.1 Jul 07 09:37:11 syslog debug 100351439 52519073: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/1/sip_iwf_sip_md_local_rcvd_sdp_hdlr: is srtp param changed: [0] 10.10.10.1 Jul 07 09:37:11 syslog debug 100351440 52519074: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/1/sip_iwf_sip_md_local_rcvd_sdp_hdlr: is_media_change_hdl: [1] 10.10.10.1 Jul 07 09:37:11 syslog debug 100351441 52519075: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 23 10.10.10.1 Jul 07 09:37:11 syslog debug 100351442 52519076: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_LOCAL_HANDLE_SDP 10.10.10.1 Jul 07 09:37:11 syslog debug 100351443 52519077: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/2/sipSPIUpdateCallEntry: 10.10.10.1 Jul 07 09:37:11 syslog debug 100351444 Call 176886 set InfoType to SPEECH 10.10.10.1 Jul 07 09:37:11 syslog debug 100351445 52519078: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/32/sipSPI_ipip_vcc_check_DtmfTranscoderNeeded: stream->negotiated_codec 5 10.10.10.1 Jul 07 09:37:11 syslog debug 100351446 52519079: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/32/sipSPI_ipip_vcc_check_DtmfTranscoderNeeded: stream peer codec: 5 10.10.10.1 Jul 07 09:37:11 syslog debug 100351447 52519080: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/32/sipSPI_ipip_vcc_check_DtmfTranscoderNeeded: local codec 5, local_711u_or_711a 1, peer_711u_or_711a:1 10.10.10.1 Jul 07 09:37:11 syslog debug 100351448 52519081: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/32/sipSPI_ipip_vcc_check_DtmfTranscoderNeeded: local DTMF 9, peer DTMF 6 10.10.10.1 Jul 07 09:37:11 syslog debug 100351449 52519082: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/info/32/sipSPI_ipip_vcc_check_DtmfTranscoderNeeded: Transcoder not needed for DTMF 10.10.10.1 Jul 07 09:37:11 syslog debug 100351450 52519083: Jul 7 09:37:11.082: //176886/75CF6625B171/SIP/Info/verbose/272/sipSPISrtpTranscoderForConsume: voip_is_split_dataplane is TRUE. So no need of xcoder 10.10.10.1 Jul 07 09:37:12 syslog debug 100352028 52519502: Jul 7 09:37:11.424: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/sipSPIFreeOneTCB: EnQing TCB(0x7FBC474D4440) for reuse 10.10.10.1 Jul 07 09:37:12 syslog debug 100352029 52519503: Jul 7 09:37:12.407: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC4747BBD0 with refCount = 1 10.10.10.1 Jul 07 09:37:12 syslog debug 100352030 52519504: Jul 7 09:37:12.407: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_create: created msg=0x7FBC3722D430 with refCount = 1 10.10.10.1 Jul 07 09:37:12 syslog debug 100352031 52519505: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/HandleUdpIPv4SocketReads: Msg enqueued for SPI with IP addr: [X.X.X.X]:5060, local_address:[ - ] 10.10.10.1 Jul 07 09:37:12 syslog debug 100352032 52519506: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC4747BBD0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352033 52519507: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/info/4096/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 for event 1 10.10.10.1 Jul 07 09:37:12 syslog debug 100352034 52519508: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352035 52519509: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: 10.10.10.1 Jul 07 09:37:12 syslog debug 100352036 Received: 10.10.10.1 Jul 07 09:37:12 syslog debug 100352037 BYE sip:8889246872@97.75.166.78:5060 SIP/2.0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352038 Via: SIP/2.0/UDP X.X.X.X:5060;branch=z9hG4bK0eB9295422140fc9a2b 10.10.10.1 Jul 07 09:37:12 syslog debug 100352039 From: ;tag=gK0e099186 10.10.10.1 Jul 07 09:37:12 syslog debug 100352040 To: ;tag=D8051131-1255 10.10.10.1 Jul 07 09:37:12 syslog debug 100352041 Call-ID: 353284948_129142354@X.X.X.X 10.10.10.1 Jul 07 09:37:12 syslog debug 100352042 CSeq: 4931 BYE 10.10.10.1 Jul 07 09:37:12 syslog debug 100352043 Max-Forwards: 70 10.10.10.1 Jul 07 09:37:12 syslog debug 100352044 Supported: 100rel 10.10.10.1 Jul 07 09:37:12 syslog debug 100352045 Content-Length: 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352046 10.10.10.1 Jul 07 09:37:12 syslog debug 100352047 52519510: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/verbose/4096/ccsip_new_msg_preprocessor: Checking Invite Dialog 10.10.10.1 Jul 07 09:37:12 syslog debug 100352048 52519511: Jul 7 09:37:12.408: //176885/75CF6625B171/SIP/Info/verbose/4096/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x7FBC4509B730 10.10.10.1 Jul 07 09:37:12 syslog debug 100352049 52519512: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC3722D430) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:12 syslog debug 100352050 52519513: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address X.X.X.X,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352051 52519514: Jul 7 09:37:12.408: //-1/xxxxxxxxxxxx/SIP/Info/verbose/8192/sipSPISetDateHeader: Converting TimeZone MDT to SIP default timezone = GMT 10.10.10.1 Jul 07 09:37:12 syslog debug 100352052 52519515: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Subsq Transaction Address X.X.X.X,Port 5060, Transport 1, SentBy Port 5060vrfid 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352053 52519516: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/verbose/4096/ccsip_set_release_source_for_peer: ownCallId[176885], src[2] 10.10.10.1 Jul 07 09:37:12 syslog debug 100352054 52519517: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/info/4096/sipSPIStopHoldTimer: Stopping hold timer 10.10.10.1 Jul 07 09:37:12 syslog debug 100352055 52519518: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/verbose/4096/sipSPIUreasonHeader_to_ccCause: Cause=0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352056 52519519: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentGTD: No GTD found in inbound container 10.10.10.1 Jul 07 09:37:12 syslog debug 100352057 52519520: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/critical/4096/sipSPIInitiateDisconnect: Initiate call disconnect(16) for incoming call 10.10.10.1 Jul 07 09:37:12 syslog debug 100352058 52519521: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7FBC375E6D90) counter, current msg->refCount = 1 10.10.10.1 Jul 07 09:37:12 syslog debug 100352059 52519522: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQSIG: No QSIG Body found in inbound container 10.10.10.1 Jul 07 09:37:12 syslog debug 100352060 52519523: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/critical/4096/sipSPIGetContentQ931: No RawMsg Body found in inbound container 10.10.10.1 Jul 07 09:37:12 syslog debug 100352061 52519524: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 32 10.10.10.1 Jul 07 09:37:12 syslog debug 100352062 52519525: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/critical/32768/ccsip_ipip_media_forking_post_event: MF: Not a Anchor SIP leg.. 10.10.10.1 Jul 07 09:37:12 syslog debug 100352063 52519526: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/verbose/10240/sipSPI_ipip_GetHdrPassthruCfg: Hdr passthrough config:1 tag:0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352064 52519527: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/critical/10240/sipSPI_ipip_ExtractPassthruCopyListHdrsFromSipContainer: Unsupported header passthru is not configured and no headers are present in hdr_hash_queue 10.10.10.1 Jul 07 09:37:12 syslog debug 100352065 52519528: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/critical/8192/sipSPI_ipip_IsContentPassthruEnabled: - 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352066 52519529: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/notify/8192/sipSPI_ipip_ExtractPassthruContentFromSipContainer: Passthru Content Not Enabled 10.10.10.1 Jul 07 09:37:12 syslog debug 100352067 52519530: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/info/1024/sipAddSipContainerToCallEntry: Unable to add passthru hdrs to container 10.10.10.1 Jul 07 09:37:12 syslog debug 100352068 52519531: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/State/sipSPIChangeState: 0x7FBC4509B730 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE) 10.10.10.1 Jul 07 09:37:12 syslog debug 100352069 52519532: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Freed msg=0x7FBC375E6D90 10.10.10.1 Jul 07 09:37:12 syslog debug 100352070 52519533: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_lock: Increment msg (0x7FBC3722D430) counter, current msg->refCount = 3 10.10.10.1 Jul 07 09:37:12 syslog debug 100352071 52519534: Jul 7 09:37:12.409: //176885/75CF6625B171/SIP/Info/verbose/4096/sipSPIAssignCcbLastRequest: Current ccb(0x7FBC4509B730)->last_request = 0x7FBC3722D430, refCount = 3 10.10.10.1 Jul 07 09:37:12 syslog debug 100352072 52519535: Jul 7 09:37:12.409: //-1/xxxxxxxxxxxx/SIP/Info/info/1024/httpish_msg_free: Decrement msg (0x7FBC3722D430) counter, current msg->refCount = 2 10.10.10.1 Jul 07 09:37:12 syslog debug 100352073 52519536: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/verbose/4096/ccsip_ipip_media_service_get_event_data: Event id = 18 10.10.10.1 Jul 07 09:37:12 syslog debug 100352074 52519537: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IPIPMS:cur_container:ccsip_ipip_media_service_main_container, cur_state:S_IPIP_MEDIA_SERV_STATE_IDLE, event:E_IPIP_MEDIA_SERV_EV_XCODER_RESET_STREAM 10.10.10.1 Jul 07 09:37:12 syslog debug 100352075 52519538: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/verbose/256/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES***** 10.10.10.1 Jul 07 09:37:12 syslog debug 100352076 52519539: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/verbose/256/sipSPIResetXcoder: *****CLEANING UP XCODER RESOURCES***** 10.10.10.1 Jul 07 09:37:12 syslog debug 100352077 52519540: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/verbose/256/sipSPIResetXcoder: Xcoder resource already cleaned up 10.10.10.1 Jul 07 09:37:12 syslog debug 100352078 52519541: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/State/ccsip_cnfsm_debugs: IPIPMS:next_state:S_IPIP_MEDIA_SERV_STATE_IDLE 10.10.10.1 Jul 07 09:37:12 syslog debug 100352079 52519542: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/verbose/4096/ccsip_bridge_drop: ReSetting mid call signalling not supported 10.10.10.1 Jul 07 09:37:12 syslog debug 100352080 52519543: Jul 7 09:37:12.410: //176886/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for stream type 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352081 52519544: Jul 7 09:37:12.410: //176886/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for nte 10.10.10.1 Jul 07 09:37:12 syslog debug 100352082 52519545: Jul 7 09:37:12.410: //176886/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for nse 10.10.10.1 Jul 07 09:37:12 syslog debug 100352083 52519546: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for stream type 0 10.10.10.1 Jul 07 09:37:12 syslog debug 100352084 52519547: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for nte 10.10.10.1 Jul 07 09:37:12 syslog debug 100352085 52519548: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/notify/8193/updateGccbTxRxValues: No interworking required for nse 10.10.10.1 Jul 07 09:37:12 syslog debug 100352086 52519549: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Info/critical/1/sipSPIUpdateMediapolicy: media policy block does not exist 10.10.10.1 Jul 07 09:37:12 syslog debug 100352087 52519550: Jul 7 09:37:12.410: //176885/75CF6625B171/SIP/Media/sipSPIUpdateRtpSession: Stun packet reporting for ICE disabled in sipSPIUpdateRtpSession 10.10.10.1 Jul 07 09:37:13 syslog debug 100352745 SIP/2.0 481 Subscription does not exist 10.10.10.1 Jul 07 09:37:13 syslog debug 100352746 Via: SIP/2.0/UDP 10.2.21.11:5060;branch=z9hG4bKf67ec37daec9c 10.10.10.1 Jul 07 09:37:13 syslog debug 100352747 From: ;tag=2181784~b81a956d-b4eb-4321-807f-845e8fa3a514-41576574 10.10.10.1 Jul 07 09:37:13 syslog debug 100352748 To: ;tag=D805110D-248E 10.10.10.1 Jul 07 09:37:13 syslog debug 100352749 Date: Thu, 07 Jul 2016 15:37:12 GMT 10.10.10.1 Jul 07 09:37:13 syslog debug 100352750 Call-ID: 75D30FAD-438F11E6-B1779F34-3186D190@10.10.10.1 10.10.10.1 Jul 07 09:37:13 syslog debug 100352751 CSeq: 119 NOTIFY 10.10.10.1 Jul 07 09:37:13 syslog debug 100352752 Content-Length: 0 10.10.10.1 Jul 07 09:37:13 syslog debug 100352753