Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
566
Views
0
Helpful
5
Replies

2901 Gateway Calls don´t enter

Claudio Quiroz Cabello
Level 2
Level 2

‎11-08-2013 05:17 AM - edited ‎03-16-2019 08:18 PM

Hello Friends,

I have a CUCM 7.1.5 and Remote Gateway, but Calls don´t enter:

Internal Calls to 4 digits OK

GW < CUCM  OK

GW > CUCM OK

GW > PSTN OK

GW < PSTN NOK

I attached some files.

Labels:
15370602-deb_tem.txt.zip
15370601-gw_tem.txt.zip
81 KB
0 Helpful
5 Replies 5

Chris Deren
Hall of Fame
Hall of Fame

‎11-08-2013 07:11 AM

Are you using MGCP or H323 for these?

If H323 then you need "plar" config on your voice-ports to direct the call to desired extension, i.e:

voice-port 0/0/0

connection plar opx

If MGCP ensure you have the "Attendant DN" set on the voice port in CUCM with proper destiantion and CSS.

HTH,

Chris

0 Helpful

Ayodeji Okanlawon
VIP Alumni
VIP Alumni

‎11-08-2013 07:41 AM

Claudio,

Can you send the ff logs

debug mgcp packet

debug vpm signal

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts
0 Helpful

Claudio Quiroz Cabello
Level 2
Level 2
In response to Ayodeji Okanlawon

‎11-11-2013 07:34 AM

Hello Friend,

I sent you the information....Can you give me any idea please?

0 Helpful

Claudio Quiroz Cabello
Level 2
Level 2

‎11-08-2013 11:01 AM

Chris and aokanlawon:

Attendant DN is set with 7390 OK.

CSS Gateway and CSS 7390 internal number are OK.

_____________________________________________________________

DEBUG VPM and MGCP PACKET

CS120464_AFPM_TEMU#debug vpm signal

Voice Port Module signaling debugging is enabled

CS120464_AFPM_TEMU#

*Nov  8 18:37:37.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:37:37.914: htsp_timer - 125 msec

*Nov  8 18:37:38.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:37:38.042: htsp_timer - 10000 msec

*Nov  8 18:37:38.042: htsp_timer3 - 5600 msec

*Nov  8 18:37:38.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:37:38.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:37:38.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:37:39.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:37:39.246: fxols_ringing_not

*Nov  8 18:37:39.246: htsp_timer_stop

*Nov  8 18:37:39.246: htsp_timer - 10000 msec

*Nov  8 18:37:42.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:37:43.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:37:43.642: htsp_timer_stop3

*Nov  8 18:37:44.242: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:37:44.242: fxols_ringing_not

*Nov  8 18:37:44.242: htsp_timer_stop

*Nov  8 18:37:44.242: htsp_timer_stop3

*Nov  8 18:37:44.242: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:37:44.242: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:37:44.242: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:38  orig called=

*Nov  8 18:37:44.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:37:44.246: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:37:44.246: fxols_wait_setup_ack:

*Nov  8 18:37:44.246: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:37:44.246: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:37:44.246: htsp_timer_stop

*Nov  8 18:37:44.262: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:37:44.262: htsp_call_bridged invoked

*Nov  8 18:37:44.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:37:44.498: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:37:44.498: htsp_timer2 - 350 msec

*Nov  8 18:37:44.850: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:37:44.850: htsp_timer_stop

*Nov  8 18:37:44.850: htsp_timer_stop2

*Nov  8 18:37:44.850: htsp_timer_stop3

*Nov  8 18:37:44.874: htsp_timer_stop3

*Nov  8 18:37:44.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:37:44.894: htsp_timer_stop

*Nov  8 18:37:44.894: htsp_timer_stop2

*Nov  8 18:37:44.894: htsp_timer_stop3

*Nov  8 18:37:44.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:37:44.894: htsp_timer - 2000 msec

*Nov  8 18:37:46.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:37:46.898: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:37:47.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:37:47.914: htsp_timer - 125 msec

*Nov  8 18:37:48.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:37:48.042: htsp_timer - 10000 msec

*Nov  8 18:37:48.042: htsp_timer3 - 5600 msec

*Nov  8 18:37:48.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:37:48.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:37:48.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:37:49.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:37:49.246: fxols_ringing_not

*Nov  8 18:37:49.246: htsp_timer_stop

*Nov  8 18:37:49.246: htsp_timer - 10000 msec

*Nov  8 18:37:52.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:37:53.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:37:53.642: htsp_timer_stop3

*Nov  8 18:37:54.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:37:54.246: fxols_ringing_not

*Nov  8 18:37:54.246: htsp_timer_stop

*Nov  8 18:37:54.246: htsp_timer_stop3

*Nov  8 18:37:54.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:37:54.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:37:54.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:38  orig called=

*Nov  8 18:37:54.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:37:54.246: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:37:54.246: fxols_wait_setup_ack:

*Nov  8 18:37:54.246: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:37:54.246: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:37:54.246: htsp_timer_stop

*Nov  8 18:37:54.262: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:37:54.262: htsp_call_bridged invoked

*Nov  8 18:37:54.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:37:54.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:37:54.502: htsp_timer2 - 350 msec

*Nov  8 18:37:54.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:37:54.854: htsp_timer_stop

*Nov  8 18:37:54.854: htsp_timer_stop2

*Nov  8 18:37:54.854: htsp_timer_stop3

*Nov  8 18:37:54.878: htsp_timer_stop3

*Nov  8 18:37:54.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:37:54.894: htsp_timer_stop

*Nov  8 18:37:54.894: htsp_timer_stop2

*Nov  8 18:37:54.894: htsp_timer_stop3

*Nov  8 18:37:54.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:37:54.894: htsp_timer - 2000 msec

*Nov  8 18:37:56.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:37:56.894: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:37:57.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:37:57.914: htsp_timer - 125 msec

*Nov  8 18:37:58.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:37:58.042: htsp_timer - 10000 msec

*Nov  8 18:37:58.042: htsp_timer3 - 5600 msec

*Nov  8 18:37:58.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:37:58.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:37:58.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:37:59.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:37:59.246: fxols_ringing_not

*Nov  8 18:37:59.246: htsp_timer_stop

*Nov  8 18:37:59.246: htsp_timer - 10000 msec

*Nov  8 18:38:02.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:03.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:03.642: htsp_timer_stop3

*Nov  8 18:38:04.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:04.246: fxols_ringing_not

*Nov  8 18:38:04.246: htsp_timer_stop

*Nov  8 18:38:04.246: htsp_timer_stop3

*Nov  8 18:38:04.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:04.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:04.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:04.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:04.246: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:04.246: fxols_wait_setup_ack:

*Nov  8 18:38:04.246: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:04.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:04.250: htsp_timer_stop

*Nov  8 18:38:04.262: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:04.266: htsp_call_bridged invoked

*Nov  8 18:38:04.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:04.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:04.502: htsp_timer2 - 350 msec

*Nov  8 18:38:04.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:04.854: htsp_timer_stop

*Nov  8 18:38:04.854: htsp_timer_stop2

*Nov  8 18:38:04.854: htsp_timer_stop3

*Nov  8 18:38:04.878: htsp_timer_stop3

*Nov  8 18:38:04.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:04.894: htsp_timer_stop

*Nov  8 18:38:04.894: htsp_timer_stop2

*Nov  8 18:38:04.894: htsp_timer_stop3

*Nov  8 18:38:04.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:04.894: htsp_timer - 2000 msec

*Nov  8 18:38:06.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:06.894: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:07.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:07.914: htsp_timer - 125 msec

*Nov  8 18:38:08.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:08.042: htsp_timer - 10000 msec

*Nov  8 18:38:08.042: htsp_timer3 - 5600 msec

*Nov  8 18:38:08.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:08.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:08.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:09.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:09.246: fxols_ringing_not

*Nov  8 18:38:09.246: htsp_timer_stop

*Nov  8 18:38:09.246: htsp_timer - 10000 msec

*Nov  8 18:38:12.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:13.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:13.642: htsp_timer_stop3

*Nov  8 18:38:14.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:14.246: fxols_ringing_not

*Nov  8 18:38:14.246: htsp_timer_stop

*Nov  8 18:38:14.246: htsp_timer_stop3

*Nov  8 18:38:14.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:14.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:14.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:14.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:14.250: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:14.250: fxols_wait_setup_ack:

*Nov  8 18:38:14.250: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:14.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:14.250: htsp_timer_stop

*Nov  8 18:38:14.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:14.266: htsp_call_bridged invoked

*Nov  8 18:38:14.270: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:14.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:14.502: htsp_timer2 - 350 msec

*Nov  8 18:38:14.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:14.854: htsp_timer_stop

*Nov  8 18:38:14.854: htsp_timer_stop2

*Nov  8 18:38:14.854: htsp_timer_stop3

*Nov  8 18:38:14.878: htsp_timer_stop3

*Nov  8 18:38:14.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:14.894: htsp_timer_stop

*Nov  8 18:38:14.894: htsp_timer_stop2

*Nov  8 18:38:14.894: htsp_timer_stop3

*Nov  8 18:38:14.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:14.894: htsp_timer - 2000 msec

*Nov  8 18:38:16.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:16.894: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:17.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:17.914: htsp_timer - 125 msec

*Nov  8 18:38:18.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:18.042: htsp_timer - 10000 msec

*Nov  8 18:38:18.042: htsp_timer3 - 5600 msec

*Nov  8 18:38:18.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:18.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:18.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:19.242: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:19.242: fxols_ringing_not

*Nov  8 18:38:19.242: htsp_timer_stop

*Nov  8 18:38:19.242: htsp_timer - 10000 msec

*Nov  8 18:38:22.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:23.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:23.642: htsp_timer_stop3

*Nov  8 18:38:24.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:24.246: fxols_ringing_not

*Nov  8 18:38:24.246: htsp_timer_stop

*Nov  8 18:38:24.246: htsp_timer_stop3

*Nov  8 18:38:24.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:24.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:24.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:24.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:24.250: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:24.250: fxols_wait_setup_ack:

*Nov  8 18:38:24.250: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:24.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:24.250: htsp_timer_stop

*Nov  8 18:38:24.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:24.266: htsp_call_bridged invoked

*Nov  8 18:38:24.270: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:24.506: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:24.506: htsp_timer2 - 350 msec

*Nov  8 18:38:24.858: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:24.858: htsp_timer_stop

*Nov  8 18:38:24.858: htsp_timer_stop2

*Nov  8 18:38:24.858: htsp_timer_stop3

*Nov  8 18:38:24.882: htsp_timer_stop3

*Nov  8 18:38:24.902: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:24.902: htsp_timer_stop

*Nov  8 18:38:24.902: htsp_timer_stop2

*Nov  8 18:38:24.902: htsp_timer_stop3

*Nov  8 18:38:24.902: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:24.902: htsp_timer - 2000 msec

*Nov  8 18:38:26.902: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:26.902: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:27.910: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:27.910: htsp_timer - 125 msec

*Nov  8 18:38:28.038: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:28.038: htsp_timer - 10000 msec

*Nov  8 18:38:28.038: htsp_timer3 - 5600 msec

*Nov  8 18:38:28.038: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:28.038: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:28.038: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:29.242: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:29.242: fxols_ringing_not

*Nov  8 18:38:29.242: htsp_timer_stop

*Nov  8 18:38:29.242: htsp_timer - 10000 msec

*Nov  8 18:38:32.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:33.638: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:33.638: htsp_timer_stop3

*Nov  8 18:38:34.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:34.246: fxols_ringing_not

*Nov  8 18:38:34.246: htsp_timer_stop

*Nov  8 18:38:34.246: htsp_timer_stop3

*Nov  8 18:38:34.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:34.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:34.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:34.250: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:34.250: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:34.250: fxols_wait_setup_ack:

*Nov  8 18:38:34.250: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:34.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:34.250: htsp_timer_stop

*Nov  8 18:38:34.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:34.266: htsp_call_bridged invoked

*Nov  8 18:38:34.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:34.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:34.502: htsp_timer2 - 350 msec

*Nov  8 18:38:34.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:34.854: htsp_timer_stop

*Nov  8 18:38:34.854: htsp_timer_stop2

*Nov  8 18:38:34.854: htsp_timer_stop3

*Nov  8 18:38:34.878: htsp_timer_stop3

*Nov  8 18:38:34.898: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:34.898: htsp_timer_stop

*Nov  8 18:38:34.898: htsp_timer_stop2

*Nov  8 18:38:34.898: htsp_timer_stop3

*Nov  8 18:38:34.898: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:34.898: htsp_timer - 2000 msec

*Nov  8 18:38:36.898: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:36.902: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:37.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:37.914: htsp_timer - 125 msec

*Nov  8 18:38:38.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:38.042: htsp_timer - 10000 msec

*Nov  8 18:38:38.042: htsp_timer3 - 5600 msec

*Nov  8 18:38:38.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:38.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:38.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:39.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:39.246: fxols_ringing_not

*Nov  8 18:38:39.246: htsp_timer_stop

*Nov  8 18:38:39.246: htsp_timer - 10000 msec

*Nov  8 18:38:42.962: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:43.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:43.642: htsp_timer_stop3

*Nov  8 18:38:44.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:44.246: fxols_ringing_not

*Nov  8 18:38:44.246: htsp_timer_stop

*Nov  8 18:38:44.246: htsp_timer_stop3

*Nov  8 18:38:44.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:44.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:44.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:44.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:44.246: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:44.246: fxols_wait_setup_ack:

*Nov  8 18:38:44.246: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:44.246: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:44.246: htsp_timer_stop

*Nov  8 18:38:44.262: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:44.262: htsp_call_bridged invoked

*Nov  8 18:38:44.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:44.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:44.502: htsp_timer2 - 350 msec

*Nov  8 18:38:44.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:44.854: htsp_timer_stop

*Nov  8 18:38:44.854: htsp_timer_stop2

*Nov  8 18:38:44.854: htsp_timer_stop3

*Nov  8 18:38:44.878: htsp_timer_stop3

*Nov  8 18:38:44.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:44.894: htsp_timer_stop

*Nov  8 18:38:44.894: htsp_timer_stop2

*Nov  8 18:38:44.894: htsp_timer_stop3

*Nov  8 18:38:44.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:44.894: htsp_timer - 2000 msec

*Nov  8 18:38:46.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:46.894: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:47.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:47.914: htsp_timer - 125 msec

*Nov  8 18:38:48.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:48.042: htsp_timer - 10000 msec

*Nov  8 18:38:48.042: htsp_timer3 - 5600 msec

*Nov  8 18:38:48.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:48.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:48.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:49.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:49.246: fxols_ringing_not

*Nov  8 18:38:49.246: htsp_timer_stop

*Nov  8 18:38:49.246: htsp_timer - 10000 msec

*Nov  8 18:38:52.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:38:53.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:38:53.642: htsp_timer_stop3

*Nov  8 18:38:54.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:54.246: fxols_ringing_not

*Nov  8 18:38:54.246: htsp_timer_stop

*Nov  8 18:38:54.246: htsp_timer_stop3

*Nov  8 18:38:54.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:38:54.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:38:54.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:39  orig called=

*Nov  8 18:38:54.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:38:54.250: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:38:54.250: fxols_wait_setup_ack:

*Nov  8 18:38:54.250: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:38:54.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:38:54.250: htsp_timer_stop

*Nov  8 18:38:54.262: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:54.266: htsp_call_bridged invoked

*Nov  8 18:38:54.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:38:54.506: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:38:54.506: htsp_timer2 - 350 msec

*Nov  8 18:38:54.858: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:38:54.858: htsp_timer_stop

*Nov  8 18:38:54.858: htsp_timer_stop2

*Nov  8 18:38:54.858: htsp_timer_stop3

*Nov  8 18:38:54.886: htsp_timer_stop3

*Nov  8 18:38:54.906: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:38:54.906: htsp_timer_stop

*Nov  8 18:38:54.906: htsp_timer_stop2

*Nov  8 18:38:54.906: htsp_timer_stop3

*Nov  8 18:38:54.906: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:38:54.906: htsp_timer - 2000 msec

*Nov  8 18:38:56.906: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:38:56.906: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:38:57.914: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:38:57.914: htsp_timer - 125 msec

*Nov  8 18:38:58.042: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:38:58.042: htsp_timer - 10000 msec

*Nov  8 18:38:58.042: htsp_timer3 - 5600 msec

*Nov  8 18:38:58.042: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:38:58.042: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:38:58.042: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:38:59.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:38:59.246: fxols_ringing_not

*Nov  8 18:38:59.246: htsp_timer_stop

*Nov  8 18:38:59.246: htsp_timer - 10000 msec

*Nov  8 18:39:02.914: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:39:03.642: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:39:03.642: htsp_timer_stop3

*Nov  8 18:39:04.246: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:39:04.246: fxols_ringing_not

*Nov  8 18:39:04.246: htsp_timer_stop

*Nov  8 18:39:04.246: htsp_timer_stop3

*Nov  8 18:39:04.246: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:39:04.246: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:39:04.246: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:40  orig called=

*Nov  8 18:39:04.246: [0/0/1] htsp_dsm_close_done

*Nov  8 18:39:04.250: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:39:04.250: fxols_wait_setup_ack:

*Nov  8 18:39:04.250: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:39:04.250: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:39:04.250: htsp_timer_stop

*Nov  8 18:39:04.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:39:04.266: htsp_call_bridged invoked

*Nov  8 18:39:04.266: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:39:04.502: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:39:04.502: htsp_timer2 - 350 msec

*Nov  8 18:39:04.854: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:39:04.854: htsp_timer_stop

*Nov  8 18:39:04.854: htsp_timer_stop2

*Nov  8 18:39:04.854: htsp_timer_stop3

*Nov  8 18:39:04.878: htsp_timer_stop3

*Nov  8 18:39:04.894: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:39:04.894: htsp_timer_stop

*Nov  8 18:39:04.894: htsp_timer_stop2

*Nov  8 18:39:04.894: htsp_timer_stop3

*Nov  8 18:39:04.894: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:39:04.894: htsp_timer - 2000 msec

*Nov  8 18:39:06.894: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:39:06.894: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

______________________________________________________________________________________

CS120464_AFPM_TEMU#debug mgcp packet

Media Gateway Control Protocol packets debugging for all endpoints is on

CS120464_AFPM_TEMU#

*Nov  8 18:41:31.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:41:31.114: htsp_timer - 125 msec

*Nov  8 18:41:31.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:41:31.242: htsp_timer - 10000 msec

*Nov  8 18:41:31.242: htsp_timer3 - 5600 msec

*Nov  8 18:41:31.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:41:31.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:41:31.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:41:31.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188539 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:41:31.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188539

<---

*Nov  8 18:41:32.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:32.446: fxols_ringing_not

*Nov  8 18:41:32.446: htsp_timer_stop

*Nov  8 18:41:32.446: htsp_timer - 10000 msec

*Nov  8 18:41:36.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:41:36.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:41:36.842: htsp_timer_stop3

*Nov  8 18:41:37.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:37.446: fxols_ringing_not

*Nov  8 18:41:37.446: htsp_timer_stop

*Nov  8 18:41:37.446: htsp_timer_stop3

*Nov  8 18:41:37.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:41:37.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:41:37.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:42  orig called=

*Nov  8 18:41:37.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:41:37.450: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188540 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:41:37.450: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:41:37.450: fxols_wait_setup_ack:

*Nov  8 18:41:37.450: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:41:37.450: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:41:37.450: htsp_timer_stop

*Nov  8 18:41:37.462: MGCP Packet received from 10.153.50.10:2427--->

200 73188540

<---

*Nov  8 18:41:37.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16252 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a62e000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:41:37.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:37.466: htsp_call_bridged invoked

*Nov  8 18:41:37.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:37.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16252 OK

I: 609

v=0

o=- 1545 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 31088 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:41:37.482: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16253 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:41:37.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16253 OK

<---

*Nov  8 18:41:37.702: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:41:37.702: htsp_timer2 - 350 msec

*Nov  8 18:41:38.054: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:41:38.054: htsp_timer_stop

*Nov  8 18:41:38.054: htsp_timer_stop2

*Nov  8 18:41:38.054: htsp_timer_stop3

*Nov  8 18:41:38.054: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188541 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:41:38.066: MGCP Packet received from 10.153.50.10:2427--->

200 73188541

<---

*Nov  8 18:41:38.066: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16254 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:41:38.066: MGCP Packet sent to 10.153.50.10:2427--->

200 16254 OK

<---

*Nov  8 18:41:38.078: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16255 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a62e000000F5

I: 609

X: 0

<---

*Nov  8 18:41:38.078: htsp_timer_stop3

*Nov  8 18:41:38.094: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:41:38.094: htsp_timer_stop

*Nov  8 18:41:38.094: htsp_timer_stop2

*Nov  8 18:41:38.094: htsp_timer_stop3

*Nov  8 18:41:38.094: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:41:38.094: htsp_timer - 2000 msec

*Nov  8 18:41:38.098: MGCP Packet sent to 10.153.50.10:2427--->

250 16255 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:41:40.094: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:41:40.094: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:41:41.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:41:41.114: htsp_timer - 125 msec

*Nov  8 18:41:41.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:41:41.242: htsp_timer - 10000 msec

*Nov  8 18:41:41.242: htsp_timer3 - 5600 msec

*Nov  8 18:41:41.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:41:41.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:41:41.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:41:42.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:42.446: fxols_ringing_not

*Nov  8 18:41:42.446: htsp_timer_stop

*Nov  8 18:41:42.446: htsp_timer - 10000 msec

*Nov  8 18:41:46.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:41:46.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188542 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:41:46.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188542

<---

*Nov  8 18:41:46.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:41:46.842: htsp_timer_stop3

*Nov  8 18:41:47.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:47.446: fxols_ringing_not

*Nov  8 18:41:47.446: htsp_timer_stop

*Nov  8 18:41:47.446: htsp_timer_stop3

*Nov  8 18:41:47.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:41:47.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:41:47.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:42  orig called=

*Nov  8 18:41:47.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:41:47.450: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188543 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:41:47.450: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:41:47.450: fxols_wait_setup_ack:

*Nov  8 18:41:47.450: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:41:47.450: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:41:47.450: htsp_timer_stop

*Nov  8 18:41:47.462: MGCP Packet received from 10.153.50.10:2427--->

200 73188543

<---

*Nov  8 18:41:47.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16256 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a630000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:41:47.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:47.466: htsp_call_bridged invoked

*Nov  8 18:41:47.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:47.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16256 OK

I: 60A

v=0

o=- 1546 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 27548 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:41:47.482: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16257 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:41:47.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16257 OK

<---

*Nov  8 18:41:47.706: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:41:47.706: htsp_timer2 - 350 msec

*Nov  8 18:41:48.058: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:41:48.058: htsp_timer_stop

*Nov  8 18:41:48.058: htsp_timer_stop2

*Nov  8 18:41:48.058: htsp_timer_stop3

*Nov  8 18:41:48.058: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188544 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:41:48.070: MGCP Packet received from 10.153.50.10:2427--->

200 73188544

<---

*Nov  8 18:41:48.070: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16258 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:41:48.070: MGCP Packet sent to 10.153.50.10:2427--->

200 16258 OK

<---

*Nov  8 18:41:48.082: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16259 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a630000000F5

I: 60A

X: 0

<---

*Nov  8 18:41:48.082: htsp_timer_stop3

*Nov  8 18:41:48.102: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:41:48.102: htsp_timer_stop

*Nov  8 18:41:48.102: htsp_timer_stop2

*Nov  8 18:41:48.102: htsp_timer_stop3

*Nov  8 18:41:48.102: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:41:48.102: htsp_timer - 2000 msec

*Nov  8 18:41:48.102: MGCP Packet sent to 10.153.50.10:2427--->

250 16259 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:41:50.102: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:41:50.102: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:41:51.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:41:51.114: htsp_timer - 125 msec

*Nov  8 18:41:51.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:41:51.242: htsp_timer - 10000 msec

*Nov  8 18:41:51.242: htsp_timer3 - 5600 msec

*Nov  8 18:41:51.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:41:51.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:41:51.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:41:52.442: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:52.442: fxols_ringing_not

*Nov  8 18:41:52.442: htsp_timer_stop

*Nov  8 18:41:52.442: htsp_timer - 10000 msec

*Nov  8 18:41:56.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:41:56.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:41:56.842: htsp_timer_stop3

*Nov  8 18:41:57.442: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:41:57.442: fxols_ringing_not

*Nov  8 18:41:57.442: htsp_timer_stop

*Nov  8 18:41:57.442: htsp_timer_stop3

*Nov  8 18:41:57.442: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:41:57.442: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:41:57.442: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:42  orig called=

*Nov  8 18:41:57.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:41:57.446: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188545 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:41:57.446: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:41:57.446: fxols_wait_setup_ack:

*Nov  8 18:41:57.446: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:41:57.446: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:41:57.446: htsp_timer_stop

*Nov  8 18:41:57.458: MGCP Packet received from 10.153.50.10:2427--->

200 73188545

<---

*Nov  8 18:41:57.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16260 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a632000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:41:57.462: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:57.462: htsp_call_bridged invoked

*Nov  8 18:41:57.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:41:57.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16260 OK

I: 60B

v=0

o=- 1547 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 19268 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:41:57.478: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16261 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:41:57.478: MGCP Packet sent to 10.153.50.10:2427--->

200 16261 OK

<---

*Nov  8 18:41:57.698: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:41:57.698: htsp_timer2 - 350 msec

*Nov  8 18:41:58.050: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:41:58.050: htsp_timer_stop

*Nov  8 18:41:58.050: htsp_timer_stop2

*Nov  8 18:41:58.050: htsp_timer_stop3

*Nov  8 18:41:58.050: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188546 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:41:58.062: MGCP Packet received from 10.153.50.10:2427--->

200 73188546

<---

*Nov  8 18:41:58.062: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16262 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:41:58.062: MGCP Packet sent to 10.153.50.10:2427--->

200 16262 OK

<---

*Nov  8 18:41:58.074: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16263 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a632000000F5

I: 60B

X: 0

<---

*Nov  8 18:41:58.078: htsp_timer_stop3

*Nov  8 18:41:58.094: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:41:58.094: htsp_timer_stop

*Nov  8 18:41:58.094: htsp_timer_stop2

*Nov  8 18:41:58.094: htsp_timer_stop3

*Nov  8 18:41:58.094: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:41:58.094: htsp_timer - 2000 msec

*Nov  8 18:41:58.094: MGCP Packet sent to 10.153.50.10:2427--->

250 16263 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:00.094: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:00.098: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:01.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:01.114: htsp_timer - 125 msec

*Nov  8 18:42:01.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:01.242: htsp_timer - 10000 msec

*Nov  8 18:42:01.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:01.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:01.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:01.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:02.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:02.446: fxols_ringing_not

*Nov  8 18:42:02.446: htsp_timer_stop

*Nov  8 18:42:02.446: htsp_timer - 10000 msec

*Nov  8 18:42:06.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:42:06.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:06.842: htsp_timer_stop3

*Nov  8 18:42:07.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:07.446: fxols_ringing_not

*Nov  8 18:42:07.446: htsp_timer_stop

*Nov  8 18:42:07.446: htsp_timer_stop3

*Nov  8 18:42:07.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:42:07.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:42:07.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:43  orig called=

*Nov  8 18:42:07.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:42:07.446: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188547 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:42:07.446: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:42:07.446: fxols_wait_setup_ack:

*Nov  8 18:42:07.446: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:42:07.446: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:42:07.446: htsp_timer_stop

*Nov  8 18:42:07.458: MGCP Packet received from 10.153.50.10:2427--->

200 73188547

<---

*Nov  8 18:42:07.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16264 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a634000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:42:07.462: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:07.462: htsp_call_bridged invoked

*Nov  8 18:42:07.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:07.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16264 OK

I: 60C

v=0

o=- 1548 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 31592 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:42:07.478: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16265 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:42:07.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16265 OK

<---

*Nov  8 18:42:07.702: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:42:07.702: htsp_timer2 - 350 msec

*Nov  8 18:42:08.054: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:42:08.054: htsp_timer_stop

*Nov  8 18:42:08.054: htsp_timer_stop2

*Nov  8 18:42:08.054: htsp_timer_stop3

*Nov  8 18:42:08.054: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188548 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:42:08.066: MGCP Packet received from 10.153.50.10:2427--->

200 73188548

<---

*Nov  8 18:42:08.066: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16266 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:42:08.066: MGCP Packet sent to 10.153.50.10:2427--->

200 16266 OK

<---

*Nov  8 18:42:08.078: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16267 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a634000000F5

I: 60C

X: 0

<---

*Nov  8 18:42:08.082: htsp_timer_stop3

*Nov  8 18:42:08.094: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:42:08.094: htsp_timer_stop

*Nov  8 18:42:08.094: htsp_timer_stop2

*Nov  8 18:42:08.094: htsp_timer_stop3

*Nov  8 18:42:08.094: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:42:08.094: htsp_timer - 2000 msec

*Nov  8 18:42:08.094: MGCP Packet sent to 10.153.50.10:2427--->

250 16267 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:10.094: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:10.094: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:11.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:11.114: htsp_timer - 125 msec

*Nov  8 18:42:11.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:11.242: htsp_timer - 10000 msec

*Nov  8 18:42:11.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:11.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:11.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:11.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:12.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:12.446: fxols_ringing_not

*Nov  8 18:42:12.446: htsp_timer_stop

*Nov  8 18:42:12.446: htsp_timer - 10000 msec

*Nov  8 18:42:16.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:42:16.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188549 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:42:16.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188549

<---

*Nov  8 18:42:16.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:16.842: htsp_timer_stop3

*Nov  8 18:42:17.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:17.446: fxols_ringing_not

*Nov  8 18:42:17.446: htsp_timer_stop

*Nov  8 18:42:17.446: htsp_timer_stop3

*Nov  8 18:42:17.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:42:17.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:42:17.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:43  orig called=

*Nov  8 18:42:17.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:42:17.446: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188550 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:42:17.450: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:42:17.450: fxols_wait_setup_ack:

*Nov  8 18:42:17.450: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:42:17.450: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:42:17.450: htsp_timer_stop

*Nov  8 18:42:17.462: MGCP Packet received from 10.153.50.10:2427--->

200 73188550

<---

*Nov  8 18:42:17.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16268 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a636000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:42:17.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:17.466: htsp_call_bridged invoked

*Nov  8 18:42:17.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:17.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16268 OK

I: 60D

v=0

o=- 1549 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 17808 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:42:17.478: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16269 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:42:17.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16269 OK

<---

*Nov  8 18:42:17.706: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:42:17.706: htsp_timer2 - 350 msec

*Nov  8 18:42:18.058: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:42:18.058: htsp_timer_stop

*Nov  8 18:42:18.058: htsp_timer_stop2

*Nov  8 18:42:18.058: htsp_timer_stop3

*Nov  8 18:42:18.058: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188551 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:42:18.070: MGCP Packet received from 10.153.50.10:2427--->

200 73188551

<---

*Nov  8 18:42:18.070: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16270 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:42:18.070: MGCP Packet sent to 10.153.50.10:2427--->

200 16270 OK

<---

*Nov  8 18:42:18.082: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16271 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a636000000F5

I: 60D

X: 0

<---

*Nov  8 18:42:18.086: htsp_timer_stop3

*Nov  8 18:42:18.106: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:42:18.106: htsp_timer_stop

*Nov  8 18:42:18.106: htsp_timer_stop2

*Nov  8 18:42:18.106: htsp_timer_stop3

*Nov  8 18:42:18.106: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:42:18.106: htsp_timer - 2000 msec

*Nov  8 18:42:18.106: MGCP Packet sent to 10.153.50.10:2427--->

250 16271 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:20.106: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:20.106: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:21.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:21.114: htsp_timer - 125 msec

*Nov  8 18:42:21.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:21.242: htsp_timer - 10000 msec

*Nov  8 18:42:21.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:21.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:21.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:21.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:22.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:22.446: fxols_ringing_not

*Nov  8 18:42:22.446: htsp_timer_stop

*Nov  8 18:42:22.446: htsp_timer - 10000 msec

*Nov  8 18:42:26.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:42:26.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:26.842: htsp_timer_stop3

*Nov  8 18:42:27.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:27.446: fxols_ringing_not

*Nov  8 18:42:27.446: htsp_timer_stop

*Nov  8 18:42:27.446: htsp_timer_stop3

*Nov  8 18:42:27.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:42:27.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:42:27.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:43  orig called=

*Nov  8 18:42:27.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:42:27.450: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188552 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:42:27.450: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:42:27.450: fxols_wait_setup_ack:

*Nov  8 18:42:27.450: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:42:27.450: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:42:27.450: htsp_timer_stop

*Nov  8 18:42:27.462: MGCP Packet received from 10.153.50.10:2427--->

200 73188552

<---

*Nov  8 18:42:27.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16272 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a638000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:42:27.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:27.466: htsp_call_bridged invoked

*Nov  8 18:42:27.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:27.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16272 OK

I: 60E

v=0

o=- 1550 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 24260 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:42:27.482: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16273 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:42:27.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16273 OK

<---

*Nov  8 18:42:27.706: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:42:27.706: htsp_timer2 - 350 msec

*Nov  8 18:42:28.058: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:42:28.058: htsp_timer_stop

*Nov  8 18:42:28.058: htsp_timer_stop2

*Nov  8 18:42:28.058: htsp_timer_stop3

*Nov  8 18:42:28.058: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188553 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:42:28.070: MGCP Packet received from 10.153.50.10:2427--->

200 73188553

<---

*Nov  8 18:42:28.070: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16274 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:42:28.070: MGCP Packet sent to 10.153.50.10:2427--->

200 16274 OK

<---

*Nov  8 18:42:28.082: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16275 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a638000000F5

I: 60E

X: 0

<---

*Nov  8 18:42:28.086: htsp_timer_stop3

*Nov  8 18:42:28.102: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:42:28.102: htsp_timer_stop

*Nov  8 18:42:28.102: htsp_timer_stop2

*Nov  8 18:42:28.102: htsp_timer_stop3

*Nov  8 18:42:28.102: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:42:28.102: htsp_timer - 2000 msec

*Nov  8 18:42:28.102: MGCP Packet sent to 10.153.50.10:2427--->

250 16275 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:30.102: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:30.102: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:31.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:31.114: htsp_timer - 125 msec

*Nov  8 18:42:31.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:31.242: htsp_timer - 10000 msec

*Nov  8 18:42:31.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:31.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:31.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:31.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:32.442: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:32.442: fxols_ringing_not

*Nov  8 18:42:32.442: htsp_timer_stop

*Nov  8 18:42:32.442: htsp_timer - 10000 msec

*Nov  8 18:42:36.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:42:36.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:36.842: htsp_timer_stop3

*Nov  8 18:42:37.442: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:37.442: fxols_ringing_not

*Nov  8 18:42:37.442: htsp_timer_stop

*Nov  8 18:42:37.442: htsp_timer_stop3

*Nov  8 18:42:37.442: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:42:37.442: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:42:37.442: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:43  orig called=

*Nov  8 18:42:37.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:42:37.446: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188554 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:42:37.446: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:42:37.446: fxols_wait_setup_ack:

*Nov  8 18:42:37.446: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:42:37.446: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:42:37.446: htsp_timer_stop

*Nov  8 18:42:37.458: MGCP Packet received from 10.153.50.10:2427--->

200 73188554

<---

*Nov  8 18:42:37.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16276 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a63a000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:42:37.462: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:37.462: htsp_call_bridged invoked

*Nov  8 18:42:37.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:37.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16276 OK

I: 60F

v=0

o=- 1551 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 21846 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:42:37.478: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16277 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:42:37.478: MGCP Packet sent to 10.153.50.10:2427--->

200 16277 OK

<---

*Nov  8 18:42:37.698: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:42:37.698: htsp_timer2 - 350 msec

*Nov  8 18:42:38.050: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:42:38.050: htsp_timer_stop

*Nov  8 18:42:38.050: htsp_timer_stop2

*Nov  8 18:42:38.050: htsp_timer_stop3

*Nov  8 18:42:38.050: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188555 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:42:38.062: MGCP Packet received from 10.153.50.10:2427--->

200 73188555

<---

*Nov  8 18:42:38.062: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16278 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:42:38.062: MGCP Packet sent to 10.153.50.10:2427--->

200 16278 OK

<---

*Nov  8 18:42:38.074: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16279 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a63a000000F5

I: 60F

X: 0

<---

*Nov  8 18:42:38.074: htsp_timer_stop3

*Nov  8 18:42:38.094: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:42:38.094: htsp_timer_stop

*Nov  8 18:42:38.094: htsp_timer_stop2

*Nov  8 18:42:38.094: htsp_timer_stop3

*Nov  8 18:42:38.094: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:42:38.094: htsp_timer - 2000 msec

*Nov  8 18:42:38.094: MGCP Packet sent to 10.153.50.10:2427--->

250 16279 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:40.094: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:40.098: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:41.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:41.114: htsp_timer - 125 msec

*Nov  8 18:42:41.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:41.242: htsp_timer - 10000 msec

*Nov  8 18:42:41.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:41.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:41.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:41.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:42.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:42.446: fxols_ringing_not

*Nov  8 18:42:42.446: htsp_timer_stop

*Nov  8 18:42:42.446: htsp_timer - 10000 msec

*Nov  8 18:42:46.114: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0000]

*Nov  8 18:42:46.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188556 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:42:46.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188556

<---

*Nov  8 18:42:46.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:46.842: htsp_timer_stop3

*Nov  8 18:42:47.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:47.446: fxols_ringing_not

*Nov  8 18:42:47.446: htsp_timer_stop

*Nov  8 18:42:47.446: htsp_timer_stop3

*Nov  8 18:42:47.446: [0/0/1] htsp_stop_caller_id_rx. message length 0htsp_setup_ind

*Nov  8 18:42:47.446: [0/0/1] get_fxo_caller_id:Caller ID receive failed.  parseCallerIDString:no da

ta.

*Nov  8 18:42:47.446: [0/0/1] get_local_station_id calling num= calling name=Troncal Publica calling

time=11/08 14:43  orig called=

*Nov  8 18:42:47.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:42:47.446: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188557 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hd

<---

*Nov  8 18:42:47.446: htsp_process_event: [0/0/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

*Nov  8 18:42:47.446: fxols_wait_setup_ack:

*Nov  8 18:42:47.446: [0/0/1] set signal state = 0xC timestamp = 0fxols_check_auto_call

*Nov  8 18:42:47.446: htsp_process_event: [0/0/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_con

nect

*Nov  8 18:42:47.446: htsp_timer_stop

*Nov  8 18:42:47.458: MGCP Packet received from 10.153.50.10:2427--->

200 73188557

<---

*Nov  8 18:42:47.462: MGCP Packet received from 10.153.50.10:2427--->

CRCX 16280 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a63c000000F5

X: 0

L: p:20, a:PCMU, s:off, t:00

M: recvonly

R: L/hu

Q: process,loop

<---

*Nov  8 18:42:47.462: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:47.462: htsp_call_bridged invoked

*Nov  8 18:42:47.466: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_conn

ect_proc_voice

*Nov  8 18:42:47.466: MGCP Packet sent to 10.153.50.10:2427--->

200 16280 OK

I: 610

v=0

o=- 1552 0 IN IP4 10.151.73.26

s=Cisco SDP 0

c=IN IP4 10.151.73.26

t=0 0

m=audio 27758 RTP/AVP 0 100

a=rtpmap:100 X-NSE/8000

a=fmtp:100 200-202

a=X-sqn:0

a=X-cap: 1 audio RTP/AVP 100

a=X-cpar: a=rtpmap:100 X-NSE/8000

a=X-cpar: a=fmtp:100 200-202

a=X-cap: 2 image udptl t38

<---

*Nov  8 18:42:47.478: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16281 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hu, D/[0-9ABCD*#]

S: G/rt

Q: process,loop

<---

*Nov  8 18:42:47.482: MGCP Packet sent to 10.153.50.10:2427--->

200 16281 OK

<---

*Nov  8 18:42:47.702: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc

*Nov  8 18:42:47.702: htsp_timer2 - 350 msec

*Nov  8 18:42:48.054: htsp_process_event: [0/0/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_conf

irm

*Nov  8 18:42:48.054: htsp_timer_stop

*Nov  8 18:42:48.054: htsp_timer_stop2

*Nov  8 18:42:48.054: htsp_timer_stop3

*Nov  8 18:42:48.054: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188558 aaln/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

N: ca@10.153.50.10:2427

X: 0

O: L/hu

<---

*Nov  8 18:42:48.066: MGCP Packet received from 10.153.50.10:2427--->

200 73188558

<---

*Nov  8 18:42:48.066: MGCP Packet received from 10.153.50.10:2427--->

RQNT 16282 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

X: 0

R: L/hd

Q: process,loop

<---

*Nov  8 18:42:48.066: MGCP Packet sent to 10.153.50.10:2427--->

200 16282 OK

<---

*Nov  8 18:42:48.078: MGCP Packet received from 10.153.50.10:2427--->

DLCX 16283 AALN/S0/SU0/1@CS120464_AFPM_TEMU MGCP 0.1

C: A000000001e5a63c000000F5

I: 610

X: 0

<---

*Nov  8 18:42:48.078: htsp_timer_stop3

*Nov  8 18:42:48.094: htsp_process_event: [0/0/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_off

hook_release

*Nov  8 18:42:48.094: htsp_timer_stop

*Nov  8 18:42:48.094: htsp_timer_stop2

*Nov  8 18:42:48.094: htsp_timer_stop3

*Nov  8 18:42:48.094: [0/0/1] set signal state = 0x4 timestamp = 0

*Nov  8 18:42:48.094: htsp_timer - 2000 msec

*Nov  8 18:42:48.094: MGCP Packet sent to 10.153.50.10:2427--->

250 16283 OK

P: PS=0, OS=0, PR=0, OR=0, PL=0, JI=0, LA=0

<---

*Nov  8 18:42:50.094: htsp_process_event: [0/0/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_ou

t_timeout

*Nov  8 18:42:50.094: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0100]

*Nov  8 18:42:51.114: htsp_process_event: [0/0/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing

*Nov  8 18:42:51.114: htsp_timer - 125 msec

*Nov  8 18:42:51.242: htsp_process_event: [0/0/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait

_ring_min_timer

*Nov  8 18:42:51.242: htsp_timer - 10000 msec

*Nov  8 18:42:51.242: htsp_timer3 - 5600 msec

*Nov  8 18:42:51.242: [0/0/1] htsp_start_caller_id_rx:BELLCORE

*Nov  8 18:42:51.242: htsp_start_caller_id_rx create dsp_stream_manager

*Nov  8 18:42:51.242: [0/0/1] htsp_dsm_create_success  returns 1

*Nov  8 18:42:52.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_DSP_SIG_0100]

*Nov  8 18:42:52.446: fxols_ringing_not

*Nov  8 18:42:52.446: htsp_timer_stop

*Nov  8 18:42:52.446: htsp_timer - 10000 msec

*Nov  8 18:42:56.842: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_cli

d_stop

*Nov  8 18:42:56.842: htsp_timer_stop3

*Nov  8 18:43:01.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188559 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:43:01.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188559

<---

*Nov  8 18:43:02.446: htsp_process_event: [0/0/1, FXOLS_RINGING, E_HTSP_EVENT_TIMER]fxols_ringing_di

sc

*Nov  8 18:43:02.446: htsp_timer_stop3

*Nov  8 18:43:02.446: htsp_timer_stop

*Nov  8 18:43:02.446: [0/0/1] htsp_stop_caller_id_rx. message length 0

*Nov  8 18:43:02.446: [0/0/1] htsp_dsm_close_done

*Nov  8 18:43:16.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188560 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:43:16.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188560

<---

*Nov  8 18:43:31.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188561 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:43:31.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188561

<---

*Nov  8 18:43:46.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188562 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:43:46.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188562

<---

*Nov  8 18:44:01.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188563 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:44:01.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188563

<---

*Nov  8 18:44:16.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188564 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:44:16.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188564

<---

*Nov  8 18:44:31.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188565 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:44:31.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188565

<---

*Nov  8 18:44:46.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188566 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:44:46.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188566

<---

*Nov  8 18:45:01.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188567 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:45:01.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188567

<---

*Nov  8 18:45:16.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188568 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:45:16.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188568

<---

*Nov  8 18:45:31.738: MGCP Packet sent to 10.153.50.10:2427--->

NTFY 73188569 *@CS120464_AFPM_TEMU MGCP 0.1

X: 0

O:

<---

*Nov  8 18:45:31.750: MGCP Packet received from 10.153.50.10:2427--->

200 73188569

<---


0 Helpful

Ayodeji Okanlawon
VIP Alumni
VIP Alumni
In response to Claudio Quiroz Cabello

‎11-11-2013 08:31 AM

Claudio,

I cant make any sense of the traces. I dont see anything useful in them. Can you collect CUCM traces using RTMT and send them over.

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts
0 Helpful
Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Customers Also Viewed These Support Documents