CFWD goes busy, but regular call works

Jaswild999 · ‎02-04-2014

I am assisting with CME 7.0, the site users want to use CFWD, but the call either responds with fast busy or call can not be completed as dialed. If I have the user dial the number directly it dials out and the call connects.

If they press CFWD on the phone and dial the same call that just completed, the call fails. The regular call will hit the PRI with dial-peer 8, the CFWD skips dial-peer 8 and 19 then fails. Hoping someone can spot something in the following debugs or has another idea as to why this occurs?

The following are from 'deb voice ccapi inout':

Regular call, connects:

Feb 5 04:04:59.305: //22126/7FD887CCBC96/CCAPI/ccCallSetupRequest:

Calling Number=123(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9######6403(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=SteveG

Account Number=, Final Destination Flag=FALSE,

Guid=7FD887CC-8D51-11E3-BC96-80330B61217B, Outgoing Dial-peer=8

Feb 5 04:04:59.305: //22126/7FD887CCBC96/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=123

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9######6403

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

Feb 5 04:04:59.305: //22126/7FD887CCBC96/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4B82536C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=123,(Calling Name=SteveG)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=A

llowed),

Called Number=9######6403(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=8, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Feb 5 04:04:59.305: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:04:59.305: :cc_get_feature_vsa malloc success

Feb 5 04:04:59.305: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:04:59.305: cc_get_feature_vsa count is 2

Feb 5 04:04:59.305: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:04:59.305: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268693680,feature_id:44252

Feb 5 04:04:59.309: //22127/7FD887CCBC96/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 5 04:04:59.309: //22127/7FD887CCBC96/CCAPI/ccCallSetContext:

Context=0x4BA085C0

Feb 5 04:04:59.309: //22126/7FD887CCBC96/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=8

chrs-phx-cme#

Feb 5 04:04:59.341: //22127/7FD887CCBC96/CCAPI/cc_api_call_proceeding:

Interface=0x4B82536C, Progress Indication=NULL(0)

Feb 5 04:05:00.417: //22127/7FD887CCBC96/CCAPI/cc_api_call_cut_progress:

Interface=0x4B82536C, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),

Cause Value=0

Feb 5 04:05:00.417: //22127/7FD887CCBC96/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccCallCutProgress:

Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0

Voice Call Send Alert=FALSE, Call Entry(Alert Sent=FALSE)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccCallCutProgress:

Call Entry(Responsed=TRUE)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=22126

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccConferenceCreate:

(confID=0x4BACF6DC, callID1=0x566E, gcid=7FD887CC-8D5111E3-BC968033-B61217B, tag=0x0)

Feb 5 04:05:00.421: //22127/7FD887CCBC96/CCAPI/ccConferenceCreate:

(confID=0x4BACF6DC, callID2=0x566F, gcid=7FD887CC-8D5111E3-BC968033-B61217B, tag=0x0)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccConferenceCreate:

Conference Id=0x4BACF6DC, Call Id1=22126, Call Id2=22127, Tag=0x0

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/cc_api_bridge_done:

Conference Id=0x1B47, Source Interface=0x4B3E529C, Source Call Id=22126,

Destination Call Id=22127, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:05:00.421: //22127/7FD887CCBC96/CCAPI/cc_api_bridge_done:

Conference Id=0x1B47, Source Interface=0x4B82536C, Source Call Id=22127,

Destination Call Id=22126, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B47, Source Interface=0x4B82536C, Source Call Id=22127,

Destination Call Id=22126, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B47, Destination Call Id=22127)

Feb 5 04:05:00.421: //22127/7FD887CCBC96/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B47, Destination Call Id=22126)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B82536C, Destination Call Id=22127, Source Call Id=22126,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/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))

Feb 5 04:05:00.421: //22127/7FD887CCBC96/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B3E529C, Destination Call Id=22126, Source Call Id=22127,

Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:05:00.421: //22127/7FD887CCBC96/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))

Feb 5 04:05:00.421: //22127/7FD887CCBC96/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B3E529C, Destination Call Id=22126, Source Call Id=22127,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=9639)

Feb 5 04:05:00.421: //22126/7FD887CCBC96/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B82536C, Destination Call Id=22127, Source Call Id=22126,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=3197)

Feb 5 04:05:00.425: //22126/7FD887CCBC96/CCAPI/ccCallFeature:

Feature Type=25, Call Id=22126

Feb 5 04:05:00.425: //22126/7FD887CCBC96/CCAPI/cc_api_voice_mode_event:

Call Id=22126

Feb 5 04:05:00.425: //22126/7FD887CCBC96/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA17620)

Feb 5 04:05:00.429: //22127/7FD887CCBC96/CCAPI/cc_api_voice_mode_event:

Call Id=22127

chrs-phx-cme#

Feb 5 04:05:00.429: //22127/7FD887CCBC96/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA085C0)

Feb 5 04:05:00.429: //22126/7FD887CCBC96/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x1B47, Call Id1=22126, Call Id2=22127

chrs-phx-cme#

Feb 5 04:05:04.633: //22126/7FD887CCBC96/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x4B3E529C, Call Id=22126

Feb 5 04:05:04.633: //22126/7FD887CCBC96/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

Feb 5 04:05:04.633: //22126/7FD887CCBC96/CCAPI/ccConferenceDestroy:

Conference Id=0x1B47, Tag=0x0

Feb 5 04:05:04.633: //22126/7FD887CCBC96/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B47, Source Interface=0x4B3E529C, Source Call Id=22126,

Destination Call Id=22127, Disposition=0x0, Tag=0x0

Feb 5 04:05:04.633: //22127/7FD887CCBC96/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B47, Source Interface=0x4B82536C, Source Call Id=22127,

Destination Call Id=22126, Disposition=0x0, Tag=0x0

Feb 5 04:05:04.633: //22126/7FD887CCBC96/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B47, Source Interface=0x4B82536C, Source Call Id=22127,

Destination Call Id=22126, Disposition=0x0, Tag=0x0

Feb 5 04:05:04.633: //22127/7FD887CCBC96/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 5 04:05:04.633: //22127/7FD887CCBC96/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 5 04:05:04.633: //22127/7FD887CCBC96/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:05:04.637: //22126/7FD887CCBC96/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

Feb 5 04:05:04.637: //22126/7FD887CCBC96/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 5 04:05:04.637: //22126/7FD887CCBC96/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:05:04.641: //22126/7FD887CCBC96/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B3E529C, Tag=0x0, Call Id=22126,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:05:04.641: //22126/7FD887CCBC96/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:05:04.641: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:05:04.641: :cc_free_feature_vsa freeing 4B9ED248

Feb 5 04:05:04.641: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:05:04.641: vsacount in free is 1

Feb 5 04:05:04.681: //22127/7FD887CCBC96/CCAPI/cc_api_call_disconnect_done:

chrs-phx-cme#Disposition=0, Interface=0x4B82536C, Tag=0x0, Call Id=22127,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:05:04.681: //22127/7FD887CCBC96/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:05:04.681: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:05:04.681: :cc_free_feature_vsa freeing 4B9EBAA8

Feb 5 04:05:04.681: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:05:04.681: vsacount in free is 0

phx-cme#

CFWD Call, fails (starts at dial-peer 8 and then dial-peer 19):

Feb 5 04:01:14.989: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=123(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=JRuddy

Account Number=, Final Destination Flag=FALSE,

Guid=FCBFBDC6-8D50-11E3-BC8C-80330B61217B, Outgoing Dial-peer=20011

Feb 5 04:01:14.989: //22122/FCBFBDC6BC8C/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=142

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=123

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

Feb 5 04:01:14.989: //22122/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4B3E529C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=142,(Calling Name=JRuddy)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=A

llowed),

Called Number=123(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=20011, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Feb 5 04:01:14.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:14.989: :cc_get_feature_vsa malloc success

Feb 5 04:01:14.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:14.989: cc_get_feature_vsa count is 2

Feb 5 04:01:14.989: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:14.989: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268699728,feature_id:44242

Feb 5 04:01:14.989: //22123/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 5 04:01:14.989: //22123/FCBFBDC6BC8C/CCAPI/ccCallSetContext:

Context=0x4B9FF988

Feb 5 04:01:14.989: //22122/FCBFBDC6BC8C/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=20011

Feb 5 04:01:14.993: //22123/FCBFBDC6BC8C/CCAPI/cc_api_update_call_info:

Interface=0x4B3E529C, Call Id=0x566B

Feb 5 04:01:14.997: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_proceeding:

Interface=0x4B3E529C, Progress Indication=NULL(0)

Feb 5 04:01:15.001: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_alert:

Interface=0x4B3E529C, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

Feb 5 04:01:15.001: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_alert:

Call Entry(Retry Count=0, Responsed=TRUE)

Feb 5 04:01:15.001: //22122/FCBFBDC6BC8C/CCAPI/ccCallAlert:

Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

Feb 5 04:01:15.001: //22122/FCBFBDC6BC8C/CCAPI/ccCallAlert:

Call Entry(Responsed=TRUE, Alert Sent=TRUE)

chrs-phx-cme#

Feb 5 04:01:15.001: //22122/FCBFBDC6BC8C/CCAPI/ccCallFeature:

Feature Type=25, Call Id=22122

chrs-phx-cme#

Feb 5 04:01:25.001: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Cause Value=19, Interface=0x4B3E529C, Call Id=22123

Feb 5 04:01:25.001: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=19, Retry Count=0)

Feb 5 04:01:25.001: //22122/FCBFBDC6BC8C/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

Feb 5 04:01:25.001: //22123/FCBFBDC6BC8C/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=123

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:

type=6 Original Called Number=123, Called Number=123, Calling Number=142, Calling DN=216 Calling Id=22122,

Redirect Number=9###6403, Redirect Reason=2

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=123

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: cc_get_feature_vsa count is 3

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268693232,feature_id:44243

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.001: feature_vsa is 4B9ED6A8,featname is 0

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: cc_get_feature_vsa count is 4

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268696592,feature_id:44244

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.001: : Generating new featurevsa,inserting it

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.001: feature call forward featname is 3

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.001: feature_vsa is 4B9ED248,featname is 0

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.001: cc_get_feature_vsa count is 5

Feb 5 04:01:25.001: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.005: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268693680,feature_id:44245

Feb 5 04:01:25.005: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.005: : Generating new featurevsa,inserting it

Feb 5 04:01:25.005: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.005: feature call forward featname is 3

Feb 5 04:01:25.005: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.005: :cc_free_feature_vsa freeing 4B9EB8E8

Feb 5 04:01:25.005: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.005: vsacount in free is 4

Feb 5 04:01:25.005: //22122/FCBFBDC6BC8C/CCAPI/ccCallForwardTo:

Call Forward Number=96025056403, Call Forward Cause Reason=2, Call Id=22122

Feb 5 04:01:25.005: //22122/FCBFBDC6BC8C/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=22122

Feb 5 04:01:25.005: //22123/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=19)

Feb 5 04:01:25.005: //22123/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=19, Call Entry(Responsed=TRUE, Cause Value=19)

Feb 5 04:01:25.005: //22123/FCBFBDC6BC8C/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:01:25.009: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B3E529C, Tag=0x0, Call Id=22123,

Call Entry(Disconnect Cause=19, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:01:25.013: //22123/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.013: :cc_free_feature_vsa freeing 4B9EBAA8

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.013: vsacount in free is 3

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.013: vsacount in free is 2

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.013: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.013: cc_get_feature_vsa count is 3

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.013: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268699728,feature_id:44246

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.013: feature call forward featname is 3

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.013: :cc_free_feature_vsa freeing 4B9ED248

Feb 5 04:01:25.013: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:25.013: vsacount in free is 2

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCallForwardTo:

Call Forward Number=96025056403, Call Forward Cause Reason=2, Call Id=22122

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=8, Params=0x4BA021E8, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCheckClipClir:

In: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCheckClipClir:

Out: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Destination Pattern=9T, Called Number=96025056403, Digit Strip=TRUE

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=96025056403(TON=Unknown, NPI=Unknown),

Redirect Number=123, Display Info=JRuddy

Account Number=, Final Destination Flag=FALSE,

Guid=03FF379D-8D51-11E3-BC91-80330B61217B, Outgoing Dial-peer=8

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=142

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9###6403

cisco-desttype=0

cisco-destplan=0

cisco-rdie=73

cisco-rdn=123

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=2 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

Feb 5 04:01:25.017: //22122/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4B82536C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=142,(Calling Name=JRuddy)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=A

llowed),

Called Number=9###6403(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=8, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Feb 5 04:01:25.017: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.017: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.017: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.021: cc_get_feature_vsa count is 3

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.021: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268699728,feature_id:44247

Feb 5 04:01:25.021: //22124/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 5 04:01:25.021: //22124/FCBFBDC6BC8C/CCAPI/ccCallSetContext:

Context=0x4BA02198

Feb 5 04:01:25.021: //22122/FCBFBDC6BC8C/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=8

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.021: feature call forward featname is 3

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.021: feature_vsa is 4B9ED248,featname is 0

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.021: :cc_get_feature_vsa malloc success

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.021: cc_get_feature_vsa count is 4

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:25.021: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268693680,feature_id:44248

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

chrs-phx-cme#

Feb 5 04:01:25.021: : Generating new featurevsa,inserting it

Feb 5 04:01:25.021: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:25.021: feature call forward featname is 3

Feb 5 04:01:25.045: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_proceeding:

Interface=0x4B82536C, Progress Indication=NULL(0)

Feb 5 04:01:25.141: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_cut_progress:

Interface=0x4B82536C, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),

Cause Value=0

Feb 5 04:01:25.141: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccCallCutProgress:

Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0

Voice Call Send Alert=FALSE, Call Entry(Alert Sent=TRUE)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccCallCutProgress:

Call Entry(Responsed=TRUE)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=22122

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

(confID=0x4BACF64C, callID1=0x566A, gcid=FCBFBDC6-8D5011E3-BC8C8033-B61217B, tag=0x0)

Feb 5 04:01:25.145: //22124/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

(confID=0x4BACF64C, callID2=0x566C, gcid=FCBFBDC6-8D5011E3-BC8C8033-B61217B, tag=0x0)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Conference Id=0x4BACF64C, Call Id1=22122, Call Id2=22124, Tag=0x0

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/cc_api_bridge_done:

Conference Id=0x1B45, Source Interface=0x4B4ABC14, Source Call Id=22122,

Destination Call Id=22124, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:25.145: //22124/FCBFBDC6BC8C/CCAPI/cc_api_bridge_done:

Conference Id=0x1B45, Source Interface=0x4B82536C, Source Call Id=22124,

Destination Call Id=22122, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B45, Source Interface=0x4B82536C, Source Call Id=22124,

Destination Call Id=22122, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B45, Destination Call Id=22124)

Feb 5 04:01:25.145: //22124/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B45, Destination Call Id=22122)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B82536C, Destination Call Id=22124, Source Call Id=22122,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:01:25.145: //22122/FCBFBDC6BC8C/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))

Feb 5 04:01:25.145: //22124/FCBFBDC6BC8C/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B4ABC14, Destination Call Id=22122, Source Call Id=22124,

Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:01:25.145: //22124/FCBFBDC6BC8C/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))

Feb 5 04:01:25.149: //22124/FCBFBDC6BC8C/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B4ABC14, Destination Call Id=22122, Source Call Id=22124,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=7794)

Feb 5 04:01:25.149: //22122/FCBFBDC6BC8C/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B82536C, Destination Call Id=22124, Source Call Id=22122,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=2870)

Feb 5 04:01:25.149: //22122/FCBFBDC6BC8C/CCAPI/ccCallFeature:

Feature Type=25, Call Id=22122

Feb 5 04:01:25.149: //22122/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Id=22122

Feb 5 04:01:25.153: //22122/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA16218)

Feb 5 04:01:25.153: //22124/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Id=22124

chrs-phx-cme#

Feb 5 04:01:25.153: //22124/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA02198)

Feb 5 04:01:25.153: //22122/FCBFBDC6BC8C/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x1B45, Call Id1=22122, Call Id2=22124

chrs-phx-cme#

Feb 5 04:01:35.585: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Cause Value=18, Interface=0x4B82536C, Call Id=22124

Feb 5 04:01:35.585: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=18, Retry Count=0)

Feb 5 04:01:35.585: //22122/FCBFBDC6BC8C/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

Feb 5 04:01:35.585: //22124/FCBFBDC6BC8C/CCAPI/ccCallSetAAA_Accounting:

Accounting=1, Call Id=22124

Feb 5 04:01:35.585: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceDestroy:

Conference Id=0x1B45, Tag=0x0

Feb 5 04:01:35.589: //22122/FCBFBDC6BC8C/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B45, Source Interface=0x4B4ABC14, Source Call Id=22122,

Destination Call Id=22124, Disposition=0x0, Tag=0x0

Feb 5 04:01:35.589: //22124/FCBFBDC6BC8C/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B45, Source Interface=0x4B82536C, Source Call Id=22124,

Destination Call Id=22122, Disposition=0x0, Tag=0x0

Feb 5 04:01:35.589: //22122/FCBFBDC6BC8C/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B45, Source Interface=0x4B82536C, Source Call Id=22124,

Destination Call Id=22122, Disposition=0x0, Tag=0x0

Feb 5 04:01:35.589: //22124/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=18, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=18)

Feb 5 04:01:35.589: //22124/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=18, Call Entry(Responsed=TRUE, Cause Value=18)

Feb 5 04:01:35.589: //22124/FCBFBDC6BC8C/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:01:35.589: //22122/FCBFBDC6BC8C/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Ring Back,

Tone Direction=Network, Params=0x0, Call Id=22122

Feb 5 04:01:35.605: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B82536C, Tag=0x0, Call Id=22124,

Call Entry(Disconnect Cause=18, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:01:35.605: //22124/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:01:35.605: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:35.605: :cc_free_feature_vsa freeing 4B9EBAA8

Feb 5 04:01:35.605: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:35.605: vsacount in free is 3

Feb 5 04:01:35.605: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:35.605: vsacount in free is 2

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=19, Params=0x4BA07208, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccCheckClipClir:

In: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccCheckClipClir:

Out: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Destination Pattern=9T, Called Number=96025056403, Digit Strip=TRUE

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccCallSetupRequest:

Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=96025056403(TON=Unknown, NPI=Unknown),

Redirect Number=123, Display Info=JRuddy

Account Number=, Final Destination Flag=FALSE,

Guid=03FF379D-8D51-11E3-BC91-80330B61217B, Outgoing Dial-peer=19

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=142

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9###6403

cisco-desttype=0

cisco-destplan=0

cisco-rdie=73

cisco-rdn=123

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=2 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

Feb 5 04:01:35.609: //22122/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4B82536C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=142,(Calling Name=JRuddy)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=A

llowed),

Called Number=9###6403(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=19, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Feb 5 04:01:35.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.609: :cc_get_feature_vsa malloc success

Feb 5 04:01:35.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.609: cc_get_feature_vsa count is 3

Feb 5 04:01:35.609: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.609: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268699728,feature_id:44249

Feb 5 04:01:35.613: //22125/FCBFBDC6BC8C/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 5 04:01:35.613: //22125/FCBFBDC6BC8C/CCAPI/ccCallSetContext:

Context=0x4BA071B8

Feb 5 04:01:35.613: //22122/FCBFBDC6BC8C/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=19

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:35.613: feature call forward featname is 3

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:35.613: feature_vsa is 4B9ED248,featname is 0

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.613: :cc_get_feature_vsa malloc success

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.613: cc_get_feature_vsa count is 4

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 5 04:01:35.613: :FEATURE_VSA attributes are: feature_name:0,feature_time:1268693680,feature_id:44250

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:35.613: : Generating new featurevsa,inserting it

Feb 5 04:01:35.613: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 5 04:01:35.613: feature call forward featname is 3

Feb 5 04:01:35.645: //22125/FCBFBDC6BC8C/CCAPI/cc_api_call_proceeding:

Interface=0x4B82536C, Progress Indication=NULL(0)

Feb 5 04:01:35.745: //22125/FCBFBDC6BC8C/CCAPI/cc_api_call_cut_progress:

Interface=0x4B82536C, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),

Cause Value=0

Feb 5 04:01:35.745: //22125/FCBFBDC6BC8C/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccCallCutProgress:

Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0

Voice Call Send Alert=FALSE, Call Entry(Alert Sent=TRUE)

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccCallCutProgress:

Call Entry(Responsed=TRUE)

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=22122

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

(confID=0x4BACF4E4, callID1=0x566A, gcid=FCBFBDC6-8D5011E3-BC8C8033-B61217B, tag=0x0)

Feb 5 04:01:35.745: //22125/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

(confID=0x4BACF4E4, callID2=0x566D, gcid=FCBFBDC6-8D5011E3-BC8C8033-B61217B, tag=0x0)

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Conference Id=0x4BACF4E4, Call Id1=22122, Call Id2=22125, Tag=0x0

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/cc_api_bridge_done:

Conference Id=0x1B46, Source Interface=0x4B4ABC14, Source Call Id=22122,

Destination Call Id=22125, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:35.745: //22125/FCBFBDC6BC8C/CCAPI/cc_api_bridge_done:

Conference Id=0x1B46, Source Interface=0x4B82536C, Source Call Id=22125,

Destination Call Id=22122, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B46, Source Interface=0x4B82536C, Source Call Id=22125,

Destination Call Id=22122, Disposition=0x0, Tag=0xFFFFFFFF

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B46, Destination Call Id=22125)

Feb 5 04:01:35.745: //22125/FCBFBDC6BC8C/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1B46, Destination Call Id=22122)

Feb 5 04:01:35.745: //22122/FCBFBDC6BC8C/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B82536C, Destination Call Id=22125, Source Call Id=22122,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:01:35.749: //22122/FCBFBDC6BC8C/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))

Feb 5 04:01:35.749: //22125/FCBFBDC6BC8C/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B4ABC14, Destination Call Id=22122, Source Call Id=22125,

Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 5 04:01:35.749: //22125/FCBFBDC6BC8C/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))

Feb 5 04:01:35.749: //22125/FCBFBDC6BC8C/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B4ABC14, Destination Call Id=22122, Source Call Id=22125,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=81)

Feb 5 04:01:35.749: //22122/FCBFBDC6BC8C/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B82536C, Destination Call Id=22125, Source Call Id=22122,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=4431)

Feb 5 04:01:35.749: //22122/FCBFBDC6BC8C/CCAPI/ccCallFeature:

Feature Type=25, Call Id=22122

Feb 5 04:01:35.753: //22122/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Id=22122

Feb 5 04:01:35.753: //22122/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA16218)

Feb 5 04:01:35.753: //22125/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Id=22125

chrs-phx-cme#

Feb 5 04:01:35.753: //22125/FCBFBDC6BC8C/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x4BA071B8)

Feb 5 04:01:35.753: //22122/FCBFBDC6BC8C/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x1B46, Call Id1=22122, Call Id2=22125

chrs-phx-cme#

Feb 5 04:01:39.145: //22122/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x4B4ABC14, Call Id=22122

Feb 5 04:01:39.145: //22122/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

Feb 5 04:01:39.145: //22122/FCBFBDC6BC8C/CCAPI/ccConferenceDestroy:

Conference Id=0x1B46, Tag=0x0

Feb 5 04:01:39.145: //22122/FCBFBDC6BC8C/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B46, Source Interface=0x4B4ABC14, Source Call Id=22122,

Destination Call Id=22125, Disposition=0x0, Tag=0x0

Feb 5 04:01:39.145: //22125/FCBFBDC6BC8C/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1B46, Source Interface=0x4B82536C, Source Call Id=22125,

Destination Call Id=22122, Disposition=0x0, Tag=0x0

Feb 5 04:01:39.145: //22122/FCBFBDC6BC8C/CCAPI/cc_generic_bridge_done:

Conference Id=0x1B46, Source Interface=0x4B82536C, Source Call Id=22125,

Destination Call Id=22122, Disposition=0x0, Tag=0x0

Feb 5 04:01:39.145: //22125/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 5 04:01:39.145: //22125/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 5 04:01:39.145: //22125/FCBFBDC6BC8C/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:01:39.149: //22122/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

Feb 5 04:01:39.149: //22122/FCBFBDC6BC8C/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 5 04:01:39.149: //22122/FCBFBDC6BC8C/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 5 04:01:39.153: //22122/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B4ABC14, Tag=0x0, Call Id=22122,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:01:39.153: //22122/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:01:39.153: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.153: :cc_free_feature_vsa freeing 4B9EC608

Feb 5 04:01:39.153: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.153: vsacount in free is 3

Feb 5 04:01:39.153: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.153: vsacount in free is 2

Feb 5 04:01:39.185: //22125/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

chrs-phx-cme#Disposition=0, Interface=0x4B82536C, Tag=0x0, Call Id=22125,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 5 04:01:39.185: //22125/FCBFBDC6BC8C/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 5 04:01:39.185: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.185: :cc_free_feature_vsa freeing 4B9EBAA8

Feb 5 04:01:39.185: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.185: vsacount in free is 1

Feb 5 04:01:39.185: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 5 04:01:39.185: vsacount in free is 0

chrs-phx-cme#

Nishant Savalia · ‎02-06-2014

Hi Jason,

Can you please share "debug isdn q931" and "debug voice ccapi inout" & "debug voice dialpeer" separately for the failed call.

Regards,
Nishant Savalia

Regards, Nishant Savalia

Jaswild999 · ‎02-13-2014

Nishant,

Console logging: disabled

Monitor logging: disabled

Buffer logging: level debugging, 617 messages logged, xml disabled,

filtering disabled

Exception Logging: size (4096 bytes)

Count and timestamp logging messages: disabled

Persistent logging: disabled

Trap logging: level informational, 610 message lines logged

--More--

Log Buffer (300000 bytes):

Feb 14 01:03:42.644: ISDN Se0/3/0:23 Q931: Applying typeplan for sw-type 0xD is 0x0 0x0, Calling num 142

Feb 14 01:03:42.644: ISDN Se0/3/0:23 Q931: Sending SETUP callref = 0x00EF callID = 0x8070 switch = primary-ni interface

= User

Feb 14 01:03:42.644: ISDN Se0/3/0:23 Q931: TX -> SETUP pd = 8 callref = 0x00EF

Bearer Capability i = 0x8090A2

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA9838A

Exclusive, Channel 10

Progress Ind i = 0x8183 - Origination address is non-ISDN

Calling Party Number i = 0x0080, '142'

Plan:Unknown, Type:Unknown

Called Party Number i = 0xA1, '602XXXX03'

Plan:ISDN, Type:National

Redirecting Number i = 0x00008F, '123'

Plan:Unknown, Type:Unknown

Feb 14 01:03:42.676: ISDN Se0/3/0:23 Q931: RX <- CALL_PROC pd = 8 callref = 0x80EF

Channel ID i = 0xA9838A

Exclusive, Channel 10

Feb 14 01:03:42.776: ISDN Se0/3/0:23 Q931: RX <- PROGRESS pd = 8 callref = 0x80EF

Progress Ind i = 0x8288 - In-band info or appropriate now available

Feb 14 01:03:47.696: ISDN Se0/3/0:23 Q931: TX -> DISCONNECT pd = 8 callref = 0x00EF

Cause i = 0x8090 - Normal call clearing

Feb 14 01:03:47.716: ISDN Se0/3/0:23 Q931: RX <- RELEASE pd = 8 callref = 0x80EF

--More-- Feb 14 01:03:47.716: ISDN Se0/3/0:23 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x00EF

chrs-phx-cme#

chrs-phx-cme#u all

All possible debugging has been turned off

chrs-phx-cme#

chrs-phx-cme#cle logg

Clear logging buffer [confirm]

chrs-phx-cme#

chrs-phx-cme#debug voice ccapi inout

voip ccapi inout debugging is on

chrs-phx-cme#sh term len 0

chrs-phx-cme#sh logg

Syslog logging: enabled (0 messages dropped, 10 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering di

sabled)

No Active Message Discriminator.

No Inactive Message Discriminator.

Console logging: disabled

Monitor logging: disabled

Buffer logging: level debugging, 910 messages logged, xml disabled,

filtering disabled

Exception Logging: size (4096 bytes)

Count and timestamp logging messages: disabled

Persistent logging: disabled

Trap logging: level informational, 610 message lines logged

Log Buffer (300000 bytes):

Feb 14 01:04:24.280: //-1/C74292F49068/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=142

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=

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

Feb 14 01:04:24.280: //-1/C74292F49068/CCAPI/cc_api_call_setup_ind_common:

Interface=0x4B53AD64, Call Info(

Calling Number=142,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,

Incoming Dial-peer=20027, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1

Feb 14 01:04:24.280: //-1/C74292F49068/CCAPI/ccCheckClipClir:

In: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 14 01:04:24.280: //-1/C74292F49068/CCAPI/ccCheckClipClir:

Out: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 14 01:04:24.280: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:24.280: :cc_get_feature_vsa malloc success

Feb 14 01:04:24.280: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:24.280: cc_get_feature_vsa count is 3

Feb 14 01:04:24.280: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:24.280: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205170384,feature_id:2681

Feb 14 01:04:24.280: //1486/C74292F49068/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown))

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_process_call_setup_ind:

Event=0x4943C8E0

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccCallSetContext:

Context=0x47D7EC18

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 1486 with tag 20027 to app "_ManagedAppProcess_Default"

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccCallSetupAck:

Call Id=1486

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_api_set_transfer_info:

Transfer Number=, Transfer Reason=0x0

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=TRUE, Tone=Dial Tone,

Tone Direction=Network, Params=0x0, Call Id=1486

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccSetDigitTimeouts:

Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccSetDigitTimeouts:

Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccCallReportDigits:

(callID=0x5CE, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=1486

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x4B53AD64, callID=0x5CE, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x4B53AD64, Call Id=1486

Feb 14 01:04:24.284: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))

Feb 14 01:04:25.280: //1486/C74292F49068/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=1, DigitBeginFlags=0x0,

Rtp Timestamp=0x130718, Rtp Expiration=0x0

Feb 14 01:04:25.280: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=1, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Feb 14 01:04:25.280: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Feb 14 01:04:25.568: //1486/C74292F49068/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=2, DigitBeginFlags=0x0,

Rtp Timestamp=0x138418, Rtp Expiration=0x0

Feb 14 01:04:25.568: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=2, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Feb 14 01:04:25.568: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=3, DigitBeginFlags=0x0,

Rtp Timestamp=0x140118, Rtp Expiration=0x0

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1486, Digit=3, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/ccCallReportDigits:

(callID=0x5CE, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=1486

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x4B53AD64, callID=0x5CE, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x4B53AD64, Call Id=1486

Feb 14 01:04:25.832: //1486/C74292F49068/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))

Feb 14 01:04:25.836: //1486/C74292F49068/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

Feb 14 01:04:25.836: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=123

Feb 14 01:04:25.836: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:

type=6 Original Called Number=123, Called Number=123, Calling Number=142, Calling DN=216 Calling Id=1486,

Redirect Number=9602XXXX03, Redirect Reason=15

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=123

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: :cc_get_feature_vsa malloc success

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: cc_get_feature_vsa count is 4

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205168592,feature_id:2682

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.840: feature_vsa is 47D570C8,featname is 0

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: :cc_get_feature_vsa malloc success

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: cc_get_feature_vsa count is 5

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205166576,feature_id:2683

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.840: : Generating new featurevsa,inserting it

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.840: feature call forward featname is 1

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:25.840: :cc_free_feature_vsa freeing 47D569C8

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:25.840: vsacount in free is 4

Feb 14 01:04:25.840: //1486/C74292F49068/CCAPI/ccCallForwardTo:

Call Forward Number=9602XXXX03, Call Forward Cause Reason=15, Call Id=1486

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.840: :cc_get_feature_vsa malloc success

Feb 14 01:04:25.840: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.844: cc_get_feature_vsa count is 5

Feb 14 01:04:25.844: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.844: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205168592,feature_id:2684

Feb 14 01:04:25.844: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.844: feature call forward featname is 1

Feb 14 01:04:25.844: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:25.844: :cc_free_feature_vsa freeing 47D569C8

Feb 14 01:04:25.844: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:25.844: vsacount in free is 4

Feb 14 01:04:25.844: //1486/C74292F49068/CCAPI/ccCallForwardTo:

Call Forward Number=9602XXXX03, Call Forward Cause Reason=15, Call Id=1486

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=8, Params=0x47D6FC08, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccCheckClipClir:

In: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccCheckClipClir:

Out: Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccCallSetupRequest:

Destination Pattern=9T, Called Number=9602XXXX03, Digit Strip=TRUE

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccCallSetupRequest:

Calling Number=142(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9602XXXX03(TON=Unknown, NPI=Unknown),

Redirect Number=123, Display Info=JRuddy

Account Number=, Final Destination Flag=FALSE,

Guid=C831D423-944A-11E3-906C-ECFAA818FF8E, Outgoing Dial-peer=8

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=142

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9602XXXX03

cisco-desttype=0

cisco-destplan=0

cisco-rdie=73

cisco-rdn=123

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=15 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x4A0C1364, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=142,(Calling Name=JRuddy)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=A

llowed),

Called Number=9602XXXX03(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=8, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: :cc_get_feature_vsa malloc success

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: cc_get_feature_vsa count is 5

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205168592,feature_id:2685

Feb 14 01:04:25.848: //1487/C74292F49068/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 14 01:04:25.848: //1487/C74292F49068/CCAPI/ccCallSetContext:

Context=0x47D6FBB8

Feb 14 01:04:25.848: //1486/C74292F49068/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=8

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.848: feature call forward featname is 1

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.848: feature_vsa is 47D569C8,featname is 0

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: :cc_get_feature_vsa malloc success

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: cc_get_feature_vsa count is 6

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 14 01:04:25.848: :FEATURE_VSA attributes are: feature_name:0,feature_time:1205167248,feature_id:2686

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.848: : Generating new featurevsa,inserting it

Feb 14 01:04:25.848: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Feb 14 01:04:25.848: feature call forward featname is 1

Feb 14 01:04:25.880: //1487/C74292F49068/CCAPI/cc_api_call_proceeding:

Interface=0x4A0C1364, Progress Indication=NULL(0)

Feb 14 01:04:25.980: //1487/C74292F49068/CCAPI/cc_api_call_cut_progress:

Interface=0x4A0C1364, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),

Cause Value=0

Feb 14 01:04:25.980: //1487/C74292F49068/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/ccCallCutProgress:

Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0

Voice Call Send Alert=FALSE, Call Entry(Alert Sent=FALSE)

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/ccCallCutProgress:

Call Entry(Responsed=TRUE)

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=1486

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/ccConferenceCreate:

(confID=0x4BCAA234, callID1=0x5CE, gcid=C74292F4-944A11E3-9068ECFA-A818FF8E, tag=0x0)

Feb 14 01:04:25.980: //1487/C74292F49068/CCAPI/ccConferenceCreate:

(confID=0x4BCAA234, callID2=0x5CF, gcid=C74292F4-944A11E3-9068ECFA-A818FF8E, tag=0x0)

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/ccConferenceCreate:

Conference Id=0x4BCAA234, Call Id1=1486, Call Id2=1487, Tag=0x0

Feb 14 01:04:25.980: //1486/C74292F49068/CCAPI/cc_api_bridge_done:

Conference Id=0x1AC, Source Interface=0x4B53AD64, Source Call Id=1486,

Destination Call Id=1487, Disposition=0x0, Tag=0xFFFFFFFF

Feb 14 01:04:25.984: //1487/C74292F49068/CCAPI/cc_api_bridge_done:

Conference Id=0x1AC, Source Interface=0x4A0C1364, Source Call Id=1487,

Destination Call Id=1486, Disposition=0x0, Tag=0xFFFFFFFF

Feb 14 01:04:25.984: //1486/C74292F49068/CCAPI/cc_generic_bridge_done:

Conference Id=0x1AC, Source Interface=0x4A0C1364, Source Call Id=1487,

Destination Call Id=1486, Disposition=0x0, Tag=0xFFFFFFFF

Feb 14 01:04:25.984: //1486/C74292F49068/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1AC, Destination Call Id=1487)

Feb 14 01:04:25.984: //1487/C74292F49068/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x1AC, Destination Call Id=1486)

Feb 14 01:04:25.984: //1486/C74292F49068/CCAPI/cc_api_caps_ind:

Destination Interface=0x4A0C1364, Destination Call Id=1487, Source Call Id=1486,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 14 01:04:25.984: //1486/C74292F49068/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))

Feb 14 01:04:25.984: //1487/C74292F49068/CCAPI/cc_api_caps_ind:

Destination Interface=0x4B53AD64, Destination Call Id=1486, Source Call Id=1487,

Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

Modem=0x2, Codec Bytes=160, Signal Type=2)

Feb 14 01:04:25.984: //1487/C74292F49068/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))

Feb 14 01:04:25.984: //1487/C74292F49068/CCAPI/cc_api_caps_ack:

Destination Interface=0x4B53AD64, Destination Call Id=1486, Source Call Id=1487,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=5173)

Feb 14 01:04:25.984: //1486/C74292F49068/CCAPI/cc_api_caps_ack:

Destination Interface=0x4A0C1364, Destination Call Id=1487, Source Call Id=1486,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_NONE(0x1), Vad=OFF(0x1),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=5156)

Feb 14 01:04:25.988: //1486/C74292F49068/CCAPI/ccCallFeature:

Feature Type=25, Call Id=1486

Feb 14 01:04:25.988: //1486/C74292F49068/CCAPI/cc_api_voice_mode_event:

Call Id=1486

Feb 14 01:04:25.988: //1486/C74292F49068/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x47D7EC18)

Feb 14 01:04:25.988: //1487/C74292F49068/CCAPI/cc_api_voice_mode_event:

Call Id=1487

Feb 14 01:04:25.992: //1487/C74292F49068/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x47D6FBB8)

Feb 14 01:04:25.992: //1486/C74292F49068/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x1AC, Call Id1=1486, Call Id2=1487

Feb 14 01:04:30.176: //1486/C74292F49068/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x4B53AD64, Call Id=1486

Feb 14 01:04:30.176: //1486/C74292F49068/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

Feb 14 01:04:30.176: //1486/C74292F49068/CCAPI/ccConferenceDestroy:

Conference Id=0x1AC, Tag=0x0

Feb 14 01:04:30.176: //1486/C74292F49068/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1AC, Source Interface=0x4B53AD64, Source Call Id=1486,

Destination Call Id=1487, Disposition=0x0, Tag=0x0

Feb 14 01:04:30.176: //1487/C74292F49068/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x1AC, Source Interface=0x4A0C1364, Source Call Id=1487,

Destination Call Id=1486, Disposition=0x0, Tag=0x0

Feb 14 01:04:30.176: //1486/C74292F49068/CCAPI/cc_generic_bridge_done:

Conference Id=0x1AC, Source Interface=0x4A0C1364, Source Call Id=1487,

Destination Call Id=1486, Disposition=0x0, Tag=0x0

Feb 14 01:04:30.176: //1487/C74292F49068/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 14 01:04:30.176: //1487/C74292F49068/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 14 01:04:30.176: //1487/C74292F49068/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 14 01:04:30.180: //1486/C74292F49068/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

Feb 14 01:04:30.180: //1486/C74292F49068/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 14 01:04:30.180: //1486/C74292F49068/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Feb 14 01:04:30.184: //1486/C74292F49068/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4B53AD64, Tag=0x0, Call Id=1486,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 14 01:04:30.184: //1486/C74292F49068/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 14 01:04:30.184: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.184: :cc_free_feature_vsa freeing 47D561E8

Feb 14 01:04:30.184: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.184: vsacount in free is 5

Feb 14 01:04:30.184: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.184: vsacount in free is 4

Feb 14 01:04:30.220: //1487/C74292F49068/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x4A0C1364, Tag=0x0, Call Id=1487,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 14 01:04:30.220: //1487/C74292F49068/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Feb 14 01:04:30.224: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.224: :cc_free_feature_vsa freeing 47D56488

Feb 14 01:04:30.224: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.224: vsacount in free is 3

Feb 14 01:04:30.224: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 14 01:04:30.224: vsacount in free is 2

chrs-phx-cme# u all

% Ambiguous command: " u all"

chrs-phx-cme#u all

All possible debugging has been turned off

chrs-phx-cme#debug voice dialpeer

voip dialpeer default debugging is on

chrs-phx-cme#sh logg

Syslog logging: enabled (0 messages dropped, 10 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering di

sabled)

No Active Message Discriminator.

No Inactive Message Discriminator.

Console logging: disabled

Monitor logging: disabled

Buffer logging: level debugging, 1145 messages logged, xml disabled,