urgent voice-port dial-peer issue

Mohammed Al-odhari · ‎11-03-2013

Dear all

am facing starnge issue recently, when outside call is received, an autoattendent is heard and when the user press 2, he will be transferred to international call, but the call doesnt go out from the port (0/1/1) specified in the dial-peer. when i keep doing show voice port summ it first shows that will go out from 0/1/1 and after maybe one second or less the port 0/1/1 returns on-hook and the call processes out using another port (0/1/0, 0/1/2 or 0/1/3).

Also i have attached some of show run info.

!

voice-port 0/1/0

supervisory disconnect dualtone mid-call

supervisory custom-cptone Test

no battery-reversal

cptone SA

timeouts interdigit 2

timeouts call-disconnect 1

timeouts wait-release 1

connection plar opx immediate 4001

impedance complex2

caller-id enable

!

voice-port 0/1/1

supervisory disconnect dualtone mid-call

supervisory custom-cptone Test

no battery-reversal

cptone SA

timeouts call-disconnect 1

timeouts wait-release 1

connection plar opx immediate 4446

impedance complex2

caller-id enable

!

voice-port 0/1/2

supervisory disconnect dualtone mid-call

supervisory custom-cptone Test

no battery-reversal

cptone SA

timeouts interdigit 2

timeouts call-disconnect 1

timeouts wait-release 1

connection plar opx immediate 4015

impedance complex2

caller-id enable

!

voice-port 0/1/3

trunk-group 2

supervisory disconnect dualtone mid-call

supervisory custom-cptone Test

no battery-reversal

cptone SA

timeouts call-disconnect 1

timeouts wait-release 1

connection plar opx immediate 4446

shutdown

impedance complex2

caller-id enable

!

dial-peer voice 1013 pots

description QATAR CALL CENTER FOR Auto Attendant

destination-pattern 90097444407761$

port 0/1/1

forward-digits 13

!

dial-peer voice 1014 pots

description QATAR CALL CENTER FOR Auto Attendant

destination-pattern 90097444407762$

port 0/1/1

forward-digits 13

!

pleses can you help in solving my issue.

Chris Deren · ‎11-03-2013

Is it only that international number fails on that port or any number fails?

Chris

Mohammed Al-odhari · ‎11-04-2013

Hi Chris and sorry and sorry for the delay,

yes only the international port fails on that port, it is a strange problem , any ideas you can help please.

regards,

Mohammed Al-odhari · ‎11-05-2013

Hi Chris again,

i test it today , i changed the number from international to local in the dial peer and in the autoattendent (GUI), and i faced the same issue, so the issue is not from the international calls.

Can you help plz??

Chris Deren · ‎11-05-2013

Sounds like the line may be dead, do "debug vpm signal"

Chris

Mohammed Al-odhari · ‎11-06-2013

Hi again,

As you can see I have made sh voice port summ then I call from outside to the cme, call is received on port 0/0/0 as you can see. Then while am listening to the auto attendant I have made sh voice port summ more than once.

Then I press 2 which suppose to transfer me to international call via the port 0/1/1. As you can see from the sh voice port summ ,the port 0/1/1 starts to go off-hook then in a second it returns on-hook and the call processed via 0/1/0.

I would like to inform you too, after the call is processed via 0/1/2 this port hanged up (remains off-hook) even after I close the session. I have to do shut and no shut.

#d sh voice port summ conf t sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up dorm idle on-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

^

% Invalid input detected at '^' marker.

GW#debug vm pm signal

Voice Port Module signaling debugging is enabled

GW#debug vpm port 0/1

Nov 6 08:15:04.803: htsp_timer_stop3

Nov 6 08:15:04.803: htsp_process_event: [50/0/6.1, EFXS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_waitoff_release

Nov 6 08:15:04.803: [50/0/6.1] set signal state = 0x4 timestamp = 0htsp_call_feature:feature 25

htsp_call_feature: caller id enable 0x3 call_connected 0

Nov 6 08:15:04.831: htsp_process_event: [50/0/27.1, EFXS_OFFHOOK, E_HTSP_CALLERID_WAITING]

Nov 6 08:15:04.831: efxs_callerid_update

Nov 6 08:15:04.831: efxs_callerid_update process caller_id_string

Nov 6 08:15:04.831: efxs_callerid_update process caller_id_string OK

Nov 6 08:15:04.831: efxs_callerid_update number= [4445] name= []

Nov 6 08:15:04.847: htsp_call_bridged invokedhtsp_call_feature:feature 25

htsp_call_feature: caller id enable 0x3 call_connected 1

Nov 6 08:15:04.851: htsp_process_event: [50/0/27.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect

Nov 6 08:15:04.851: [50/0/27.1] set signal state = 0x6 timestamp = 0

Nov 6 08:15:04.851: htsp_process_event: [50/0/27.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]

Nov 6 08:15:04.851: efxs_callerid_update

Nov 6 08:15:04.851: efxs_callerid_update process caller_id_string

Nov 6 08:15:04.851: efxs_callerid_update process caller_id_string OK

Nov 6 08:15:04.851: efxs_callerid_update number= [4445] name= []

Nov 6 08:15:04.851: htsp_process_event: [50/0/27.1, EFXS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]efxs_connect_cutthru

Nov 6 08:15:04.851: [50/0/27.1] set signal state = 0x7 timestamp = 0/10

QNB_VoiceGW#

Nov 6 08:15:08.827: htsp_process_event: [50/0/27.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook

Nov 6 08:15:08.827: htsp_timer - 10 msec

Nov 6 08:15:08.839: htsp_process_event: [50/0/27.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req

Nov 6 08:15:08.839: htsp_timer_stop

Nov 6 08:15:08.839: htsp_timer_stop3

Nov 6 08:15:08.839: htsp_process_event: [50/0/27.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig

Nov 6 08:15:09.747: htsp_process_event: [50/0/27.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind

Nov 6 08:15:09.747: [50/0/27.1] get_local_station_id calling num=4026 calling name=Guard (Reception) calling time=11/06 11:15 orig called=

Nov 6 08:15:09.747: htsp_process_event: [50/0/27.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call

VoiceGW#debug vpm port 0/1/1

VoiceGW#sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up dorm idle on-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up up off-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up up off-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 6 08:16:03.959: htsp_timer_stop3 htsp_setup_req

Nov 6 08:16:03.959: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup

Nov 6 08:16:03.959: [0/1/1] set signal state = 0xC timestamp = 0

Nov 6 08:16:03.959: htsp_timer - 1300 msec

Nov 6 08:16:04.211: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected

Nov 6 08:16:04.211: htsp_timer2 - 1000 msec

Nov 6 08:16:04.211: htsp_timer_stop

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up up off-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 6 08:16:05.211: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc

Nov 6 08:16:05.211: htsp_timer_stop

Nov 6 08:16:05.211: htsp_timer_stop2

Nov 6 08:16:05.211: [0/1/1] set signal state = 0x4 timestamp = 0

Nov 6 08:16:05.211: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release

Nov 6 08:16:05.211: htsp_timer_stop3 htsp_setup_req

Nov 6 08:16:05.211: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup

Nov 6 08:16:05.211: [0/1/1] set signal state = 0xC timestamp = 0

Nov 6 08:16:05.215: htsp_timer - 1300 msec

VoiceGW#[A

% Bad IP address or host name

% Unknown command or computer name, or unable to find computer address

VoiceGW#

Nov 6 08:16:05.467: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected

Nov 6 08:16:05.467: htsp_timer2 - 1000 msec

Nov 6 08:16:05.467: htsp_timer_stop

Nov 6 08:16:06.467: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc

Nov 6 08:16:06.467: htsp_timer_stop

Nov 6 08:16:06.467: htsp_timer_stop2

Nov 6 08:16:06.467: [0/1/1] set signal state = 0x4 timestamp = 0

Nov 6 08:16:06.467: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up up idle off-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up up off-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up up idle off-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up up off-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#conf t

Enter configuration commands, one per line. End with CNTL/Z.

VoiceGW(config)#voice-port 0/1/0

VoiceGW(config-voiceport)#sh

VoiceGW(config-voiceport)#no sh

VoiceGW(config-voiceport)#^Z

VoiceGW#

Nov 6 08:17:13.267: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/0, changed state to Administrative Shutdown

Nov 6 08:17:13.483: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/0, changed state to up

Nov 6 08:17:14.435: %SYS-5-CONFIG_I: Configured from console by qnb on vty0 (10.201.46.59)

Carlo Poggiarelli · ‎11-07-2013

Hi Mohammed.

Port 0/1/1 is detecting power denial.Try to add "no supervisory disconnect signal" to your voice port and send again a debug vpm signal

HTH

Regards

Carlo

Please rate all helpful posts

"The more you help the more you learn"

Please rate all helpful posts "The more you help the more you learn"

Mohammed Al-odhari · ‎11-10-2013

Hi Carlo,

I did as you request, but the same issue. it start processing via 0/1/1 then switch back to 0/1/2 and process the call

VoiceGW(config-voiceport)#no supervisory disconnect signal

VoiceGW#debug vpm sig

Voice Port Module signaling debugging is enabled

VoiceGW#debu

Nov 10 06:08:04.903: htsp_process_event: [50/0/25.1, EFXS_WAIT_OFFHOOK, E_DSP_SIG_1100]efxs_waitoff_offhook

Nov 10 06:08:04.903: [50/0/25.1] set signal state = 0x4 timestamp = 0 htsp_dial

Nov 10 06:08:04.903: htsp_process_event: [50/0/25.1, EFXS_CONNECT, E_DSP_DIALING_DONE]

Nov 10 06:08:04.907: htsp_call_bridged invoked

Nov 10 06:08:04.907: htsp_call_bridged invokedhtsp_call_feature:feature 25

htsp_call_feature: caller id enable 0x3 call_connected 1

Nov 10 06:08:04.907: htsp_process_event: [50/0/24.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect

Nov 10 06:08:04.907: [50/0/24.1] set signal state = 0x6 timestamp = 0

Nov 10 06:08:04.907: htsp_process_event: [50/0/24.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]

Nov 10 06:08:04.907: efxs_callerid_update

Nov 10 06:08:04.907: efxs_callerid_update process caller_id_string

Nov 10 06:08:04.907: efxs_callerid_update process caller_id_string OK

Nov 10 06:08:04.907: efxs_callerid_update number= [4024] name= [Wedad AlGhashm]

Nov 10 06:08:04.907: htsp_process_event: [50/0/25.1, EFXS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]efxs_connect_cutthru

Nov 10 06:08:04.907: [50/0/25.1] set signal state = 0x7 timestamp = 0

Nov 10 06:08:04.907: htsp_process_event: [50/0/24.1, EFXS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]efxs_connect_cutthrug

Nov 10 06:08:04.911: [50/0/24.1] set signal state = 0x7 timestamp = 0vpm port 0/1/1

Nov 10 06:08:14.183: htsp_process_event: [50/0/2.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook

Nov 10 06:08:14.183: htsp_timer - 10 msec

Nov 10 06:08:14.195: htsp_process_event: [50/0/2.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req

Nov 10 06:08:14.195: htsp_timer_stop

Nov 10 06:08:14.195: htsp_timer_stop3

Nov 10 06:08:14.195: htsp_process_event: [50/0/2.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig

Nov 10 06:08:14.211: htsp_process_event: [0/1/0, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release

Nov 10 06:08:14.211: htsp_timer_stop

Nov 10 06:08:14.211: htsp_timer_stop2

Nov 10 06:08:14.211: htsp_timer_stop3

Nov 10 06:08:14.211: [0/1/0] set signal state = 0x4 timestamp = 0

Nov 10 06:08:14.211: htsp_timer - 2000 msec

Nov 10 06:08:14.487: htsp_process_event: [0/1/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110]debug vpm port 0/1/1

VoiceGW#sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up dorm idle on-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up up off-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm off-hook idle y

--More--

Nov 10 06:09:14.995: htsp_timer_stop3 htsp_setup_req

Nov 10 06:09:14.995: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup

Nov 10 06:09:14.995: [0/1/1] set signal state = 0xC timestamp = 0

Nov 10 06:09:14.995: htsp_timer - 1300 msec

Nov 10 06:09:15.251: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected

Nov 10 06:09:15.251: htsp_timer2 - 1000 msec

Nov 10 06:09:15.251: htsp_timer_stop

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up up idle off-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm off-hook idle y

--More--

Nov 10 06:09:16.251: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc

Nov 10 06:09:16.251: htsp_timer_stop

Nov 10 06:09:16.251: htsp_timer_stop2

Nov 10 06:09:16.251: [0/1/1] set signal state = 0x4 timestamp = 0

Nov 10 06:09:16.251: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up up idle off-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up up idle off-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up up idle off-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

QNB_VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up idle off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up up idle off-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Enter configuration commands, one per line. End with CNTL/Z.

VoiceGW(config)#voice-port 0/1/2

VoiceGW(config-voiceport)#sh

VoiceGW(config-voiceport)#no sh

VoiceGW(config-voiceport)#^Z

VoiceGW#

Nov 10 06:10:03.283: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/2, changed state to Administrative Shutdown

Nov 10 06:10:03.411: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/2, changed state to upun

Nov 10 06:10:04.611: %SYS-5-CONFIG_I: Configured from console by qnb on vty0 (10.201.46.59)de

VoiceGW#undebug all

All possible debugging has been turned off

Carlo Poggiarelli · ‎11-10-2013

Hi Mohammed.

Your FXO port is still receiving a power denial.

fxols_power_den_disc

Check cabling between FXO and Telco device or call them to verify all is working correctly on their side.

HTH

Regards

Carlo

Please rate all helpful posts

"The more you help the more you learn"

Please rate all helpful posts "The more you help the more you learn"

Mohammed Al-odhari · ‎11-10-2013

thanks agin Mr. Carlo

the line is working fine and nothing has been changed recently.

is there anything you want me to check.

regards,

Chris Deren · ‎11-10-2013

Does inbound call work on the line?

Power denial in my experience is typically caused by bad cable on loop start connections, replace the cable or change the polarity of the wires and try again.

HTH,

Chris

Mohammed Al-odhari · ‎11-10-2013

thanks

please can u go through my replay to Carlo and replay me.

regards,

Carlo Poggiarelli · ‎11-10-2013

Hi Mohammed.
Most of the time this is caused from wrong cptone used.
Please use the following link which could help you on troubleshooting the issue.
http://www.cisco.com/en/US/tech/tk652/tk653/technologies_tech_note09186a00800ae2d1.shtml

HTH

Regards

Carlo

Sent from Cisco Technical Support iPhone App

Please rate all helpful posts "The more you help the more you learn"

Mohammed Al-odhari · ‎11-10-2013

Dear Carlo

thanks for your replay again,

regarding the CPtone, after testing in all of customers, we see the that cptone SA is the best that works with the fxo port but for this the below situation it doesnt.

is there anything else which i can do.

thanks.

Mohammed Al-odhari · ‎11-10-2013

I would like to inform you that I replaced the Teleco line and the call now is processed normally via the port 0/1/1, but when I close the session, the port 0/1/1 remains off-hook.

so please can you help in solving the hanging of the port 0/1/1 after I close the session.

Call is as follows:

Outside is calling to the cme port 0/0/0àhe listen to the auto attendantàcaller press 2àthe call is transferred to international call via the port 0/1/1 normallyàafter the caller hangs up, the port 0/1/1 remains off-hook.

the below debug is for a test call:

VoiceGW#terminal monitor

VoiceGW#debug vpm signal

Voice Port Module signaling debugging is enabled

VoiceGW#debug vpm p

VoiceGW#debug vpm port 0/1/1

VoiceGW#debug voip vtsp all

voip vtsp all debugging is on

VoiceGW#debu

Nov 10 07:27:09.063: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED9BF8

Nov 10 07:27:09.063: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_do_call_setup_ind:

Event=E_TSP_SETUP_IND

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

Nov 10 07:27:09.063: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_do_call_setup_ind:

Calling Number=4025, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_timer:

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

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_do_normal_call_setup_ind:

IC State Machine (ICSM) Added

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_opened_cb:

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_setup_ind_pend_success:

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_realloc_cdb:

CDB=0x31ED9BF8

Nov 10 07:27:09.067: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_indicate_call:

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_insert_cdb:

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer:

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

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_report_digit_control:

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_report_digit_control: g

Digit Reporting=TRUE

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK]

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_setup_ind_ack:

Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE

Nov 10 07:27:09.067: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300454

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300512

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:27:09.639: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=0

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300550

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=0

Nov 10 07:27:10.027: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300594

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:10.459: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.927: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=9

Nov 10 07:27:10.927: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.927: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:10.927: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300641

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=9

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_report_digit_control:

Digit Reporting=FALSE

Nov 10 07:27:10.931: //-1/xxxxxxxxxxxx/VTSP:(50/0/20):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED8DC0

Nov 10 07:27:10.931: //-1/xxxxxxxxxxxx/VTSP:(50/0/20):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_spi_event_handler:

Event Category=1, Event=176

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING]

Nov 10 07:27:10.931: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_dcollect_proc:

Nov 10 07:27:10.931: //-1/xxxxxxxxxxxx/VTSP:(50/0/20):-1:-1:-1/vtsp_process_event:

[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_og_call_req:

Echo Cancel=TRUE, Gain Control=0

Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_do_call_setup_req:

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_dsm_opened_cb:

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_req_pend_succ:

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_request_call:

Mode=0, Peer=20020

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_create_call_active_on_setup_req:

Target Carrier ID=

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_create_call_active_on_setup_req:

Source Carrier ID=

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string =

Nov 10 07:27:10.931: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_request_call:

Progress Indication=3, Info Trans Capability=0

Calling Number=4025, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

Called Number=4019, TON=Unknown, NPI=Unknown

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_pend_proceeding:

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_timer:

Timer Start Time=300641, Timer Value=25000(ms)

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_pend_proceeding:

Peer=20020, Progress Indication=0, Return Code=2

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_NULL, event:E_TSP_ALERT]

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_req_pend_defer:

Event=6

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_dsm_opened_cb:

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_req_wait_pend_success:

Event=75

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_timer_stop:

Timer Stop Time=300641

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_pend_alert:

Progress Indication=0, Signal Indication=1

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_ring_noan_timer_start:

Timer Start Time=300641, No Answer Timer Value=45000(ms)

Nov 10 07:27:10.935: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_setup_pend_alert:

Ringback Indication=FALSE, Ring Timeout=45(s)

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_ALERT]

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_alert:

Progress Indication=0, Signal Indication=1, Setup Progress Indication=3

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300641

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_alert:

[Ring Back]-Tone Played In Direction [Network]

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_open_packet_path:

Progress Indication=8

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_alert:

Progress Indication=8, Tone=Ring Backvo

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_CC_FEATURE]

Nov 10 07:27:10.935: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_call_feature:

Feature Type=25

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_TSP_DISCONNECT_IND]

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_generate_disc:

Cause Value=16

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300763

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_ring_noan_timer_stop:

Timer Stop Time=300763

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_set_release_source:

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

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_cc_call_disconnected:

Cause Value=16

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer:

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

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_generate_disc:

Return Code=0

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_CC_DISCONNECT]

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_disconnect:

Cause Value=16

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_ring_noan_timer_stop:

Timer Stop Time=300763

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_HOST_DISC, event:E_CC_DISCONNECT]

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_host_disc_cleanup:

dsm_indicate_error=0, short_cleardown_timeout=1, Mode=0

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_fax_config:

Call Entry=NULL, Call Id=-1

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_host_disc_cleanup:

Exit State=16

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_dsm_get_levels_done_cb:

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_dsm_get_levels_done_cb:

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_dsm_dsp_stats_complete:

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_timer:

Timer Start Time=300763, Timer Value=60000(ms)

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

Unexpected EVENT [E_VTSP_DSM_STATS_COMPLETE] Received For STATE [S_WAIT_RELEASE];

Previous STATE [0.16 ]

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_dsm_dsp_stats_complete:

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer:

Timer Start Time=300763, Timer Value=60000(ms)

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:12.155: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

Unexpected EVENT [E_VTSP_DSM_STATS_COMPLETE] Received For STATE [S_WAIT_RELEASE];

Previous STATE [0.16 ]

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:27:12.155: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/act_wrelease_release:

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_play_busy_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_do_call_history:

Coder Rate=-1

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:0:153/vtsp_is_valid_dsm_handle:

DMGR=0x2C810798, VTSP CDB=0x31ED8DC0

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/act_wrelease_release:

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_play_busy_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_do_call_history:

Coder Rate=-1

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:0:201/vtsp_is_valid_dsm_handle:

DMGR=0x2ACC9794, VTSP CDB=0x31ED9BF8

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/act_terminate:

Nov 10 07:27:12.159: //317/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //-1/57AEFFD8829E/VTSP:(50/0/20):-1:-1:-1/vtsp_free_cdb:

CDB=0x31ED8DC0

QNB_VoiceGW#debug voi

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/act_terminate:

Nov 10 07:27:12.159: //316/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=300764

Nov 10 07:27:12.159: //-1/57AEFFD8829E/VTSP:(50/0/26):-1:-1:-1/vtsp_free_cdb:

CDB=0x31ED9BF8

Nov 10 07:27:12.579: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED9920

Nov 10 07:27:12.579: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_do_call_setup_ind:

Event=E_TSP_SETUP_IND

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

Nov 10 07:27:12.579: //-1/xxxxxxxxxxxx/VTSP:(50/0/26):-1:-1:-1/vtsp_do_call_setup_ind:

Calling Number=4025, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_timer:

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

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_do_normal_call_setup_ind:

IC State Machine (ICSM) Added

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_opened_cb:

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_setup_ind_pend_success:

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_realloc_cdb:

CDB=0x31ED9920

Nov 10 07:27:12.579: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_indicate_call:

Nov 10 07:27:12.579: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_insert_cdb:

Nov 10 07:27:12.583: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer:

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

Nov 10 07:27:12.583: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_report_digit_control:

Digit Reporting=TRUE

Nov 10 07:27:12.583: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK]

Nov 10 07:27:12.583: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_setup_ind_ack:

Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE

Nov 10 07:27:12.583: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300806

Nov 10 07:27:13.151: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:27:13.151: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUEp

QNB_VoiceGW#debug voip

Nov 10 07:27:13.151: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:13.151: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300863

Nov 10 07:27:13.155: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:27:13.155: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=0

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300888

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=0

Nov 10 07:27:13.407: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300914

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:13.659: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Begin Digit=6

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300944

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

End Digit=6

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_report_digit_control:

Digit Reporting=FALSE

Nov 10 07:27:13.967: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED9648

Nov 10 07:27:13.967: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_spi_event_handler:

Event Category=1, Event=176

Nov 10 07:27:13.967: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING]

Nov 10 07:27:13.971: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_dcollect_proc:

Nov 10 07:27:13.971: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_process_event:

[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_og_call_req:

Echo Cancel=TRUE, Gain Control=0

Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_do_call_setup_req:

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_opened_cb:

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_req_pend_succ:

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_request_call:

Mode=0, Peer=20004

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_create_call_active_on_setup_req:

Target Carrier ID=

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_create_call_active_on_setup_req:

Source Carrier ID=

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_call_set_gw_outpulsed_numbers: Unable to update dial_string =

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_request_call:

Progress Indication=3, Info Trans Capability=0

Calling Number=4025, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

Called Number=4016, TON=Unknown, NPI=Unknown

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_pend_proceeding:

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_timer:

Timer Start Time=300945, Timer Value=25000(ms)

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_pend_proceeding:

Peer=20004, Progress Indication=0, Return Code=2

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_NULL, event:E_TSP_ALERT]

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_req_pend_defer:

Event=6

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_opened_cb:

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_req_wait_pend_success:

Event=75

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=300945

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_pend_alert:

Progress Indication=0, Signal Indication=1

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_start:

Timer Start Time=300945, No Answer Timer Value=45000(ms)

Nov 10 07:27:13.971: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_pend_alert:

Ringback Indication=FALSE, Ring Timeout=45(s)

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_ALERT]

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_alert:

Progress Indication=0, Signal Indication=1, Setup Progress Indication=3

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=300945

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_alert:

[Ring Back]-Tone Played In Direction [Network]

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_open_packet_path:

Progress Indication=8

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_alert:

Progress Indication=8, Tone=Ring Back

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_CC_FEATURE]

Nov 10 07:27:13.975: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_call_feature:

Feature Type=25ccap

Nov 10 07:27:17.839: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT]

Nov 10 07:27:17.839: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_setup_pend_connect:

Progress Indication=0

Nov 10 07:27:17.839: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_stop:

Timer Stop Time=301332

Nov 10 07:27:17.839: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_bridge_check_cb:

Nov 10 07:27:17.839: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_tdm_hpm_check:

Interface Type=26, Destination Interface Type=26

Nov 10 07:27:17.839: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_tdm_hpm_check:

Hairpin Allowed=FALSE, CDB=FALSE, Destination CDB=FALSE

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_bridge_check_cb:

Return Code=5

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=DISABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=20004

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_check_cb:

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_tdm_hpm_check:

Interface Type=26, Destination Interface Type=26

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_tdm_hpm_check:

Hairpin Allowed=FALSE, CDB=FALSE, Destination CDB=FALSE

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_check_cb:

Return Code=5

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=DISABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=20026

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_CC_DO_CAPS_IND]

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DO_CAPS_IND]

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_CC_CAPS_IND]

Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_CC_CAPS_IND]

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_call_connect: Connected Name Osama Ali AlHuthi

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_call_connect: Connected Number 4016

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_call_connect: Connected oct3a 29

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_ALERTING, event:E_CC_CONNECT]

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_alert_connect:

Progress Indication=0

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_ring_noan_timer_stop:

Timer Stop Time=301332

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_CONNECT, event:E_CC_FEATURE]

Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_call_feature:

Feature Type=25

Nov 10 07:27:17.847: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:27:17.847: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_reactivate_ringback:

Nov 10 07:27:17.847: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_reactivate_ringback:exit@1268 i

Nov 10 07:27:17.847: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:27:17.847: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_reactivate_ringback:

Nov 10 07:27:17.847: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_reactivate_ringback:exit@1268

VoiceGW#debug voip ccapi ino

VoiceGW#debug voip ccapi inout

voip ccapi inout debugging is on

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_CONNECT, event:E_TSP_DISCONNECT_IND]

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_generate_disc:

Cause Value=16

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=303443

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_ring_noan_timer_stop:

Timer Stop Time=303443

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_set_release_source:

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_cc_call_disconnected:

Cause Value=16

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x319D3158, Call Id=318

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/cc_api_call_disconnected:

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer:

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_generate_disc:

Return Code=0

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/ccConferenceDestroy:

Conference Id=0x50, Tag=0x0

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x50, Source Interface=0x319D3158, Source Call Id=318,

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

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:38.955: //319/59C77ECE82A3/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x50, Source Interface=0x2C182FA8, Source Call Id=319,

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/cc_generic_bridge_done:

Conference Id=0x50, Source Interface=0x2C182FA8, Source Call Id=319,

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/ccCallDisconnect:

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/ccCallDisconnect:

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

Nov 10 07:27:38.955: //318/59C77ECE82A3/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:27:38.955: //319/59C77ECE82A3/CCAPI/ccCallDisconnect:

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

Nov 10 07:27:38.955: //319/59C77ECE82A3/CCAPI/ccCallDisconnect:

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

Nov 10 07:27:38.955: //319/59C77ECE82A3/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_disconnect:

Cause Value=16, Previous Cause Value=16

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_ring_noan_timer_stop:

Timer Stop Time=303443

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=DISABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=20004

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_disconnect:

Cause Value=16

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_stop:

Timer Stop Time=303443

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_get_levels_done_cb:

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_get_levels_done_cb:

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_dsm_dsp_stats_complete:

Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer:

Timer Start Time=303443, Timer Value=60000(ms)

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_dsm_dsp_stats_complete:

Nov 10 07:27:38.955: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_timer:

Timer Start Time=303443, Timer Value=60000(ms)

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_CALL_FEATURE_IND]

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_call_feature_ind:

Feature Type=6

Nov 10 07:27:38.959: //318/59C77ECE82A3/CCAPI/cc_api_call_feature:

Feature Type=6, Interface=0x319D3158, Call Id=318

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/act_wrelease_release:

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_play_busy_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_is_valid_dsm_handle:

DMGR=0x2ACC9794, VTSP CDB=0x31ED9920

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_CALL_FEATURE_IND]

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_call_feature_ind:

Feature Type=6

Nov 10 07:27:38.959: //319/59C77ECE82A3/CCAPI/cc_api_call_feature:

Feature Type=6, Interface=0x2C182FA8, Call Id=319

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/act_wrelease_release:

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_play_busy_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_is_valid_dsm_handle:

DMGR=0x2C810798, VTSP CDB=0x31ED9648

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/act_terminate:

Nov 10 07:27:38.959: //318/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //318/59C77ECE82A3/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x319D3158, Tag=0x0, Call Id=318,

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

Nov 10 07:27:38.959: //318/59C77ECE82A3/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:27:38.959: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:38.959: :cc_free_feature_vsa freeing 2C729D40

Nov 10 07:27:38.959: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:38.959: vsacount in free is 3

Nov 10 07:27:38.959: //-1/59C77ECE82A3/VTSP:(50/0/26):-1:-1:-1/vtsp_free_cdb:

CDB=0x31ED9920

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/act_terminate:

Nov 10 07:27:38.959: //319/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=303444

Nov 10 07:27:38.959: //319/59C77ECE82A3/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x2C182FA8, Tag=0x0, Call Id=319,

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

Nov 10 07:27:38.959: //319/59C77ECE82A3/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:27:38.963: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:38.963: :cc_free_feature_vsa freeing 2C72A360

Nov 10 07:27:38.963: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:38.963: vsacount in free is 2

Nov 10 07:27:38.963: //-1/59C77ECE82A3/VTSP:(50/0/4):-1:-1:-1/vtsp_free_cdb:

CDB=0x31ED9648

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31EDA480

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_do_call_setup_ind:

Event=E_TSP_SETUP_IND

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

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/VTSP:(50/0/4):-1:-1:-1/vtsp_do_call_setup_ind:

Calling Number=4016, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

CLIR=FALSE, CLID Transparent=FALSE, Null Originating Calling Number=FALSE, Calling Translated=FALSE

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_timer:

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

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_do_normal_call_setup_ind:

IC State Machine (ICSM) Added

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_opened_cb:

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_setup_ind_pend_success:

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_realloc_cdb:

CDB=0x31EDA480

Nov 10 07:27:40.935: //-1/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_indicate_call:

Nov 10 07:27:40.935: //-1/6AAE467282A8/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

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

cisco-ani=4016

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

Nov 10 07:27:40.935: //-1/6AAE467282A8/CCAPI/cc_api_call_setup_ind_common:

Interface=0x2C182FA8, Call Info(

Calling Number=4016,(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=20004, 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

Nov 10 07:27:40.935: //-1/6AAE467282A8/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:40.935: //-1/6AAE467282A8/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:40.935: :cc_get_feature_vsa malloc success

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:40.935: cc_get_feature_vsa count is 3

Nov 10 07:27:40.935: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:40.935: :FEATURE_VSA attributes are: feature_name:0,feature_time:745710440,feature_id:306

Nov 10 07:27:40.935: //322/6AAE467282A8/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

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

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

Nov 10 07:27:40.935: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_insert_cdb:

Nov 10 07:27:40.935: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer:

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

Nov 10 07:27:40.935: //322/6AAE467282A8/CCAPI/cc_process_call_setup_ind:

Event=0x2B805148

Nov 10 07:27:40.935: //322/6AAE467282A8/CCAPI/ccCallSetContext:

Context=0x31F1C6EC

Nov 10 07:27:40.935: //322/6AAE467282A8/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 322 with tag 20004 to app "_ManagedAppProcess_Default"

Nov 10 07:27:40.935: //322/6AAE467282A8/CCAPI/ccCallSetupAck:

Call Id=322

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/cc_api_set_transfer_info:

Transfer Number=, Transfer Reason=0x0

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/ccGenerateToneInfo:

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

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

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/ccSetDigitTimeouts:

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

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/ccSetDigitTimeouts:

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

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/ccCallReportDigits:

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

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=322

Nov 10 07:27:40.939: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_report_digit_control:

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x2C182FA8, callID=0x142, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x2C182FA8, Call Id=322

Nov 10 07:27:40.939: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

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

Nov 10 07:27:40.939: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_report_digit_control:

Digit Reporting=TRUE

Nov 10 07:27:40.939: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_CC_SETUP_ACK]

Nov 10 07:27:40.939: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_setup_ind_ack:

Fax Relay=TRUE, Dial Tone=TRUE, Digit Collect=TRUE, Overlap=FALSE, DID=FALSE

Nov 10 07:27:40.939: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303642

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=9

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:42.567: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

Source Call Id=322, Digit=9, DigitBeginFlags=0x0,

Rtp Timestamp=0x372D00, Rtp Expiration=0x0

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303804

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=9

Nov 10 07:27:42.567: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:42.567: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

Source Call Id=322, Digit=9, Duration=100,

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

Nov 10 07:27:42.567: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=5

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.183: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

Source Call Id=322, Digit=5, DigitBeginFlags=0x0,

Rtp Timestamp=0x37AA00, Rtp Expiration=0x0

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303866

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=5

Nov 10 07:27:43.183: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.183: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

Source Call Id=322, Digit=5, Duration=100,

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

Nov 10 07:27:43.183: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.187: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

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

Rtp Timestamp=0x382700, Rtp Expiration=0x0

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303866

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.187: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

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

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

Nov 10 07:27:43.187: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.187: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

Source Call Id=322, Digit=7, DigitBeginFlags=0x0,

Rtp Timestamp=0x38A400, Rtp Expiration=0x0

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:43.187: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303867

Nov 10 07:27:43.191: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:27:43.191: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.191: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

Source Call Id=322, Digit=7, Duration=100,

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

Nov 10 07:27:43.191: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=5

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.579: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

Source Call Id=322, Digit=5, DigitBeginFlags=0x0,

Rtp Timestamp=0x392100, Rtp Expiration=0x0

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303906

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=5

Nov 10 07:27:43.579: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.579: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

Source Call Id=322, Digit=5, Duration=100,

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

Nov 10 07:27:43.579: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.583: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

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

Rtp Timestamp=0x399E00, Rtp Expiration=0x0

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=303906

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:43.583: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:43.583: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

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

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

Nov 10 07:27:43.583: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:44.543: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:44.547: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

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

Source Call Id=322, Digit=7, DigitBeginFlags=0x0,

Rtp Timestamp=0x3A1B00, Rtp Expiration=0x0

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=304002

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:27:44.547: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=TRUE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:44.547: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

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

Source Call Id=322, Digit=7, Duration=100,

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

Nov 10 07:27:44.547: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/ccCallReportDigits:

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

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=322

Nov 10 07:27:44.551: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_report_digit_control:

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x2C182FA8, callID=0x142, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x2C182FA8, Call Id=322

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/cc_api_call_report_digits_done:

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

Nov 10 07:27:44.551: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_report_digit_control:

Digit Reporting=FALSE

Nov 10 07:27:44.551: //322/6AAE467282A8/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCallSetupRequest:

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

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

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/cc_fill_tg_params:

Not a cic call

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCallSetupRequest:

Trunk Group Select Interface Success;

Interface=0x30FB2650, Selected Interface=1, Selected DSL=-1

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCallSetupRequest:

Destination Pattern=9[2-6]....., Called Number=9517517, Digit Strip=TRUE

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCallSetupRequest:

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

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

Redirect Number=, Display Info=Osama Ali AlHuthi

Account Number=, Final Destination Flag=FALSE,

Guid=6AAE4672-4910-11E3-82A8-98163B8C14B3, Outgoing Dial-peer=2000

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

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

cisco-ani=4016

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9517517

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

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccIFCallSetupRequestPrivate:

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

Call Params(Calling Number=4016,(Calling Name=Osama Ali AlHuthi)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

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

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

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

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:44.555: :cc_get_feature_vsa malloc success

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:44.555: cc_get_feature_vsa count is 4

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:44.555: :FEATURE_VSA attributes are: feature_name:0,feature_time:745708872,feature_id:307

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/VTSP:(0/0/2):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31EDAA30

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/VTSP:(0/0/2):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:44.555: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_spi_event_handler:

Event Category=1, Event=176

Nov 10 07:27:44.555: //323/6AAE467282A8/CCAPI/ccIFCallSetupRequestPrivate:

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

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccCallSetupRequest:

Trunk Group Call Setting;

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

Nov 10 07:27:44.555: //323/6AAE467282A8/CCAPI/ccCallSetContext:

Context=0x31F0E2AC

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=2000

Nov 10 07:27:44.555: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_DIGIT_COLLECT, event:E_CC_PROCEEDING]

Nov 10 07:27:44.555: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_dcollect_proc:

Nov 10 07:27:44.555: //-1/xxxxxxxxxxxx/VTSP:(0/0/2):-1:-1:-1/vtsp_process_event:

[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]

Nov 10 07:27:44.555: //323/xxxxxxxxxxxx/VTSP:(0/0/2):-1:-1:-1/vtsp_og_call_req:

Target Trkgrp Route Label=2

Nov 10 07:27:44.555: //322/6AAE467282A8/CCAPI/cc_api_modify_tgt_cid_call_active_record:

Target Trkgrp Route Label=2, tg_label_flag=TRUE, Call Id=0x142

Nov 10 07:27:44.555: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_og_call_req:

Echo Cancel=TRUE, Gain Control=0

Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE

Nov 10 07:27:44.555: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_do_call_setup_req:

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_opened_cb:

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_req_pend_succ:

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_request_call:

Mode=0, Peer=2000

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_create_call_active_on_setup_req:

Target Trkgrp Route Label=2

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_create_call_active_on_setup_req:

Source Trkgrp Route Label=

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_request_call:

Progress Indication=3, Info Trans Capability=0

Calling Number=4016, TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed

Called Number=9517517, TON=Unknown, NPI=Unknown

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_proceeding:

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer:

Timer Start Time=304004, Timer Value=25000(ms)

Nov 10 07:27:44.559: //323/6AAE467282A8/CCAPI/cc_api_call_proceeding:

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

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_proceeding:

Peer=2000, Progress Indication=0, Return Code=2

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_opened_cb:

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]

QNB_VoiceGW#[A

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_req_wait_pend_success:

Event=75

Nov 10 07:27:44.559: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304004 sh voice port summ

Nov 10 07:27:46.143: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_DIAL]

Nov 10 07:27:46.143: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_dial:

Digits=517517, Tone Mode=0

Nov 10 07:27:46.143: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dial:

Nov 10 07:27:46.143: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/ds_do_dial:

Digits To Dial=517517

Nov 10 07:27:46.407: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Begin Digit=1

IN OUT

--More--

Nov 10 07:27:46.407: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304250

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304250

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Begin Digit=5

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304250

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

End Digit=5

Nov 10 07:27:47.027: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.035: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:47.035: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304270

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:27:47.223: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=304354

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_dial_done_cb:

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_DS_DIALING, event:E_VTSP_DSM_DIALING_COMPLETE]

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/ds_dialing:

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/ds_do_dial:

Digits To Dial=

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_digit_pop:

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_dial_comp:

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/ds_do_dial:exit@571

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_PROGRESS]

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_progress:

Progress Indication=8, Signal Indication=2, cdb->answer_supervision=0

Nov 10 07:27:48.059: //323/6AAE467282A8/CCAPI/cc_api_call_cut_progress:

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

Cause Value=0

Nov 10 07:27:48.059: //323/6AAE467282A8/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Nov 10 07:27:48.059: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_ring_noan_timer_start:

Timer Start Time=304354, No Answer Timer Value=180000(ms)

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccCallCutProgress:

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

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

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccCallCutProgress:

Call Entry(Responsed=TRUE)

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

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

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccConferenceCreate:

(confID=0x2C7B184C, callID1=0x142, gcid=6AAE4672-491011E3-82A89816-3B8C14B3, tag=0x0)

Nov 10 07:27:48.071: //323/6AAE467282A8/CCAPI/ccConferenceCreate:

(confID=0x2C7B184C, callID2=0x143, gcid=6AAE4672-491011E3-82A89816-3B8C14B3, tag=0x0)

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccConferenceCreate:

Conference Id=0x2C7B184C, Call Id1=322, Call Id2=323, Tag=0x0

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_check_cb:

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_tdm_hpm_check:

Interface Type=26, Destination Interface Type=2

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_tdm_hpm_check:

Hairpin Allowed=FALSE, CDB=FALSE, Destination CDB=FALSE

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_check_cb:

Return Code=5

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/cc_api_bridge_done:

Conference Id=0x51, Source Interface=0x2C182FA8, Source Call Id=322,

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

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=2000

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_bridge_check_cb:

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_tdm_hpm_check:

Interface Type=2, Destination Interface Type=26

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_tdm_hpm_check:

Hairpin Allowed=FALSE, CDB=FALSE, Destination CDB=FALSE

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_bridge_check_cb:

Return Code=5

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:48.071: //323/6AAE467282A8/CCAPI/cc_api_bridge_done:

Conference Id=0x51, Source Interface=0x30FB2650, Source Call Id=323,

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

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/cc_generic_bridge_done:

Conference Id=0x51, Source Interface=0x30FB2650, Source Call Id=323,

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

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=DISABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=20004

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x51, Destination Call Id=323)

Nov 10 07:27:48.071: //323/6AAE467282A8/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x51, Destination Call Id=322)

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_PROGRESS]

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_progress:

Progress Indication=8

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=304355

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_open_packet_path:

Progress Indication=8

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_open_packet_path: Setting pak_suppress to DSP_PAK_SUPPRESS_DISABLE

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_DO_CAPS_IND]

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:27:48.071: //322/6AAE467282A8/CCAPI/cc_api_caps_ind:

Destination Interface=0x30FB2650, Destination Call Id=323, Source Call Id=322,

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

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

Nov 10 07:27:48.071: //322/6AAE467282A8/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))

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_CC_DO_CAPS_IND]

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:27:48.071: //323/6AAE467282A8/CCAPI/cc_api_caps_ind:

Destination Interface=0x2C182FA8, Destination Call Id=322, Source Call Id=323,

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

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

Nov 10 07:27:48.071: //323/6AAE467282A8/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))

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_CC_CAPS_IND]

Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:27:48.071: //323/6AAE467282A8/CCAPI/cc_api_caps_ack:

Destination Interface=0x2C182FA8, Destination Call Id=322, Source Call Id=323,

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=8099)

Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_CAPS_IND]

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:27:48.075: //322/6AAE467282A8/CCAPI/cc_api_caps_ack:

Destination Interface=0x30FB2650, Destination Call Id=323, Source Call Id=322,

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=3542)

Nov 10 07:27:48.075: //322/6AAE467282A8/CCAPI/ccCallFeature:

Feature Type=25, Call Id=322

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_FEATURE]

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_call_feature:

Feature Type=25

Nov 10 07:27:48.075: //322/6AAE467282A8/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x51, Call Id1=322, Call Id2=323

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_reactivate_ringback:

Nov 10 07:27:48.075: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_reactivate_ringback:exit@1268

Nov 10 07:27:48.075: //322/6AAE467282A8/CCAPI/cc_api_voice_mode_event:

Call Id=322

Nov 10 07:27:48.075: //322/6AAE467282A8/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x31F1C6EC)

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_reactivate_ringback:

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_reactivate_ringback:exit@1268

Nov 10 07:27:48.075: //323/6AAE467282A8/CCAPI/cc_api_voice_mode_event:

Call Id=323

Nov 10 07:27:48.075: //323/6AAE467282A8/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x31F0E2AC)

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT]

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_setup_pend_connect:

Progress Indication=2

Nov 10 07:27:48.075: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_ring_noan_timer_stop:

Timer Stop Time=304355

Nov 10 07:27:48.075: //323/6AAE467282A8/CCAPI/cc_api_call_connected:

Interface=0x30FB2650, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),

Connection Handle=0

Nov 10 07:27:48.075: //323/6AAE467282A8/CCAPI/cc_api_call_connected:

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

Nov 10 07:27:48.695: //322/6AAE467282A8/CCAPI/ccCallConnect:

Progress Indication=DESTINATION IS NON ISDN(2), Data Bitmask=0x1

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_call_connect: Connected Name

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_call_connect: Connected Number 517517

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_call_connect: Connected oct3a 29

Nov 10 07:27:48.695: //322/6AAE467282A8/CCAPI/ccCallConnect:

Call Entry(Connected=TRUE, Responsed=TRUE)

Nov 10 07:27:48.695: //322/6AAE467282A8/CCAPI/ccCallFeature:

Feature Type=25, Call Id=322

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_CONNECT]

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_connect:

Progress Indication=2

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=304417

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_stop:

Timer Stop Time=304417

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_CC_FEATURE]

Nov 10 07:27:48.695: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_call_feature:

Feature Type=25

Nov 10 07:27:48.695: //-1/xxxxxxxxxxxx/VTSP:(0/0/0):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED9ED0

Nov 10 07:27:48.695: //-1/xxxxxxxxxxxx/VTSP:(0/0/0):-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

Nov 10 07:27:48.695: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:-1:-1/vtsp_do_normal_call_setup_ind:

Nov 10 07:27:48.695: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:-1:-1/vtsp_timer:

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

Nov 10 07:27:48.695: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:-1:-1/vtsp_do_normal_call_setup_ind:

IC State Machine (ICSM) Added

Nov 10 07:27:48.695: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_opened_cb:

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_SETUP_IND_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_setup_ind_pend_success:

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_realloc_cdb:

CDB=0x31ED9ED0

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_indicate_call:

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/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=4446

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

Nov 10 07:27:48.699: //-1/6F4E5BB782AD/CCAPI/cc_api_call_setup_ind_common:

Interface=0x2B8565D8, Call Info(

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

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

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

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

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

Nov 10 07:27:48.703: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.703: :cc_get_feature_vsa malloc success

Nov 10 07:27:48.703: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.703: cc_get_feature_vsa count is 5

Nov 10 07:27:48.703: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.703: :FEATURE_VSA attributes are: feature_name:0,feature_time:745710216,feature_id:308

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

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

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

Nov 10 07:27:48.703: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_insert_cdb:

Nov 10 07:27:48.703: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_timer:

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

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/cc_process_call_setup_ind:

Event=0x2B804650

Nov 10 07:27:48.703: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 4446

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCallSetContext:

Context=0x31F1B22C

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 324 with tag 0 to app "_ManagedAppProcess_Default"

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

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

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

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

Destination Pattern=444[5678], Called Number=4446, Digit Strip=FALSE

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

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

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

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=TRUE,

Guid=6F4E5BB7-4910-11E3-82AD-98163B8C14B3, Outgoing Dial-peer=4445

Nov 10 07:27:48.703: //324/6F4E5BB782AD/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

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

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=4446

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

Nov 10 07:27:48.707: //324/6F4E5BB782AD/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x2B48170C, Interface Type=3, Destination=, Mode=0x0,

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

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

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

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

Nov 10 07:27:48.707: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.707: :cc_get_feature_vsa malloc success

Nov 10 07:27:48.707: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.707: cc_get_feature_vsa count is 6

Nov 10 07:27:48.707: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:48.707: :FEATURE_VSA attributes are: feature_name:0,feature_time:745709992,feature_id:309

Nov 10 07:27:48.707: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_spi_event_handler:

Event Category=1, Event=176

Nov 10 07:27:48.707: //325/6F4E5BB782AD/CCAPI/ccIFCallSetupRequestPrivate:

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

Nov 10 07:27:48.707: //325/6F4E5BB782AD/CCAPI/ccCallSetContext:

Context=0x31F173EC

Nov 10 07:27:48.707: //324/6F4E5BB782AD/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=4445

Nov 10 07:27:48.707: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_SETUP_INDICATED, event:E_CC_PROCEEDING]

Nov 10 07:27:48.707: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_proceeding:

Progress Indication=0

Nov 10 07:27:48.707: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_timer_stop:

Timer Stop Time=304418

Nov 10 07:27:48.707: //325/6F4E5BB782AD/CCAPI/cc_api_call_proceeding:

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

Nov 10 07:27:49.551: //325/6F4E5BB782AD/CCAPI/cc_api_call_alert:

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

Nov 10 07:27:49.551: //325/6F4E5BB782AD/CCAPI/cc_api_call_alert:

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

Nov 10 07:27:49.551: //324/6F4E5BB782AD/CCAPI/ccCallAlert:

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

Nov 10 07:27:49.551: //324/6F4E5BB782AD/CCAPI/ccCallAlert:

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

Nov 10 07:27:49.551: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_PROCEEDING, event:E_CC_ALERT]

Nov 10 07:27:49.555: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_alert:

Progress Indication=0, Signal Indication=1, Setup Progress Indication=3

Nov 10 07:27:49.555: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_timer_stop:

Timer Stop Time=304503

Nov 10 07:27:49.555: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_alert:

[Ring Back]-Tone Played In Direction [Network]

Nov 10 07:27:49.555: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_open_packet_path:

Progress Indication=0

Nov 10 07:27:49.555: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_alert:

Progress Indication=0, Tone=Ring Back

Nov 10 07:27:49.575: //325/6F4E5BB782AD/CCAPI/cc_api_call_connected:

Interface=0x2B48170C, Data Bitmask=0x1, Progress Indication=NULL(0),

Connection Handle=0

Nov 10 07:27:49.575: //325/6F4E5BB782AD/CCAPI/cc_api_call_connected:

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

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

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

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

(confID=0x2C7B157C, callID1=0x144, gcid=6F4E5BB7-491011E3-82AD9816-3B8C14B3, tag=0x0)

Nov 10 07:27:49.579: //325/6F4E5BB782AD/CCAPI/ccConferenceCreate:

(confID=0x2C7B157C, callID2=0x145, gcid=6F4E5BB7-491011E3-82AD9816-3B8C14B3, tag=0x0)

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Conference Id=0x2C7B157C, Call Id1=324, Call Id2=325, Tag=0x0

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_check_cb:

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_check_cb:exit@1477

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/cc_api_bridge_done:

Conference Id=0x52, Source Interface=0x2B8565D8, Source Call Id=324,

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

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=4445

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_fax_config:

Fax Version:=0, LS Red=0, HS Red=0

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_set_fax_feat_param:

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:27:49.579: //325/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:49.579: cc_api_get_xcode_stream : 4702

Nov 10 07:27:49.579: //325/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:49.579: cc_api_get_xcode_stream : 4702

Nov 10 07:27:49.579: //325/6F4E5BB782AD/CCAPI/cc_api_bridge_done:

Conference Id=0x52, Source Interface=0x2B48170C, Source Call Id=325,

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

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/cc_generic_bridge_done:

Conference Id=0x52, Source Interface=0x2B48170C, Source Call Id=325,

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

Nov 10 07:27:49.579: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x52, Destination Call Id=325)

Nov 10 07:27:49.579: //325/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x52, Destination Call Id=324)

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_ALERTING, event:E_CC_DO_CAPS_IND]

Nov 10 07:27:49.579: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/cc_api_caps_ind:

Destination Interface=0x2B48170C, Destination Call Id=325, Source Call Id=324,

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

Modem=0x2, Codec Bytes=20, Signal Type=3)

Nov 10 07:27:49.583: //324/6F4E5BB782AD/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))

Nov 10 07:27:49.583: //325/6F4E5BB782AD/CCAPI/cc_api_caps_ind:

Destination Interface=0x2B8565D8, Destination Call Id=324, Source Call Id=325,

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

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

Nov 10 07:27:49.583: //325/6F4E5BB782AD/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))

Nov 10 07:27:49.583: //325/6F4E5BB782AD/CCAPI/cc_api_caps_ack:

Destination Interface=0x2B8565D8, Destination Call Id=324, Source Call Id=325,

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

Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=4193)

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_ALERTING, event:E_CC_CAPS_IND]

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/cc_api_caps_ack:

Destination Interface=0x2B48170C, Destination Call Id=325, Source Call Id=324,

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

Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=4193)

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/ccCallConnect:

Progress Indication=NULL(0), Data Bitmask=0x1

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_call_connect: Connected Name

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_call_connect: Connected Number 4446

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_call_connect: Connected oct3a 80

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/ccCallConnect:

Call Entry(Connected=TRUE, Responsed=TRUE)

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_ALERTING, event:E_CC_CONNECT]

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/act_alert_connect:

Progress Indication=0

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_ring_noan_timer_stop:

Timer Stop Time=304506

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_reactivate_ringback:

Nov 10 07:27:49.583: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_reactivate_ringback:exit@1268

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/cc_api_voice_mode_event:

Call Id=324

Nov 10 07:27:49.583: //324/6F4E5BB782AD/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x31F1B22C)

Nov 10 07:27:49.587: //324/6F4E5BB782AD/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x52, Call Id1=324, Call Id2=325

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:55.151: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

Consume mask is not set. Relaying Digit 1 to dstCallId 0x143

Nov 10 07:27:55.151: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_call_digit_begin:

Begin Digit=1

Nov 10 07:27:55.151: //322/6AAE467282A8/CCAPI/cc_relay_digit_begin_for_3way_conference:

Check DTMF relay digit begin for 3way conf

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=305063

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:55.151: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:55.151: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Consume mask is not set. Relaying Digit 1 to dstCallId 0x143

Nov 10 07:27:55.151: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_call_digit_end:

End Digit=1, Duration=100

Nov 10 07:27:55.151: //322/6AAE467282A8/CCAPI/cc_relay_digit_end_for_3way_conference:

Check DTMF relay digit end for 3way conf

Nov 10 07:27:55.215: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:27:55.215: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:55.215: //324/6F4E5BB782AD/CCAPI/cc_api_call_digit_begin:

Consume mask is not set. Relaying Digit 1 to dstCallId 0x145

Nov 10 07:27:55.215: //324/6F4E5BB782AD/CCAPI/cc_relay_digit_begin_for_3way_conference:

Check DTMF relay digit begin for 3way conf

Nov 10 07:27:55.303: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

Nov 10 07:27:55.303: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_timer_stop:

Timer Stop Time=305078

Nov 10 07:27:55.303: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:27:55.303: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

QNB_VoiceGW#[A

Nov 10 07:27:55.303: //324/6F4E5BB782AD/CCAPI/cc_api_call_digit_end:

Consume mask is not set. Relaying Digit 1 to dstCallId 0x145

Nov 10 07:27:55.303: //324/6F4E5BB782AD/CCAPI/cc_relay_digit_end_for_3way_conference:

Check DTMF relay digit end for 3way conf sh voice port summ

IN OUT

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Begin Digit=2

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:58.199: //322/6AAE467282A8/CCAPI/cc_api_call_digit_begin:

Consume mask is not set. Relaying Digit 2 to dstCallId 0x143

Nov 10 07:27:58.199: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_call_digit_begin:

Begin Digit=2

Nov 10 07:27:58.199: //322/6AAE467282A8/CCAPI/cc_relay_digit_begin_for_3way_conference:

Check DTMF relay digit begin for 3way conf

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=305368

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

End Digit=2

Nov 10 07:27:58.199: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:58.199: //322/6AAE467282A8/CCAPI/cc_api_call_digit_end:

Consume mask is not set. Relaying Digit 2 to dstCallId 0x143

Nov 10 07:27:58.199: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_call_digit_end:

End Digit=2, Duration=100

Nov 10 07:27:58.199: //322/6AAE467282A8/CCAPI/cc_relay_digit_end_for_3way_conference:

Check DTMF relay digit end for 3way conf

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_begin_cb:

Begin Digit=2

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

Nov 10 07:27:58.527: //324/6F4E5BB782AD/CCAPI/cc_api_call_digit_begin:

Consume mask is not set. Relaying Digit 2 to dstCallId 0x145

Nov 10 07:27:58.527: //324/6F4E5BB782AD/CCAPI/cc_relay_digit_begin_for_3way_conference:

Check DTMF relay digit begin for 3way conf

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_timer_stop:

Timer Stop Time=305400

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

End Digit=2

Nov 10 07:27:58.527: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=TRUE

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 10 07:27:58.527: //324/6F4E5BB782AD/CCAPI/cc_api_call_digit_end:

Consume mask is not set. Relaying Digit 2 to dstCallId 0x145

Nov 10 07:27:58.527: //324/6F4E5BB782AD/CCAPI/cc_relay_digit_end_for_3way_conference:

Check DTMF relay digit end for 3way conf

Nov 10 07:27:59.359: //324/6F4E5BB782AD/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Sum Network, Params=0x0, Call Id=324

Nov 10 07:27:59.359: //325/6F4E5BB782AD/CCAPI/cc_api_call_facility:

Interface=0x2B48170C, Call Id=325

Nov 10 07:27:59.359: //325/6F4E5BB782AD/CCAPI/cc_api_get_ssCTreRoutingNotSupported:

CallInfo(ssCTreRoutingNotSupported=FALSE)

Nov 10 07:27:59.359: //324/6F4E5BB782AD/CCAPI/ccConferenceDestroy:

Conference Id=0x52, Tag=0x0

Nov 10 07:27:59.359: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_status_cb:

Nov 10 07:27:59.359: //324/6F4E5BB782AD/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x52, Source Interface=0x2B8565D8, Source Call Id=324,

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

Nov 10 07:27:59.359: //325/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:59.359: cc_api_get_xcode_stream : 4702

Nov 10 07:27:59.359: //324/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:59.359: cc_api_get_xcode_stream : 4702

Nov 10 07:27:59.359: //325/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:59.359: cc_api_get_xcode_stream : 4702

Nov 10 07:27:59.359: //325/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

Nov 10 07:27:59.359: cc_api_get_xcode_stream : 4702

Nov 10 07:27:59.359: //325/6F4E5BB782AD/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x52, Source Interface=0x2B48170C, Source Call Id=325,

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

Nov 10 07:27:59.363: //324/6F4E5BB782AD/CCAPI/cc_generic_bridge_done:

Conference Id=0x52, Source Interface=0x2B48170C, Source Call Id=325,

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

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :cc_get_feature_vsa malloc success

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: cc_get_feature_vsa count is 7

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :FEATURE_VSA attributes are: feature_name:0,feature_time:745708424,feature_id:310

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: feature_vsa is 2C72A1A0,featname is 0

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :cc_get_feature_vsa malloc success

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: cc_get_feature_vsa count is 8

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :FEATURE_VSA attributes are: feature_name:0,feature_time:745709544,feature_id:311

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: : Generating new featurevsa,inserting it

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: feature call forward featname is 4

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: feature_vsa is 2C72A280,featname is 0

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :cc_get_feature_vsa malloc success

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: cc_get_feature_vsa count is 9

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.363: :FEATURE_VSA attributes are: feature_name:0,feature_time:745709096,feature_id:312

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: : Generating new featurevsa,inserting it

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.363: feature call forward featname is 4

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:59.363: :cc_free_feature_vsa freeing 2C729B80

Nov 10 07:27:59.363: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:59.363: vsacount in free is 8

Nov 10 07:27:59.371: //324/6F4E5BB782AD/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=0

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.371: :cc_get_feature_vsa malloc success

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.371: cc_get_feature_vsa count is 9

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.371: :FEATURE_VSA attributes are: feature_name:0,feature_time:745708424,feature_id:313

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.371: : updating existing feature vsa

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

Nov 10 07:27:59.371: feature call forward featname is 4

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:59.371: :cc_free_feature_vsa freeing 2C729B80

Nov 10 07:27:59.371: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:27:59.371: vsacount in free is 8

Nov 10 07:27:59.371: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

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

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

Nov 10 07:27:59.371: //324/6F4E5BB782AD/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/ccCheckClipClir:

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

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

Destination Pattern=90097444407761$, Called Number=90097444407761, Digit Strip=TRUE

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/ccCallSetupRequest:

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

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

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=TRUE,

Guid=6F4E5BB7-4910-11E3-82AD-98163B8C14B3, Outgoing Dial-peer=1013

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

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

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=90097444407761

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=4446

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

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/ccIFCallSetupRequestPrivate:

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

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

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

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

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

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.375: :cc_get_feature_vsa malloc success

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.375: cc_get_feature_vsa count is 9

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:27:59.375: :FEATURE_VSA attributes are: feature_name:0,feature_time:745708424,feature_id:314

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/VTSP:(0/1/1):-1:-1:-1/vtsp_allocate_cdb:

CDB=0x31ED9370

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/VTSP:(0/1/1):-1:-1:-1/vtsp_insert_cdb:

Nov 10 07:27:59.375: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_spi_event_handler:

Event Category=1, Event=176

Nov 10 07:27:59.375: //327/6F4E5BB782AD/CCAPI/ccIFCallSetupRequestPrivate:

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

Nov 10 07:27:59.375: //327/6F4E5BB782AD/CCAPI/ccCallSetContext:

Context=0x31F0CDEC

Nov 10 07:27:59.375: //324/6F4E5BB782AD/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=1013

Nov 10 07:27:59.375: //-1/xxxxxxxxxxxx/VTSP:(0/1/1):-1:-1:-1/vtsp_process_event:

[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]

Nov 10 07:27:59.375: htsp_timer_stop3

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:-1:-1/vtsp_og_call_req:

Echo Cancel=TRUE, Gain Control=0

Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:-1:-1/vtsp_do_call_setup_req:

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_opened_cb:

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_req_pend_succ:

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_request_call:

Mode=0, Peer=1013

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_create_call_active_on_setup_req:

Target Carrier ID=

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_create_call_active_on_setup_req:

Source Carrier ID=htsp_setup_req

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_request_call:

Progress Indication=3, Info Trans Capability=144

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

Called Number=90097444407761, TON=Unknown, NPI=Unknown

Nov 10 07:27:59.375: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup

Nov 10 07:27:59.375: [0/1/1] set signal state = 0xC timestamp = 0

Nov 10 07:27:59.375: htsp_timer - 1300 msec

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_proceeding:

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer:

Timer Start Time=305485, Timer Value=25000(ms)

Nov 10 07:27:59.375: //327/6F4E5BB782AD/CCAPI/cc_api_call_proceeding:

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

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_proceeding:

Peer=1013, Progress Indication=0, Return Code=2

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_opened_cb:

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

QNB_VoiceGW#[A

[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_req_wait_pend_success:

Event=75

Nov 10 07:27:59.375: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305485

Nov 10 07:27:59.763: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0110]fxols_disc_clear

Nov 10 07:27:59.763: htsp_timer_stop2

Nov 10 07:27:59.763: htsp_timer - 1300 msec sh voice port summ

Nov 10 07:28:01.195: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer htsp_dial

Nov 10 07:28:01.195: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_DIAL]

Nov 10 07:28:01.195: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_dial:

Digits=0097444407761, Tone Mode=0

Nov 10 07:28:01.195: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dial:

Nov 10 07:28:01.195: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/ds_do_dial:

Digits To Dial=0097444407761

Nov 10 07:28:01.463: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=0

Nov 10 07:28:01.463: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:01.699: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:01.699: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305718

Nov 10 07:28:01.699: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=0

Nov 10 07:28:01.699: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305818

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305818

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:28:02.703: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.711: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:02.711: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305819

Nov 10 07:28:02.711: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:28:02.711: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.711: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305819

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=4

Nov 10 07:28:02.715: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305851

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=4

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=0

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305851

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

QNB_VoiceGW#[A sh voice port summ

End Digit=0

Nov 10 07:28:03.035: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305930

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=7

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305930

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=7

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.823: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=6

Nov 10 07:28:03.827: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305931

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=6

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Begin Digit=1

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_begin_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_timer_stop:

Timer Stop Time=305931

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

End Digit=1

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_digit_end_cb:

Digit Reporting=FALSE, Digit Consume=FALSE, Digit Relay=FALSE

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_dial_done_cb:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_DS_DIALING, event:E_VTSP_DSM_DIALING_COMPLETE]

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/ds_dialing:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/ds_do_dial:

Digits To Dial=

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_digit_pop:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_dial_comp:

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/ds_do_dial:exit@571

Nov 10 07:28:03.835: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress

Nov 10 07:28:03.835: htsp_timer - 350 msec

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_PROGRESS]

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_progress:

Progress Indication=8, Signal Indication=2, cdb->answer_supervision=0

Nov 10 07:28:03.835: //327/6F4E5BB782AD/CCAPI/cc_api_call_cut_progress:

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

Cause Value=0

Nov 10 07:28:03.835: //327/6F4E5BB782AD/CCAPI/cc_api_call_cut_progress:

Call Entry(Responsed=TRUE)

Nov 10 07:28:03.835: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_ring_noan_timer_start:

Timer Start Time=305931, No Answer Timer Value=180000(ms)

Nov 10 07:28:03.835: //324/6F4E5BB782AD/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

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

Nov 10 07:28:03.835: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

(confID=0x2C7B1924, callID1=0x144, gcid=6F4E5BB7-491011E3-82AD9816-3B8C14B3, tag=0x0)

Nov 10 07:28:03.835: //327/6F4E5BB782AD/CCAPI/ccConferenceCreate:

(confID=0x2C7B1924, callID2=0x147, gcid=6F4E5BB7-491011E3-82AD9816-3B8C14B3, tag=0x0)

Nov 10 07:28:03.835: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Conference Id=0x2C7B1924, Call Id1=324, Call Id2=327, Tag=0x0

Nov 10 07:28:03.835: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_check_cb:

Nov 10 07:28:03.835: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_tdm_hpm_check:

Interface Type=2, Destination Interface Type=2

Nov 10 07:28:03.835: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_tdm_hpm_check:

Hairpin Allowed=TRUE

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_check_cb:

Return Code=1

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:03.839: //324/6F4E5BB782AD/CCAPI/cc_api_bridge_done:

Conference Id=0x53, Source Interface=0x2B8565D8, Source Call Id=324,

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

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=1013

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_set_fax_feat_param:

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_tdm_hpm_complete:

CDB=0x31ED9ED0, Destination CDB=0x31ED9370

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_tdm_hpm_complete:

Return Code=2

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_bridge_status_cb:exit@1571

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_bridge_check_cb:

Nov 10 07:28:03.839: htsp_call_bridged invoked

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_tdm_hpm_check:

Interface Type=2, Destination Interface Type=2

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_tdm_hpm_check:

Hairpin Allowed=TRUE

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_bridge_check_cb:

Return Code=1

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/cc_api_bridge_done:

Conference Id=0x53, Source Interface=0x30FB70B8, Source Call Id=327,

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

Nov 10 07:28:03.839: //324/6F4E5BB782AD/CCAPI/cc_generic_bridge_done:

Conference Id=0x53, Source Interface=0x30FB70B8, Source Call Id=327,

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

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Global Settings

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_save_fax_config:

Fax Version:=0, LS Red=0, HS Red=0

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_set_fax_feat_param:

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_save_tty_config:

Save TTY configuration

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_tdm_hpm_complete:

CDB=0x31ED9370, Destination CDB=0x31ED9ED0

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_tdm_hpm_complete:

Return Code=2

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_bridge_status_cb:exit@1571

Nov 10 07:28:03.839: //324/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x53, Destination Call Id=327)

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x53, Destination Call Id=324)

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DO_CAPS_IND]

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:28:03.839: //324/6F4E5BB782AD/CCAPI/cc_api_caps_ind:

Destination Interface=0x30FB70B8, Destination Call Id=327, Source Call Id=324,

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

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

Nov 10 07:28:03.839: //324/6F4E5BB782AD/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))

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_CC_DO_CAPS_IND]

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_IND

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/cc_api_caps_ind:

Destination Interface=0x2B8565D8, Destination Call Id=324, Source Call Id=327,

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

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

Nov 10 07:28:03.839: //327/6F4E5BB782AD/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))

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_CC_CAPS_IND]

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/cc_api_caps_ack:

Destination Interface=0x2B8565D8, Destination Call Id=324, Source Call Id=327,

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

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

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_process_event:

[state:S_CONNECT, event:E_CC_CAPS_IND]

Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_peer_event_cb:

Event=E_DSM_CC_CAPS_ACK

Nov 10 07:28:03.839: //324/6F4E5BB782AD/CCAPI/cc_api_caps_ack:

Destination Interface=0x30FB70B8, Destination Call Id=327, Source Call Id=324,

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

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

Nov 10 07:28:03.839: //325/6F4E5BB782AD/CCAPI/ccCallFacility:

Call Id=325

Nov 10 07:28:03.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.839: :cc_get_feature_vsa malloc success

Nov 10 07:28:03.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.839: cc_get_feature_vsa count is 10

Nov 10 07:28:03.839: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.839: :FEATURE_VSA attributes are: feature_name:0,feature_time:745709320,feature_id:315

Nov 10 07:28:03.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.839: :cc_free_feature_vsa freeing 2C729F00

Nov 10 07:28:03.839: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.839: vsacount in free is 9

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_feature_notify_cb:

Feature ID=0, Feature Status=1

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_reactivate_ringback:

Nov 10 07:28:03.839: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_dsm_reactivate_ringback:exit@1268

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/cc_api_voice_mode_event:

Call Id=327

Nov 10 07:28:03.839: //327/6F4E5BB782AD/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x31F0CDEC)

Nov 10 07:28:03.839: htsp_process_event: [0/1/1, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru

Nov 10 07:28:03.843: htsp_timer_stop

Nov 10 07:28:03.843: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_process_event:

[state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT]

Nov 10 07:28:03.843: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/act_setup_pend_connect:

Progress Indication=2

Nov 10 07:28:03.843: //327/6F4E5BB782AD/VTSP:(0/1/1):-1:1:4/vtsp_ring_noan_timer_stop:

Timer Stop Time=305932

Nov 10 07:28:03.843: //327/6F4E5BB782AD/CCAPI/cc_api_call_connected:

Interface=0x30FB70B8, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),

Connection Handle=0

Nov 10 07:28:03.843: //327/6F4E5BB782AD/CCAPI/cc_api_call_connected:

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

Nov 10 07:28:03.843: //324/6F4E5BB782AD/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x53, Call Id1=324, Call Id2=327

Nov 10 07:28:03.843: //325/6F4E5BB782AD/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:03.843: //325/6F4E5BB782AD/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.843: :cc_get_feature_vsa malloc success

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.843: cc_get_feature_vsa count is 10

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Nov 10 07:28:03.843: :FEATURE_VSA attributes are: feature_name:0,feature_time:745709320,feature_id:316

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.843: :cc_free_feature_vsa freeing 2C729F00

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.843: vsacount in free is 9

Nov 10 07:28:03.843: //324/6F4E5BB782AD/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

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

Nov 10 07:28:03.843: //325/6F4E5BB782AD/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x2B48170C, Tag=0x0, Call Id=325,

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

Nov 10 07:28:03.843: //325/6F4E5BB782AD/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 10 07:28:03.843: :cc_free_feature_vsa freeing 2C729FE0

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.843: vsacount in free is 8

Nov 10 07:28:03.843: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:03.843: vsacount in free is 7

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up up idle off-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_TSP_DISCONNECT_IND]

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_generate_disc:

Cause Value=16

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_set_release_source:

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

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_cc_call_disconnected:

Cause Value=16

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x2C182FA8, Call Id=322

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/cc_api_call_disconnected:

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

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer:

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

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_generate_disc:

Return Code=0

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/ccConferenceDestroy:

Conference Id=0x51, Tag=0x0

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x51, Source Interface=0x2C182FA8, Source Call Id=322,

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

Nov 10 07:28:13.419: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:13.419: //323/6AAE467282A8/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x51, Source Interface=0x30FB2650, Source Call Id=323,

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

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/cc_generic_bridge_done:

Conference Id=0x51, Source Interface=0x30FB2650, Source Call Id=323,

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

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:13.419: //322/6AAE467282A8/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:28:13.419: //323/6AAE467282A8/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:13.419: //323/6AAE467282A8/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:13.419: //323/6AAE467282A8/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_disconnect:

Cause Value=16, Previous Cause Value=16

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_ring_noan_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=2000

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_set_fax_feat_param:

Nov 10 07:28:13.419: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:28:13.419: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_disconnect:

Cause Value=16

Nov 10 07:28:13.419: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_ring_noan_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_get_levels_done_cb:

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_dsm_dsp_stats_complete:

Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer:

Timer Start Time=306890, Timer Value=60000(ms)

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_CALL_FEATURE_IND]

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_call_feature_ind:

Feature Type=6

Nov 10 07:28:13.423: //322/6AAE467282A8/CCAPI/cc_api_call_feature:

Feature Type=6, Interface=0x2C182FA8, Call Id=322

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/act_wrelease_release:

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_play_busy_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_is_valid_dsm_handle:

DMGR=0x2C810798, VTSP CDB=0x31EDA480

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/act_terminate:

Nov 10 07:28:13.423: //322/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=306890

Nov 10 07:28:13.423: //322/6AAE467282A8/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x2C182FA8, Tag=0x0, Call Id=322,

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

Nov 10 07:28:13.423: //322/6AAE467282A8/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:28:13.423: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:13.423: :cc_free_feature_vsa freeing 2C72A360

Nov 10 07:28:13.423: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:13.423: vsacount in free is 6

Nov 10 07:28:13.423: //-1/6AAE467282A8/VTSP:(50/0/4):-1:-1:-1/vtsp_free_cdb:

CDB=0x31EDA480

Nov 10 07:28:13.667: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_get_levels_done_cb:

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_dsm_dsp_stats_complete:

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer:

Timer Start Time=306916, Timer Value=1000(ms)

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/act_wrelease_release:

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_play_busy_timer_stop:

Timer Stop Time=306916

Nov 10 07:28:13.679: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_timer_stop:

Timer Stop Time=306916

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_is_valid_dsm_handle:

DMGR=0x2ACC9794, VTSP CDB=0x31EDAA30

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/act_terminate:

Nov 10 07:28:13.683: //323/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=306916

Nov 10 07:28:13.683: //323/6AAE467282A8/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x30FB2650, Tag=0x0, Call Id=323,

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

Nov 10 07:28:13.683: //323/6AAE467282A8/CCAPI/cc_api_call_disconnect_done:

tg for this call is 2 success/fail is 1

Nov 10 07:28:13.683: //323/6AAE467282A8/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:28:13.683: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:13.683: :cc_free_feature_vsa freeing 2C729D40

Nov 10 07:28:13.683: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:13.683: vsacount in free is 5

Nov 10 07:28:13.683: //-1/6AAE467282A8/VTSP:(0/0/2):-1:-1:-1/vtsp_free_cdb:

CDB=0x31EDAA30

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#[A sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up up idle off-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_process_event:

[state:S_CONNECT, event:E_TSP_DISCONNECT_IND]

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_generate_disc:

Cause Value=16

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_ring_noan_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_set_release_source:

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

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_cc_call_disconnected:

Cause Value=16

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x318CD9F0, Call Id=312

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_api_call_disconnected:

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

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_timer:

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

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_generate_disc:

Return Code=0

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/ccConferenceDestroy:

Conference Id=0x4F, Tag=0x0

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x4F, Source Interface=0x318CD9F0, Source Call Id=312,

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

Nov 10 07:28:48.019: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_dsm_bridge_status_cb:

Nov 10 07:28:48.019: //315/08C594C78299/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x4F, Source Interface=0x30FB0940, Source Call Id=315,

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

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_generic_bridge_done:

Conference Id=0x4F, Source Interface=0x30FB0940, Source Call Id=315,

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

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:28:48.019: //315/08C594C78299/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:48.019: //315/08C594C78299/CCAPI/ccCallDisconnect:

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

Nov 10 07:28:48.019: //315/08C594C78299/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_disconnect:

Cause Value=16, Previous Cause Value=16

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_ring_noan_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_dsm_save_fax_config:

Fax Relay=ENABLED

Primary Fax Protocol=IGNORE_FAX_RELAY, Fallback Fax Protocol=NONE_FAX_RELAY

Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED

Fax Parameters Set By=Dialpeer, Peer=2002

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_dsm_set_fax_feat_param:

Nov 10 07:28:48.019: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_process_event:

[state:S_CONNECT, event:E_CC_DISCONNECT]

Nov 10 07:28:48.019: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/act_disconnect:

Cause Value=16

Nov 10 07:28:48.019: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_ring_noan_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_dsm_get_levels_done_cb:

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_dsm_dsp_stats_complete:

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_timer:

Timer Start Time=310350, Timer Value=60000(ms)

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_CALL_FEATURE_IND]

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_call_feature_ind:

Feature Type=6

Nov 10 07:28:48.019: //312/08C594C78299/CCAPI/cc_api_call_feature:

Feature Type=6, Interface=0x318CD9F0, Call Id=312

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/act_wrelease_release:

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_play_busy_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_do_call_history:

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_is_valid_dsm_handle:

DMGR=0x2ACC2808, VTSP CDB=0x31ED9098

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:-1:-1/act_terminate:

Nov 10 07:28:48.023: //312/08C594C78299/VTSP:(50/0/10):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=310350

Nov 10 07:28:48.023: //312/08C594C78299/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x318CD9F0, Tag=0x0, Call Id=312,

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

Nov 10 07:28:48.023: //312/08C594C78299/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:28:48.023: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:48.023: :cc_free_feature_vsa freeing 2C729AA0

Nov 10 07:28:48.023: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:48.023: vsacount in free is 4

Nov 10 07:28:48.023: //-1/08C594C78299/VTSP:(50/0/10):-1:-1:-1/vtsp_free_cdb:

CDB=0x31ED9098

Nov 10 07:28:48.027: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_dsm_get_levels_done_cb:

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_process_event:

[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/act_dsm_dsp_stats_complete:

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_timer:

Timer Start Time=310351, Timer Value=1000(ms)

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_process_event:

[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/act_wrelease_release:

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_play_busy_timer_stop:

Timer Stop Time=310351

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_timer_stop:

Timer Stop Time=310351

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_do_call_history:

Coder Rate=5

Nov 10 07:28:48.035: //315/08C594C78299/VTSP:(0/0/1):-1:1:1/vtsp_is_valid_dsm_handle:

DMGR=0x31FC735C, VTSP CDB=0x31EDA758

Nov 10 07:28:48.039: //315/08C594C78299/VTSP:(0/0/1):-1:-1:-1/vtsp_dsm_closed_cb:

Nov 10 07:28:48.039: //315/08C594C78299/VTSP:(0/0/1):-1:-1:-1/vtsp_process_event:

[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]

Nov 10 07:28:48.039: //315/08C594C78299/VTSP:(0/0/1):-1:-1:-1/act_terminate:

Nov 10 07:28:48.039: //315/08C594C78299/VTSP:(0/0/1):-1:-1:-1/vtsp_timer_stop:

Timer Stop Time=310352

Nov 10 07:28:48.039: //315/08C594C78299/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x30FB0940, Tag=0x0, Call Id=315,

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

Nov 10 07:28:48.039: //315/08C594C78299/CCAPI/cc_api_call_disconnect_done:

tg for this call is 2 success/fail is 1

Nov 10 07:28:48.039: //315/08C594C78299/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

Nov 10 07:28:48.039: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:48.039: :cc_free_feature_vsa freeing 2C72A0C0

Nov 10 07:28:48.039: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Nov 10 07:28:48.039: vsacount in free is 3

Nov 10 07:28:48.039: //-1/08C594C78299/VTSP:(0/0/1):-1:-1:-1/vtsp_free_cdb:

CDB=0x31EDA758

QNB_VoiceGW#unde

QNB_VoiceGW#undebug all

All possible debugging has been turned off

VoiceGW#undebug all sh voice port summ

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up up ring-det off-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up up idle off-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y

--More--

VoiceGW#conf t

Enter configuration commands, one per line. End with CNTL/Z.

VoiceGW(config)#voice-po

VoiceGW(config)#voice-port 0/1/1

VoiceGW(config-voiceport)#sh

VoiceGW(config-voiceport)#no sh

VoiceGW(config-voiceport)#

Nov 10 07:30:02.223: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/1, changed state to Administrative Shutdown

Nov 10 07:30:02.611: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/1/1, changed state to up^Z

VoiceGW#conf t sh voice port summ

Nov 10 07:30:04.659: %SYS-5-CONFIG_I: Configured from console by qnb on vty0 (10.201.46.59)

IN OUT

PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC

=============== == ============ ===== ==== ======== ======== ==

0/0/0 -- fxo-ls up dorm idle on-hook y

0/0/1 -- fxo-ls up dorm idle on-hook y

0/0/2 -- fxo-ls up dorm idle on-hook y

0/0/3 -- fxo-ls up dorm idle on-hook y

0/1/0 -- fxo-ls up dorm idle on-hook y

0/1/1 -- fxo-ls up dorm idle on-hook y

0/1/2 -- fxo-ls up dorm idle on-hook y

0/1/3 -- fxo-ls down down idle on-hook y

0/2/0 -- fxs-ls up dorm on-hook idle y

0/2/1 -- fxs-ls up dorm on-hook idle y

0/2/2 -- fxs-ls up dorm on-hook idle y

0/2/3 -- fxs-ls up dorm on-hook idle y

50/0/1 1 efxs up dorm on-hook idle y

50/0/1 2 efxs up dorm on-hook idle y

50/0/1 3 efxs up dorm on-hook idle y

50/0/1 4 efxs up dorm on-hook idle y

50/0/1 5 efxs up dorm on-hook idle y

50/0/1 6 efxs up dorm on-hook idle y

50/0/1 7 efxs up dorm on-hook idle y

50/0/1 8 efxs up dorm on-hook idle y

50/0/2 1 efxs up dorm on-hook idle y