Thank you Anthony very much for your help.
The 19529473283 for connection plar 19529473283 I was testing with. After
changing of few things on the gateway, I'm now seeing different messages when I
run debug. The calls used to ring only one time and then fast busy, now it
rings twice and then fax busy for incoming. for outgoing, it's says calls
connot be completed as dialed.
Attached the new logs again. I really appreciate your help.
thanks again.
Hamu
________________________________
From: Anthony Holloway <avholloway+cisco-v...@gmail.com>
Sent: Thursday, March 1, 2018 11:05 AM
To: Hamu Ebiso
Cc: Cisco VoIP Group
Subject: Re: [cisco-voip] Need help configuring router for 911 with POTS Lines
connected to FXO port on the router
According to the debug, you're calling 19529473283. Is that correct?
Called Number=19529473283(TON=Unknown, NPI=Unknown),
If so, then this debug is not for this gateway, because you're hitting Outgoing
Dial-peer=5
Outgoing Dial-peer=5, Params=0x688FF3FC, Progress Indication=ORIGINATING SIDE
IS NON ISDN(3)
And dial-peer 5, while it exists in your config you pasted, it doesn't match
the called number.
dial-peer voice 5 voip
description FXO test
destination-pattern 12083437020
session target ipv4:10.0.2.5
!
Even if it did match the destination pattern, you're missing some config on
this dial-peer to make a SIP capable dial-peer, which means it's sending H323
setup to CUCM. If you built the gateway as H323, then I can see how it rang
your CIPC, otherwise, if you built it as SIP, which you said you did, then it
would never ring your CIPC.
So, something is not adding up here.
Also, you're hitting Incoming Dial-peer=0, which on a POTS leg is not too
terrible, but it's also not good practice to be using dial-peer 0. It's really
VoIP DP 0 that is messy though.
Incoming Dial-peer=0, Progress Indication=ORIGINATING SIDE IS NON ISDN(3),
Calling IE Present=FALSE,
On Thu, Mar 1, 2018 at 10:06 AM Hamu Ebiso
<hebiso2...@hotmail.com<mailto:hebiso2...@hotmail.com>> wrote:
Hi Anthony,
yes the call was hitting the gateway because I was seeing FXO port going
Off-Hook. The problem was that Logging was not setup in the gateway. After I
setup the Logging, I'm now seeing logs.
I have attached the log.
thanks
Hamu
________________________________
From: avhollo...@gmail.com<mailto:avhollo...@gmail.com>
<avhollo...@gmail.com<mailto:avhollo...@gmail.com>> on behalf of Anthony
Holloway
<avholloway+cisco-v...@gmail.com<mailto:avholloway%2bcisco-v...@gmail.com>>
Sent: Thursday, March 1, 2018 8:45 AM
To: Hamu Ebiso
Cc: Cisco VoIP Group
Subject: Re: [cisco-voip] Need help configuring router for 911 with POTS Lines
connected to FXO port on the router
Since you didn't see the call in your debugs, your call likely didn't hit your
gateway.
Can you confirm how you made your test call, when you had your debug running?
On Feb 28, 2018 2:46 PM, "Hamu Ebiso"
<hebiso2...@hotmail.com<mailto:hebiso2...@hotmail.com>> wrote:
Here is the dial-peer output
1#show run | sec dial-peer
dial-peer voice 1 pots
trunkgroup FXO_EM
translation-profile incoming Inbound
call-block translation-profile incoming block_profile
call-block disconnect-cause incoming call-reject
incoming called-number .
direct-inward-dial
dial-peer voice 911 pots
trunkgroup FXO_EM
description Services
translation-profile outgoing Strip9
destination-pattern 911
dial-peer voice 9911 pots
trunkgroup FXO_EM
description Services
translation-profile outgoing Strip9
destination-pattern 9911
dial-peer voice 2 voip
description CUCM Dial-Peer - Inbound
preference 1
destination-pattern 12086858038<tel:(208)%20685-8038>
session protocol sipv2
session target ipv4:10.0.2.6
incoming uri via 10
voice-class codec 1
dtmf-relay rtp-nte sip-kpml
fax-relay ecm disable
fax nsf 000000
ip qos dscp cs3 signaling
no vad
dial-peer voice 3 voip
preference 2
destination-pattern 12086858038<tel:(208)%20685-8038>
session target ipv4:10.0.2.3
dial-peer voice 650 pots
trunkgroup Local
description Local outbound
translation-profile outgoing Strip9
destination-pattern 9208.......
dial-peer voice 4 pots
trunkgroup FXO_EM
description ** 10-digit Local Call **
translation-profile outgoing Strip9
destination-pattern ^9[2-9]..[2-9]......$
forward-digits 10
dial-peer voice 5 voip
description FXO test
destination-pattern 12083437020<tel:(208)%20343-7020>
session target ipv4:10.0.2.5
thank you very much!!
________________________________
From: Anthony Holloway
<avholloway+cisco-v...@gmail.com<mailto:avholloway%2bcisco-v...@gmail.com>>
Sent: Wednesday, February 28, 2018 1:29 PM
To: Hamu Ebiso
Cc: cisco-voip voyp list
Subject: Re: [cisco-voip] Need help configuring router for 911 with POTS Lines
connected to FXO port on the router
Maybe you're actually using MGCP then? You still haven't shown the dial-peers
on the gateway. Is that because you don't have any?
show run | section dial-peer
On Wed, Feb 28, 2018 at 12:19 PM Hamu Ebiso
<hebiso2...@hotmail.com<mailto:hebiso2...@hotmail.com>> wrote:
debug was turned on as shown below.
1#debug voice ccapi inout
voip ccapi inout debugging is on
made test calls and then show log, I see below output.
1#show log
Syslog logging: enabled (0 messages dropped, 37 messages rate-limited, 0
flushes, 0 overruns, xml disabled, filtering disabled)
No Active Message Discriminator.
No Inactive Message Discriminator.
Console logging: level debugging, 517 messages logged, xml disabled,
filtering disabled
Monitor logging: level debugging, 0 messages logged, xml disabled,
filtering disabled
Buffer logging: level debugging, 550 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled
No active filter modules.
Trap logging: level debugging, 620 message lines logged
Logging to 10.0.7.139 (udp port 514, audit disabled,
link up),
435 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging to 10.0.8.12 (udp port 514, audit disabled,
link up),
435 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging Source-Interface: VRF Name:
Loopback0
I don't see any calls activity.
thanks
________________________________
From: Anthony Holloway
<avholloway+cisco-v...@gmail.com<mailto:avholloway%2bcisco-v...@gmail.com>>
Sent: Wednesday, February 28, 2018 11:19 AM
To: Hamu Ebiso
Cc: cisco-voip voyp list
Subject: Re: [cisco-voip] Need help configuring router for 911 with POTS Lines
connected to FXO port on the router
But you didn't send all the information, because your output doesn't show the
dial-peers.
One method is to capture the output from "debug voip ccapi inout" and look for
the following line "Outgoing Dial-peer="
You also didn't address the regions either. Can you send that too?
On Wed, Feb 28, 2018 at 11:13 AM Hamu Ebiso
<hebiso2...@hotmail.com<mailto:hebiso2...@hotmail.com>> wrote:
I was just trying to give you all the information so that you will be able to
see the whole picture.
Regarding your questions, I don't know how to check that.
________________________________
From: Anthony Holloway
<avholloway+cisco-v...@gmail.com<mailto:avholloway%2bcisco-v...@gmail.com>>
Sent: Wednesday, February 28, 2018 10:45 AM
To: Hamu Ebiso
Cc: cisco-voip voyp list
Subject: Re: [cisco-voip] Need help configuring router for 911 with POTS Lines
connected to FXO port on the router
That's interesting that I asked for the dial-peer config, and you sent
everything but.
Do you know how to confirm which dial-peer the gateway is using?
On Wed, Feb 28, 2018 at 10:38 AM Hamu Ebiso
<hebiso2...@hotmail.com<mailto:hebiso2...@hotmail.com>> wrote:
Below is gateway configuration
isdn switch-type primary-ni
!
!
trunk group FXO_EM
hunt-scheme sequential
translation-profile incoming Incoming
!
!
trunk group Local
hunt-scheme sequential
translation-profile outgoing outbound
!
!
trunk group LD
translation-profile outgoing outbound
!
voice-card 0
dspfarm
dsp services dspfarm
!
!
voice call send-alert
voice rtp send-recv
!
voice service voip
ip address trusted list
ipv4 10.170.99.12
ipv4 10.44.50.14
ipv4 10.44.50.39
no ip address trusted authenticate
clid substitute name
clid network-provided
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
redirect ip2ip
signaling forward unconditional
fax protocol t38 nse force version 0 ls-redundancy 0 hs-redundancy 0 fallback
pass-through g711ulaw
no fax-relay sg3-to-g3
modem passthrough nse codec g711ulaw
sip
bind control source-interface Loopback0
bind media source-interface Loopback0
session transport tcp
min-se 360 session-expires 360
ds0-num
header-passing
error-passthru
registrar server expires max 600 min 60
early-offer forced
midcall-signaling passthru
no call service stop
!
!
voice class uri 10 sip
host ipv4:10.x.x.x
host ipv4:10..x.x.x
host ipv4:10..x.x.x
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8
!
voice class codec 2
codec preference 1 g711ulaw
!
!
!
!
voice translation-rule 1
rule 1 /^208343\(....\)$/ /1208343\1/
!
voice translation-rule 9
rule 1 /5.../ /1208343\0/
!
voice translation-rule 10
rule 1 /^911/ /911/
rule 2 /^9911/ /911/
!
voice translation-rule 15
!
voice translation-rule 30
rule 1 /^911/ /911/
rule 2 /^9911/ /911/
rule 4 /^9\(011.*\)/ /\1/
rule 9 /^9\(.*\)/ /\1/
!
!
voice translation-profile Add1
translate calling 91
translate called 1
!
voice translation-profile Inbound
translate called 1
!
voice translation-profile Strip9
translate called 10
!
voice translation-profile block_profile
translate calling 15
!
voice translation-profile outbound
translate calling 10
!
!
crypto pki token default removal timeout 0
!
!
!
!
license udi pid CISCO3845-MB sn FOC123128X5
license accept end user agreement
archive
log config
hidekeys
username admin privilege 15 password 7 105D1F0C
username svuntbd privilege 15 secret 5 $1$vRxN$nu4JPZttcuPskPHoSdQZx.
!
!
controller T1 0/0/0
shutdown
cablelength long 0db
!
controller T1 0/1/0
shutdown
cablelength long 0db
!
!
!
!
!
!
interface Loopback0
ip address 10.x.x.x 255.255.255.128
!
interface GigabitEthernet0/0
description uplink to Lan1
ip address 10.x.x.x 255.255.255.252
duplex auto
speed auto
media-type rj45
!
interface GigabitEthernet0/1
description uplink to Lan2
ip address 10.x.x.x 255.255.255.252
duplex auto
speed auto
media-type rj45
!
router ospf 1
auto-cost reference-bandwidth 10000
network 10.0.0.0 0.0.255.255 area 10.0.0.0
!
ip forward-protocol nd
!
!
no ip http server
no ip http secure-server
!
voipgw1#show log
Syslog logging: enabled (0 messages dropped, 37 messages rate-limited, 0
flushes, 0 overruns, xml disabled, filtering disabled)
No Active Message Discriminator.
No Inactive Message Discriminator.
Console logging: disabled
Monitor logging: disabled
Buffer logging: level debugging, 2295 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled
No active filter modules.
Trap logging: level debugging, 5681 message lines logged
Logging to 10.0.7.139 (udp port 514, audit disabled,
link up),
5412 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging to 10.0.8.12 (udp port 514, audit disabled,
link up),
5412 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging Source-Interface: VRF Name:
Loopback0
Log Buffer (99999 bytes):
Mar 1 18:02:08.320 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=24065 systime=601889613
Mar 1 18:02:08.320 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:02:08.320 UTC: htsp_timer - 125 msec
Mar 1 18:02:08.448 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:02:08.448 UTC: htsp_timer - 10000 msec
Mar 1 18:02:08.448 UTC: htsp_timer3 - 5600 msec
Mar 1 18:02:08.448 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:02:08.448 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:02:08.448 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:02:10.408 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=26154 systime=601889822
Mar 1 18:02:10.408 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:02:10.408 UTC: fxols_ringing_not
Mar 1 18:02:10.408 UTC: htsp_timer_stop
Mar 1 18:02:10.408 UTC: htsp_timer - 10000 msec
Mar 1 18:02:14.048 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:02:14.048 UTC: htsp_timer_stop3
Mar 1 18:02:14.320 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=30062 systime=601890213
Mar 1 18:02:14.320 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:02:16.409 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=32149 systime=601890422
Mar 1 18:02:16.409 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:02:16.409 UTC: fxols_ringing_not
Mar 1 18:02:16.409 UTC: htsp_timer_stop
Mar 1 18:02:16.409 UTC: htsp_timer_stop3
Mar 1 18:02:16.409 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:02:16.409 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:02:16.409 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:02 orig called=
Mar 1 18:02:16.409 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:02:16.409 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:02:16.409 UTC: fxols_wait_setup_ack:
Mar 1 18:02:16.409 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:02:16.413 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:02:16.413 UTC: htsp_timer - 120000 msec
Mar 1 18:02:16.489 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:02:16.489 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:02:16.685 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=32426 systime=601890449
Mar 1 18:02:16.685 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:02:16.685 UTC: htsp_timer_stop2
Mar 1 18:02:20.821 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=36566 systime=601890863
Mar 1 18:02:20.821 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:02:20.821 UTC: htsp_timer2 - 350 msec
Mar 1 18:02:21.173 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:02:21.173 UTC: htsp_timer_stop
Mar 1 18:02:21.173 UTC: htsp_timer_stop2
Mar 1 18:02:21.173 UTC: htsp_timer_stop3
Mar 1 18:02:21.173 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:02:21.173 UTC: htsp_timer_stop
Mar 1 18:02:21.173 UTC: htsp_timer_stop2
Mar 1 18:02:21.173 UTC: htsp_timer_stop3
Mar 1 18:02:21.173 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:02:21.173 UTC: htsp_timer - 2000 msec
Mar 1 18:02:21.657 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=37397 systime=601890946
Mar 1 18:02:21.657 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:02:23.173 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:02:23.173 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=37397 systime=601891098
Mar 1 18:02:23.173 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:11:29.733 UTC: %SYS-5-CONFIG_I: Configured from console by dlog007 on
vty1 (10.82.116.56)
Mar 1 18:11:40.685 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=6595 systime=601946848
Mar 1 18:11:40.685 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:11:40.685 UTC: htsp_timer - 125 msec
Mar 1 18:11:40.813 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:11:40.813 UTC: htsp_timer - 10000 msec
Mar 1 18:11:40.813 UTC: htsp_timer3 - 5600 msec
Mar 1 18:11:40.813 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:11:40.813 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:11:40.813 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:11:42.773 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=8683 systime=601947056
Mar 1 18:11:42.773 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:11:42.773 UTC: fxols_ringing_not
Mar 1 18:11:42.773 UTC: htsp_timer_stop
Mar 1 18:11:42.773 UTC: htsp_timer - 10000 msec
Mar 1 18:11:46.413 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:11:46.413 UTC: htsp_timer_stop3
Mar 1 18:11:46.705 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=12615 systime=601947450
Mar 1 18:11:46.705 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:11:48.765 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=14678 systime=601947656
Mar 1 18:11:48.765 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:11:48.765 UTC: fxols_ringing_not
Mar 1 18:11:48.765 UTC: htsp_timer_stop
Mar 1 18:11:48.765 UTC: htsp_timer_stop3
Mar 1 18:11:48.765 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:11:48.765 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:11:48.765 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:11 orig called=
Mar 1 18:11:48.769 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:11:48.769 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:11:48.769 UTC: fxols_wait_setup_ack:
Mar 1 18:11:48.769 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:11:48.769 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:11:48.773 UTC: htsp_timer - 120000 msec
Mar 1 18:11:48.849 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:11:48.849 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:11:49.041 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=14952 systime=601947683
Mar 1 18:11:49.041 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:11:49.041 UTC: htsp_timer_stop2
Mar 1 18:11:54.221 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=20132 systime=601948201
Mar 1 18:11:54.221 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:11:54.221 UTC: htsp_timer2 - 350 msec
Mar 1 18:11:54.573 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:11:54.573 UTC: htsp_timer_stop
Mar 1 18:11:54.573 UTC: htsp_timer_stop2
Mar 1 18:11:54.573 UTC: htsp_timer_stop3
Mar 1 18:11:54.573 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:11:54.573 UTC: htsp_timer_stop
Mar 1 18:11:54.573 UTC: htsp_timer_stop2
Mar 1 18:11:54.573 UTC: htsp_timer_stop3
Mar 1 18:11:54.573 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:11:54.573 UTC: htsp_timer - 2000 msec
Mar 1 18:11:55.049 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=20958 systime=601948284
Mar 1 18:11:55.049 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:11:56.573 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:11:56.573 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=20958 systime=601948436
Mar 1 18:11:56.573 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:18:15.776 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=8464 systime=601986356
Mar 1 18:18:15.776 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:18:15.776 UTC: htsp_timer - 125 msec
Mar 1 18:18:15.904 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:18:15.904 UTC: htsp_timer - 10000 msec
Mar 1 18:18:15.904 UTC: htsp_timer3 - 5600 msec
Mar 1 18:18:15.904 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:18:15.904 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:18:15.904 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:18:17.864 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=10553 systime=601986564
Mar 1 18:18:17.864 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:18:17.864 UTC: fxols_ringing_not
Mar 1 18:18:17.864 UTC: htsp_timer_stop
Mar 1 18:18:17.864 UTC: htsp_timer - 10000 msec
Mar 1 18:18:21.504 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:18:21.504 UTC: htsp_timer_stop3
Mar 1 18:18:21.768 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=14460 systime=601986955
Mar 1 18:18:21.768 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:18:23.857 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=16547 systime=601987164
Mar 1 18:18:23.857 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:18:23.857 UTC: fxols_ringing_not
Mar 1 18:18:23.857 UTC: htsp_timer_stop
Mar 1 18:18:23.857 UTC: htsp_timer_stop3
Mar 1 18:18:23.857 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:18:23.857 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:18:23.857 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:18 orig called=
Mar 1 18:18:23.861 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:18:23.861 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:18:23.861 UTC: fxols_wait_setup_ack:
Mar 1 18:18:23.861 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:18:23.865 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:18:23.865 UTC: htsp_timer - 120000 msec
Mar 1 18:18:23.941 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:18:23.941 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:18:24.137 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=16822 systime=601987191
Mar 1 18:18:24.137 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:18:24.137 UTC: htsp_timer_stop2
Mar 1 18:18:36.917 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=29607 systime=601988470
Mar 1 18:18:36.917 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:18:36.917 UTC: htsp_timer2 - 350 msec
Mar 1 18:18:37.269 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:18:37.269 UTC: htsp_timer_stop
Mar 1 18:18:37.269 UTC: htsp_timer_stop2
Mar 1 18:18:37.269 UTC: htsp_timer_stop3
Mar 1 18:18:37.269 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:18:37.269 UTC: htsp_timer_stop
Mar 1 18:18:37.269 UTC: htsp_timer_stop2
Mar 1 18:18:37.269 UTC: htsp_timer_stop3
Mar 1 18:18:37.269 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:18:37.269 UTC: htsp_timer - 2000 msec
Mar 1 18:18:37.745 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=30435 systime=601988552
Mar 1 18:18:37.745 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:18:39.269 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:18:39.269 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=30435 systime=601988705
Mar 1 18:18:39.269 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:23:54.542 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=19546 systime=602020231
Mar 1 18:23:54.542 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:23:54.542 UTC: htsp_timer - 125 msec
Mar 1 18:23:54.670 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:23:54.670 UTC: htsp_timer - 10000 msec
Mar 1 18:23:54.670 UTC: htsp_timer3 - 5600 msec
Mar 1 18:23:54.670 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:23:54.670 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:23:54.670 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:23:56.626 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=21633 systime=602020440
Mar 1 18:23:56.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:23:56.626 UTC: fxols_ringing_not
Mar 1 18:23:56.626 UTC: htsp_timer_stop
Mar 1 18:23:56.626 UTC: htsp_timer - 10000 msec
Mar 1 18:24:00.270 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:24:00.270 UTC: htsp_timer_stop3
Mar 1 18:24:00.538 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=25540 systime=602020831
Mar 1 18:24:00.538 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:24:02.626 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=27629 systime=602021040
Mar 1 18:24:02.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:24:02.626 UTC: fxols_ringing_not
Mar 1 18:24:02.626 UTC: htsp_timer_stop
Mar 1 18:24:02.626 UTC: htsp_timer_stop3
Mar 1 18:24:02.626 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:24:02.626 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:24:02.626 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:24 orig called=
Mar 1 18:24:02.630 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:24:02.630 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:24:02.630 UTC: fxols_wait_setup_ack:
Mar 1 18:24:02.630 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:24:02.634 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:24:02.634 UTC: htsp_timer - 120000 msec
Mar 1 18:24:02.706 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:24:02.706 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:24:02.902 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=27907 systime=602021067
Mar 1 18:24:02.902 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:24:02.902 UTC: htsp_timer_stop2
Mar 1 18:24:15.779 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=40784 systime=602022355
Mar 1 18:24:15.779 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:24:15.779 UTC: htsp_timer2 - 350 msec
Mar 1 18:24:16.131 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:24:16.131 UTC: htsp_timer_stop
Mar 1 18:24:16.131 UTC: htsp_timer_stop2
Mar 1 18:24:16.131 UTC: htsp_timer_stop3
Mar 1 18:24:16.131 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:24:16.131 UTC: htsp_timer_stop
Mar 1 18:24:16.131 UTC: htsp_timer_stop2
Mar 1 18:24:16.131 UTC: htsp_timer_stop3
Mar 1 18:24:16.131 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:24:16.131 UTC: htsp_timer - 2000 msec
Mar 1 18:24:16.599 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=41606 systime=602022437
Mar 1 18:24:16.599 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:24:18.131 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:24:18.131 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=41606 systime=602022590
Mar 1 18:24:18.131 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:29:41.628 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=38945 systime=602054939
Mar 1 18:29:41.628 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:29:41.628 UTC: htsp_timer - 125 msec
Mar 1 18:29:41.756 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:29:41.756 UTC: htsp_timer - 10000 msec
Mar 1 18:29:41.756 UTC: htsp_timer3 - 5600 msec
Mar 1 18:29:41.756 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:29:41.756 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:29:41.756 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:29:43.716 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=41034 systime=602055148
Mar 1 18:29:43.716 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:29:43.716 UTC: fxols_ringing_not
Mar 1 18:29:43.716 UTC: htsp_timer_stop
Mar 1 18:29:43.716 UTC: htsp_timer - 10000 msec
Mar 1 18:29:47.356 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:29:47.356 UTC: htsp_timer_stop3
Mar 1 18:29:47.620 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=44941 systime=602055538
Mar 1 18:29:47.620 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:29:49.708 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=47028 systime=602055747
Mar 1 18:29:49.708 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:29:49.708 UTC: fxols_ringing_not
Mar 1 18:29:49.708 UTC: htsp_timer_stop
Mar 1 18:29:49.708 UTC: htsp_timer_stop3
Mar 1 18:29:49.708 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:29:49.708 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:29:49.708 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:29 orig called=
Mar 1 18:29:49.712 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:29:49.712 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:29:49.712 UTC: fxols_wait_setup_ack:
Mar 1 18:29:49.712 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:29:49.716 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:29:49.716 UTC: htsp_timer - 120000 msec
Mar 1 18:29:49.788 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:29:49.788 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:29:49.984 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=47304 systime=602055774
Mar 1 18:29:49.984 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:29:49.984 UTC: htsp_timer_stop2
Mar 1 18:30:01.273 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=58594 systime=602056903
Mar 1 18:30:01.273 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:30:01.273 UTC: htsp_timer2 - 350 msec
Mar 1 18:30:01.625 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:30:01.625 UTC: htsp_timer_stop
Mar 1 18:30:01.625 UTC: htsp_timer_stop2
Mar 1 18:30:01.625 UTC: htsp_timer_stop3
Mar 1 18:30:01.625 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:30:01.625 UTC: htsp_timer_stop
Mar 1 18:30:01.625 UTC: htsp_timer_stop2
Mar 1 18:30:01.625 UTC: htsp_timer_stop3
Mar 1 18:30:01.625 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:30:01.625 UTC: htsp_timer - 2000 msec
Mar 1 18:30:02.105 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=59422 systime=602056986
Mar 1 18:30:02.105 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:30:03.625 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:30:03.625 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=59422 systime=602057138
Mar 1 18:30:03.625 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:35:11.898 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=41532 systime=602087965
Mar 1 18:35:11.898 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:35:11.898 UTC: htsp_timer - 125 msec
Mar 1 18:35:12.026 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:35:12.026 UTC: htsp_timer - 10000 msec
Mar 1 18:35:12.026 UTC: htsp_timer3 - 5600 msec
Mar 1 18:35:12.026 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:35:12.026 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:35:12.026 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:35:13.986 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=43619 systime=602088174
Mar 1 18:35:13.986 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:35:13.986 UTC: fxols_ringing_not
Mar 1 18:35:13.986 UTC: htsp_timer_stop
Mar 1 18:35:13.986 UTC: htsp_timer - 10000 msec
Mar 1 18:35:17.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:35:17.626 UTC: htsp_timer_stop3
Mar 1 18:35:17.918 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=47552 systime=602088567
Mar 1 18:35:17.918 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:35:19.982 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=49615 systime=602088773
Mar 1 18:35:19.982 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:35:19.982 UTC: fxols_ringing_not
Mar 1 18:35:19.982 UTC: htsp_timer_stop
Mar 1 18:35:19.982 UTC: htsp_timer_stop3
Mar 1 18:35:19.982 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:35:19.982 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:35:19.982 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:35 orig called=
Mar 1 18:35:19.982 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:35:19.986 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:35:19.986 UTC: fxols_wait_setup_ack:
Mar 1 18:35:19.986 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:35:19.986 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:35:19.986 UTC: htsp_timer - 120000 msec
Mar 1 18:35:20.062 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:35:20.062 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:35:20.258 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=49892 systime=602088801
Mar 1 18:35:20.258 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:35:20.258 UTC: htsp_timer_stop2
Mar 1 18:35:30.842 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=60477 systime=602089859
Mar 1 18:35:30.842 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:35:30.842 UTC: htsp_timer2 - 350 msec
Mar 1 18:35:31.194 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:35:31.194 UTC: htsp_timer_stop
Mar 1 18:35:31.194 UTC: htsp_timer_stop2
Mar 1 18:35:31.194 UTC: htsp_timer_stop3
Mar 1 18:35:31.194 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:35:31.194 UTC: htsp_timer_stop
Mar 1 18:35:31.194 UTC: htsp_timer_stop2
Mar 1 18:35:31.194 UTC: htsp_timer_stop3
Mar 1 18:35:31.194 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:35:31.194 UTC: htsp_timer - 2000 msec
Mar 1 18:35:31.650 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=61287 systime=602089940
Mar 1 18:35:31.650 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:35:33.194 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:35:33.194 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=61287 systime=602090094
Mar 1 18:35:33.194 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
Mar 1 18:39:41.837 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on
vty0 (10.82.116.50)
Mar 1 18:40:39.451 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=41400 systime=602120719
Mar 1 18:40:39.451 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:40:39.451 UTC: htsp_timer - 125 msec
Mar 1 18:40:39.579 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:40:39.579 UTC: htsp_timer - 10000 msec
Mar 1 18:40:39.579 UTC: htsp_timer3 - 5600 msec
Mar 1 18:40:39.579 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:40:39.579 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:40:39.579 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:40:41.511 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=43462 systime=602120925
Mar 1 18:40:41.511 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:40:41.511 UTC: fxols_ringing_not
Mar 1 18:40:41.511 UTC: htsp_timer_stop
Mar 1 18:40:41.511 UTC: htsp_timer - 10000 msec
Mar 1 18:40:45.179 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:40:45.179 UTC: htsp_timer_stop3
Mar 1 18:40:45.447 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=47395 systime=602121319
Mar 1 18:40:45.447 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:40:47.507 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=49458 systime=602121525
Mar 1 18:40:47.507 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:40:47.507 UTC: fxols_ringing_not
Mar 1 18:40:47.507 UTC: htsp_timer_stop
Mar 1 18:40:47.507 UTC: htsp_timer_stop3
Mar 1 18:40:47.511 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:40:47.511 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:40:47.511 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:40 orig called=
Mar 1 18:40:47.511 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:40:47.511 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:40:47.511 UTC: fxols_wait_setup_ack:
Mar 1 18:40:47.511 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:40:47.515 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:40:47.515 UTC: htsp_timer - 120000 msec
Mar 1 18:40:47.587 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:40:47.587 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:40:47.787 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=49734 systime=602121553
Mar 1 18:40:47.787 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:40:47.787 UTC: htsp_timer_stop2
Mar 1 18:41:03.400 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=65351 systime=602123114
Mar 1 18:41:03.400 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:41:03.400 UTC: htsp_timer2 - 350 msec
Mar 1 18:41:03.752 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:41:03.752 UTC: htsp_timer_stop
Mar 1 18:41:03.752 UTC: htsp_timer_stop2
Mar 1 18:41:03.752 UTC: htsp_timer_stop3
Mar 1 18:41:03.752 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:41:03.752 UTC: htsp_timer_stop
Mar 1 18:41:03.752 UTC: htsp_timer_stop2
Mar 1 18:41:03.752 UTC: htsp_timer_stop3
Mar 1 18:41:03.752 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:41:03.752 UTC: htsp_timer - 2000 msec
Mar 1 18:41:04.212 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=626 systime=602123195
Mar 1 18:41:04.212 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:41:05.752 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:41:05.752 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=626 systime=602123349
Mar 1 18:41:05.752 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
voipgw1#
voipgw1#
voipgw1#
voipgw1#
voipgw1#clear log
Clear logging buffer [confirm]
q70007voipgw1#un all
All possible debugging has been turned off
voipgw1#
voipgw1#
voipgw1#debug vpm signal
Voice Port Module signaling debugging is enabled
voipgw1#
voipgw1#show log
Syslog logging: enabled (0 messages dropped, 37 messages rate-limited, 0
flushes, 0 overruns, xml disabled, filtering disabled)
No Active Message Discriminator.
No Inactive Message Discriminator.
Console logging: disabled
Monitor logging: disabled
Buffer logging: level debugging, 2361 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled
No active filter modules.
Trap logging: level debugging, 5736 message lines logged
Logging to 10.0.7.139 (udp port 514, audit disabled,
link up),
5467 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging to 10.0.8.12 (udp port 514, audit disabled,
link up),
5467 message lines logged,
0 message lines rate-limited,
0 message lines dropped-by-MD,
xml disabled, sequence number disabled
filtering disabled
Logging Source-Interface: VRF Name:
Loopback0
Log Buffer (99999 bytes):
Mar 1 18:48:37.809 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=60999 systime=602168554
Mar 1 18:48:37.809 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0000]fxols_onhook_ringing
Mar 1 18:48:37.809 UTC: htsp_timer - 125 msec
Mar 1 18:48:37.937 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN,
E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Mar 1 18:48:37.937 UTC: htsp_timer - 10000 msec
Mar 1 18:48:37.937 UTC: htsp_timer3 - 5600 msec
Mar 1 18:48:37.937 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE
Mar 1 18:48:37.937 UTC: htsp_start_caller_id_rx create dsp_stream_manager
Mar 1 18:48:37.937 UTC: [0/2/0] htsp_dsm_create_success returns 1
Mar 1 18:48:39.897 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=63087 systime=602168762
Mar 1 18:48:39.897 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:48:39.897 UTC: fxols_ringing_not
Mar 1 18:48:39.897 UTC: htsp_timer_stop
Mar 1 18:48:39.897 UTC: htsp_timer - 10000 msec
Mar 1 18:48:43.537 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop
Mar 1 18:48:43.537 UTC: htsp_timer_stop3
Mar 1 18:48:43.801 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0
timestamp=1458 systime=602169153
Mar 1 18:48:43.801 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0000]
Mar 1 18:48:45.889 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4
timestamp=3544 systime=602169362
Mar 1 18:48:45.889 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING,
E_DSP_SIG_0100]
Mar 1 18:48:45.889 UTC: fxols_ringing_not
Mar 1 18:48:45.889 UTC: htsp_timer_stop
Mar 1 18:48:45.889 UTC: htsp_timer_stop3
Mar 1 18:48:45.889 UTC: [0/2/0] htsp_stop_caller_id_rx. message length
0htsp_setup_ind
Mar 1 18:48:45.889 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed.
parseCallerIDString:no data.
Mar 1 18:48:45.889 UTC: [0/2/0] get_local_station_id calling num= calling
name= calling time=03/01 18:48 orig called=
Mar 1 18:48:45.893 UTC: [0/2/0] htsp_dsm_close_done
Mar 1 18:48:45.893 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK,
E_HTSP_SETUP_ACK]
Mar 1 18:48:45.893 UTC: fxols_wait_setup_ack:
Mar 1 18:48:45.893 UTC: [0/2/0] set signal state = 0xC timestamp =
0fxols_check_auto_call
Mar 1 18:48:45.897 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PROCEEDING]fxols_offhook_proc
Mar 1 18:48:45.897 UTC: htsp_timer - 120000 msec
Mar 1 18:48:45.969 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26
Mar 1 18:48:45.969 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING,
E_HTSP_PRE_CONN_DISC]
Mar 1 18:48:46.169 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=3821 systime=602169390
Mar 1 18:48:46.169 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_0110]
Mar 1 18:48:46.169 UTC: htsp_timer_stop2
Mar 1 18:49:08.958 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC
timestamp=26609 systime=602171668
Mar 1 18:49:08.958 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_DSP_SIG_1100]fxols_offhook_disc
Mar 1 18:49:08.958 UTC: htsp_timer2 - 350 msec
Mar 1 18:49:09.310 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK,
E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Mar 1 18:49:09.310 UTC: htsp_timer_stop
Mar 1 18:49:09.310 UTC: htsp_timer_stop2
Mar 1 18:49:09.310 UTC: htsp_timer_stop3
Mar 1 18:49:09.310 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE,
E_HTSP_RELEASE_REQ]fxols_offhook_release
Mar 1 18:49:09.310 UTC: htsp_timer_stop
Mar 1 18:49:09.310 UTC: htsp_timer_stop2
Mar 1 18:49:09.310 UTC: htsp_timer_stop3
Mar 1 18:49:09.310 UTC: [0/2/0] set signal state = 0x4 timestamp = 0
Mar 1 18:49:09.310 UTC: htsp_timer - 2000 msec
Mar 1 18:49:09.782 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6
timestamp=27438 systime=602171751
Mar 1 18:49:09.782 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_DSP_SIG_0110]
Mar 1 18:49:11.310 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT,
E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Mar 1 18:49:11.310 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4
timestamp=27438 systime=602171904
Mar 1 18:49:11.310 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK,
E_DSP_SIG_0100]
voipgw1#
voipgw1#
voipgw1#
_______________________________________________
cisco-voip mailing list
cisco-voip@puck.nether.net
https://puck.nether.net/mailman/listinfo/cisco-voip
