Syslog logging: enabled (0 messages dropped, 15 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator. Console logging: disabled Monitor logging: disabled Buffer logging: level debugging, 385 messages logged, xml disabled, filtering disabled Logging Exception size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. ESM: 0 messages dropped Trap logging: level informational, 878 message lines logged Log Buffer (3000000 bytes): 001235: Jul 14 12:21:28.621: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: INVITE sip:697556262@172.16.5.2:5060 SIP/2.0 Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: Date: Fri, 14 Jul 2017 15:26:12 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 Supported: timer,resource-priority,replaces Min-SE: 1800 User-Agent: Cisco-CUCM8.5 Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY CSeq: 101 INVITE Expires: 180 Allow-Events: presence, kpml Supported: X-cisco-srtp-fallback Supported: Geolocation Call-Info: ;method="NOTIFY;Event=telephone-event;Duration=500" Cisco-Guid: 3277670272-0000065536-0000000657-0017109164 Session-Expires: 1800 P-Asserted-Identity: Remote-Party-ID: ;party=calling;screen=yes;privacy=off Contact: Max-Forwards: 70 Content-Length: 0 001236: Jul 14 12:21:28.625: //-1/C35D47800000/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username=33439504 ----- ccCallInfo IE subfields ----- cisco-ani=33439504 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=1 dest=697556262 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-lastrdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 001237: Jul 14 12:21:28.625: //-1/C35D47800000/CCAPI/cc_api_call_setup_ind_common: Interface=0x2913A754, Call Info( Calling Number=33439504,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=697556262(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Incoming Dial-peer=99, Progress Indication=NULL(0), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=68 001238: Jul 14 12:21:28.625: //-1/C35D47800000/CCAPI/ccCheckClipClir: In: Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 001239: Jul 14 12:21:28.625: //-1/C35D47800000/CCAPI/ccCheckClipClir: Out: Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 001240: Jul 14 12:21:28.625: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001241: Jul 14 12:21:28.625: :cc_get_feature_vsa malloc success 001242: Jul 14 12:21:28.625: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001243: Jul 14 12:21:28.625: cc_get_feature_vsa count is 1 001244: Jul 14 12:21:28.625: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001245: Jul 14 12:21:28.625: :FEATURE_VSA attributes are: feature_name:0,feature_time:832036640,feature_id:68 001246: Jul 14 12:21:28.625: //68/C35D47800000/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=697556262(TON=Unknown, NPI=Unknown)) 001247: Jul 14 12:21:28.625: //68/C35D47800000/CCAPI/cc_process_call_setup_ind: Event=0x2922ACD8 001248: Jul 14 12:21:28.625: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 697556262 001249: Jul 14 12:21:28.625: //68/C35D47800000/CCAPI/ccCallSetContext: Context=0x29E5C788 001250: Jul 14 12:21:28.625: //68/C35D47800000/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 68 with tag 99 to app "_ManagedAppProcess_Default" 001251: Jul 14 12:21:28.633: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 100 Trying Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: Date: Fri, 14 Jul 2017 12:21:28 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 CSeq: 101 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Content-Length: 0 001252: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCallProceeding: Progress Indication=NULL(0) 001253: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=101, Params=0x29E600F0, Progress Indication=NULL(0) 001254: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCheckClipClir: In: Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 001255: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCheckClipClir: Out: Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed) 001256: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCallSetupRequest: Destination Pattern=.T, Called Number=697556262, Digit Strip=TRUE 001257: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccCallSetupRequest: Calling Number=33439504(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=697556262(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=33439504, Final Destination Flag=TRUE, Guid=C35D4780-0001-0000-0000-0291010510AC, Outgoing Dial-peer=101 001258: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username=33439504 ----- ccCallInfo IE subfields ----- cisco-ani=33439504 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=1 dest=697556262 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-lastrdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 001259: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x316EA808, Interface Type=6, Destination=, Mode=0x0, Call Params(Calling Number=33439504,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed), Called Number=697556262(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=101, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) 001260: Jul 14 12:21:28.633: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001261: Jul 14 12:21:28.633: :cc_get_feature_vsa malloc success 001262: Jul 14 12:21:28.633: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001263: Jul 14 12:21:28.633: cc_get_feature_vsa count is 2 001264: Jul 14 12:21:28.633: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001265: Jul 14 12:21:28.633: :FEATURE_VSA attributes are: feature_name:0,feature_time:832036416,feature_id:69 001266: Jul 14 12:21:28.633: //69/C35D47800000/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 001267: Jul 14 12:21:28.633: //69/C35D47800000/CCAPI/ccCallSetContext: Context=0x29E600A0 001268: Jul 14 12:21:28.633: //68/C35D47800000/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=101 001269: Jul 14 12:21:28.637: ISDN Se0/3/0:15 Q931: Applying typeplan for sw-type 0xD is 0x0 0x0, Calling num 33439504 001270: Jul 14 12:21:28.637: ISDN Se0/3/0:15 Q931: Sending SETUP callref = 0x009E callID = 0x801F switch = primary-ni interface = User 001271: Jul 14 12:21:28.637: ISDN Se0/3/0:15 Q931: TX -> SETUP pd = 8 callref = 0x009E Bearer Capability i = 0x8090A3 Standard = CCITT Transfer Capability = Speech Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98381 Exclusive, Channel 1 Calling Party Number i = 0x0081, '33439504' Plan:Unknown, Type:Unknown Called Party Number i = 0x80, '697556262' Plan:Unknown, Type:Unknown 001272: Jul 14 12:21:28.669: ISDN Se0/3/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x809E Channel ID i = 0xA98381 Exclusive, Channel 1 001273: Jul 14 12:21:28.669: ISDN Se0/3/0:15 **ERROR**: Ux_BadMsg: Invalid Message for call state 1, call id 0x801F, call ref 0x9E, event 0xD 001274: Jul 14 12:21:28.673: ISDN Se0/3/0:15 Q931: TX -> STATUS pd = 8 callref = 0x009E Cause i = 0xC0E50D - Message not compatible with call state Call State i = 0x01 001275: Jul 14 12:21:32.637: ISDN Se0/3/0:15 Q931: TX -> SETUP pd = 8 callref = 0x009E Bearer Capability i = 0x8090A3 Standard = CCITT Transfer Capability = Speech Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98381 Exclusive, Channel 1 Calling Party Number i = 0x0081, '33439504' Plan:Unknown, Type:Unknown Called Party Number i = 0x80, '697556262' Plan:Unknown, Type:Unknown 001276: Jul 14 12:21:35.797: ISDN Se0/3/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x809E 001277: Jul 14 12:21:35.797: ISDN Se0/3/0:15 Q931: TX -> STATUS pd = 8 callref = 0x009E Cause i = 0x80E0 - Mandatory information element missing Call State i = 0x01 001278: Jul 14 12:21:45.421: ISDN Se0/3/0:15 Q931: RX <- CONNECT pd = 8 callref = 0x809E Date/Time i = 0x11070E101B Date (dd-mm-yr) = 17-07-14 Time (hr:mnt:sec) = 16:27:76 Connected Number i = '!', 0x83, '697556262' 001279: Jul 14 12:21:45.421: ISDN Se0/3/0:15 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x009E Cause i = 0x80E0 - Mandatory information element missing 001280: Jul 14 12:21:45.437: ISDN Se0/3/0:15 Q931: RX <- STATUS pd = 8 callref = 0x809E Cause i = 0x82E39808 - Information element not implemented Call State i = 0x0A 001281: Jul 14 12:21:45.653: %SSH-5-SSH2_SESSION: SSH2 Session request from 172.16.4.50 (tty = 1) using crypto cipher 'aes128-cbc', hmac 'hmac-sha1' Succeeded 001282: Jul 14 12:21:45.853: %SSH-5-SSH2_USERAUTH: User 'cbc' authentication for SSH2 Session from 172.16.4.50 (tty = 1) using crypto cipher 'aes128-cbc', hmac 'hmac-sha1' Succeeded 001283: Jul 14 12:21:51.877: %SSH-5-SSH2_CLOSE: SSH2 Session from 172.16.4.50 (tty = 1) for user 'cbc' using crypto cipher 'aes128-cbc', hmac 'hmac-sha1' closed 001284: Jul 14 12:22:47.753: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: CANCEL sip:697556262@172.16.5.2:5060 SIP/2.0 Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: Date: Fri, 14 Jul 2017 15:26:12 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 CSeq: 101 CANCEL Max-Forwards: 70 Content-Length: 0 001285: Jul 14 12:22:47.757: //68/C35D47800000/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x2913A754, Call Id=68 001286: Jul 14 12:22:47.757: //68/C35D47800000/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0) 001287: Jul 14 12:22:47.757: //69/C35D47800000/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 001288: Jul 14 12:22:47.757: //69/C35D47800000/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=FALSE, Cause Value=16) 001289: Jul 14 12:22:47.757: //69/C35D47800000/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null 001290: Jul 14 12:22:47.757: //68/C35D47800000/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) 001291: Jul 14 12:22:47.757: //68/C35D47800000/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 001292: Jul 14 12:22:47.769: ISDN Se0/3/0:15 Q931: TX -> DISCONNECT pd = 8 callref = 0x009E Cause i = 0x8090 - Normal call clearing 001293: Jul 14 12:22:47.769: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: Date: Fri, 14 Jul 2017 12:22:47 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 CSeq: 101 CANCEL Content-Length: 0 001294: Jul 14 12:22:47.769: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 487 Request Cancelled Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: ;tag=4810178-162A Date: Fri, 14 Jul 2017 12:22:47 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 CSeq: 101 INVITE Allow-Events: telephone-event Server: Cisco-SIPGateway/IOS-12.x Reason: Q.850;cause=16 Content-Length: 0 001295: Jul 14 12:22:47.769: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: ACK sip:697556262@172.16.5.2:5060 SIP/2.0 Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18572dc49736 From: ;tag=76681~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293684 To: ;tag=4810178-162A Date: Fri, 14 Jul 2017 15:26:12 GMT Call-ID: c35d4780-9681e294-bcd-10510ac@172.16.5.1 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: presence, kpml Content-Length: 0 001296: Jul 14 12:22:47.773: //68/C35D47800000/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x2913A754, Tag=0x0, Call Id=68, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 001297: Jul 14 12:22:47.773: //68/C35D47800000/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 001298: Jul 14 12:22:47.773: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001299: Jul 14 12:22:47.773: :cc_free_feature_vsa freeing 3197DF18 001300: Jul 14 12:22:47.773: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001301: Jul 14 12:22:47.773: vsacount in free is 1 001302: Jul 14 12:22:47.825: ISDN Se0/3/0:15 Q931: RX <- RELEASE pd = 8 callref = 0x809E Facility i = 0x91A11102014702012430093007A1053003020104 Protocol Profile = Remote Operations Protocol 0xA11102014702012430093007A1053003020104 Component = Invoke component Invoke Id = 71 Operation = AOCECharging Unit Display i = '4 UNITES' 001303: Jul 14 12:22:47.825: ISDN Se0/3/0:15 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x009E 001304: Jul 14 12:22:47.829: //69/C35D47800000/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x316EA808, Tag=0x0, Call Id=69, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 001305: Jul 14 12:22:47.829: //69/C35D47800000/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 001306: Jul 14 12:22:47.829: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001307: Jul 14 12:22:47.829: :cc_free_feature_vsa freeing 3197DE38 001308: Jul 14 12:22:47.829: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001309: Jul 14 12:22:47.829: vsacount in free is 0 001310: Jul 14 12:23:34.605: ISDN Se0/3/0:15 Q931: RX <- SETUP pd = 8 callref = 0x00A8 Sending Complete Bearer Capability i = 0x8090A3 Standard = CCITT Transfer Capability = Speech Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98381 Exclusive, Channel 1 Calling Party Number i = 0x2183, '697556262' Plan:ISDN, Type:National Called Party Number i = 0x81, '33439504' Plan:ISDN, Type:Unknown 001311: Jul 14 12:23:34.605: ISDN Se0/3/0:15 Q931: Received SETUP callref = 0x80A8 callID = 0x0017 switch = primary-ni interface = User 001312: Jul 14 12:23:34.609: //-1/170DBD938009/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=697556262 cisco-anitype=2 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=3 dest=33439504 cisco-desttype=0 cisco-destplan=1 cisco-rdie=FFFFFFFF cisco-rdn= cisco-lastrdn= cisco-rdntype=-1 cisco-rdnplan=-1 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 001313: Jul 14 12:23:34.609: //-1/170DBD938009/CCAPI/cc_api_call_setup_ind_common: Interface=0x316EA808, Call Info( Calling Number=697556262,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=33439504(TON=Unknown, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Incoming Dial-peer=100, Progress Indication=NULL(0), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 001314: Jul 14 12:23:34.609: //-1/170DBD938009/CCAPI/ccCheckClipClir: In: Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed) 001315: Jul 14 12:23:34.609: //-1/170DBD938009/CCAPI/ccCheckClipClir: Out: Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed) 001316: Jul 14 12:23:34.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001317: Jul 14 12:23:34.609: :cc_get_feature_vsa malloc success 001318: Jul 14 12:23:34.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001319: Jul 14 12:23:34.609: cc_get_feature_vsa count is 1 001320: Jul 14 12:23:34.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001321: Jul 14 12:23:34.609: :FEATURE_VSA attributes are: feature_name:0,feature_time:805732688,feature_id:70 001322: Jul 14 12:23:34.609: //70/170DBD938009/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=33439504(TON=Unknown, NPI=ISDN)) 001323: Jul 14 12:23:34.609: //70/170DBD938009/CCAPI/cc_process_call_setup_ind: Event=0x2922ACD8 001324: Jul 14 12:23:34.609: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 33439504 001325: Jul 14 12:23:34.609: //70/170DBD938009/CCAPI/ccCallSetContext: Context=0x29E613A8 001326: Jul 14 12:23:34.609: //70/170DBD938009/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 70 with tag 100 to app "_ManagedAppProcess_Default" 001327: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCallProceeding: Progress Indication=NULL(0) 001328: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=99, Params=0x29E62700, Progress Indication=NULL(0) 001329: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCheckClipClir: In: Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed) 001330: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCheckClipClir: Out: Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed) 001331: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCallSetupRequest: Destination Pattern=3......., Called Number=33439504, Digit Strip=FALSE 001332: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccCallSetupRequest: Calling Number=697556262(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=33439504(TON=Unknown, NPI=ISDN), Redirect Number=, Display Info= Account Number=, Final Destination Flag=TRUE, Guid=170DBD93-67C6-11E7-8009-588D093F4648, Outgoing Dial-peer=99 001333: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=697556262 cisco-anitype=2 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=3 dest=33439504 cisco-desttype=0 cisco-destplan=1 cisco-rdie=FFFFFFFF cisco-rdn= cisco-lastrdn= cisco-rdntype=-1 cisco-rdnplan=-1 cisco-rdnpi=-1 cisco-rdnsi=-1 cisco-redirectreason=-1 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 001334: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x2913A754, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=697556262,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed), Called Number=33439504(TON=Unknown, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=99, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) 001335: Jul 14 12:23:34.617: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001336: Jul 14 12:23:34.617: :cc_get_feature_vsa malloc success 001337: Jul 14 12:23:34.617: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001338: Jul 14 12:23:34.617: cc_get_feature_vsa count is 2 001339: Jul 14 12:23:34.617: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 001340: Jul 14 12:23:34.617: :FEATURE_VSA attributes are: feature_name:0,feature_time:805732464,feature_id:71 001341: Jul 14 12:23:34.617: //71/170DBD938009/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 001342: Jul 14 12:23:34.617: //71/170DBD938009/CCAPI/ccCallSetContext: Context=0x29E626B0 001343: Jul 14 12:23:34.617: //70/170DBD938009/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=99 001344: Jul 14 12:23:34.621: //71/170DBD938009/CCAPI/cc_api_call_proceeding: Interface=0x2913A754, Progress Indication=NULL(0) 001345: Jul 14 12:23:34.629: ISDN Se0/3/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0x80A8 Channel ID i = 0xA98381 Exclusive, Channel 1 001346: Jul 14 12:23:34.629: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: INVITE sip:33439504@172.16.5.1:5060 SIP/2.0 Via: SIP/2.0/TCP 172.16.5.2:5060;branch=z9hG4bK10C20 Remote-Party-ID: ;party=calling;screen=yes;privacy=off From: ;tag=481B87C-238A To: Date: Fri, 14 Jul 2017 12:23:34 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 Supported: 100rel,timer,resource-priority,replaces,sdp-anat Min-SE: 1800 Cisco-Guid: 0386776467-1741033959-2148096141-0155141704 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1500035014 Contact: Expires: 180 Allow-Events: telephone-event Content-Type: application/sdp Content-Disposition: session;handling=required Content-Length: 297 v=0 o=CiscoSystemsSIP-GW-UserAgent 264 0 IN IP4 172.16.5.2 s=SIP Call c=IN IP4 172.16.5.2 t=0 0 m=audio 31350 RTP/AVP 8 0 18 101 c=IN IP4 172.16.5.2 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 001347: Jul 14 12:23:34.629: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 100 Trying Via: SIP/2.0/TCP 172.16.5.2:5060;branch=z9hG4bK10C20 From: ;tag=481B87C-238A To: Date: Fri, 14 Jul 2017 15:28:18 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 CSeq: 101 INVITE Allow-Events: presence Content-Length: 0 001348: Jul 14 12:23:34.633: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 180 Ringing Via: SIP/2.0/TCP 172.16.5.2:5060;branch=z9hG4bK10C20 From: ;tag=481B87C-238A To: ;tag=76684~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293705 Date: Fri, 14 Jul 2017 15:28:18 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: X-cisco-srtp-fallback Supported: Geolocation P-Asserted-Identity: "TEST E1" Remote-Party-ID: "TEST E1" ;party=called;screen=yes;privacy=off Contact: Content-Length: 0 001349: Jul 14 12:23:34.633: //71/170DBD938009/CCAPI/cc_api_call_alert: Interface=0x2913A754, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 001350: Jul 14 12:23:34.633: //71/170DBD938009/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) 001351: Jul 14 12:23:34.633: //70/170DBD938009/CCAPI/ccCallAlert: Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 001352: Jul 14 12:23:34.633: //70/170DBD938009/CCAPI/ccCallAlert: Call Entry(Responsed=TRUE, Alert Sent=TRUE) 001353: Jul 14 12:23:34.633: //70/170DBD938009/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Ring Back, Tone Direction=Network, Params=0x0, Call Id=70 001354: Jul 14 12:23:34.637: ISDN Se0/3/0:15 Q931: TX -> ALERTING pd = 8 callref = 0x80A8 001355: Jul 14 12:23:36.353: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: SIP/2.0 200 OK Via: SIP/2.0/TCP 172.16.5.2:5060;branch=z9hG4bK10C20 From: ;tag=481B87C-238A To: ;tag=76684~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293705 Date: Fri, 14 Jul 2017 15:28:18 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence, kpml Supported: replaces Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas Require: timer P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 210 v=0 o=CiscoSystemsCCM-SIP 2000 1 IN IP4 172.16.5.1 s=SIP Call c=IN IP4 172.16.5.151 t=0 0 m=audio 27652 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 001356: Jul 14 12:23:36.353: //71/170DBD938009/CCAPI/cc_api_call_connected: Interface=0x2913A754, Data Bitmask=0x83000001, Progress Indication=NULL(0), Connection Handle=0 001357: Jul 14 12:23:36.353: //71/170DBD938009/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) 001358: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=70 001359: Jul 14 12:23:36.357: //70/xxxxxxxxxxxx/CCAPI/ccConferenceCreate: (confID=0x3005DFA4, callID1=0x46, callID2=0x47, tag=0x0) 001360: Jul 14 12:23:36.357: //70/xxxxxxxxxxxx/CCAPI/ccConferenceCreate: (confID=0x3005DFA4, callID1=0x46, gcid=170DBD93-67C611E7-8009588D-93F4648, tag=0x0) 001361: Jul 14 12:23:36.357: //71/xxxxxxxxxxxx/CCAPI/ccConferenceCreate: (confID=0x3005DFA4, callID2=0x47, gcid=170DBD93-67C611E7-8009588D-93F4648, tag=0x0) 001362: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/ccConferenceCreate: Conference Id=0x3005DFA4, Call Id1=70, Call Id2=71, Tag=0x0 001363: Jul 14 12:23:36.357: //70/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: Conference Id=0xB, Source Interface=0x316EA808, Source Call Id=70, Destination Call Id=71, Disposition=0x0, Tag=0xFFFFFFFF 001364: Jul 14 12:23:36.357: //71/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: 001365: Jul 14 12:23:36.357: cc_api_get_xcode_stream : 4534 001366: Jul 14 12:23:36.357: //71/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: Conference Id=0xB, Source Interface=0x2913A754, Source Call Id=71, Destination Call Id=70, Disposition=0x0, Tag=0x0 001367: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_generic_bridge_done: Conference Id=0xB, Source Interface=0x2913A754, Source Call Id=71, Destination Call Id=70, Disposition=0x0, Tag=0x0 001368: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0xB, Destination Call Id=71) 001369: Jul 14 12:23:36.357: //71/170DBD938009/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0xB, Destination Call Id=70) 001370: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_api_caps_ind: Destination Interface=0x2913A754, Destination Call Id=71, Source Call Id=70, Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) 001371: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) 001372: Jul 14 12:23:36.357: //71/170DBD938009/CCAPI/cc_api_caps_ind: Destination Interface=0x316EA808, Destination Call Id=70, Source Call Id=71, Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1, Modem=0x0, Codec Bytes=160, Signal Type=2) 001373: Jul 14 12:23:36.357: //71/170DBD938009/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=1000(ms), Fax Nom=300(ms)) 001374: Jul 14 12:23:36.357: //71/170DBD938009/CCAPI/cc_api_caps_ack: Destination Interface=0x316EA808, Destination Call Id=70, Source Call Id=71, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=9451) 001375: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_api_caps_ack: Destination Interface=0x2913A754, Destination Call Id=71, Source Call Id=70, Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=9451) 001376: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/ccCallConnect: Progress Indication=NULL(0), Data Bitmask=0x83000001 001377: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/ccCallConnect: Call Entry(Connected=TRUE, Responsed=TRUE) 001378: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_api_voice_mode_event: Call Id=70 001379: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x29E613A8) 001380: Jul 14 12:23:36.357: //70/170DBD938009/CCAPI/cc_process_notify_bridge_done: Conference Id=0xB, Call Id1=70, Call Id2=71 001381: Jul 14 12:23:36.361: ISDN Se0/3/0:15 Q931: TX -> CONNECT pd = 8 callref = 0x80A8 001382: Jul 14 12:23:36.361: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: ACK sip:33439504@172.16.5.1:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 172.16.5.2:5060;branch=z9hG4bK112455 From: ;tag=481B87C-238A To: ;tag=76684~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293705 Date: Fri, 14 Jul 2017 12:23:34 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 Max-Forwards: 70 CSeq: 101 ACK Allow-Events: telephone-event Content-Length: 0 001383: Jul 14 12:23:36.385: ISDN Se0/3/0:15 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x00A8 001384: Jul 14 12:23:36.385: %ISDN-6-CONNECT: Interface Serial0/3/0:0 is now connected to 697556262 N/A 001385: Jul 14 12:23:41.201: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Received: BYE sip:697556262@172.16.5.2:5060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18589cc535a From: ;tag=76684~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293705 To: ;tag=481B87C-238A Date: Fri, 14 Jul 2017 15:28:20 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 User-Agent: Cisco-CUCM8.5 Max-Forwards: 70 CSeq: 101 BYE Content-Length: 0 001386: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Sum Network, Params=0x0, Call Id=70 001387: Jul 14 12:23:41.201: //71/170DBD938009/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x2913A754, Call Id=71 001388: Jul 14 12:23:41.201: //71/170DBD938009/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) 001389: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/ccConferenceDestroy: Conference Id=0xB, Tag=0x0 001390: Jul 14 12:23:41.201: //70/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: Conference Id=0xB, Source Interface=0x316EA808, Source Call Id=70, Destination Call Id=71, Disposition=0x0, Tag=0x0 001391: Jul 14 12:23:41.201: //71/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: Conference Id=0xB, Source Interface=0x2913A754, Source Call Id=71, Destination Call Id=70, Disposition=0x0, Tag=0x0 001392: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/cc_generic_bridge_done: Conference Id=0xB, Source Interface=0x2913A754, Source Call Id=71, Destination Call Id=70, Disposition=0x0, Tag=0x0 001393: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 001394: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 001395: Jul 14 12:23:41.201: //70/170DBD938009/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null 001396: Jul 14 12:23:41.201: //71/170DBD938009/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) 001397: Jul 14 12:23:41.201: //71/170DBD938009/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 001398: Jul 14 12:23:41.209: //71/170DBD938009/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x2913A754, Tag=0x0, Call Id=71, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 001399: Jul 14 12:23:41.209: //71/170DBD938009/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 001400: Jul 14 12:23:41.209: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001401: Jul 14 12:23:41.209: :cc_free_feature_vsa freeing 30068068 001402: Jul 14 12:23:41.209: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001403: Jul 14 12:23:41.209: vsacount in free is 1 001404: Jul 14 12:23:41.209: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg: Sent: SIP/2.0 200 OK Via: SIP/2.0/TCP 172.16.5.1:5060;branch=z9hG4bK18589cc535a From: ;tag=76684~f527e749-42b2-4f7b-aef5-209e7bba3e03-30293705 To: ;tag=481B87C-238A Date: Fri, 14 Jul 2017 12:23:41 GMT Call-ID: 17102ECB-67C611E7-80AAB23C-CA10E2D@172.16.5.2 Server: Cisco-SIPGateway/IOS-12.x CSeq: 101 BYE Reason: Q.850;cause=16 Content-Length: 0 001405: Jul 14 12:23:41.229: %ISDN-6-DISCONNECT: Interface Serial0/3/0:0 disconnected from 697556262 , call lasted 4 seconds 001406: Jul 14 12:23:41.229: ISDN Se0/3/0:15 Q931: TX -> DISCONNECT pd = 8 callref = 0x80A8 Cause i = 0x8090 - Normal call clearing 001407: Jul 14 12:23:41.293: ISDN Se0/3/0:15 Q931: RX <- RELEASE pd = 8 callref = 0x00A8 001408: Jul 14 12:23:41.293: ISDN Se0/3/0:15 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x80A8 001409: Jul 14 12:23:41.301: //70/170DBD938009/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x316EA808, Tag=0x0, Call Id=70, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 001410: Jul 14 12:23:41.301: //70/170DBD938009/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 001411: Jul 14 12:23:41.301: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001412: Jul 14 12:23:41.301: :cc_free_feature_vsa freeing 30068148 001413: Jul 14 12:23:41.301: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 001414: Jul 14 12:23:41.301: vsacount in free is 0