Incoming Calls Have No Ringback, Goes to Fast Busy Tone

Rowell Dionicio · ‎01-24-2014

Recently, our provider had an outage. Coming back from the outage, our incoming calls are not working. Caller dials in and gets no ring back and then gets a fast busy tone. Carrier says everything is okay on their end. Any clues to why calls aren't working normally?

These are the logs I collected:

vg-3845#debug voip vtsp all

voip vtsp all debugging is on

vg-3845#u all

Jan 24 19:02:36 MST: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:1):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x6B0F98C0

Jan 24 19:02:36 MST: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_call_setup_ind:

Event=E_TSP_SETUP_IND

Progress Indication=3, CarrierIDCode=, Info Trans Capability=144, Source Carrier ID=, tg_label_flag=0

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_timer:

Timer Start Time=2788497, Timer Value=180000(ms)

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_normal_call_setup_ind:

XCC State Machine (XCCSM) Added

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_insert_cdb:

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_dsm_opened_cb:

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/act_setup_ind_pend_success:

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_realloc_cdb:

CDB=0x6B0F98C0

Jan 24 19:02:36 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_indicate_call:

Jan 24 19:02:36 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_insert_cdb:

vg-3845#u all

Jan 24 19:02:36 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_timer:

Timer Start Time=2788497, Timer Value=180000(ms)

vg-3845#u all

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_TSP_DISCONNECT_IND]

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/act_generate_disc:

Cause Value=31

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_timer_stop:

Timer Stop Time=2788797

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_ring_noan_timer_stop:

Timer Stop Time=2788797

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_set_release_source:

Release Direction=PSTN, Release Source=Calling Party-PSTN

vg-3845#u all

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_cc_call_disconnected:

Cause Value=31

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_timer:

Timer Start Time=2788797, Timer Value=15000(ms)

Jan 24 19:02:39 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/act_generate_disc:

Return Code=0

vg-3845#u all

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_WAIT_HOST_DISC, event:E_TSP_CALL_FEATURE_IND]

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

Unexpected EVENT [E_TSP_CALL_FEATURE_IND] Received For STATE [S_WAIT_HOST_DISC];

Previous STATE [0.3 ]

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_WAIT_HOST_DISC, event:E_TSP_DISCONNECT_CONF]

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/act_wrelease_release:

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_play_busy_timer_stop:

Timer Stop Time=2789008

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_timer_stop:

Timer Stop Time=2789008

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:1:1/vtsp_do_call_history:

Coder Rate=-1

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_dsm_closed_cb:

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/act_terminate:

Jan 24 19:02:41 MST: //458/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=2789008

Jan 24 19:02:41 MST: //-1/983D3CBA8726/VTSP:(0/1/0:1):-1:-1:-1/vtsp_free_cdb:

CDB=0x6B0F98C0

Jan 24 19:02:41 MST: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:1):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x6B0F9600

Jan 24 19:02:41 MST: //-1/xxxxxxxxxxxx/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_call_setup_ind:

Event=E_TSP_SETUP_IND

Progress Indication=3, CarrierIDCode=, Info Trans Capability=144, Source Carrier ID=, tg_label_flag=0

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_timer:

Timer Start Time=2789016, Timer Value=180000(ms)

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_do_normal_call_setup_ind:

XCC State Machine (XCCSM) Added

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_insert_cdb:

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_dsm_opened_cb:

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/act_setup_ind_pend_success:

vg-3845#u all

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_realloc_cdb:

CDB=0x6B0F9600

Jan 24 19:02:41 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_indicate_call:

Jan 24 19:02:41 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_insert_cdb:

Jan 24 19:02:41 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_timer:

Timer Start Time=2789016, Timer Value=180000(ms)

vg-3845#u all

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_TSP_DISCONNECT_IND]

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/act_generate_disc:

Cause Value=31

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_timer_stop:

Timer Stop Time=2789316

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_ring_noan_timer_stop:

Timer Stop Time=2789316

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_set_release_source:

Release Direction=PSTN, Release Source=Calling Party-PSTN

vg-3845#u all

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_cc_call_disconnected:

Cause Value=31

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_timer:

Timer Start Time=2789316, Timer Value=15000(ms)

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/act_generate_disc:

Return Code=0

vg-3845#u all

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_WAIT_HOST_DISC, event:E_TSP_CALL_FEATURE_IND]

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

Unexpected EVENT [E_TSP_CALL_FEATURE_IND] Received For STATE [S_WAIT_HOST_DISC];

Previous STATE [0.3 ]

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_process_event:

[state:S_WAIT_HOST_DISC, event:E_TSP_DISCONNECT_CONF]

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/act_wrelease_release:

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_play_busy_timer_stop:

Timer Stop Time=2789525

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_timer_stop:

Timer Stop Time=2789525

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_do_call_history:

Coder Rate=-1

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_dsm_closed_cb:

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/act_terminate:

vg-3845#u all

Jan 24 19:02:46 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=2789525

Jan 24 19:02:46 MST: //-1/9B54DDED872A/VTSP:(0/1/0:1):-1:-1:-1/vtsp_free_cdb:

CDB=0x6B0F9600

vg-3845#debug voip ccapi inout

voip ccapi inout debugging is on

vg-3845#ual

Jan 24 19:22:00 MST: htsp_process_event: [0/1/0:1(24), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:00 MST: htsp_timer - 50 msec

Jan 24 19:22:00 MST: htsp_process_event: [0/1/0:1(24), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:00 MST: [0/1/0:1(24)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:00 MST: htsp_timer - 3000 msec

Jan 24 19:22:00 MST: //-1/4E84F6B58782/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=

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

Jan 24 19:22:00 MST: //-1/4E84F6B58782/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:00 MST: //-1/4E84F6B58782/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:00 MST: //-1/4E84F6B58782/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:00 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:00 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:00 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:00 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:00 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:00 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:481

Jan 24 19:22:00 MST: //481/4E84F6B58782/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:00 MST: //481/4E84F6B58782/CCAPI/cc_process_call_setup_ind:

Event=0x6B504AE0

Jan 24 19:22:00 MST: //481/4E84F6B58782/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 481 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:03 MST: htsp_process_event: [0/1/0:1(24), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:03 MST: em_start_timer: 400 ms

Jan 24 19:22:03 MST: htsp_timer - 400 msec

Jan 24 19:22:03 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(24)] set signal state = 0x0

Jan 24 19:22:03 MST: //481/4E84F6B58782/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=481

Jan 24 19:22:03 MST: //481/4E84F6B58782/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:04 MST: htsp_process_event: [0/1/0:1(24), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:04 MST: em_stop_timers

Jan 24 19:22:04 MST: htsp_timer_stop

Jan 24 19:22:04 MST: em_start_timer: 3000 ms

Jan 24 19:22:04 MST: htsp_timer - 3000 msec

Jan 24 19:22:04 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:04 MST: htsp_timer_stop

Jan 24 19:22:04 MST: htsp_timer_stop2

Jan 24 19:22:04 MST: htsp_timer2 - 10000 msec

Jan 24 19:22:05 MST: htsp_process_event: [0/1/0:1(23), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:05 MST: htsp_timer - 50 msec

Jan 24 19:22:05 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:05 MST: htsp_timer_stop

Jan 24 19:22:05 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:05 MST: //481/4E84F6B58782/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=481,

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

Jan 24 19:22:05 MST: //481/4E84F6B58782/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:05 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:05 MST: :cc_free_feature_vsa freeing 66AD2FA8

Jan 24 19:22:05 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:05 MST: vsacount in free is 0

Jan 24 19:22:06 MST: htsp_process_event: [0/1/0:1(23), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:06 MST: [0/1/0:1(23)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:06 MST: htsp_timer - 3000 msec

Jan 24 19:22:06 MST: //-1/519B619D8786/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=

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

Jan 24 19:22:06 MST: //-1/519B619D8786/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:06 MST: //-1/519B619D8786/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:06 MST: //-1/519B619D8786/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:06 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:06 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:06 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:06 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:06 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:06 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:482

Jan 24 19:22:06 MST: //482/519B619D8786/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:06 MST: //482/519B619D8786/CCAPI/cc_process_call_setup_ind:

Event=0x6B502EF0

Jan 24 19:22:06 MST: //482/519B619D8786/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 482 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:09 MST: htsp_process_event: [0/1/0:1(23), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:09 MST: em_start_timer: 400 ms

Jan 24 19:22:09 MST: htsp_timer - 400 msec

Jan 24 19:22:09 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(23)] set signal state = 0x0

Jan 24 19:22:09 MST: //482/519B619D8786/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=482

Jan 24 19:22:09 MST: //482/519B619D8786/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:09 MST: htsp_process_event: [0/1/0:1(23), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:09 MST: em_stop_timers

Jan 24 19:22:09 MST: htsp_timer_stop

Jan 24 19:22:09 MST: em_start_timer: 3000 ms

Jan 24 19:22:09 MST: htsp_timer - 3000 msec

Jan 24 19:22:09 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:09 MST: htsp_timer_stop

Jan 24 19:22:09 MST: htsp_timer_stop2

Jan 24 19:22:09 MST: htsp_timer2 - 10000 msec

vg-3845#

Jan 24 19:22:11 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:11 MST: htsp_timer_stop

Jan 24 19:22:11 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:11 MST: //482/519B619D8786/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=482,

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

Jan 24 19:22:11 MST: //482/519B619D8786/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:11 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:11 MST: :cc_free_feature_vsa freeing 66AD2FA8

vg-3845#

Jan 24 19:22:11 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:11 MST: vsacount in free is 0

Jan 24 19:22:12 MST: htsp_process_event: [0/1/0:1(24), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:12 MST: htsp_timer - 50 msec

Jan 24 19:22:12 MST: htsp_process_event: [0/1/0:1(24), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:12 MST: [0/1/0:1(24)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:12 MST: htsp_timer - 3000 msec

Jan 24 19:22:12 MST: //-1/5573456C878A/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=

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

Jan 24 19:22:12 MST: //-1/5573456C878A/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:12 MST: //-1/5573456C878A/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:12 MST: //-1/5573456C878A/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:12 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:12 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:12 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:12 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:12 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:12 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:483

Jan 24 19:22:12 MST: //483/5573456C878A/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:12 MST: //483/5573456C878A/CCAPI/cc_process_call_setup_ind:

Event=0x6B501300

Jan 24 19:22:12 MST: //483/5573456C878A/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 483 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:15 MST: htsp_process_event: [0/1/0:1(24), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:15 MST: em_start_timer: 400 ms

Jan 24 19:22:15 MST: htsp_timer - 400 msec

Jan 24 19:22:15 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(24)] set signal state = 0x0

Jan 24 19:22:15 MST: //483/5573456C878A/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=483

Jan 24 19:22:15 MST: //483/5573456C878A/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:15 MST: htsp_process_event: [0/1/0:1(24), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:15 MST: em_stop_timers

Jan 24 19:22:15 MST: htsp_timer_stop

Jan 24 19:22:15 MST: em_start_timer: 3000 ms

Jan 24 19:22:15 MST: htsp_timer - 3000 msec

Jan 24 19:22:15 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:15 MST: htsp_timer_stop

Jan 24 19:22:15 MST: htsp_timer_stop2

Jan 24 19:22:15 MST: htsp_timer2 - 10000 msec

vg-3845#

Jan 24 19:22:17 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:17 MST: htsp_timer_stop

Jan 24 19:22:17 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:17 MST: //483/5573456C878A/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=483,

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

Jan 24 19:22:17 MST: //483/5573456C878A/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:17 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:17 MST: :cc_free_feature_vsa freeing 66AD2FA8

Jan 24 19:22:17 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:17 MST: vsacount in free is 0

Jan 24 19:22:17 MST: htsp_process_event: [0/1/0:1(23), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:17 MST: htsp_timer - 50 msec

Jan 24 19:22:17 MST: htsp_process_event: [0/1/0:1(23), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:17 MST: [0/1/0:1(23)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:17 MST: htsp_timer - 3000 msec

Jan 24 19:22:17 MST: //-1/588C1F88878E/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=

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

Jan 24 19:22:17 MST: //-1/588C1F88878E/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:17 MST: //-1/588C1F88878E/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:17 MST: //-1/588C1F88878E/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:17 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:17 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:17 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:17 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:17 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:17 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:484

Jan 24 19:22:17 MST: //484/588C1F88878E/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:17 MST: //484/588C1F88878E/CCAPI/cc_process_call_setup_ind:

Event=0x6B4FF710

Jan 24 19:22:17 MST: //484/588C1F88878E/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 484 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:20 MST: htsp_process_event: [0/1/0:1(23), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:20 MST: em_start_timer: 400 ms

Jan 24 19:22:20 MST: htsp_timer - 400 msec

Jan 24 19:22:20 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(23)] set signal state = 0x0

Jan 24 19:22:20 MST: //484/588C1F88878E/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=484

Jan 24 19:22:20 MST: //484/588C1F88878E/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:21 MST: htsp_process_event: [0/1/0:1(23), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:21 MST: em_stop_timers

Jan 24 19:22:21 MST: htsp_timer_stop

Jan 24 19:22:21 MST: em_start_timer: 3000 ms

Jan 24 19:22:21 MST: htsp_timer - 3000 msec

Jan 24 19:22:21 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:21 MST: htsp_timer_stop

Jan 24 19:22:21 MST: htsp_timer_stop2

Jan 24 19:22:21 MST: htsp_timer2 - 10000 msec

Jan 24 19:22:22 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:22 MST: htsp_timer_stop

Jan 24 19:22:22 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:22 MST: //484/588C1F88878E/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=484,

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

Jan 24 19:22:22 MST: //484/588C1F88878E/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:22 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:22 MST: :cc_free_feature_vsa freeing 66AD2FA8

vg-3845#

Jan 24 19:22:22 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:22 MST: vsacount in free is 0

vg-3845#

Jan 24 19:22:24 MST: htsp_process_event: [0/1/0:1(24), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:24 MST: htsp_timer - 50 msec

Jan 24 19:22:24 MST: htsp_process_event: [0/1/0:1(24), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:24 MST: [0/1/0:1(24)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:24 MST: htsp_timer - 3000 msec

Jan 24 19:22:24 MST: //-1/5C7172948792/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=

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

Jan 24 19:22:24 MST: //-1/5C7172948792/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:24 MST: //-1/5C7172948792/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:24 MST: //-1/5C7172948792/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:24 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:24 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:24 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:24 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:24 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:24 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:485

Jan 24 19:22:24 MST: //485/5C7172948792/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:24 MST: //485/5C7172948792/CCAPI/cc_process_call_setup_ind:

Event=0x6B4FDB20

Jan 24 19:22:24 MST: //485/5C7172948792/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 485 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:27 MST: htsp_process_event: [0/1/0:1(24), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:27 MST: em_start_timer: 400 ms

Jan 24 19:22:27 MST: htsp_timer - 400 msec

Jan 24 19:22:27 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(24)] set signal state = 0x0

Jan 24 19:22:27 MST: //485/5C7172948792/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=485

Jan 24 19:22:27 MST: //485/5C7172948792/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:27 MST: htsp_process_event: [0/1/0:1(24), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:27 MST: em_stop_timers

Jan 24 19:22:27 MST: htsp_timer_stop

Jan 24 19:22:27 MST: em_start_timer: 3000 ms

Jan 24 19:22:27 MST: htsp_timer - 3000 msec

Jan 24 19:22:27 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:27 MST: htsp_timer_stop

Jan 24 19:22:27 MST: htsp_timer_stop2

Jan 24 19:22:27 MST: htsp_timer2 - 10000 msec

vg-3845#

Jan 24 19:22:29 MST: htsp_process_event: [0/1/0:1(23), EM_ONHOOK, E_DSP_SIG_1100]em_onhook_offhook

Jan 24 19:22:29 MST: htsp_timer - 50 msec

Jan 24 19:22:29 MST: htsp_process_event: [0/1/0:1(24), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:29 MST: htsp_timer_stop

Jan 24 19:22:29 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:29 MST: //485/5C7172948792/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=485,

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

Jan 24 19:22:29 MST: //485/5C7172948792/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:29 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:29 MST: :cc_free_feature_vsa freeing 66AD2FA8

Jan 24 19:22:29 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:29 MST: vsacount in free is 0

Jan 24 19:22:29 MST: htsp_process_event: [0/1/0:1(23), EM_QUALIFY_SEIZURE, E_HTSP_EVENT_TIMER]em_qualify_seizure_timeouthtsp_setup_ind

Jan 24 19:22:29 MST: [0/1/0:1(23)] get_local_station_id calling num= calling name= calling time=01/24 19:22 orig called=

Jan 24 19:22:29 MST: htsp_timer - 3000 msec

Jan 24 19:22:29 MST: //-1/5F87DB788796/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=

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

Jan 24 19:22:29 MST: //-1/5F87DB788796/CCAPI/cc_api_call_setup_ind_common:

Interface=0x66F27200, Call Info(

Calling Number=,(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=999010991, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

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

Jan 24 19:22:29 MST: //-1/5F87DB788796/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:29 MST: //-1/5F87DB788796/CCAPI/ccCheckClipClir:

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

Jan 24 19:22:29 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:29 MST: :cc_get_feature_vsa malloc success

Jan 24 19:22:29 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:29 MST: cc_get_feature_vsa count is 1

Jan 24 19:22:29 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Jan 24 19:22:29 MST: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722625968,feature_id:486

Jan 24 19:22:29 MST: //486/5F87DB788796/CCAPI/cc_api_call_setup_ind_common:

vg-3845#Set Up Event Sent;

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

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

Jan 24 19:22:29 MST: //486/5F87DB788796/CCAPI/cc_process_call_setup_ind:

Event=0x6B4FBF30

Jan 24 19:22:29 MST: //486/5F87DB788796/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 486 with tag 999010991 to app "MGCPAPP"

vg-3845#

Jan 24 19:22:32 MST: htsp_process_event: [0/1/0:1(23), EM_WAIT_SETUP_ACK, E_HTSP_EVENT_TIMER]em_wait_setup_ack_timer

Jan 24 19:22:32 MST: em_start_timer: 400 ms

Jan 24 19:22:32 MST: htsp_timer - 400 msec

Jan 24 19:22:32 MST: htsp_timer_stop2 em_onhook (0)vnm_dsp_set_sig_state:[recEive and transMit0/1/0:1(23)] set signal state = 0x0

Jan 24 19:22:32 MST: //486/5F87DB788796/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x66F27200, Call Id=486

Jan 24 19:22:32 MST: //486/5F87DB788796/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=FALSE, Cause Value=31, Retry Count=0)

vg-3845#

Jan 24 19:22:32 MST: htsp_process_event: [0/1/0:1(23), EM_GUARD_ALL, E_HTSP_EVENT_TIMER]em_guard_all_timer

Jan 24 19:22:32 MST: em_stop_timers

Jan 24 19:22:32 MST: htsp_timer_stop

Jan 24 19:22:32 MST: em_start_timer: 3000 ms

Jan 24 19:22:32 MST: htsp_timer - 3000 msec

Jan 24 19:22:32 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_1100]em_park_offhook

Jan 24 19:22:32 MST: htsp_timer_stop

Jan 24 19:22:32 MST: htsp_timer_stop2

Jan 24 19:22:32 MST: htsp_timer2 - 10000 msec

vg-3845#

Jan 24 19:22:34 MST: htsp_process_event: [0/1/0:1(23), EM_PARK, E_DSP_SIG_0000]em_park_onhook

Jan 24 19:22:34 MST: htsp_timer_stop

Jan 24 19:22:34 MST: htsp_timer_stop2 htsp_report_onhook_sig

Jan 24 19:22:34 MST: //486/5F87DB788796/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x66F27200, Tag=0x0, Call Id=486,

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

Jan 24 19:22:34 MST: //486/5F87DB788796/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Jan 24 19:22:34 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:34 MST: :cc_free_feature_vsa freeing 66AD2FA8

vg-3845#

Jan 24 19:22:34 MST: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Jan 24 19:22:34 MST: vsacount in free is 0

Suresh Subramanian · ‎01-24-2014

Hi,

It seems the provider is releasing the call with cause code 31,

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/act_generate_disc:

Cause Value=31 >> this is reported as a normal event only when no other cause in the normal class applies

Jan 24 19:02:44 MST: //459/9B54DDED872A/VTSP:(0/1/0:1):-1:1:1/vtsp_set_release_source:

Release Direction=PSTN, Release Source=Calling Party-PSTN

So please check with your provider once again and tell them we are receiving the disconnect from pstn.

>> Are the outbound calls working ok?

Please rate all the useful posts

//Suresh Please rate all the useful posts.

Rowell Dionicio · ‎01-29-2014

I ended up figuring it out. There was a number expansion command redirecting the caller to an extension that wasn't available due to a bug in the system.

I had to run "no mgcp" and then "mgcp" because the voice gateway lost connectivity to Call Manager.