[cisco-voip] fxo inbound call problem

Cathy Cheng cchengcc at gmail.com
Tue Dec 1 20:26:39 EST 2009


 the call arrives the fxo port, ip phone rings, the caller can hear a
continous ring tone, but the ip phone isn't ringing continously. it rings
once, then stops, after 2 seconds, one ring again, there is no answer button
showing up on the phone allows the user to pick up the call,
I swapped the fxo port, still no luck. all the other fxo ports work fine
with the same configuration

the config on the fxo port is

voice-port 0/2/3
 supervisory disconnect dualtone mid-call
 cptone AU
 timeouts call-disconnect 1
 timeouts ringing 30
 timeouts wait-release 1
 connection plar 11485
 impedance complex1

the following is the output of the "debug vpm signal"


Dec 2 10:14:23.041 Melbour: htsp_process_event: [0/2/3, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing

Dec 2 10:14:23.041 Melbour: htsp_timer - 125 msec

Dec 2 10:14:23.169 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:23.169 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:24.141 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:24.141 Melbour: fxols_ringing_not

Dec 2 10:14:24.141 Melbour: htsp_timer_stop

Dec 2 10:14:24.141 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:24.141 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:24.145 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:24.145 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:24.145 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:24.153 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:24.153 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:24.293 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:24.401 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:24.401 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:24.753 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:24.753 Melbour: htsp_timer_stop

Dec 2 10:14:24.753 Melbour: htsp_timer_stop2

Dec 2 10:14:24.753 Melbour: htsp_timer_stop3

Dec 2 10:14:24.761 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:24.761 Melbour: htsp_timer_stop

Dec 2 10:14:24.761 Melbour: htsp_timer_stop2

Dec 2 10:14:24.761 Melbour: htsp_timer_stop3

Dec 2 10:14:24.761 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:24.761 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:26.041 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_DSP_SIG_0000]fxols_guard_out_ringing

Dec 2 10:14:26.041 Melbour: htsp_timer_stop fxols_onhook_ringing

Dec 2 10:14:26.041 Melbour: htsp_timer - 125 msec

Dec 2 10:14:26.169 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:26.169 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:27.145 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:27.145 Melbour: fxols_ringing_not

Dec 2 10:14:27.145 Melbour: htsp_timer_stop

Dec 2 10:14:27.145 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:27.145 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:27.149 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:27.149 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:27.149 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:27.161 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:27.161 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:27.273 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:27.401 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:27.405 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:27.757 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:27.757 Melbour: htsp_timer_stop

Dec 2 10:14:27.757 Melbour: htsp_timer_stop2

Dec 2 10:14:27.757 Melbour: htsp_timer_stop3

Dec 2 10:14:27.769 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:27.769 Melbour: htsp_timer_stop

Dec 2 10:14:27.769 Melbour: htsp_timer_stop2

Dec 2 10:14:27.769 Melbour: htsp_timer_stop3

Dec 2 10:14:27.769 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:27.769 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:29.057 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_DSP_SIG_0000]fxols_guard_out_ringing

Dec 2 10:14:29.057 Melbour: htsp_timer_stop fxols_onhook_ringing

Dec 2 10:14:29.057 Melbour: htsp_timer - 125 msec

Dec 2 10:14:29.185 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:29.185 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:30.141 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:30.141 Melbour: fxols_ringing_not

Dec 2 10:14:30.141 Melbour: htsp_timer_stop

Dec 2 10:14:30.141 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:30.141 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:30.149 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:30.149 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:30.149 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:30.157 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:30.157 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:30.273 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:30.405 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:30.405 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:30.757 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:30.757 Melbour: htsp_timer_stop

Dec 2 10:14:30.757 Melbour: htsp_timer_stop2

Dec 2 10:14:30.757 Melbour: htsp_timer_stop3

Dec 2 10:14:30.765 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:30.765 Melbour: htsp_timer_stop

Dec 2 10:14:30.765 Melbour: htsp_timer_stop2

Dec 2 10:14:30.765 Melbour: htsp_timer_stop3

Dec 2 10:14:30.765 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:30.765 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:32.053 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_DSP_SIG_0000]fxols_guard_out_ringing

Dec 2 10:14:32.053 Melbour: htsp_timer_stop fxols_onhook_ringing

Dec 2 10:14:32.053 Melbour: htsp_timer - 125 msec

Dec 2 10:14:32.181 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:32.181 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:33.145 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:33.145 Melbour: fxols_ringing_not

Dec 2 10:14:33.145 Melbour: htsp_timer_stop

Dec 2 10:14:33.145 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:33.145 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:33.153 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:33.153 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:33.153 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:33.161 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:33.161 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:33.277 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:33.405 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:33.405 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:33.757 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:33.757 Melbour: htsp_timer_stop

Dec 2 10:14:33.757 Melbour: htsp_timer_stop2

Dec 2 10:14:33.757 Melbour: htsp_timer_stop3

Dec 2 10:14:33.769 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:33.769 Melbour: htsp_timer_stop

Dec 2 10:14:33.769 Melbour: htsp_timer_stop2

Dec 2 10:14:33.769 Melbour: htsp_timer_stop3

Dec 2 10:14:33.769 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:33.769 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:35.053 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_DSP_SIG_0000]fxols_guard_out_ringing

Dec 2 10:14:35.053 Melbour: htsp_timer_stop fxols_onhook_ringing

Dec 2 10:14:35.053 Melbour: htsp_timer - 125 msec

Dec 2 10:14:35.181 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:35.181 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:36.137 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:36.141 Melbour: fxols_ringing_not

Dec 2 10:14:36.141 Melbour: htsp_timer_stop

Dec 2 10:14:36.141 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:36.141 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:36.145 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:36.145 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:36.145 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:36.153 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:36.153 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:36.269 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:36.397 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:36.397 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:36.749 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:36.749 Melbour: htsp_timer_stop

Dec 2 10:14:36.749 Melbour: htsp_timer_stop2

Dec 2 10:14:36.749 Melbour: htsp_timer_stop3

Dec 2 10:14:36.757 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:36.757 Melbour: htsp_timer_stop

Dec 2 10:14:36.757 Melbour: htsp_timer_stop2

Dec 2 10:14:36.757 Melbour: htsp_timer_stop3

Dec 2 10:14:36.757 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:36.757 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:38.049 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_DSP_SIG_0000]fxols_guard_out_ringing

Dec 2 10:14:38.049 Melbour: htsp_timer_stop fxols_onhook_ringing

Dec 2 10:14:38.049 Melbour: htsp_timer - 125 msec

Dec 2 10:14:38.177 Melbour: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer

Dec 2 10:14:38.177 Melbour: htsp_timer - 10000 msec

Dec 2 10:14:38.821 Melbour: htsp_process_event: [0/2/3, FXOLS_RINGING,
E_DSP_SIG_0100]

Dec 2 10:14:38.821 Melbour: fxols_ringing_not

Dec 2 10:14:38.821 Melbour: htsp_timer_stop

Dec 2 10:14:38.821 Melbour: htsp_timer_stop3 htsp_setup_ind

Dec 2 10:14:38.821 Melbour: [0/2/3] get_local_station_id calling num=
calling name= calling time=12/02 10:14 orig called=

Dec 2 10:14:38.825 Melbour: htsp_process_event: [0/2/3,
FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]

Dec 2 10:14:38.825 Melbour: fxols_wait_setup_ack:

Dec 2 10:14:38.829 Melbour: [0/2/3] set signal state = 0xC timestamp =
0fxols_check_auto_call

Dec 2 10:14:38.837 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc

Dec 2 10:14:38.837 Melbour: htsp_timer - 120000 msechtsp_alert_notify

Dec 2 10:14:38.957 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_ALERT]fxols_offhook_alert

Dec 2 10:14:39.081 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_DSP_SIG_1100]fxols_offhook_disc

Dec 2 10:14:39.081 Melbour: htsp_timer2 - 350
msechtsp_call_service_msghtsp_call_service_msg not EFXS (2)

Dec 2 10:14:39.433 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm

Dec 2 10:14:39.433 Melbour: htsp_timer_stop

Dec 2 10:14:39.433 Melbour: htsp_timer_stop2

Dec 2 10:14:39.433 Melbour: htsp_timer_stop3

Dec 2 10:14:39.441 Melbour: htsp_process_event: [0/2/3, FXOLS_PROCEEDING,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:14:39.441 Melbour: htsp_timer_stop

Dec 2 10:14:39.441 Melbour: htsp_timer_stop2

Dec 2 10:14:39.441 Melbour: htsp_timer_stop3

Dec 2 10:14:39.441 Melbour: [0/2/3] set signal state = 0x4 timestamp = 0

Dec 2 10:14:39.441 Melbour: htsp_timer - 2000 msec

Dec 2 10:14:41.441 Melbour: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout

Dec 2 10:14:41.441 Melbour: htsp_process_event: [0/2/3, FXOLS_ONHOOK,
E_DSP_SIG_0100]

Dec 2 10:15:49.614 Melbour: htsp_timer_stop3

Dec 2 10:15:49.638 Melbour: htsp_process_event: [0/2/1, FXOLS_OFFHOOK,
E_HTSP_RELEASE_REQ]fxols_offhook_release

Dec 2 10:15:49.642 Melbour: htsp_timer_stop

Dec 2 10:15:49.642 Melbour: htsp_timer_stop2

Dec 2 10:15:49.642 Melbour: htsp_timer_stop3

Dec 2 10:15:49.642 Melbour: [0/2/1] set signal state = 0x4 timestamp = 0

Dec 2 10:15:49.642 Melbour: htsp_timer - 2000 msec

Dec 2 10:15:51.642 Melbour: htsp_process_event: [0/2/1, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout

Dec 2 10:15:51.642 Melbour: htsp_process_event: [0/2/1, FXOLS_ONHOOK,
E_DSP_SIG_0100]
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20091202/6da5d691/attachment.html>


More information about the cisco-voip mailing list