Log Buffer (8192 bytes):
ing oct3 0x81, called oct3 0x81
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:-1:-1/vtsp_og_call_req: Ca
ll ID=36078, guid=66961330
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:-1:-1/vtsp_dump_debugs_set
up_req: type=0, under_spec=1, name=, ds0=0, ds1=-1, echo_cancel=1, gain_control 0, auto_g
ain_control 0, dual_tone_detect 0, seq_tone_detect 0, calling=111, called=2348033441118,
playout mode=0,playout_init = 0, playout_min = 0, playout_max = 0
 playout_fax_nom = 0
redirect DN:  reason: -1
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:0:0/dsm_start_basic_sm: .
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_start_basic_sm:
 dsp resource manager opened. ret 4
Feb 12 01:25:43.253 UTC: digit_strip:1, pcn:2348033441118, poa:234803T
Feb 12 01:25:43.253 UTC: pcn:3441118, poa:T
Feb 12 01:25:43.253 UTC: Final pcn:3441118, poa:T, dial_string:08033441118
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_request_call:
 calling oct3 0x81, called oct3 0x81
Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_request_call:
 cdb->sdb->type = 5
Feb 12 01:25:43.257 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_create_call_active_on_se
tup_req: target route label is
Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_create_call_a
ctive_on_setup_req: tgt carrier id

Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_create_call_a
ctive_on_setup_req: src carrier id

Feb 12 01:25:43.345 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:43.345 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_handle_ignored_
event: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:43.613 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:43.613 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_handle_ignored_
event: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:43.885 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_process_event:
 vtsp:[6/0:D (224359), S_SETUP_REQUEST, E_TSP_PROCEEDING]
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/act_setup_pend_proc
eeding: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsp_stream_mgr_rein
it_platform_info: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_open_voice_and_
set_params: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_playout_dmgr: p
layout default

Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_dsp_echo_cancel
ler_control: echo_cancel: 1
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_process_event:
 vtsp:[6/0:D (224359), S_SETUP_REQ_PROC, E_TSP_ALERT]
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/act_setup_pend_aler
t: .
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_ring_noan_time
r_start: 9309116
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_INIT E:E_DSM_CC_BRIDGE]
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_bridge: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_bridge_sta
tus_cb: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_save_fax_c
onfig:  Fax relay is ENABLED, Primary Fax protocol is T38_FAX_RELAY, Fallback Fax protoco
l is NONE_FAX_RELAY, Fax Parameters set by Dialpeer
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_peer_event
_cb: E_DSM_CC_CAPS_IND
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_IND]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: R
TP PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],Cl
earChan[125],PCMu[0],PCMa[8]Codec[256], TxDynamicPayload[0], RxDynamicPayload[0]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: d
tmf relay: mode=1, codec=1
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: p
assthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mod
em_relay 0, gw-xid 0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_debug_caps_enca
p: Encap 1, Vad 2, Codec 0x100, CodecBytes 24,
             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
             SignalType 1
             DtmfRelay 1, Modem 0, SeqNumStart 0x11B3
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: F
ORKING Parameters are forking mask: 3, simple_forking_codec_mask: 39039, complex_forking_
codec_mask 39039
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_peer_event
_cb: E_DSM_CC_CAPS_ACK
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: p
layout: [ mode:1,init:60, min:40, max:200]. data_mode:0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_ACK]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: p
assthrough: cap_modem_proto 0, cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mo
dem_relay 0, gw-xid 0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: N
amed Telephone Event payload rcv 101, tx 101
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_switch_codec: R
equired codec is 8,  current dsp codec is -1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_INIT E:E_DSM_DSPRM_PEND_SUCCESS]
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_pend_codec_
success: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_dsp_encap_confi
g: logical ssrc 20
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_open_voice_and_
set_params: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_dsp_encap_confi
g: logical ssrc 20
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_playout_dmgr: p
layout default

Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_dsp_echo_cancel
ler_control: echo_cancel: 1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_setup_stream_af
ter_switch_codec_succ: codec change success
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_add_fork: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: add_fork=0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_get_xmit_info_n
ode: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o:  xmit func is 61123BB0, context is 66340618 peer_call_id: 224358, stream_count: 1, upd
ate_flag 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The stream bit-mask is 1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The stream type is 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The logical ssrc is 32 for stream 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_stream_c
ount:
Feb 12 01:25:44.673 UTC:  g711_voice_count=0 g711_avt_count = 0
 g711_voice_avt_count = 0 complex_voice_count = 1
 complex_avt_count = 0 complex_voice_avt_count = 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_feature_no
tify_cb: id=0 status=1
















vtsp:[6/0:D (216013), S_WAIT_RELEASE, E_TSP_DISCONNECT_CONF]
Feb 12 01:25:37.321 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/act_wrelease_release
: .
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_play_busy_timer
_stop: 9308384
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_timer_stop: 930
8384
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_do_call_history
: .
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_do_call_history
: src carrier id
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_do_call_history
: tgt carrier id
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_do_call_history
: CoderRate 8
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsm_exec_cleanup: [F
eat SM: S:NONE B SM: S:S_DSM_CLOSE E:E_DSM_CLEANUP]
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsm_act_cleanup: .
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsp_stream_mgr_destr
oy: dsm_exec status: DSM_PENDING
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_CLOSE E:E_DSM_DSPRM_CLOSE_COMPLETE]
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsm_act_terminate: .
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:8275:0/vtsp_dsm_closed_cb:
.
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/DSM:(6/0:D):-1:8275:0/dsm_cleanup_handle:
.
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:-1:-1/vtsp_process_event: v
tsp:[6/0:D (216013), S_CLOSE_DSPRM, E_VTSP_DSM_CLOSE_COMPLETE]
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:-1:-1/act_terminate: .
Feb 12 01:25:37.325 UTC: //216013/2D35E48ABA06/VTSP:(6/0:D):5:-1:-1/vtsp_timer_stop: 9308
384
Feb 12 01:25:37.325 UTC: //-1/2D35E48ABA06/VTSP:(6/0:D):5:-1:-1/vtsp_free_cdb: ,cdb 0x65D
C2270
Feb 12 01:25:39.233 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:39.233 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_handle_ignored_
event: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:40.177 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:40.177 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:40.357 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:40.357 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:40.593 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:40.593 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_process_event:
vtsp:[6/0:D (224355), S_SETUP_REQ_PROC, E_TSP_ALERT]
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/act_setup_pend_alert
: .
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_ring_noan_timer
_start: 9308718
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_INIT E:E_DSM_CC_BRIDGE]
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_bridge: .
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_dsm_bridge_stat
us_cb: .
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_dsm_save_fax_co
nfig:  Fax relay is ENABLED, Primary Fax protocol is T38_FAX_RELAY, Fallback Fax protocol
 is NONE_FAX_RELAY, Fax Parameters set by Dialpeer
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_dsm_peer_event_
cb: E_DSM_CC_CAPS_IND
Feb 12 01:25:40.657 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_IND]
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: .
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: RT
P PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],Cle
arChan[125],PCMu[0],PCMa[8]Codec[256], TxDynamicPayload[0], RxDynamicPayload[0]
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: dt
mf relay: mode=1, codec=1
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: pa
ssthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mode
m_relay 0, gw-xid 0
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_debug_caps_encap
: Encap 1, Vad 2, Codec 0x100, CodecBytes 24,
             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
             SignalType 1
             DtmfRelay 1, Modem 0, SeqNumStart 0x107
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: FO
RKING Parameters are forking mask: 3, simple_forking_codec_mask: 39039, complex_forking_c
odec_mask 39039
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_dsm_peer_event_
cb: E_DSM_CC_CAPS_ACK
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ind: pl
ayout: [ mode:1,init:60, min:40, max:200]. data_mode:0
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_ACK]
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ack: .
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ack: pa
ssthrough: cap_modem_proto 0, cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mod
em_relay 0, gw-xid 0
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_caps_ack: Na
med Telephone Event payload rcv 101, tx 101
Feb 12 01:25:40.661 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_switch_codec: Re
quired codec is 8,  current dsp codec is -1
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_INIT E:E_DSM_DSPRM_PEND_SUCCESS]
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_act_pend_codec_s
uccess: .
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/set_dsp_encap_config
: logical ssrc 20
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_open_voice_and_s
et_params: .
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/set_dsp_encap_config
: logical ssrc 20
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/set_playout_dmgr: pl
ayout default

Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_dsp_echo_cancell
er_control: echo_cancel: 1
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_setup_stream_aft
er_switch_codec_succ: codec change success
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_add_fork: .
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_fork_info
: add_fork=0
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_get_xmit_info_no
de: .
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_fork_info
:  xmit func is 61123BB0, context is 65D82770 peer_call_id: 224354, stream_count: 1, upda
te_flag 0
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_fork_info
: The stream bit-mask is 1
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_fork_info
: The stream type is 0
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_fork_info
: The logical ssrc is 32 for stream 0
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/DSM:(6/0:D):-1:4339:0/dsm_update_stream_co
unt:
Feb 12 01:25:40.681 UTC:  g711_voice_count=0 g711_avt_count = 0
 g711_voice_avt_count = 0 complex_voice_count = 1
 complex_avt_count = 0 complex_voice_avt_count = 0
Feb 12 01:25:40.681 UTC: //224355/1900527F8BE6/VTSP:(6/0:D):6:4339:0/vtsp_dsm_feature_not
ify_cb: id=0 status=1
Feb 12 01:25:40.689 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:40.689 UTC: //220531/DCBC75C89D4A/DSM:(6/0:D):-1:8229:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:40.853 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_allocate_cdb: cdb 0x663D
FA50
Feb 12 01:25:40.853 UTC: //-1/xxxxxxxxxxxx/VTSP:(6/0:D):-1:-1:-1/vtsp_insert_cdb: ,cdb 0x
663DFA50, CallID=224357
Feb 12 01:25:40.853 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_og_call_req: .
Feb 12 01:25:40.853 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:-1:-1/vtsp_og_call_req:  c
alling oct3 0x81, called oct3 0x81
Feb 12 01:25:40.853 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:-1:-1/vtsp_og_call_req: Ca
ll ID=34389, guid=6632D848
Feb 12 01:25:40.853 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:-1:-1/vtsp_dump_debugs_set
up_req: type=0, under_spec=1, name=, ds0=0, ds1=-1, echo_cancel=1, gain_control 0, auto_g
ain_control 0, dual_tone_detect 0, seq_tone_detect 0, calling=111, called=2348033020811,
playout mode=0,playout_init = 0, playout_min = 0, playout_max = 0
 playout_fax_nom = 0
redirect DN:  reason: -1
Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:0:0/dsm_start_basic_sm: .
Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:16501:0/dsm_start_basic_sm:
 dsp resource manager opened. ret 4
Feb 12 01:25:40.857 UTC: digit_strip:1, pcn:2348033020811, poa:234803T
Feb 12 01:25:40.857 UTC: pcn:3020811, poa:T
Feb 12 01:25:40.857 UTC: Final pcn:3020811, poa:T, dial_string:08033020811
Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:16501:0/vtsp_request_call:
 calling oct3 0x81, called oct3 0x81
Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:16501:0/vtsp_request_call:
 cdb->sdb->type = 5
Feb 12 01:25:40.857 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_create_call_active_on_se
tup_req: target route label is
Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:16501:0/vtsp_create_call_a
ctive_on_setup_req: tgt carrier id

Feb 12 01:25:40.857 UTC: //224357/FDEC55108655/VTSP:(6/0:D):-1:16501:0/vtsp_create_call_a
ctive_on_setup_req: src carrier id

Feb 12 01:25:41.089 UTC: //224163/682F5C388DEC/DSM:(6/0:D):-1:8291:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:41.089 UTC: //224163/682F5C388DEC/DSM:(6/0:D):-1:8291:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:41.357 UTC: //224163/682F5C388DEC/DSM:(6/0:D):-1:8291:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:41.357 UTC: //224163/682F5C388DEC/DSM:(6/0:D):-1:8291:0/dsm_handle_ignored_e
vent: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/VTSP:(6/0:D):7:16501:0/vtsp_process_event:
 vtsp:[6/0:D (224357), S_SETUP_REQUEST, E_TSP_PROCEEDING]
Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/VTSP:(6/0:D):7:16501:0/act_setup_pend_proc
eeding: .
Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:16501:0/dsp_stream_mgr_rein
it_platform_info: .
Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:16501:0/dsm_open_voice_and_
set_params: .
Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:16501:0/set_playout_dmgr: p
layout default

Feb 12 01:25:41.489 UTC: //224357/FDEC55108655/DSM:(6/0:D):-1:16501:0/dsm_dsp_echo_cancel
ler_control: echo_cancel: 1
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/VTSP:(6/0:D):2:8373:0/vtsp_process_event:
vtsp:[6/0:D (224353), S_SETUP_REQ_PROC, E_TSP_CONNECT]
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/VTSP:(6/0:D):2:8373:0/act_setup_pend_conne
ct: .
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/VTSP:(6/0:D):2:8373:0/vtsp_ring_noan_timer
_stop: 9308862
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/DSM:(6/0:D):-1:8373:0/dsp_stream_mgr_play_
tone: .
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/DSM:(6/0:D):-1:8373:0/dsm_exec: [Feat SM:
S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_CC_GEN_TONE]
Feb 12 01:25:42.097 UTC: //224353/172A59038B7B/DSM:(6/0:D):-1:8373:0/dsm_act_gen_tone: To
ne is not on, ignoring
Feb 12 01:25:43.253 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_allocate_cdb: cdb 0x663D
7428
Feb 12 01:25:43.253 UTC: //-1/xxxxxxxxxxxx/VTSP:(6/0:D):-1:-1:-1/vtsp_insert_cdb: ,cdb 0x
663D7428, CallID=224359
Feb 12 01:25:43.253 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_og_call_req: .
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:-1:-1/vtsp_og_call_req:  c
alling oct3 0x81, called oct3 0x81
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:-1:-1/vtsp_og_call_req: Ca
ll ID=36078, guid=66961330
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:-1:-1/vtsp_dump_debugs_set
up_req: type=0, under_spec=1, name=, ds0=0, ds1=-1, echo_cancel=1, gain_control 0, auto_g
ain_control 0, dual_tone_detect 0, seq_tone_detect 0, calling=111, called=2348033441118,
playout mode=0,playout_init = 0, playout_min = 0, playout_max = 0
 playout_fax_nom = 0
redirect DN:  reason: -1
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:0:0/dsm_start_basic_sm: .
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_start_basic_sm:
 dsp resource manager opened. ret 4
Feb 12 01:25:43.253 UTC: digit_strip:1, pcn:2348033441118, poa:234803T
Feb 12 01:25:43.253 UTC: pcn:3441118, poa:T
Feb 12 01:25:43.253 UTC: Final pcn:3441118, poa:T, dial_string:08033441118
Feb 12 01:25:43.253 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_request_call:
 calling oct3 0x81, called oct3 0x81
Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_request_call:
 cdb->sdb->type = 5
Feb 12 01:25:43.257 UTC: //-1/xxxxxxxxxxxx/VTSP:():-1:-1:-1/vtsp_create_call_active_on_se
tup_req: target route label is
Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_create_call_a
ctive_on_setup_req: tgt carrier id

Feb 12 01:25:43.257 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):-1:12515:0/vtsp_create_call_a
ctive_on_setup_req: src carrier id

Feb 12 01:25:43.345 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:43.345 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_handle_ignored_
event: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:43.613 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGED E:E_DSM_DSP_PROTOCOL_ERROR]
Feb 12 01:25:43.613 UTC: //221641/B5DBBB28A901/DSM:(6/0:D):-1:16417:0/dsm_handle_ignored_
event: Event E_DSM_DSP_PROTOCOL_ERROR, bS S_DSM_BRIDGED, fid 9, fS NONE
Feb 12 01:25:43.885 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_process_event:
 vtsp:[6/0:D (224359), S_SETUP_REQUEST, E_TSP_PROCEEDING]
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/act_setup_pend_proc
eeding: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsp_stream_mgr_rein
it_platform_info: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_open_voice_and_
set_params: .
Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_playout_dmgr: p
layout default

Feb 12 01:25:43.889 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_dsp_echo_cancel
ler_control: echo_cancel: 1
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_process_event:
 vtsp:[6/0:D (224359), S_SETUP_REQ_PROC, E_TSP_ALERT]
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/act_setup_pend_aler
t: .
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_ring_noan_time
r_start: 9309116
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_INIT E:E_DSM_CC_BRIDGE]
Feb 12 01:25:44.645 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_bridge: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_bridge_sta
tus_cb: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_save_fax_c
onfig:  Fax relay is ENABLED, Primary Fax protocol is T38_FAX_RELAY, Fallback Fax protoco
l is NONE_FAX_RELAY, Fax Parameters set by Dialpeer
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_peer_event
_cb: E_DSM_CC_CAPS_IND
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_IND]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: R
TP PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],Cl
earChan[125],PCMu[0],PCMa[8]Codec[256], TxDynamicPayload[0], RxDynamicPayload[0]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: d
tmf relay: mode=1, codec=1
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: p
assthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mod
em_relay 0, gw-xid 0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_debug_caps_enca
p: Encap 1, Vad 2, Codec 0x100, CodecBytes 24,
             FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
             SignalType 1
             DtmfRelay 1, Modem 0, SeqNumStart 0x11B3
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: F
ORKING Parameters are forking mask: 3, simple_forking_codec_mask: 39039, complex_forking_
codec_mask 39039
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_peer_event
_cb: E_DSM_CC_CAPS_ACK
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ind: p
layout: [ mode:1,init:60, min:40, max:200]. data_mode:0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_BRIDGING E:E_DSM_CC_CAPS_ACK]
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: .
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: p
assthrough: cap_modem_proto 0, cap_modem_codec 0, cap_modem_redundancy 0, payload 100, mo
dem_relay 0, gw-xid 0
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_caps_ack: N
amed Telephone Event payload rcv 101, tx 101
Feb 12 01:25:44.649 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_switch_codec: R
equired codec is 8,  current dsp codec is -1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_exec: [Feat SM:
 S:NONE B SM: S:S_DSM_INIT E:E_DSM_DSPRM_PEND_SUCCESS]
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_act_pend_codec_
success: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_dsp_encap_confi
g: logical ssrc 20
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_open_voice_and_
set_params: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_dsp_encap_confi
g: logical ssrc 20
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/set_playout_dmgr: p
layout default

Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_dsp_echo_cancel
ler_control: echo_cancel: 1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_setup_stream_af
ter_switch_codec_succ: codec change success
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_add_fork: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: add_fork=0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_get_xmit_info_n
ode: .
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o:  xmit func is 61123BB0, context is 66340618 peer_call_id: 224358, stream_count: 1, upd
ate_flag 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The stream bit-mask is 1
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The stream type is 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_fork_inf
o: The logical ssrc is 32 for stream 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/DSM:(6/0:D):-1:12515:0/dsm_update_stream_c
ount:
Feb 12 01:25:44.673 UTC:  g711_voice_count=0 g711_avt_count = 0
 g711_voice_avt_count = 0 complex_voice_count = 1
 complex_avt_count = 0 complex_voice_avt_count = 0
Feb 12 01:25:44.673 UTC: //224359/1E051EAF8CEE/VTSP:(6/0:D):9:12515:0/vtsp_dsm_feature_no
tify_cb: id=0 status=1