11-03-2013 05:39 AM - edited 03-16-2019 08:12 PM
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.
11-03-2013 06:15 AM
Is it only that international number fails on that port or any number fails?
Chris
11-04-2013 09:57 PM
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,
11-05-2013 05:51 AM
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??
11-05-2013 08:30 PM
Sounds like the line may be dead, do "debug vpm signal"
Chris
11-06-2013 09:55 PM
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)
11-07-2013 01:58 AM
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"
11-10-2013 01:26 AM
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_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
11-10-2013 02:07 AM
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"
11-10-2013 02:28 AM
thanks agin Mr. Carlo
the line is working fine and nothing has been changed recently.
is there anything you want me to check.
regards,
11-10-2013 06:50 AM
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
11-10-2013 12:54 PM
thanks
please can u go through my replay to Carlo and replay me.
regards,
11-10-2013 02:56 PM
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
11-10-2013 10:02 PM
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.
11-10-2013 12:50 PM
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
Nov 10 07:27:17.843: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_fax_config:
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:
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:
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:
Nov 10 07:27:17.843: //319/59C77ECE82A3/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:
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:
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:
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:
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:
Nov 10 07:27:38.955: //318/59C77ECE82A3/VTSP:(50/0/26):-1:0:201/vtsp_dsm_save_fax_config:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
Nov 10 07:27:48.071: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:
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:
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:
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:
Nov 10 07:27:48.071: //323/6AAE467282A8/VTSP:(0/0/2):-1:1:2/vtsp_dsm_save_fax_config:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
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:
Nov 10 07:28:03.839: //324/6F4E5BB782AD/VTSP:(0/0/0):-1:1:3/vtsp_dsm_save_fax_config:
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:
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:
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:
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:
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:
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:
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:
Nov 10 07:28:13.419: //322/6AAE467282A8/VTSP:(50/0/4):-1:0:25/vtsp_dsm_save_fax_config:
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:
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:
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:
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:
Nov 10 07:28:48.019: //312/08C594C78299/VTSP:(50/0/10):-1:0:73/vtsp_dsm_save_fax_config:
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:
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
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide