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.
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
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"
11-11-2013 07:34 AM
Hello Friend,
I sent you the information....Can you give me any idea please?
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
<---
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"
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide