=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2017.02.23 11:58:25 =~=~=~=~=~=~=~=~=~=~=~= AUH_SRST#terminal monitor AUH_SRST#debug isdn 9 q931 debug isdn q931 is ON. AUH_SRST# 102946: Feb 23 12:22:24.151: ISDN Se0/1/0:15 Q931: RX <- CONNECT pd = 8 callref = 0x8CC0 102947: Feb 23 12:22:24.151: ISDN Se0/1/0:15 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x0CC0 AUH_SRST# 102948: Feb 23 12:22:25.799: ISDN Se0/1/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x2 0x1, Calling num 022035951 102949: Feb 23 12:22:25.803: ISDN Se0/1/0:15 Q931: Sending SETUP callref = 0x0CC1 callID = 0x8C42 switch = primary-net5 interface = User 102950: Feb 23 12:22:25.803: ISDN Se0/1/0:15 Q931: TX -> SETUP pd = 8 callref = 0x0CC1 Bearer Capability i = 0x8090A3 Standard = CCITT Transfer Capability = Speech Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98382 Exclusive, Channel 2 Calling Party Number i = 0x2181, '022035951' Plan: AUH_SRST#ISDN, Type:National Called Party Number i = 0x81, '0501236958' Plan:ISDN, Type:Unknown 102951: Feb 23 12:22:25.815: ISDN Se0/1/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x8CC1 Channel ID i = 0xA98382 Exclusive, Channel 2 AUH_SRST# 102952: Feb 23 12:22:29.815: ISDN Se0/1/0:15 Q931: RX <- CALL_PROC pd = 8 callref = 0x8CC1 102953: Feb 23 12:22:29.907: ISDN Se0/1/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x2 0x1, Calling num 022035955 102954: Feb 23 12:22:29.911: ISDN Se0/1/0:15 Q931: Sending SETUP callref = 0x0CC2 callID = 0x8C43 switch = primary-net5 interface = User AUH_SRST# 102955: Feb 23 12:22:29.911: ISDN Se0/1/0:15 Q931: TX -> SETUP pd = 8 callref = 0x0CC2 Bearer Capability i = 0x8890 Standard = CCITT Transfer Capability = Unrestricted Digital Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98384 Exclusive, Channel 4 Calling Party Number i = 0x2181, '022035955' Plan:ISDN, Type:National Called Party Number i = 0x81, '0555033469' Plan:ISDN, Type:Unknown 102956: Feb 23 12:22:29.923: ISDN Se0/1/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x8CC2 Channel ID i = 0xA98384 Exclusive, Channel 4 AUH_SRST# 102957: Feb 23 12:22:33.183: ISDN Se0/1/0:15 Q931: RX <- PROGRESS pd = 8 callref = 0x8CC1 Progress Ind i = 0x8088 - In-band info or appropriate now available 102958: Feb 23 12:22:33.223: ISDN Se0/1/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x8CC1 Progress Ind i = 0x8088 - In-band info or appropriate now available 102959: Feb 23 12:22:33.915: ISDN Se0/1/0:15 Q931: RX <- CALL_PROC pd = 8 callref = 0x8CC2 102960: Feb 23 12:22:33.951: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x8CC2 Cause i = 0x80FF - Interworking error; unspecified 102961: Feb 23 12:22:33.955: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8 callref = 0x0CC2 AUH_SRST# 102962: Feb 23 12:22:33.963: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x8CC2 AUH_SRST# 102963: Feb 23 12:22:40.319: ISDN Se0/1/0:15 Q931: RX <- CONNECT pd = 8 callref = 0x8CC1 102964: Feb 23 12:22:40.323: ISDN Se0/1/0:15 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x0CC1 AUH_SRST#un 102965: Feb 23 12:22:45.311: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x8CC0 Cause i = 0x8090 - Normal call clearing 102966: Feb 23 12:22:45.311: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8 callref = 0x0CC0 102967: Feb 23 12:22:45.319: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x8CC0 102968: Feb 23 12:22:46.511: ISDN Se0/1/0:15 Q931: RX <- SETUP pd = 8 callref = 0x0E01 Bearer Capability i = 0x8090A3 Standard = CCITT Transfer Capability = Speech Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA98383 Exclusive, Channel 3 Calling Party Number i = 0x2180, '0525643562' Plan:ISDN, Type:National Sending Complete Called Party Number i = 0x81, '022035944' Plan:ISDN, Type:Unknown 102969: Feb 23 12:22:46.511: ISDN Se0/1/0:15 Q931: Received SETUP callref = 0x8E01 callID = 0x0237 switch = primary-net5 interface = User AUH_SRST#un 102970: Feb 23 12:22:46.519: ISDN Se0/1/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0x8E01 Channel ID i = 0xA98383 Exclusive, Channel 3 102971: Feb 23 12:22:46.671: ISDN Se0/1/0:15 Q931: TX -> ALERTING pd = 8 callref = 0x8E01 AUH_SRST#undebug all All possible debugging has been turned off AUH_SRST# AUH_SRST#debug voip ccapi inout voip ccapi inout debugging is on AUH_SRST# 102972: Feb 23 12:23:53.423: //7491/000C85466405/CCAPI/cc_api_call_cut_progress: Interface=0x3F31633C, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0 102973: Feb 23 12:23:53.423: //7491/000C85466405/CCAPI/cc_api_call_cut_progress: Call Entry(Responsed=TRUE) 102974: Feb 23 12:23:53.423: //7490/000C85466405/CCAPI/ccCallProgIndProgress: Progress Indication=INBAND(8) 102975: Feb 23 12:23:53.423: //7491/000C85466405/CCAPI/cc_api_get_called_ccm_detected: CallInfo(ccm detected=0) 102976: Feb 23 12:23:53.423: //7490/000C85466405/CCAPI/ccCallNotify: Data Bitmask=0x7, Call Id=7490 AUH_SRST# 102977: Feb 23 12:23:53.423: //7491/000C85466405/CCAPI/cc_api_get_called_ccm_detected: CallInfo(ccm detected=0) 102978: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x3F31633C, Call Id=7491 102979: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) 102980: Feb 23 12:23:54.823: //7490/000C85466405/CCAPI/ccCallReleaseResources: release reserved xcoding resource. 102981: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=7491 102982: Feb 23 12:23:54.823: //7490/000C85466405/CCAPI/ccConferenceDestroy: Conference Id=0xC55, Tag=0x0 102983: Feb 23 12:23:54.823: //7490/000C85466405/CCAPI/ccConferenceDestroy: 102984: Feb 23 12:23:54.823: confID:0xC55; callEntry1 callID1:0x1D42, type:1; callEntry2 callID2:0x1D43, type:6 102985: Feb 23 12:23:54.823: //7490/000C85466405/CCAPI/cc_api_bridge_drop_done: Conference Id=0xC55, Source Interface=0x3E6B9488, Source Call Id=7490, Destination Call Id=7491, Disposition=0x0, Tag=0x0 102986: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/cc_api_bridge_drop_done: Conference Id=0xC55, Source Interface=0x3F31633C, Source Call Id=7491, Destination Call Id=7490, Disposition=0x0, Tag=0x0 102987: Feb 23 12:23:54.823: //7490/000C85466405/CCAPI/cc_generic_bridge_done: Conference Id=0xC55, Source Interface=0x3F31633C, Source Call Id=7491, Destination Call Id=7490, Disposition=0x0, Tag=0x0 102988: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) 102989: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 102990: Feb 23 12:23:54.823: //7491/000C85466405/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 102991: Feb 23 12:23:54.839: //7491/000C85466405/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x3F31633C, Tag=0x0, Call Id=7491, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 102992: Feb 23 12:23:54.839: //7491/000C85466405/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 102993: Feb 23 12:23:54.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 102994: Feb 23 12:23:54.839: :cc_free_feature_vsa freeing 3FF17940 102995: Feb 23 12:23:54.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 102996: Feb 23 12:23:54.839: vsacount in free is 3 102997: Feb 23 12:23:54.839: //7490/000C85466405/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 102998: Feb 23 12:23:54.839: //7490/000C85466405/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 102999: Feb 23 12:23:54.839: //7490/000C85466405/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 103000: Feb 23 12:23:54.839: //7490/000C85466405/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x3E6B9488, Tag=0x0, Call Id=7490, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) AUH_SRST# 103001: Feb 23 12:23:54.839: //7490/000C85466405/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 103002: Feb 23 12:23:54.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103003: Feb 23 12:23:54.839: :cc_free_feature_vsa freeing 3FF17A20 103004: Feb 23 12:23:54.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103005: Feb 23 12:23:54.839: vsacount in free is 2 AUH_SRST# 103006: Feb 23 12:23:56.951: //-1/80BBAF696505/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username=5955 ----- ccCallInfo IE subfields ----- cisco-ani=5955 cisco-anitype=0 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=1 dest=90555033469 cisco-desttype=0 cisco-destplan=1 cisco-rdie=FFFFFFFF cisco-rdn= 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 103007: Feb 23 12:23:56.951: //-1/80BBAF696505/CCAPI/cc_api_call_setup_ind_common: Interface=0x3E6B9488, Call Info( Calling Number=5955,(Calling Name=)(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed), Called Number=90555033469(TON=Unknown, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Incoming Dial-peer=1000, Progress Indication=NULL(0), Calling IE Present=TRUE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=7492 103008: Feb 23 12:23:56.951: //-1/80BBAF696505/CCAPI/ccCheckClipClir: In: Calling Number=5955(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed) 103009: Feb 23 12:23:56.951: //-1/80BBAF696505/CCAPI/ccCheckClipClir: Out: Calling Number=5955(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed) 103010: Feb 23 12:23:56.951: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103011: Feb 23 12:23:56.951: :cc_get_feature_vsa malloc success 103012: Feb 23 12:23:56.951: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103013: Feb 23 12:23:56.951: cc_get_feature_vsa count is 3 103014: Feb 23 12:23:56.951: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103015: Feb 23 12:23:56.951: :FEATURE_VSA attributes are: feature_name:0,feature_time:1072790056,feature_id:7492 103016: Feb 23 12:23:56.951: //7492/80BBAF696505/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=5955(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed), Called Number=90555033469(TON=Unknown, NPI=ISDN)) 103017: Feb 23 12:23:56.951: //7492/80BBAF696505/CCAPI/cc_process_call_setup_ind: Event=0x3EA02A88 103018: Feb 23 12:23:56.951: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 90555033469 103019: Feb 23 12:23:56.951: //7492/80BBAF696505/CCAPI/ccCallSetContext: Context=0x39F500E0 103020: Feb 23 12:23:56.951: //7492/80BBAF696505/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 7492 with tag 1000 to app "_ManagedAppProcess_Default" 103021: Feb 23 12:23:56.951: //7492/80BBAF696505/CCAPI/ccCallProceeding: Progress Indication=NULL(0) 103022: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=103, Params=0x39F453F0, Progress Indication=NULL(0) 103023: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccCheckClipClir: In: Calling Number=022035955(TON=National, NPI=ISDN, Screening=User, Passed, Presentation=Allowed) 103024: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccCheckClipClir: Out: Calling Number=022035955(TON=National, NPI=ISDN, Screening=User, Passed, Presentation=Allowed) 103025: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccCallSetupRequest: Destination Pattern=905[0,2,5,6,4,8].......$, Called Number=90555033469, Digit Strip=TRUE 103026: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccCallSetupRequest: Calling Number=022035955(TON=National, NPI=ISDN, Screening=User, Passed, Presentation=Allowed), Called Number=90555033469(TON=Unknown, NPI=ISDN), Redirect Number=, Display Info= Account Number=5955, Final Destination Flag=TRUE, Guid=80BBAF69-1C9C-E18A-6505-B1020A0A039B, Outgoing Dial-peer=103 103027: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username=5955 ----- ccCallInfo IE subfields ----- cisco-ani=022035955 cisco-anitype=2 cisco-aniplan=1 cisco-anipi=0 cisco-anisi=1 dest=90555033469 cisco-desttype=0 cisco-destplan=1 cisco-rdie=FFFFFFFF cisco-rdn= 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 103028: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x3F31633C, Interface Type=6, Destination=, Mode=0x0, Call Params(Calling Number=022035955,(Calling Name=)(TON=National, NPI=ISDN, Screening=User, Passed, Presentation=Allowed), Called Number=90555033469(TON=Unknown, NPI=ISDN), Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=103, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) 103029: Feb 23 12:23:56.955: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103030: Feb 23 12:23:56.955: :cc_get_feature_vsa malloc success AUH_SRST# 103031: Feb 23 12:23:56.955: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103032: Feb 23 12:23:56.955: cc_get_feature_vsa count is 4 103033: Feb 23 12:23:56.955: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 103034: Feb 23 12:23:56.955: :FEATURE_VSA attributes are: feature_name:0,feature_time:1072789832,feature_id:7493 103035: Feb 23 12:23:56.955: //7493/80BBAF696505/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 103036: Feb 23 12:23:56.955: //7493/80BBAF696505/CCAPI/ccCallSetContext: Context=0x39F453A0 103037: Feb 23 12:23:56.955: //7492/80BBAF696505/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=103 AUH_SRST# 103038: Feb 23 12:24:00.955: //7493/80BBAF696505/CCAPI/cc_api_call_proceeding: Interface=0x3F31633C, Progress Indication=NULL(0) 103039: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/cc_api_call_disconnected: Cause Value=127, Interface=0x3F31633C, Call Id=7493 103040: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=127, Retry Count=0) 103041: Feb 23 12:24:00.991: //7492/80BBAF696505/CCAPI/ccCallReleaseResources: release reserved xcoding resource. 103042: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/ccCallSetAAA_Accounting: Accounting=0, Call Id=7493 103043: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/ccCallDisconnect: Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=127) 103044: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/ccCallDisconnect: Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127) 103045: Feb 23 12:24:00.991: //7493/80BBAF696505/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 103046: Feb 23 12:24:00.999: //7493/80BBAF696505/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x3F31633C, Tag=0x0, Call Id=7493, Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0) 103047: Feb 23 12:24:00.999: //7493/80BBAF696505/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 103048: Feb 23 12:24:00.999: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103049: Feb 23 12:24:00.999: :cc_free_feature_vsa freeing 3FF17940 103050: Feb 23 12:24:00.999: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103051: Feb 23 12:24:00.999: vsacount in free is 3 103052: Feb 23 12:24:00.999: //7492/80BBAF696505/CCAPI/ccCallDisconnect: Cause Value=127, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 103053: Feb 23 12:24:00.999: //7492/80BBAF696505/CCAPI/ccCallDisconnect: Cause Value=127, Call Entry(Responsed=TRUE, Cause Value=127) 103054: Feb 23 12:24:00.999: //7492/80BBAF696505/CCAPI/cc_api_get_transfer_info: Transfer Number=NULL 103055: Feb 23 12:24:01.003: //7492/80BBAF696505/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x3E6B9488, Tag=0x0, Call Id=7492, Call Entry(Disconnect Cause=127, Voice Class Cause Code=0, Retry Count=0) 103056: Feb 23 12:24:01.003: //7492/80BBAF696505/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent 103057: Feb 23 12:24:01.003: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103058: Feb 23 12:24:01.003: :cc_free_feature_vsa freeing 3FF17A20 AUH_SRST# 103059: Feb 23 12:24:01.003: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 103060: Feb 23 12:24:01.003: vsacount in free is 2 AUH_SRST#undebug all All possible debugging has been turned off AUH_SRST#terminatio   l no monitor AUH_SRST#clear log AUH_SRST#clear logging Clear logging buffer [confirm]y AUH_SRST# AUH_SRST#exit