cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
6854
Views
0
Helpful
11
Replies

FXO : Outgoing calls getting fast busy

georgemaier
Level 1
Level 1

Hello,

I must be doing something wrong, but can't figure out what.

3 POTS lines connected to 3 FXO ports.  Incoming calls seem to work as planned.

Outgoing calls are a different story: when some makes the first outgoing call on an idle system then it rings thu OK , but when a second extension tries to also call out it will get a busy signal most of the time (4 out of 5 times).

The interesting thing is that if the second extension hits "redial" immediately, the calls go though! Any idea why?

Thank you!

Config:

version 12.4

service timestamps debug datetime msec

service timestamps log datetime msec

no service password-encryption

!

hostname cisco

!

boot-start-marker

boot-end-marker

!

logging message-counter syslog

enable secret 5 **************

enable password **************

!

no aaa new-model

clock timezone CST -6

clock summer-time CDT recurring

dot11 syslog

no ip source-route

!

!

ip dhcp excluded-address 10.10.10.1 10.10.10.10

!

ip dhcp pool VOICE

   network 10.10.10.0 255.255.255.0

   default-router 10.10.10.1

   dns-server 4.2.2.2

   option 150 ip 10.10.10.1

!

!

ip cef

no ip domain lookup

ip name-server 208.67.222.222

ip name-server 208.67.220.220

no ipv6 cef

!

multilink bundle-name authenticated

!

stcapp ccm-group 1

stcapp

!

stcapp supplementary-services

port 0/2/0

  fallback-dn 203

!

!

!

!

!

trunk group 1

hunt-scheme round-robin

!

!

!

voice service voip

allow-connections h323 to sip

allow-connections sip to h323

allow-connections sip to sip

fax protocol cisco

sip

  registrar server expires max 36000 min 600

!

!

!

!

!

!

!

!

!

!

!

!

!

!

voice register global

mode cme

source-address 10.10.10.1 port 5060

max-dn 12

max-pool 12

authenticate realm cisco.com

time-format 24

date-format D/M/Y

create profile sync 0042103825165098

!

voice register dn  1

number 222

name Scale Speaker

no-reg

!

voice register pool  1

id mac 0020.F750.0B3A

number 1 dn 1

username 222 password ext222

codec g711ulaw

no vad

!

!

voice translation-rule 4

rule 1 /^9\(1..........\)/ /\1/

rule 2 /^9/ //

!

voice translation-rule 5

rule 1 /^8\(1..........\)/ /\1/

rule 2 /^8/ //

!

!

voice translation-profile POTS

translate called 5

!

!

voice-card 0

!

!

!

!

!

archive

log config

  hidekeys

!

!

!

!

!

!

!

!

!

interface FastEthernet0/0

ip address 192.168.1.210 255.255.255.0

duplex auto

speed auto

no mop enabled

!

interface FastEthernet0/0.10

encapsulation dot1Q 10

ip address 10.10.10.1 255.255.255.0

ip helper-address 192.168.1.210

!

interface Service-Engine0/1

ip unnumbered FastEthernet0/0.10

service-module ip address 10.10.10.2 255.255.255.0

service-module ip default-gateway 10.10.10.1

!

interface FastEthernet0/1

no ip address

shutdown

duplex auto

speed auto

no cdp enable

!

ip forward-protocol nd

ip route 0.0.0.0 0.0.0.0 192.168.1.254

ip route 10.10.10.2 255.255.255.255 Service-Engine0/1

ip http server

no ip http secure-server

ip http path flash:gui

!

!

!

!

!

!

!

!

tftp-server flash:apps11.8-4-1-23.sbn

tftp-server flash:apps31.8-4-1-23.sbn

tftp-server flash:apps37sccp.1-2-1-0.bin

tftp-server flash:apps41.8-4-1-23.sbn

tftp-server flash:apps42.8-4-1-23.sbn

tftp-server flash:apps45.8-4-1-23.sbn

tftp-server flash:apps70.8-4-1-23.sbn

tftp-server flash:apps75.8-4-1-23.sbn

tftp-server flash:cnu11.8-4-1-23.sbn

tftp-server flash:cnu31.8-4-1-23.sbn

tftp-server flash:cnu41.8-4-1-23.sbn

tftp-server flash:cnu42.8-4-1-23.sbn

tftp-server flash:cnu45.8-4-1-23.sbn

tftp-server flash:cnu70.8-4-1-23.sbn

tftp-server flash:cnu75.8-4-1-23.sbn

tftp-server flash:CP7921G-1.2.1.LOADS

tftp-server flash:cvm11sccp.8-4-1-23.sbn

tftp-server flash:cvm31sccp.8-4-1-23.sbn

tftp-server flash:cvm41sccp.8-4-1-23.sbn

tftp-server flash:cvm42sccp.8-4-1-23.sbn

tftp-server flash:cvm45sccp.8-4-1-23.sbn

tftp-server flash:cvm70sccp.8-4-1-23.sbn

tftp-server flash:cvm75sccp.8-4-1-23.sbn

tftp-server flash:dsp11.8-4-1-23.sbn

tftp-server flash:dsp31.8-4-1-23.sbn

tftp-server flash:dsp41.8-4-1-23.sbn

tftp-server flash:dsp42.8-4-1-23.sbn

tftp-server flash:dsp45.8-4-1-23.sbn

tftp-server flash:dsp70.8-4-1-23.sbn

tftp-server flash:dsp75.8-4-1-23.sbn

tftp-server flash:jar11sccp.8-4-1-23.sbn

tftp-server flash:jar31sccp.8-4-1-23.sbn

tftp-server flash:jar41sccp.8-4-1-23.sbn

tftp-server flash:jar42sccp.8-4-1-23.sbn

tftp-server flash:jar45sccp.8-4-1-23.sbn

tftp-server flash:jar70sccp.8-4-1-23.sbn

tftp-server flash:jar75sccp.8-4-1-23.sbn

tftp-server flash:SCCP11.8-4-2S.loads

tftp-server flash:SCCP31.8-4-2S.loads

tftp-server flash:SCCP41.8-4-2S.loads

tftp-server flash:SCCP42.8-4-2S.loads

tftp-server flash:SCCP45.8-4-2S.loads

tftp-server flash:SCCP70.8-4-2S.loads

tftp-server flash:SCCP75.8-4-2S.loads

tftp-server flash:term06.default.loads

tftp-server flash:term11.default.loads

tftp-server flash:term31.default.loads

tftp-server flash:term41.default.loads

tftp-server flash:term42.default.loads

tftp-server flash:term45.default.loads

tftp-server flash:term61.default.loads

tftp-server flash:term62.default.loads

tftp-server flash:term65.default.loads

tftp-server flash:term70.default.loads

tftp-server flash:term71.default.loads

tftp-server flash:term75.default.loads

tftp-server flash:Desktops/320x212x12/CampusNight.png

tftp-server flash:Desktops/320x212x12/CiscoFountain.png

tftp-server flash:Desktops/320x212x12/CiscoLogo.png

tftp-server flash:Desktops/320x212x12/Fountain.png

tftp-server flash:Desktops/320x212x12/List.xml

tftp-server flash:Desktops/320x212x12/MorroRock.png

tftp-server flash:Desktops/320x212x12/NantucketFlowers.png

tftp-server flash:Desktops/320x212x12/TN-CampusNight.png

tftp-server flash:Desktops/320x212x12/TN-CiscoFountain.png

tftp-server flash:Desktops/320x212x12/TN-CiscoLogo.png

tftp-server flash:Desktops/320x212x12/TN-Fountain.png

tftp-server flash:Desktops/320x212x12/TN-MorroRock.png

tftp-server flash:Desktops/320x212x12/TN-NantucketFlowers.png

tftp-server flash:Desktops/320x216x16/List.xml

tftp-server flash:Desktops/320x212x16/List.xml

tftp-server flash:ringtones/Analog1.raw

tftp-server flash:ringtones/Analog2.raw

tftp-server flash:ringtones/AreYouThere.raw

tftp-server flash:ringtones/AreYouThereF.raw

tftp-server flash:ringtones/Bass.raw

tftp-server flash:ringtones/CallBack.raw

tftp-server flash:ringtones/Chime.raw

tftp-server flash:ringtones/Classic1.raw

tftp-server flash:ringtones/Classic2.raw

tftp-server flash:ringtones/ClockShop.raw

tftp-server flash:ringtones/DistinctiveRingList.xml

tftp-server flash:ringtones/Drums1.raw

tftp-server flash:ringtones/Drums2.raw

tftp-server flash:ringtones/FilmScore.raw

tftp-server flash:ringtones/HarpSynth.raw

tftp-server flash:ringtones/Jamaica.raw

tftp-server flash:ringtones/KotoEffect.raw

tftp-server flash:ringtones/MusicBox.raw

tftp-server flash:ringtones/Piano1.raw

tftp-server flash:ringtones/Piano2.raw

tftp-server flash:ringtones/Pop.raw

tftp-server flash:ringtones/Pulse1.raw

tftp-server flash:ringtones/Ring1.raw

tftp-server flash:ringtones/Ring2.raw

tftp-server flash:ringtones/Ring3.raw

tftp-server flash:ringtones/Ring4.raw

tftp-server flash:ringtones/Ring5.raw

tftp-server flash:ringtones/Ring6.raw

tftp-server flash:ringtones/Ring7.raw

tftp-server flash:ringtones/RingList.xml

tftp-server flash:ringtones/Sax1.raw

tftp-server flash:ringtones/Sax2.raw

tftp-server flash:Desktops/320x212x12/CRCLogo.png

tftp-server flash:Desktops/320x212x12/TN-CRCLogo.png

!

control-plane

!

!

!

voice-port 0/1/0

trunk-group 1

pre-dial-delay 0

no vad

timeouts wait-release 5

timing hookflash-out 250

connection plar opx 601

description FXO 630-555-1499

station-id number 6305551499

caller-id enable

!

voice-port 0/1/1

trunk-group 1

pre-dial-delay 0

no vad

timeouts wait-release 5

timing hookflash-out 250

connection plar opx 601

description FXO 630-555-1500

station-id number 6305551500

caller-id enable

!

voice-port 0/1/2

trunk-group 1

pre-dial-delay 0

no vad

timeouts wait-release 5

timing hookflash-out 250

connection plar opx 601

description FXO 630-555-1502

station-id number 6305551502

caller-id enable

!

voice-port 0/1/3

!

voice-port 0/2/0

timeouts ringing infinity

caller-id enable

!

voice-port 0/2/1

!

!

!

sccp local FastEthernet0/0.10

sccp ccm 10.10.10.1 identifier 1 priority 1 version 4.1

sccp

!

sccp ccm group 1

associate ccm 1 priority 1

switchback method graceful

!

!

dial-peer voice 900 pots

trunkgroup 1

description prefix 8 uses POTS line

translation-profile outgoing POTS

destination-pattern 81[2-9].........

prefix ,

no sip-register

!

dial-peer voice 600 voip

description *** Voicemail Pilot ***

destination-pattern 600

session protocol sipv2

session target ipv4:10.10.10.2

dtmf-relay sip-notify

codec g711ulaw

no vad

!

dial-peer voice 10 pots

service stcapp

port 0/2/0

!

dial-peer voice 601 voip

description *** Auto Attendant ***

destination-pattern 601

session protocol sipv2

session target ipv4:10.10.10.2

dtmf-relay sip-notify

codec g711ulaw

no vad

!

dial-peer voice 911 pots

trunkgroup 1

destination-pattern 911

prefix 911

no sip-register

!

dial-peer voice 9911 pots

trunkgroup 1

destination-pattern 9911

forward-digits 3

no sip-register

!

dial-peer voice 8911 pots

trunkgroup 1

destination-pattern 8911

forward-digits 3

no sip-register

!

dial-peer voice 602 voip

description *** AvT ***

destination-pattern 602

session protocol sipv2

session target ipv4:10.10.10.2

dtmf-relay sip-notify

codec g711ulaw

no vad

!

!

sip-ua

!

!

!

telephony-service

authentication credential admin ********

max-ephones 30

max-dn 150

ip source-address 10.10.10.1 port 2000

url services http://10.10.10.2/voiceview/common/login.do

url authentication http://10.10.10.1/CCMCIP/authenticate.asp

load 7916-24 B016-1-0-2

load 7965 SCCP45.8-4-2S.loads

load 7975 SCCP75.8-4-2S.loads

time-zone 8

voicemail 600

max-conferences 4 gain -6

moh music-on-hold.au

web admin system name admin secret 5 **********

dn-webedit

time-webedit

transfer-system full-consult

secondary-dialtone 8

create cnf-files version-stamp 7960 Nov 30 2011 13:22:23

!

!

ephone-dn  11

number 6305551499

label Line1 630-555-1499

name ATT Line 1

!

!

ephone-dn  12

number 6305551500

label Line2 630-555-1500

name ATT Line 2

!

!

ephone-dn  13

number 6305551502

label Line3 630-555-1502

name ATT Line 3

!

!

ephone-dn  21  dual-line

call-waiting ring

number 204

label x204

allow watch

call-forward busy 600

call-forward noan 202 timeout 6

!

!

ephone-dn  22  dual-line

call-waiting ring

number 201

label x201

allow watch

call-forward all 600600

call-forward busy 600

call-forward noan 600 timeout 10

!

!

ephone-dn  23  dual-line

call-waiting ring

number 205

label x205

allow watch

call-forward busy 600

call-forward noan 600 timeout 10

!

!

ephone-dn  24  dual-line

call-waiting ring

number 202

label x202

allow watch

call-forward busy 600

call-forward noan 600 timeout 20

!

!

ephone-dn  25  dual-line

call-waiting ring

number 206

label x206

allow watch

call-forward busy 600

call-forward noan 600 timeout 20

!

!

ephone-dn  26

number 203

label x203 Collection Center

name Collection Center

allow watch

call-forward busy 205

call-forward noan 205 timeout 60

!

!

ephone-dn  27

number 208

label x208

call-forward busy 600

call-forward noan 600 timeout 10

!

!

ephone-dn  28

number 209

label x209

!

!

ephone-dn  30

number 8000...

mwi on

!

!

ephone-dn  31

number 8001...

mwi off

!

!

ephone-dn  50

number 500 no-reg primary

park-slot timeout 30 limit 10

!

!

ephone-dn  51

number 501 no-reg primary

park-slot timeout 30 limit 10

!

!

ephone-dn  52

number 502

park-slot timeout 30 limit 10

!

!

ephone  1

device-security-mode none

description aaa

mac-address E840.40A3.C8AB

username "aaa"

type 7965

button  1:21 2w22 3w23 4w24

button  5w25 6w26

!

!

!

ephone  2

device-security-mode none

description 7975

mac-address ECC8.82B1.88C2

username "bbb"

type 7975 addon 1 7916-24

button  1:22 2w24 3w26 4w21

button  5w23 6w25

!

!

!

ephone  3

device-security-mode none

description ccc

mac-address E840.40A3.C413

username "ccc"

type 7965

button  1:23 2w22 3w24 4w25

button  5w26 6:27

!

!

!

ephone  4

device-security-mode none

description ddd

mac-address E840.40A3.C982

username "ddd"

type 7965

button  1:24 2w22 3w23 4w21

button  5w26

!

!

!

ephone  5

device-security-mode none

description eee

mac-address E840.40A3.C7BD

username "eee"

type 7965

button  1:25 2w22 3w23 4w21

button  5w26 6:27

!

!

!

ephone  6

device-security-mode none

description fff

mac-address E840.40A3.C727

username "fff"

type 7965

button  1:28

!

!

!

ephone  9

device-security-mode none

description Analog Phone

mac-address 5632.2F50.2100

max-calls-per-button 2

type anl

button  1:26

!

!

!

line con 0

exec-timeout 0 0

logging synchronous

line aux 0

line 130

no activation-character

no exec

transport preferred none

transport input all

transport output lat pad telnet rlogin lapb-ta mop udptn v120 ssh

line vty 0 4

password ********

login

!

scheduler allocate 20000 1000

no process cpu extended

no process cpu autoprofile hog

ntp master 1

ntp server 192.43.244.18

ntp server 129.6.15.28

end

11 Replies 11

paolo bevilacqua
Hall of Fame
Hall of Fame

Maybe the ports remained off-hook due to non-working supervisory disconnect, or perhaps there is no dialtone.

Check "show voice-port summary | inc fxo" and "debug vpm signal" with "term mon".

Chris Deren
Hall of Fame
Hall of Fame

Try adding trunk group preferences to the voice-ports, i.e.

voice-port 0/1/0

trunk-group 1 1

!

voice-port 0/1/1

trunk-group 1 2

etc

etc

Chris

Thank you for the replies.

Made the changes suggested by Chris but it made no difference.

We only have 3 pots lines plugged in to 3 of 4 ports on the FXO. Is it possible that CME is trying to use port 0/1/3 (which has nothing plugged into it)?

If I start out like this:

show voice port summary | inc fxo

0/1/0           --  fxo-ls      up    dorm idle     on-hook  y

0/1/1           --  fxo-ls      up    dorm idle     on-hook  y

0/1/2           --  fxo-ls      up    dorm idle     on-hook  y

0/1/3           --  fxo-ls      up    dorm idle     on-hook  y

And I place and outgoing call one of those lines go off hook as expected:

show voice port summary | inc fxo
0/1/0           --  fxo-ls      up    dorm idle     on-hook  y
0/1/1           --  fxo-ls      up    dorm idle     on-hook  y
0/1/2           --  fxo-ls      up    up   idle     off-hook y
0/1/3           --  fxo-ls      up    dorm idle     on-hook  y

Then I tried to place a second call form a diferent extension/phone and get a busy (most of time).

Thanks.

George,

Can you try putting the pre dial delay back to default of 1 second

!
voice-port 0/1/0
no pre-dial-delay
!
!
voice-port 0/1/0
no pre-dial-delay
!
!
voice-port 0/1/2
no pre-dial-delay
!

Regards,
Alex.
Please rate useful posts.

Regards, Alex. Please rate useful posts.

You can solve it with "cable-detect" command if you are running IOS 15.1.1T or newer:

The FXO port is in the busyout state if the Digital Signal Processor (DSP) detects that no cable is connected between the analog Foreign eXchange Subscriber (FXS) and FXO ports. If you have configured the no cable-detect command and the analog FXO voice port is in busyout state because no cable is connected, the Cisco IOS software stops polling the cable connection. The analog FXO voice port remains in the busyout state until you use the shutdownand no shutdowncommands to switch the analog FXO voice port to the idle state. The cable-detect command supports loop start and Central Automatic Message Accounting (CAMA) signaling.

The cable-detect command can be configured on the following analog FXO voice interface cards (VICs):

  • VIC2-4FXO
  • EM-HDA-6FXO
  • EM-HDA-3FXS-4FXO
  • EM-HDA-4FXO

Examples

The following example shows how to enable cable polling on an FXO voice port:

Router> enable

Router# configure terminal

Router(config)# voice-port 1/2/3

Router(config-voiceport)# cable-detect

HTH,

Chris

"no pre-dial-delay" made no difference.

Cable detect won't work I am on 12.4

Here is the output from the debug ( I tried to place a call remotely I have no physical access to the phones at the moment):

cisco#term mon
cisco#debug vpm signal
Voice Port Module signaling debugging is enabled
cisco#csim start 816306775555
csim: called number = 816306775555, loop count = 1 ping count = 0

Jul 24 14:50:01.559: htsp_timer_stop3 htsp_setup_req
Jul 24 14:50:01.563: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]                                              fxols_onhook_setup
Jul 24 14:50:01.563: [0/1/2] set signal state = 0xC timestamp = 0
Jul 24 14:50:01.563: htsp_timer - 1300 msec
Jul 24 14:50:01.835: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG                                              _0110]fxols_disc_clear
Jul 24 14:50:01.835: htsp_timer_stop2
Jul 24 14:50:01.835: htsp_timer - 1300 msec
Jul 24 14:50:02.135: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG                                              _1100]fxols_power_denial_detected
Jul 24 14:50:02.135: htsp_timer2 - 1000 msec
Jul 24 14:50:02.135: htsp_timer_stop
Jul 24 14:50:02.343: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG                                              _0110]fxols_disc_clear
Jul 24 14:50:02.343: htsp_timer_stop2
Jul 24 14:50:02.343: htsp_timer - 1300 msec
Jul 24 14:50:03.643: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_HTSP_EV                                              ENT_TIMER]fxols_wait_dial_timer  htsp_dial
Jul 24 14:50:06.691: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_DONE, E_DSP_DIA                                              LING_DONE]fxols_wait_dial_done htsp_progress
Jul 24 14:50:06.691: htsp_timer - 350 msec
Jul 24 14:50:07.043: htsp_process_event: [0/1/2, FXOLS_WAIT_CUT_THRU, E_HTSP_EVE                                              NT_TIMER]fxols_handle_cut_thru
Jul 24 14:50:07.043: htsp_timer_stop .
csim: loop = 1, failed = 0
csim: call attempted = 1, setup failed = 0, tone failed = 1

cisco#
Jul 24 14:50:30.043: htsp_timer_stop3
Jul 24 14:50:30.043: htsp_process_event: [0/1/2, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 24 14:50:30.043: htsp_timer_stop
Jul 24 14:50:30.043: htsp_timer_stop2
Jul 24 14:50:30.043: htsp_timer_stop3
Jul 24 14:50:30.043: [0/1/2] set signal state = 0x4 timestamp = 0
Jul 24 14:50:30.043: htsp_timer - 2000 msec
Jul 24 14:50:30.315: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
Jul 24 14:50:32.043: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 24 14:50:32.043: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_DSP_SIG_0100]

Seems like there is no service on the line

Jul 24 14:50:02.135: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE,  E_DSP_SIG                                          _1100]fxols_power_denial_detected

Have it tested using a regular phone.

Tested that line (0/1/2) using an old telephone and it has a dial tone to and are able to dial out OK. Also calls placed to the number on that port are answered by the CME just fine.

So is it still possible that the phone line is flaky (or borderline) or possible a bad FXO card?

Thanks for the help so far!

Try updating IOS and check again.

Also try swapping lines, does problem stay with line, or port ?

OK so I had some one try to make outgoing calls and it looks like that "power denial detected" happens on more ports. Have not moved around the lines yet, but getting dial tone and able to dial with a standard phone from all 3 lines.

Version 12.4(24)T6  (as it was recommended by Cisco), though we can upgrade just afraid we may break something else..

cisco#sh voice port summary | inc fxo
0/1/0           --  fxo-ls      up    dorm idle     on-hook  y
0/1/1           --  fxo-ls      up    dorm idle     on-hook  y
0/1/2           --  fxo-ls      up    dorm idle     on-hook  y
0/1/3           --  fxo-ls      up    dorm idle     on-hook  y
Jul 25 16:36:16.709: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:36:16.709: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:36  orig called=
Jul 25 16:36:16.725: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:36:17.429: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:17.817: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:18.157: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:18.381: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:18.729: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:19.097: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:36:19.389: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:19.657: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:19.889: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:20.165: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:36:20.409: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:20.657: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:20.665: htsp_timer_stop3
Jul 25 16:36:20.669: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:36:20.669: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:36:20.669: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:36:20.673: [0/1/0] set signal state = 0xC timestamp = 0
Jul 25 16:36:20.673: htsp_timer - 1300 msec
Jul 25 16:36:20.921: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:36:20.925: htsp_timer2 - 1000 msec
Jul 25 16:36:20.925: htsp_timer_stop
Jul 25 16:36:21.925: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc
Jul 25 16:36:21.925: htsp_timer_stop
Jul 25 16:36:21.925: htsp_timer_stop2
Jul 25 16:36:21.925: [0/1/0] set signal state = 0x4 timestamp = 0
Jul 25 16:36:21.929: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release
Jul 25 16:36:25.913: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:36:25.913: htsp_timer - 10 msec
Jul 25 16:36:25.925: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer
Jul 25 16:36:25.925: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release
Jul 25 16:36:25.925: htsp_timer_stop
Jul 25 16:36:25.925: [50/0/22.1] set signal state = 0x4 timestamp = 0
Jul 25 16:36:30.505: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:36:30.505: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:36  orig called=
Jul 25 16:36:30.513: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:36:34.941: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:36:34.945: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:34.949: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:34.965: htsp_timer_stop3
Jul 25 16:36:34.965: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:36:34.965: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:36:34.969: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:36:34.969: [0/1/1] set signal state = 0xC timestamp = 0
Jul 25 16:36:34.969: htsp_timer - 1300 msec
Jul 25 16:36:35.317: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:36:35.317: htsp_timer2 - 1000 msec
Jul 25 16:36:35.317: htsp_timer_stop
Jul 25 16:36:35.493: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0100]fxols_disc_clear
Jul 25 16:36:35.493: htsp_timer_stop2
Jul 25 16:36:35.493: htsp_timer - 1300 msec
Jul 25 16:36:36.793: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer  htsp_dial
Jul 25 16:36:40.041: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress
Jul 25 16:36:40.041: htsp_timer - 350 msec
Jul 25 16:36:40.045: htsp_call_bridged invoked
Jul 25 16:36:40.045: htsp_call_bridged invokedhtsp_progress_notifyhtsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 0
Jul 25 16:36:40.053: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CALLERID_WAITING]
Jul 25 16:36:40.053: efxs_callerid_update
Jul 25 16:36:40.053: efxs_callerid_update process caller_id_string
Jul 25 16:36:40.053: efxs_callerid_update process caller_id_string OK
Jul 25 16:36:40.053: efxs_callerid_update number= [16307680431] name= []
Jul 25 16:36:40.053: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]efxs_offhook_cutthru
Jul 25 16:36:40.053: [50/0/22.1] set signal state = 0x7 timestamp = 0
Jul 25 16:36:40.057: htsp_process_event: [0/1/1, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru
Jul 25 16:36:40.057: htsp_timer_stop htsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 1
Jul 25 16:36:40.061: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect
Jul 25 16:36:40.061: [50/0/22.1] set signal state = 0x6 timestamp = 0
Jul 25 16:36:40.061: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]
Jul 25 16:36:40.061: efxs_callerid_update
Jul 25 16:36:40.061: efxs_callerid_update process caller_id_string
Jul 25 16:36:40.061: efxs_callerid_update process caller_id_string OK
Jul 25 16:36:40.061: efxs_callerid_update number= [8154161500] name= []
Jul 25 16:36:43.057: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:36:43.057: htsp_timer - 10 msec
Jul 25 16:36:43.069: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req
Jul 25 16:36:43.069: htsp_timer_stop
Jul 25 16:36:43.073: htsp_timer_stop3
Jul 25 16:36:43.073: htsp_timer_stop3
Jul 25 16:36:43.077: htsp_process_event: [50/0/22.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig
Jul 25 16:36:43.089: htsp_process_event: [0/1/1, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:36:43.089: htsp_timer_stop
Jul 25 16:36:43.089: htsp_timer_stop2
Jul 25 16:36:43.089: htsp_timer_stop3
Jul 25 16:36:43.089: [0/1/1] set signal state = 0x4 timestamp = 0
Jul 25 16:36:43.089: htsp_timer - 2000 msec
Jul 25 16:36:45.089: htsp_process_event: [0/1/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:36:45.089: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_DSP_SIG_0100]
Jul 25 16:36:46.865: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:36:46.865: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:36  orig called=
Jul 25 16:36:46.877: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:36:47.741: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:48.169: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:48.569: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:48.729: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:49.101: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:49.397: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:36:49.729: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:36:50.025: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:36:50.273: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:36:50.637: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:36:50.937: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:36:51.245: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:36:51.253: htsp_timer_stop3
Jul 25 16:36:51.257: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:36:51.257: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:36:51.257: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:36:51.257: [0/1/2] set signal state = 0xC timestamp = 0
Jul 25 16:36:51.257: htsp_timer - 1300 msec
Jul 25 16:36:51.529: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0110]fxols_disc_clear
Jul 25 16:36:51.529: htsp_timer_stop2
Jul 25 16:36:51.529: htsp_timer - 1300 msec
Jul 25 16:36:51.613: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:36:51.613: htsp_timer2 - 1000 msec
Jul 25 16:36:51.613: htsp_timer_stop
Jul 25 16:36:51.825: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0110]fxols_disc_clear
Jul 25 16:36:51.825: htsp_timer_stop2
Jul 25 16:36:51.825: htsp_timer - 1300 msec
Jul 25 16:36:53.125: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer  htsp_dial
Jul 25 16:36:56.373: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress
Jul 25 16:36:56.373: htsp_timer - 350 msec
Jul 25 16:36:56.377: htsp_call_bridged invoked
Jul 25 16:36:56.377: htsp_call_bridged invokedhtsp_progress_notifyhtsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 0
Jul 25 16:36:56.385: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CALLERID_WAITING]
Jul 25 16:36:56.385: efxs_callerid_update
Jul 25 16:36:56.385: efxs_callerid_update process caller_id_string
Jul 25 16:36:56.385: efxs_callerid_update process caller_id_string OK
Jul 25 16:36:56.385: efxs_callerid_update number= [16307680431] name= []
Jul 25 16:36:56.385: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]efxs_offhook_cutthru
Jul 25 16:36:56.385: [50/0/22.1] set signal state = 0x7 timestamp = 0
Jul 25 16:36:56.389: htsp_process_event: [0/1/2, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru
Jul 25 16:36:56.389: htsp_timer_stop htsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 1
Jul 25 16:36:56.393: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect
Jul 25 16:36:56.393: [50/0/22.1] set signal state = 0x6 timestamp = 0
Jul 25 16:36:56.393: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]
Jul 25 16:36:56.393: efxs_callerid_update
Jul 25 16:36:56.393: efxs_callerid_update process caller_id_string
Jul 25 16:36:56.393: efxs_callerid_update process caller_id_string OK
Jul 25 16:36:56.393: efxs_callerid_update number= [8154161502] name= []
Jul 25 16:36:59.505: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:36:59.505: htsp_timer - 10 msec
Jul 25 16:36:59.517: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req
Jul 25 16:36:59.517: htsp_timer_stop
Jul 25 16:36:59.521: htsp_timer_stop3
Jul 25 16:36:59.521: htsp_timer_stop3
Jul 25 16:36:59.525: htsp_process_event: [50/0/22.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig
Jul 25 16:36:59.541: htsp_process_event: [0/1/2, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:36:59.541: htsp_timer_stop
Jul 25 16:36:59.541: htsp_timer_stop2
Jul 25 16:36:59.541: htsp_timer_stop3
Jul 25 16:36:59.541: [0/1/2] set signal state = 0x4 timestamp = 0
Jul 25 16:36:59.541: htsp_timer - 2000 msec
Jul 25 16:36:59.810: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
Jul 25 16:37:00.174: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:37:00.174: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:37  orig called=
Jul 25 16:37:00.182: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:37:00.830: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:01.174: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:01.498: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:37:01.542: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:37:01.542: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_DSP_SIG_0100]
Jul 25 16:37:01.658: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:37:02.034: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:02.334: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:37:02.646: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:37:02.926: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:03.166: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:03.462: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:03.758: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:37:04.010: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:04.018: htsp_timer_stop3
Jul 25 16:37:04.018: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:37:04.018: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:37:04.022: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:37:04.022: [0/1/0] set signal state = 0xC timestamp = 0
Jul 25 16:37:04.022: htsp_timer - 1300 msec
Jul 25 16:37:04.274: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:37:04.274: htsp_timer2 - 1000 msec
Jul 25 16:37:04.274: htsp_timer_stop
Jul 25 16:37:05.274: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc
Jul 25 16:37:05.274: htsp_timer_stop
Jul 25 16:37:05.274: htsp_timer_stop2
Jul 25 16:37:05.274: [0/1/0] set signal state = 0x4 timestamp = 0
Jul 25 16:37:05.278: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release
Jul 25 16:37:09.406: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:09.406: htsp_timer - 10 msec
Jul 25 16:37:09.418: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer
Jul 25 16:37:09.418: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release
Jul 25 16:37:09.418: htsp_timer_stop
Jul 25 16:37:09.418: [50/0/22.1] set signal state = 0x4 timestamp = 0
Jul 25 16:37:10.374: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:37:10.374: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:37  orig called=
Jul 25 16:37:10.382: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:37:11.090: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:11.382: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:11.702: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:37:11.914: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:37:12.314: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:12.630: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:12.926: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:13.190: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:13.394: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:13.742: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:14.014: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:37:14.282: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:14.290: htsp_timer_stop3
Jul 25 16:37:14.290: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:37:14.290: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:37:14.294: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:37:14.294: [0/1/1] set signal state = 0xC timestamp = 0
Jul 25 16:37:14.294: htsp_timer - 1300 msec
Jul 25 16:37:14.814: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:37:14.814: htsp_timer2 - 1000 msec
Jul 25 16:37:14.814: htsp_timer_stop
Jul 25 16:37:14.994: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0100]fxols_disc_clear
Jul 25 16:37:14.994: htsp_timer_stop2
Jul 25 16:37:14.994: htsp_timer - 1300 msec
Jul 25 16:37:16.294: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer  htsp_dial
Jul 25 16:37:19.542: htsp_process_event: [0/1/1, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress
Jul 25 16:37:19.542: htsp_timer - 350 msec
Jul 25 16:37:19.546: htsp_call_bridged invoked
Jul 25 16:37:19.546: htsp_call_bridged invokedhtsp_progress_notifyhtsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 0
Jul 25 16:37:19.554: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CALLERID_WAITING]
Jul 25 16:37:19.554: efxs_callerid_update
Jul 25 16:37:19.554: efxs_callerid_update process caller_id_string
Jul 25 16:37:19.554: efxs_callerid_update process caller_id_string OK
Jul 25 16:37:19.554: efxs_callerid_update number= [16304041071] name= []
Jul 25 16:37:19.554: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]efxs_offhook_cutthru
Jul 25 16:37:19.554: [50/0/22.1] set signal state = 0x7 timestamp = 0
Jul 25 16:37:19.558: htsp_process_event: [0/1/1, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru
Jul 25 16:37:19.558: htsp_timer_stop htsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 1
Jul 25 16:37:19.562: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect
Jul 25 16:37:19.562: [50/0/22.1] set signal state = 0x6 timestamp = 0
Jul 25 16:37:19.562: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]
Jul 25 16:37:19.562: efxs_callerid_update
Jul 25 16:37:19.562: efxs_callerid_update process caller_id_string
Jul 25 16:37:19.562: efxs_callerid_update process caller_id_string OK
Jul 25 16:37:19.562: efxs_callerid_update number= [8154161500] name= []
Jul 25 16:37:21.238: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:21.238: htsp_timer - 10 msec
Jul 25 16:37:21.250: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req
Jul 25 16:37:21.250: htsp_timer_stop
Jul 25 16:37:21.254: htsp_timer_stop3
Jul 25 16:37:21.254: htsp_timer_stop3
Jul 25 16:37:21.258: htsp_process_event: [50/0/22.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig
Jul 25 16:37:21.270: htsp_process_event: [0/1/1, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:37:21.270: htsp_timer_stop
Jul 25 16:37:21.270: htsp_timer_stop2
Jul 25 16:37:21.270: htsp_timer_stop3
Jul 25 16:37:21.270: [0/1/1] set signal state = 0x4 timestamp = 0
Jul 25 16:37:21.270: htsp_timer - 2000 msec
Jul 25 16:37:22.034: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:37:22.034: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:37  orig called=
Jul 25 16:37:22.046: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:37:22.874: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:23.190: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:23.270: htsp_process_event: [0/1/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:37:23.270: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_DSP_SIG_0100]
Jul 25 16:37:23.494: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:37:23.662: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:37:24.014: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:24.446: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:24.718: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:24.934: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:25.150: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:25.466: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:25.718: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:37:25.970: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:25.978: htsp_timer_stop3
Jul 25 16:37:25.978: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:37:25.978: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:37:25.982: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:37:25.982: [0/1/2] set signal state = 0xC timestamp = 0
Jul 25 16:37:25.982: htsp_timer - 1300 msec
Jul 25 16:37:26.254: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0110]fxols_disc_clear
Jul 25 16:37:26.254: htsp_timer_stop2
Jul 25 16:37:26.254: htsp_timer - 1300 msec
Jul 25 16:37:26.414: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:37:26.414: htsp_timer2 - 1000 msec
Jul 25 16:37:26.414: htsp_timer_stop
Jul 25 16:37:26.626: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_0110]fxols_disc_clear
Jul 25 16:37:26.626: htsp_timer_stop2
Jul 25 16:37:26.626: htsp_timer - 1300 msec
Jul 25 16:37:27.926: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer  htsp_dial
Jul 25 16:37:31.174: htsp_process_event: [0/1/2, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress
Jul 25 16:37:31.174: htsp_timer - 350 msec
Jul 25 16:37:31.178: htsp_call_bridged invoked
Jul 25 16:37:31.178: htsp_call_bridged invokedhtsp_progress_notifyhtsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 0
Jul 25 16:37:31.182: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CALLERID_WAITING]
Jul 25 16:37:31.182: efxs_callerid_update
Jul 25 16:37:31.186: efxs_callerid_update process caller_id_string
Jul 25 16:37:31.186: efxs_callerid_update process caller_id_string OK
Jul 25 16:37:31.186: efxs_callerid_update number= [16304041071] name= []
Jul 25 16:37:31.186: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]efxs_offhook_cutthru
Jul 25 16:37:31.186: [50/0/22.1] set signal state = 0x7 timestamp = 0
Jul 25 16:37:31.190: htsp_process_event: [0/1/2, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru
Jul 25 16:37:31.190: htsp_timer_stop htsp_call_feature:feature 25
htsp_call_feature: caller id enable 0x3 call_connected 1
Jul 25 16:37:31.194: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_CONNECT]efxs_offhook_connect
Jul 25 16:37:31.194: [50/0/22.1] set signal state = 0x6 timestamp = 0
Jul 25 16:37:31.194: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_CALLERID_WAITING]
Jul 25 16:37:31.194: efxs_callerid_update
Jul 25 16:37:31.194: efxs_callerid_update process caller_id_string
Jul 25 16:37:31.194: efxs_callerid_update process caller_id_string OK
Jul 25 16:37:31.194: efxs_callerid_update number= [8154161502] name= []
Jul 25 16:37:33.034: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:33.038: htsp_timer - 10 msec
Jul 25 16:37:33.050: htsp_process_event: [50/0/22.1, EFXS_CONNECT, E_HTSP_EVENT_TIMER]efxs_connect_wait_release_req
Jul 25 16:37:33.050: htsp_timer_stop
Jul 25 16:37:33.054: htsp_timer_stop3
Jul 25 16:37:33.054: htsp_timer_stop3
Jul 25 16:37:33.058: htsp_process_event: [50/0/22.1, EFXS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]efxs_waitrls_req_rlshtsp_report_onhook_sig
Jul 25 16:37:33.070: htsp_process_event: [0/1/2, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:37:33.070: htsp_timer_stop
Jul 25 16:37:33.070: htsp_timer_stop2
Jul 25 16:37:33.070: htsp_timer_stop3
Jul 25 16:37:33.070: [0/1/2] set signal state = 0x4 timestamp = 0
Jul 25 16:37:33.070: htsp_timer - 2000 msec
Jul 25 16:37:33.342: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
Jul 25 16:37:33.890: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:37:33.890: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:37  orig called=
Jul 25 16:37:33.898: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:37:34.574: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:34.882: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:35.070: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:37:35.070: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_DSP_SIG_0100]
Jul 25 16:37:35.186: htsp_digit_ready(50/0/22.1): digit = 6
Jul 25 16:37:35.362: htsp_digit_ready(50/0/22.1): digit = 3
Jul 25 16:37:36.234: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:36.526: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:36.802: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:37.070: htsp_digit_ready(50/0/22.1): digit = 4
Jul 25 16:37:37.290: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:37.618: htsp_digit_ready(50/0/22.1): digit = 0
Jul 25 16:37:37.846: htsp_digit_ready(50/0/22.1): digit = 7
Jul 25 16:37:38.098: htsp_digit_ready(50/0/22.1): digit = 1
Jul 25 16:37:38.102: htsp_timer_stop3
Jul 25 16:37:38.106: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding
Jul 25 16:37:38.106: [50/0/22.1] set signal state = 0x8 timestamp = 0htsp_setup_req
Jul 25 16:37:38.106: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
Jul 25 16:37:38.106: [0/1/0] set signal state = 0xC timestamp = 0
Jul 25 16:37:38.110: htsp_timer - 1300 msec
Jul 25 16:37:38.358: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_DSP_SIG_1100]fxols_power_denial_detected
Jul 25 16:37:38.358: htsp_timer2 - 1000 msec
Jul 25 16:37:38.358: htsp_timer_stop
Jul 25 16:37:39.358: htsp_process_event: [0/1/0, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER2]fxols_power_den_disc
Jul 25 16:37:39.358: htsp_timer_stop
Jul 25 16:37:39.358: htsp_timer_stop2
Jul 25 16:37:39.358: [0/1/0] set signal state = 0x4 timestamp = 0
Jul 25 16:37:39.362: htsp_process_event: [0/1/0, FXOLS_ONHOOK, E_HTSP_RELEASE_REQ]fxols_onhook_release
Jul 25 16:37:40.702: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:40.702: htsp_timer - 10 msec
Jul 25 16:37:40.714: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer
Jul 25 16:37:40.714: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release
Jul 25 16:37:40.714: htsp_timer_stop
Jul 25 16:37:40.714: [50/0/22.1] set signal state = 0x4 timestamp = 0
Jul 25 16:37:41.354: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind
Jul 25 16:37:41.354: [50/0/22.1] get_local_station_id calling num=201 calling name=Mike calling time=07/25 11:37  orig called=
Jul 25 16:37:41.362: htsp_process_event: [50/0/22.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call
Jul 25 16:37:42.122: htsp_digit_ready(50/0/22.1): digit = 8
Jul 25 16:37:43.294: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:43.294: htsp_timer - 10 msec
Jul 25 16:37:43.294: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_DSP_SIG_0100]efxs_offhook_onhook
Jul 25 16:37:43.294: htsp_timer - 10 msec
Jul 25 16:37:43.306: htsp_process_event: [50/0/22.1, EFXS_OFFHOOK, E_HTSP_EVENT_TIMER]efxs_offhook_timer
Jul 25 16:37:43.306: htsp_process_event: [50/0/22.1, EFXS_ONHOOK, E_HTSP_RELEASE_REQ]efxs_onhook_release
Jul 25 16:37:43.306: htsp_timer_stop
Jul 25 16:37:43.306: [50/0/22.1] set signal state = 0x4 timestamp = 0
Jul 25 16:39:49.340: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing
Jul 25 16:39:49.340: htsp_timer - 125 msec
Jul 25 16:39:49.468: htsp_process_event: [0/1/1, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Jul 25 16:39:49.468: htsp_timer - 10000 msec
Jul 25 16:39:49.468: htsp_timer3 - 5600 msec
Jul 25 16:39:49.468: [0/1/1] htsp_start_caller_id_rx:BELLCORE
Jul 25 16:39:49.468: htsp_start_caller_id_rx create dsp_stream_manager
Jul 25 16:39:49.468: [0/1/1] htsp_dsm_create_success  returns 1
Jul 25 16:39:51.272: htsp_process_event: [0/1/1, FXOLS_RINGING, E_DSP_SIG_0110]
Jul 25 16:39:51.416: htsp_process_event: [0/1/1, FXOLS_RINGING, E_DSP_SIG_0100]
Jul 25 16:39:51.416: fxols_ringing_not
Jul 25 16:39:51.416: htsp_timer_stop
Jul 25 16:39:51.416: htsp_timer - 10000 msec
Jul 25 16:39:52.720: [0/1/1] htsp_dsm_feature_notify_cb  returns 2 id=DSM_FEATURE_SM_CALLERID_RX
Jul 25 16:39:52.720: htsp_process_event: [0/1/1, FXOLS_RINGING, E_HTSP_CALLERID_RX_DONE]
Jul 25 16:39:52.720: htsp_timer_stop
Jul 25 16:39:52.720: htsp_timer_stop3
Jul 25 16:39:52.720: [0/1/1] htsp_stop_caller_id_rx. message length 42htsp_setup_ind
Jul 25 16:39:52.720: [0/1/1] get_fxo_caller_id:Caller ID received. Message type=128 length=42 checksum=57
Jul 25 16:39:52.720: [0/1/1] Caller ID String 80 27 01 08 30 37 32 35 31 31 33 39 02 0A 36 33 30 34 30 34 31 30 37 31 07 0F 57 49 52 45 4C 45 53 53 20 43 41 4C 4C 45 52 57
Jul 25 16:39:52.720: [0/1/1] get_fxo_caller_id calling num=6304041071 calling name=WIRELESS CALLER calling time=07/25 11:39
Jul 25 16:39:52.720: fxols_callerid_done: call being answered
Jul 25 16:39:52.728: htsp_process_event: [0/1/1, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
Jul 25 16:39:52.728: fxols_wait_setup_ack:
Jul 25 16:39:52.728: htsp_timer - 6000 msec
Jul 25 16:39:52.736: htsp_process_event: [0/1/1, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc
Jul 25 16:39:52.740: [0/1/1] htsp_dsm_close_donehtsp_alert_notify
Jul 25 16:39:52.772: htsp_process_event: [0/1/1, FXOLS_PROCEEDING, E_HTSP_ALERT]fxols_offhook_alert
Jul 25 16:39:52.812: htsp_call_bridged invoked
Jul 25 16:39:52.820: htsp_process_event: [0/1/1, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_connect
Jul 25 16:39:52.820: [0/1/1] set signal state = 0xC timestamp = 0
Jul 25 16:39:52.820: htsp_timer_stop
Jul 25 16:39:52.820: htsp_process_event: [0/1/1, FXOLS_CONNECT, E_HTSP_VOICE_CUT_THROUGH]fxols_connect_proc_voice
Jul 25 16:39:53.460: htsp_process_event: [0/1/1, FXOLS_CONNECT, E_DSP_SIG_1100]fxols_offhook_disc
Jul 25 16:39:53.460: htsp_timer2 - 350 msec
Jul 25 16:39:53.812: htsp_process_event: [0/1/1, FXOLS_CONNECT, E_HTSP_EVENT_TIMER2]fxols_disc_confirm
Jul 25 16:39:53.812: htsp_timer_stop
Jul 25 16:39:53.812: htsp_timer_stop2
Jul 25 16:39:53.812: htsp_timer_stop3
Jul 25 16:39:53.816: htsp_timer_stop3
Jul 25 16:39:53.840: htsp_process_event: [0/1/1, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:39:53.840: htsp_timer_stop
Jul 25 16:39:53.840: htsp_timer_stop2
Jul 25 16:39:53.840: htsp_timer_stop3
Jul 25 16:39:53.840: [0/1/1] set signal state = 0x4 timestamp = 0
Jul 25 16:39:53.844: htsp_timer - 2000 msec
Jul 25 16:39:55.844: htsp_process_event: [0/1/1, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:39:55.844: htsp_process_event: [0/1/1, FXOLS_ONHOOK, E_DSP_SIG_0100]
Jul 25 16:40:39.429: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing
Jul 25 16:40:39.429: htsp_timer - 125 msec
Jul 25 16:40:39.557: htsp_process_event: [0/1/2, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
Jul 25 16:40:39.557: htsp_timer - 10000 msec
Jul 25 16:40:39.557: htsp_timer3 - 5600 msec
Jul 25 16:40:39.557: [0/1/2] htsp_start_caller_id_rx:BELLCORE
Jul 25 16:40:39.557: htsp_start_caller_id_rx create dsp_stream_manager
Jul 25 16:40:39.557: [0/1/2] htsp_dsm_create_success  returns 1
Jul 25 16:40:41.529: htsp_process_event: [0/1/2, FXOLS_RINGING, E_DSP_SIG_0100]
Jul 25 16:40:41.529: fxols_ringing_not
Jul 25 16:40:41.529: htsp_timer_stop
Jul 25 16:40:41.529: htsp_timer - 10000 msec
Jul 25 16:40:42.729: [0/1/2] htsp_dsm_feature_notify_cb  returns 2 id=DSM_FEATURE_SM_CALLERID_RX
Jul 25 16:40:42.729: htsp_process_event: [0/1/2, FXOLS_RINGING, E_HTSP_CALLERID_RX_DONE]
Jul 25 16:40:42.729: htsp_timer_stop
Jul 25 16:40:42.729: htsp_timer_stop3
Jul 25 16:40:42.729: [0/1/2] htsp_stop_caller_id_rx. message length 42htsp_setup_ind
Jul 25 16:40:42.729: [0/1/2] get_fxo_caller_id:Caller ID received. Message type=128 length=42 checksum=5F
Jul 25 16:40:42.729: [0/1/2] Caller ID String 80 27 01 08 30 37 32 35 31 31 34 30 02 0A 36 33 30 34 30 34 31 30 37 31 07 0F 57 49 52 45 4C 45 53 53 20 43 41 4C 4C 45 52 5F
Jul 25 16:40:42.729: [0/1/2] get_fxo_caller_id calling num=6304041071 calling name=WIRELESS CALLER calling time=07/25 11:40
Jul 25 16:40:42.729: fxols_callerid_done: call being answered
Jul 25 16:40:42.737: htsp_process_event: [0/1/2, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
Jul 25 16:40:42.737: fxols_wait_setup_ack:
Jul 25 16:40:42.737: htsp_timer - 6000 msec
Jul 25 16:40:42.749: htsp_process_event: [0/1/2, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc
Jul 25 16:40:42.749: [0/1/2] htsp_dsm_close_donehtsp_alert_notify
Jul 25 16:40:42.821: htsp_process_event: [0/1/2, FXOLS_PROCEEDING, E_HTSP_ALERT]fxols_offhook_alert
Jul 25 16:40:42.865: htsp_call_bridged invoked
Jul 25 16:40:42.869: htsp_process_event: [0/1/2, FXOLS_PROCEEDING, E_HTSP_VOICE_CUT_THROUGH]fxols_proc_voice
Jul 25 16:40:42.873: htsp_process_event: [0/1/2, FXOLS_PROCEEDING, E_HTSP_CONNECT]fxols_offhook_connect
Jul 25 16:40:42.873: [0/1/2] set signal state = 0xC timestamp = 0
Jul 25 16:40:42.873: htsp_timer_stop
Jul 25 16:40:43.145: htsp_process_event: [0/1/2, FXOLS_CONNECT, E_DSP_SIG_0110]fxols_rvs_battery
Jul 25 16:40:43.145: htsp_timer_stop2
Jul 25 16:40:43.145: htsp_timer_stop2
Jul 25 16:41:07.762: htsp_digit_ready(0/1/2): digit = 2
Jul 25 16:41:10.870: htsp_timer_stop3
Jul 25 16:41:10.894: htsp_process_event: [0/1/2, FXOLS_CONNECT, E_HTSP_RELEASE_REQ]fxols_offhook_release
Jul 25 16:41:10.894: htsp_timer_stop
Jul 25 16:41:10.894: htsp_timer_stop2
Jul 25 16:41:10.894: htsp_timer_stop3
Jul 25 16:41:10.894: [0/1/2] set signal state = 0x4 timestamp = 0
Jul 25 16:41:10.894: htsp_timer - 2000 msec
Jul 25 16:41:11.166: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
Jul 25 16:41:12.894: htsp_process_event: [0/1/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
Jul 25 16:41:12.894: htsp_process_event: [0/1/2, FXOLS_ONHOOK, E_DSP_SIG_0100]

As you said, it might be a marginal line.

If you update, you might not solve, but you gain lots of features and usability, nothing will break.

Getting Started

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