voipgw1#show log Syslog logging: enabled (0 messages dropped, 37 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, 27734 messages logged, xml disabled, filtering disabled Exception Logging: size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. Trap logging: level debugging, 189233 message lines logged Logging to 10.0.7.139 (udp port 514, audit disabled, link up), 188468 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging to 10.0.8.12 (udp port 514, audit disabled, link up), 188468 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging Source-Interface: VRF Name: Loopback0 Log Buffer (99999 bytes): Mar 2 19:38:53.804 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.82.127.177) Mar 2 19:48:47.325 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.82.127.177) Mar 3 00:34:54.065 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:38:08.255 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:39:29.877 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:52:09.743 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:54:12.039 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:55:19.709 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 00:57:09.632 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 01:02:08.317 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 01:03:52.764 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 01:05:36.711 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 3 01:09:36.698 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.245.168) Mar 5 14:21:22.251 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.247.120) Mar 5 14:22:56.890 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.247.120) Mar 5 14:28:00.031 UTC: %SYS-5-CONFIG_I: Configured from console by tckmm0 on vty0 (10.41.247.120) Mar 5 15:12:39.572 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.41.50.45) Mar 5 17:11:12.965 UTC: %SYS-5-CONFIG_I: Configured from console by dlog007 on vty1 (10.41.246.89) Mar 5 17:19:48.756 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty2 (10.41.50.45) Mar 5 18:36:05.200 UTC: %SSH-4-SSH2_UNEXPECTED_MSG: Unexpected message type has arrived. Terminating the connection Mar 5 18:39:32.946 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.41.50.45) Mar 5 18:48:05.985 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.41.50.45) Mar 5 19:24:38.032 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.41.50.45) Mar 5 19:28:47.524 UTC: //-1/4299BAA3983A/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani= cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=12089588038 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=0 cisco-rdnsi=0 cisco-redirectreason=0 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 Mar 5 19:28:47.524 UTC: //-1/4299BAA3983A/CCAPI/cc_api_call_setup_ind_common: Interface=0x70AB10DC, Call Info( Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=12089588038(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Incoming Dial-peer=0, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE, Source Trkgrp Route Label=FXO_EM, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 Mar 5 19:28:47.524 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.524 UTC: :cc_get_feature_vsa malloc success Mar 5 19:28:47.524 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.524 UTC: cc_get_feature_vsa count is 1 Mar 5 19:28:47.524 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.524 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1894009184,feature_id:314 Mar 5 19:28:47.524 UTC: //54713/4299BAA3983A/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=12089588038(TON=Unknown, NPI=Unknown)) Mar 5 19:28:47.524 UTC: //54713/4299BAA3983A/CCAPI/cc_process_call_setup_ind: Event=0x680A6390 Mar 5 19:28:47.524 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Try with the demoted called number 12089588038 Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCallSetContext: Context=0x68905B6C Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 54713 with tag 0 to app "_ManagedAppProcess_Default" Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCallProceeding: Progress Indication=NULL(0) Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=FALSE, Mode=0, Outgoing Dial-peer=2, Params=0x688FF3FC, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCheckClipClir: In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCheckClipClir: Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCallSetupRequest: Destination Pattern=1208958...., Called Number=12089588038, Digit Strip=FALSE Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccCallSetupRequest: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=12089588038(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=TRUE, Guid=4299BAA3-1FE2-11E8-983A-AFF330C1FAB3, Outgoing Dial-peer=2 Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani= cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=12089588038 cisco-desttype=0 cisco-destplan=0 cisco-rdie=FFFFFFFF cisco-rdn= cisco-rdntype=0 cisco-rdnplan=0 cisco-rdnpi=0 cisco-rdnsi=0 cisco-redirectreason=0 fwd_final_type =0 final_redirectNumber = hunt_group_timeout =0 Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x67FAA034, Interface Type=3, Destination=, Mode=0x0, Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=12089588038(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=2, Call Count On=FALSE, Source Trkgrp Route Label=FXO_EM, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=) Mar 5 19:28:47.528 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.528 UTC: :cc_get_feature_vsa malloc success Mar 5 19:28:47.528 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.528 UTC: cc_get_feature_vsa count is 2 Mar 5 19:28:47.528 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: Mar 5 19:28:47.528 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:1894009408,feature_id:315 Mar 5 19:28:47.528 UTC: //54714/4299BAA3983A/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1 Mar 5 19:28:47.528 UTC: //54714/4299BAA3983A/CCAPI/ccCallSetContext: Context=0x688FF3AC Mar 5 19:28:47.528 UTC: //54713/4299BAA3983A/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=2 Mar 5 19:28:47.532 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_proceeding: Interface=0x67FAA034, Progress Indication=NULL(0) Mar 5 19:28:47.732 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_alert: Interface=0x67FAA034, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) Mar 5 19:28:47.732 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_alert: Call Entry(Retry Count=0, Responsed=TRUE) Mar 5 19:28:47.732 UTC: //54713/4299BAA3983A/CCAPI/ccCallAlert: Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) Mar 5 19:28:47.732 UTC: //54713/4299BAA3983A/CCAPI/ccCallAlert: Call Entry(Responsed=TRUE, Alert Sent=TRUE) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_connected: Interface=0x67FAA034, Data Bitmask=0x81000001, Progress Indication=NULL(0), Connection Handle=0 Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_connected: Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=54713 Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccConferenceCreate: (confID=0x687A0210, callID1=0xD5B9, gcid=4299BAA3-1FE211E8-983AAFF3-30C1FAB3, tag=0x0) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/ccConferenceCreate: (confID=0x687A0210, callID2=0xD5BA, gcid=4299BAA3-1FE211E8-983AAFF3-30C1FAB3, tag=0x0) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccConferenceCreate: Conference Id=0x687A0210, Call Id1=54713, Call Id2=54714, Tag=0x0 Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/cc_api_bridge_done: Conference Id=0x13, Source Interface=0x70AB10DC, Source Call Id=54713, Destination Call Id=54714, Disposition=0x0, Tag=0xFFFFFFFF Mar 5 19:28:49.840 UTC: //54714/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:28:49.840 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:28:49.840 UTC: //54714/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:28:49.840 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/cc_api_bridge_done: Conference Id=0x13, Source Interface=0x67FAA034, Source Call Id=54714, Destination Call Id=54713, Disposition=0x0, Tag=0x0 Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/cc_generic_bridge_done: Conference Id=0x13, Source Interface=0x67FAA034, Source Call Id=54714, Destination Call Id=54713, Disposition=0x0, Tag=0x0 Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x13, Destination Call Id=54714) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x13, Destination Call Id=54713) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/cc_api_caps_ind: Destination Interface=0x67FAA034, Destination Call Id=54714, Source Call Id=54713, Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1, Modem=0x2, Codec Bytes=20, Signal Type=3) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/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)) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/cc_api_caps_ind: Destination Interface=0x70AB10DC, Destination Call Id=54713, Source Call Id=54714, Caps(Codec=0x4, Fax Rate=0x2, Vad=0x1, Modem=0x0, Codec Bytes=20, Signal Type=2) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/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)) Mar 5 19:28:49.840 UTC: //54714/4299BAA3983A/CCAPI/cc_api_caps_ack: Destination Interface=0x70AB10DC, Destination Call Id=54713, Source Call Id=54714, Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=4284) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/cc_api_caps_ack: Destination Interface=0x67FAA034, Destination Call Id=54714, Source Call Id=54713, Caps(Codec=g729r8(0x4), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1), Modem=OFF(0x0), Codec Bytes=20, Signal Type=2, Seq Num Start=4284) Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccCallConnect: Progress Indication=NULL(0), Data Bitmask=0x81000001 Mar 5 19:28:49.840 UTC: //54713/4299BAA3983A/CCAPI/ccCallConnect: Call Entry(Connected=TRUE, Responsed=TRUE) Mar 5 19:28:49.844 UTC: //54713/4299BAA3983A/CCAPI/cc_api_voice_mode_event: Call Id=54713 Mar 5 19:28:49.844 UTC: //54713/4299BAA3983A/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x68905B6C) Mar 5 19:28:49.844 UTC: //54713/4299BAA3983A/CCAPI/cc_process_notify_bridge_done: Conference Id=0x13, Call Id1=54713, Call Id2=54714 Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/cc_api_call_disconnected: Cause Value=16, Interface=0x70AB10DC, Call Id=54713 Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/ccConferenceDestroy: Conference Id=0x13, Tag=0x0 Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/cc_api_bridge_drop_done: Conference Id=0x13, Source Interface=0x70AB10DC, Source Call Id=54713, Destination Call Id=54714, Disposition=0x0, Tag=0x0 Mar 5 19:29:04.972 UTC: //54714/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:29:04.972 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:29:04.972 UTC: //54713/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:29:04.972 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:29:04.972 UTC: //54714/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:29:04.972 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:29:04.972 UTC: //54714/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream: Mar 5 19:29:04.972 UTC: cc_api_get_xcode_stream : 4702 Mar 5 19:29:04.972 UTC: //54714/4299BAA3983A/CCAPI/cc_api_bridge_drop_done: Conference Id=0x13, Source Interface=0x67FAA034, Source Call Id=54714, Destination Call Id=54713, Disposition=0x0, Tag=0x0 Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/cc_generic_bridge_done: Conference Id=0x13, Source Interface=0x67FAA034, Source Call Id=54714, Destination Call Id=54713, Disposition=0x0, Tag=0x0 Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) Mar 5 19:29:04.972 UTC: //54713/4299BAA3983A/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null Mar 5 19:29:04.972 UTC: //54714/4299BAA3983A/CCAPI/ccCallDisconnect: Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) Mar 5 19:29:04.972 UTC: //54714/4299BAA3983A/CCAPI/ccCallDisconnect: Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) Mar 5 19:29:04.996 UTC: //54713/4299BAA3983A/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x70AB10DC, Tag=0x0, Call Id=54713, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) Mar 5 19:29:04.996 UTC: //54713/4299BAA3983A/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent Mar 5 19:29:04.996 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Mar 5 19:29:04.996 UTC: :cc_free_feature_vsa freeing 70E44958 Mar 5 19:29:04.996 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Mar 5 19:29:04.996 UTC: vsacount in free is 1 Mar 5 19:29:05.016 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x67FAA034, Tag=0x0, Call Id=54714, Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) Mar 5 19:29:05.016 UTC: //54714/4299BAA3983A/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent Mar 5 19:29:05.016 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Mar 5 19:29:05.016 UTC: :cc_free_feature_vsa freeing 70E44A38 Mar 5 19:29:05.016 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: Mar 5 19:29:05.016 UTC: vsacount in free is 0 Mar 5 19:32:05.021 UTC: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode: ccAppShutdownMode: remove it from the queue voipgw1#