Strange FXO Problem

martynch1 · ‎12-09-2010

Hi group, trust you are all well.

I have this strange problem with my FXO card in my 2801, at first I thought it was down to my UCM but I reverted my Router config back to ccme but the problems continue, I will try and explain the best I can.

OK I call my PSTN number from my mobile and all sounds right from mobile, I can hear two rings then a pause, two rings again and then a pause; just like a standard call, however the IP phone rings one and a half times then hangs up showing a missed call, then it rings again and hangs up showing another missed call and this continues until I end the call on my mobile, once I have ended the call from my mobile then the IP phone continues to ring until I pick the call up even though I have cleared the call down on my mobile.

Below is my part config along with a debug, I hope you can help me out here as its driving me nuts.

Show ver: -

Cisco 2801 (revision 4.1) with 97280K/33792K bytes of memory.
2 FastEthernet interfaces
1 ATM interface
1 terminal line
4 Voice FXO interfaces
3 DSPs, 48 Voice resources
1 cisco service engine(s)
DRAM configuration is 64 bits wide with parity disabled.
191K bytes of NVRAM.
62592K bytes of ATA CompactFlash (Read/Write)
Configuration register is 0x2102

c2801-ipvoicek9-mz.124-11.T2.bin

Show part run: -

voice class codec 1
codec preference 1 g711ulaw
!
!
!
voice class h323 1
h225 timeout tcp establish 4
!
interface FastEthernet0/0.1
description *** Data Network Chetnet ***
encapsulation dot1Q 1 native
ip address 192.168.3.250 255.255.255.0
no ip split-horizon
!
interface FastEthernet0/0.3
description *** Voice Network Chetnet ***
encapsulation dot1Q 3
ip address 10.50.50.253 255.255.255.0
ip helper-address 192.168.3.201
no ip split-horizon
h323-gateway voip interface
h323-gateway voip h323-id 1
h323-gateway voip bind srcaddr 10.50.50.253
!
interface Service-Engine0/1
ip unnumbered FastEthernet0/0.1
service-module ip address 192.168.3.246 255.255.255.0
service-module ip default-gateway 192.168.3.250
!
voice-port 0/1/0
supervisory disconnect anytone
cptone GB
timing sup-disconnect 85
connection plar opx 1000
impedance complex5
description ***POTS LINE 1***
!
dial-peer voice 11 pots
description test outgoing calls
translation-profile outgoing 9
preference 1
destination-pattern 9T
progress_ind setup enable 3
progress_ind connect enable 8
progress_ind disconnect enable 8
port 0/1/0
!
telephony-service
no auto-reg-ephone
max-ephones 30
max-dn 150
ip source-address 10.50.50.253 port 2000
max-redirect 12
timeouts interdigit 4
network-locale GB
time-format 24
date-format dd-mm-yy
voicemail 2300
max-conferences 4 gain 6
transfer-system full-consult
create cnf-files version-stamp 7960 Dec 09 2010 22:35:23

Debug

Dec 9 23:32:26.142: htsp_process_event: [0/1/0, FXOGS_PARK, E_DSP_SIG_0110]
Dec 9 23:32:26.146: [0/1/0, FXOGS_PARK, E_DSP_SIG_0110] -> ERROR: INVALID INPUT
Dec 9 23:32:26.546: htsp_process_event: [0/1/0, FXOGS_PARK, E_DSP_SIG_1100]
Dec 9 23:32:26.546: fxogs_no_tipgnd
Dec 9 23:32:26.546: htsp_timer_stop
Dec 9 23:32:26.546: htsp_timer_stop2
Dec 9 23:32:26.546: htsp_timer2 - 350 msec
Dec 9 23:32:26.898: htsp_process_event: [0/1/0, FXOGS_PARK, E_HTSP_EVENT_TIMER2]fxogs_park_onhook
Dec 9 23:32:28.034: htsp_process_event: [0/1/0, FXOGS_ONHOOK, E_DSP_SIG_0000]fxogs_onhook_ringing
Dec 9 23:32:28.034: htsp_timer_stop
Dec 9 23:32:28.034: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_PENDING, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:28.034: htsp_timer - 125 msec
Dec 9 23:32:28.162: htsp_process_event: [0/1/0, FXOGS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxogs_wait_ring_min_timer
Dec 9 23:32:28.162: htsp_timer - 10000 msec
Dec 9 23:32:29.702: htsp_process_event: [0/1/0, FXOGS_RINGING, E_DSP_SIG_0100]
Dec 9 23:32:29.702: fxogs_ringing_not:
Dec 9 23:32:29.702: htsp_timer_stop
Dec 9 23:32:29.702: htsp_timer_stop2
Dec 9 23:32:29.702: htsp_timer_stop3 htsp_setup_ind
Dec 9 23:32:29.702: [0/1/0] get_local_station_id calling num= calling name= calling time=12/09 23:32 orig called=
Dec 9 23:32:29.702: TGRM: reg_invoke_tgrm_accept_call(1, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 0, 1, 0, 65535)
Dec 9 23:32:29.702: TGRM: Calling vtsp_tsp_call_setup_ind().
Dec 9 23:32:29.706: htsp_process_event: [0/1/0, FXOGS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
Dec 9 23:32:29.706: fxogs_wait_setup_ack:
Dec 9 23:32:29.710: htsp_timer_stop3
Dec 9 23:32:29.710: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_PROCEEDING]htsp_setup_req
Dec 9 23:32:29.714: htsp_process_event: [50/0/1.1, EFXS_ONHOOK, E_HTSP_SETUP_REQ]efxs_onhook_setup
Dec 9 23:32:29.714: htsp_ephone_start_caller_id_tx calling num= calling name = called num=1000 orig called num=
Dec 9 23:32:29.714: [50/0/1.1] set signal state = 0x0 timestamp = 0
Dec 9 23:32:29.714: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x0 timestamp=0x0
Dec 9 23:32:29.714: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:29.714: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationInsideRing
Dec 9 23:32:29.714: efxs_onhook_setup: local target is availablehtsp_alerthtsp_alert_notify
Dec 9 23:32:29.722: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT]
Dec 9 23:32:29.722: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT] -> ERROR: INVALID INPUT
Dec 9 23:32:30.102: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_DSP_SIG_1100]
Dec 9 23:32:30.102: fxogs_no_tipgnd
Dec 9 23:32:30.102: htsp_timer_stop
Dec 9 23:32:30.102: htsp_timer_stop2
Dec 9 23:32:30.102: htsp_timer2 - 350 msec
Dec 9 23:32:30.454: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxogs_offhook_disc
Dec 9 23:32:30.454: htsp_timer_stop
Dec 9 23:32:30.454: [0/1/0] set signal state = 0x4 timestamp = 0
Dec 9 23:32:30.454: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:30.454: htsp_timer - 2000 msec
Dec 9 23:32:30.454: htsp_timer_stop3 htsp_release_req: cause 16, no_onhook 0
Dec 9 23:32:30.454: htsp_process_event: [50/0/1.1, EFXS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_waitoff_release
Dec 9 23:32:30.454: [50/0/1.1] set signal state = 0x4 timestamp = 0
Dec 9 23:32:30.454: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:30.454: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:30.454: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationRingOff
Dec 9 23:32:30.458: TGRM: reg_invoke_tgrm_call_update(50, 0, 1, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
Dec 9 23:32:30.462: edsprm_close_cleanuphtsp_release_req: cause 16, no_onhook 0
Dec 9 23:32:30.466: htsp_process_event: [0/1/0, FXOGS_GUARD_OUT, E_HTSP_RELEASE_REQ]fxogs_guard_out_release
Dec 9 23:32:30.466: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:30.470: flex_dsprm_close_cleanup
Dec 9 23:32:31.598: htsp_process_event: [0/1/0, FXOGS_GUARD_OUT, E_DSP_SIG_0000]
Dec 9 23:32:31.598: fxogs_guardout_ringingfxogs_onhook_ringing
Dec 9 23:32:31.598: htsp_timer_stop
Dec 9 23:32:31.598: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_PENDING, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:31.598: htsp_timer - 125 msec
Dec 9 23:32:31.726: htsp_process_event: [0/1/0, FXOGS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxogs_wait_ring_min_timer
Dec 9 23:32:31.726: htsp_timer - 10000 msec
Dec 9 23:32:32.698: htsp_process_event: [0/1/0, FXOGS_RINGING, E_DSP_SIG_0100]
Dec 9 23:32:32.698: fxogs_ringing_not:
Dec 9 23:32:32.698: htsp_timer_stop
Dec 9 23:32:32.698: htsp_timer_stop2
Dec 9 23:32:32.698: htsp_timer_stop3 htsp_setup_ind
Dec 9 23:32:32.698: [0/1/0] get_local_station_id calling num= calling name= calling time=12/09 23:32 orig called=
Dec 9 23:32:32.698: TGRM: reg_invoke_tgrm_accept_call(1, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 0, 1, 0, 65535)
Dec 9 23:32:32.698: TGRM: Calling vtsp_tsp_call_setup_ind().
Dec 9 23:32:32.702: htsp_process_event: [0/1/0, FXOGS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
Dec 9 23:32:32.702: fxogs_wait_setup_ack:
Dec 9 23:32:32.706: htsp_timer_stop3
Dec 9 23:32:32.710: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_PROCEEDING]htsp_setup_req
Dec 9 23:32:32.710: htsp_process_event: [50/0/1.1, EFXS_ONHOOK, E_HTSP_SETUP_REQ]efxs_onhook_setup
Dec 9 23:32:32.710: htsp_ephone_start_caller_id_tx calling num= calling name = called num=1000 orig called num=
Dec 9 23:32:32.710: [50/0/1.1] set signal state = 0x0 timestamp = 0
Dec 9 23:32:32.710: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x0 timestamp=0x0
Dec 9 23:32:32.710: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:32.710: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationInsideRing
Dec 9 23:32:32.710: efxs_onhook_setup: local target is availablehtsp_alerthtsp_alert_notify
Dec 9 23:32:32.718: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT]
Dec 9 23:32:32.718: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT] -> ERROR: INVALID INPUT
Dec 9 23:32:33.102: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_DSP_SIG_1100]
Dec 9 23:32:33.102: fxogs_no_tipgnd
Dec 9 23:32:33.102: htsp_timer_stop
Dec 9 23:32:33.102: htsp_timer_stop2
Dec 9 23:32:33.102: htsp_timer2 - 350 msec
Dec 9 23:32:33.454: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxogs_offhook_disc
Dec 9 23:32:33.454: htsp_timer_stop
Dec 9 23:32:33.454: [0/1/0] set signal state = 0x4 timestamp = 0
Dec 9 23:32:33.454: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:33.454: htsp_timer - 2000 msec
Dec 9 23:32:33.454: htsp_timer_stop3 htsp_release_req: cause 16, no_onhook 0
Dec 9 23:32:33.454: htsp_process_event: [50/0/1.1, EFXS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_waitoff_release
Dec 9 23:32:33.454: [50/0/1.1] set signal state = 0x4 timestamp = 0
Dec 9 23:32:33.454: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:33.454: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:33.454: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationRingOff
Dec 9 23:32:33.458: TGRM: reg_invoke_tgrm_call_update(50, 0, 1, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
Dec 9 23:32:33.462: edsprm_close_cleanuphtsp_release_req: cause 16, no_onhook 0
Dec 9 23:32:33.466: htsp_process_event: [0/1/0, FXOGS_GUARD_OUT, E_HTSP_RELEASE_REQ]fxogs_guard_out_release
Dec 9 23:32:33.466: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:33.470: flex_dsprm_close_cleanup
Dec 9 23:32:34.599: htsp_process_event: [0/1/0, FXOGS_GUARD_OUT, E_DSP_SIG_0000]
Dec 9 23:32:34.599: fxogs_guardout_ringingfxogs_onhook_ringing
Dec 9 23:32:34.599: htsp_timer_stop
Dec 9 23:32:34.599: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_PENDING, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:34.599: htsp_timer - 125 msec
Dec 9 23:32:34.727: htsp_process_event: [0/1/0, FXOGS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxogs_wait_ring_min_timer
Dec 9 23:32:34.727: htsp_timer - 10000 msec
Dec 9 23:32:35.375: htsp_process_event: [0/1/0, FXOGS_RINGING, E_DSP_SIG_0100]
Dec 9 23:32:35.375: fxogs_ringing_not:
Dec 9 23:32:35.375: htsp_timer_stop
Dec 9 23:32:35.375: htsp_timer_stop2
Dec 9 23:32:35.375: htsp_timer_stop3 htsp_setup_ind
Dec 9 23:32:35.375: [0/1/0] get_local_station_id calling num= calling name= calling time=12/09 23:32 orig called=
Dec 9 23:32:35.375: TGRM: reg_invoke_tgrm_accept_call(1, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 0, 1, 0, 65535)
Dec 9 23:32:35.375: TGRM: Calling vtsp_tsp_call_setup_ind().
Dec 9 23:32:35.379: htsp_process_event: [0/1/0, FXOGS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
Dec 9 23:32:35.379: fxogs_wait_setup_ack:
Dec 9 23:32:35.383: htsp_timer_stop3
Dec 9 23:32:35.383: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_PROCEEDING]htsp_setup_req
Dec 9 23:32:35.387: htsp_process_event: [50/0/1.1, EFXS_ONHOOK, E_HTSP_SETUP_REQ]efxs_onhook_setup
Dec 9 23:32:35.387: htsp_ephone_start_caller_id_tx calling num= calling name = called num=1000 orig called num=
Dec 9 23:32:35.387: [50/0/1.1] set signal state = 0x0 timestamp = 0
Dec 9 23:32:35.387: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x0 timestamp=0x0
Dec 9 23:32:35.387: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:35.387: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationInsideRing
Dec 9 23:32:35.387: efxs_onhook_setup: local target is availablehtsp_alerthtsp_alert_notify
Dec 9 23:32:35.395: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT]
Dec 9 23:32:35.395: [0/1/0, FXOGS_OFFHOOK, E_HTSP_ALERT] -> ERROR: INVALID INPUT
Dec 9 23:32:41.155: htsp_process_event: [50/0/1.1, EFXS_WAIT_OFFHOOK, E_DSP_SIG_1100]efxs_waitoff_offhook
Dec 9 23:32:41.155: [50/0/1.1] set signal state = 0x4 timestamp = 0
Dec 9 23:32:41.155: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:41.155: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.155: edsp_soutput: [50/0/1.1] DN=1 chnl=1 StationRingOff htsp_dial
Dec 9 23:32:41.155: htsp_process_event: [50/0/1.1, EFXS_CONNECT, E_DSP_DIALING_DONE]
Dec 9 23:32:41.159: htsp_call_bridged invoked
Dec 9 23:32:41.159: flex_modem_relay_supported:Modem Relay supported. flex_type=1
Dec 9 23:32:41.159: htsp_call_bridged invoked
Dec 9 23:32:41.159: flex_dsprm_forking_mixing_support:
Dec 9 23:32:41.159: mars_flex_dsprm_current_codec_comp:DSP:2 FLEX Complexity Codec
Dec 9 23:32:41.159: DSPWARE Version >= 3.0, actual is 9.2.2
Dec 9 23:32:41.159: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.159: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_OPEN_VOICE_CHANNEL
Dec 9 23:32:41.159: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.159: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_IDLE_MODE
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_ENC_CONFIG 2
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_SET_VOICE_PLAYOUT_DELAY
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_ECHO_CANCELLER_CONTROL
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_GEN_PACKET_CONTROL
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_ECHO_CANCELLER_CONTROL
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_SET_GAINS
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_GEN_PACKET_CONTROL
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.163: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.167: htsp_process_event: [50/0/1.1, EFXS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]efxs_connect_cutthru
Dec 9 23:32:41.167: [50/0/1.1] set signal state = 0x7 timestamp = 0
Dec 9 23:32:41.167: edsp_set_sig_state: [50/0/1.1] packet_len=12 channel_id=1 packet_id=39 state=0x7 timestamp=0x0
Dec 9 23:32:41.167: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.167: edsp_soutput: [50/0/1.1] DN=1 chnl=1 EFXS_OPEN_VOICE_PATH count=1htsp_connect: no_offhook 0 call_connected 0
Dec 9 23:32:41.171: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_CONNECT]fxogs_proc_voice
Dec 9 23:32:41.171: htsp_timer_stop
Dec 9 23:32:41.171: [0/1/0] set signal state = 0xC timestamp = 0
Dec 9 23:32:41.171: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0xC timestamp=0x0
Dec 9 23:32:41.175: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]
Dec 9 23:32:41.839: htsp_process_event: [50/0/1.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook
Dec 9 23:32:41.839: htsp_timer - 10 msec
Dec 9 23:32:41.851: htsp_process_event: [50/0/1.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req
Dec 9 23:32:41.851: htsp_timer_stop
Dec 9 23:32:41.851: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.851: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_GEN_PACKET_CONTROL
Dec 9 23:32:41.851: htsp_timer_stop3
Dec 9 23:32:41.851: htsp_timer_stop3
Dec 9 23:32:41.855: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.855: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_GET_LEVELS
Dec 9 23:32:41.855: edsp_fake_levels: [50/0/1.1] DN=1 chnl=1 by proc_id 8
Dec 9 23:32:41.855: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.855: edsp_soutput: [50/0/1.1] DN=1 chnl=1 GET_ERROR_STAT
Dec 9 23:32:41.855: edsp_fake_error_stat: [50/0/1.1] DN=1 chnl=1 by proc_id 8htsp_release_req: cause 16, no_onhook 0htsp_release_req: cause 16, no_onhook 0
Dec 9 23:32:41.855: htsp_process_event: [0/1/0, FXOGS_OFFHOOK, E_HTSP_RELEASE_REQ]fxogs_offhook_release
Dec 9 23:32:41.855: htsp_timer_stop
Dec 9 23:32:41.855: htsp_timer_stop2
Dec 9 23:32:41.855: htsp_timer_stop3
Dec 9 23:32:41.855: [0/1/0] set signal state = 0x4 timestamp = 0
Dec 9 23:32:41.855: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0
Dec 9 23:32:41.855: htsp_timer - 2000 msec
Dec 9 23:32:41.855: htsp_process_event: [50/0/1.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig
Dec 9 23:32:41.859: TGRM: reg_invoke_tgrm_call_update(50, 0, 1, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
Dec 9 23:32:41.859: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.859: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_IDLE_MODE
Dec 9 23:32:41.859: edsp_soutput: [50/0/1.1] DN=1 chnl=1
Dec 9 23:32:41.859: edsp_soutput: [50/0/1.1] DN=1 chnl=1 MSG_RX_CLOSE_VOICE_CHANNEL
Dec 9 23:32:41.859: edsprm_close_cleanup
Dec 9 23:32:42.459: htsp_process_event: [0/1/0, FXOGS_WAIT_ONHOOK, E_DSP_SIG_0100]
Dec 9 23:32:42.459: fxogs_stop_disc_timer
Dec 9 23:32:42.459: htsp_timer_stop2
Dec 9 23:32:43.855: htsp_process_event: [0/1/0, FXOGS_WAIT_ONHOOK, E_HTSP_EVENT_TIMER]fxogs_waitonhook_timer
Dec 9 23:32:43.855: htsp_timer_stop
Dec 9 23:32:43.855: dsp_req_sig_state: [0/1/0] packet_len=8 channel_id=128 packet_id=40
Dec 9 23:32:43.855: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)
Dec 9 23:32:43.855: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
Dec 9 23:32:43.855: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)
Dec 9 23:32:43.855: htsp_process_event: [0/1/0, FXOGS_PARK, E_DSP_SIG_0100]
Dec 9 23:32:43.855: fxogs_stop_disc_timer
Dec 9 23:32:43.855: htsp_timer_stop2
Dec 9 23:32:43.855: flex_dsprm_close_cleanup

I have tried Loop Start, Ground Start no No battery reversal

Hope you can help

Regards

Chet

martynch1 · ‎12-09-2010

Forgot to show the following: -

show voice port

Foreign Exchange Office 0/1/0 Slot is 0, Sub-unit is 1, Port is 0
Type of VoicePort is FXO
Operation State is UP
Administrative State is UP
No Interface Down Failure
Description is ***POTS LINE 1***
Noise Regeneration is enabled
Non Linear Processing is enabled
Non Linear Mute is disabled
Non Linear Threshold is -21 dB
Music On Hold Threshold is Set to -38 dBm
In Gain is Set to 0 dB
Out Attenuation is Set to 3 dB
Echo Cancellation is enabled
Echo Cancellation NLP mute is disabled
Echo Cancellation NLP threshold is -21 dB
Echo Cancel Coverage is set to 64 ms
Echo Cancel worst case ERL is set to 6 dB
Playout-delay Mode is set to adaptive
Playout-delay Nominal is set to 60 ms
Playout-delay Maximum is set to 250 ms
Playout-delay Minimum mode is set to default, value 40 ms
Playout-delay Fax is set to 300 ms
Connection Mode is plar opx
Connection Number is 1000
Initial Time Out is set to 10 s
Interdigit Time Out is set to 10 s
Call Disconnect Time Out is set to 60 s
Ringing Time Out is set to 180 s
Wait Release Time Out is set to 30 s
Companding Type is u-law
Region Tone is set for GB

Analog Info Follows:
Currently processing none
Maintenance Mode Set to None (not in mtc mode)
Number of signaling protocol errors are 0
Impedance is set to complex5 Ohm
Station name None, Station number None
Translation profile (Incoming):
Translation profile (Outgoing):

Voice card specific Info Follows:
Signal Type is loopStart
Battery-Reversal is enabled
Number Of Rings is set to 1
Supervisory Disconnect is anytone
Answer Supervision is inactive
Hook Status is On Hook
Ring Detect Status is inactive
Ring Ground Status is inactive
Tip Ground Status is inactive
Dial Out Type is dtmf
Digit Duration Timing is set to 100 ms
InterDigit Duration Timing is set to 100 ms
Pulse Rate Timing is set to 10 pulses/second
InterDigit Pulse Duration Timing is set to 750 ms
Percent Break of Pulse is 65 percent
GuardOut timer is 2000 ms
Minimum ring duration timer is 125 ms
Hookflash-in Timing is set to 600 ms
Hookflash-out Timing is set to 400 ms
Supervisory Disconnnct Timing is set to 85 ms
OPX Ring Wait Timing is set to 6000 ms

Thanks all

paolo bevilacqua · ‎12-09-2010

There is nothign strange in that, these are typical FXO problems caused by the lack of supervisory disconnect.

The missed calls can be cause by the ringing pattern too spaced apart for the router to detect as a single call.

http://www.cisco.com/en/US/tech/tk652/tk653/technologies_tech_note09186a00800ae2d1.shtml

Note in my experience unless you config the exact tones, it would not work.

And of course, if you want business-level call handling and features, use ISDN, not analog.

martynch1 · ‎12-10-2010

Confused, I have it enabled, could you explain a little more please.

voice-port 0/1/0
supervisory disconnect anytone
cptone GB
timing sup-disconnect 85

Martyn

paolo bevilacqua · ‎12-10-2010

As indicated above, you need to configure the exact tones for your telco.

martynch1 · ‎12-10-2010

I have tried all that is shown on the link you sent me, tried all the

options but I still can not get a disconnect, the only thing I could not try

was the voice classes becasue my Router does not support it

Router(config-voiceport)#supervisory disconnect dualtone pre-connect?

I will keep trying to resolve but if you can come up with any other suggestions that would be great

Cheers

paolo bevilacqua · ‎12-10-2010

Dualtone is the right option, plus you have to enter the exact tone frequencies for your telco.

You can search for the many, many similar threads on the subject.

As if the problem wasn't serius enough, some IOS version are buggy and do not even work in this area.

That is why for an expensive phone system like Cisco CE or CME, a digital line is a necessity more than an option.

martynch1 · ‎12-11-2010

Appreciate what you are saying but I can not understand why it works when I set up my 2801 a ccme, I have no problems there its just when its get handed over to the call manager I get the problems.

Thanks

Martyn