Support
Community

skirschplex · ‎05-12-2016

After doing some further reading I'm not pretty sure there's a much easier way to do this... I've been trying to pull some numbers out of the PRI trunk to copper ports.

I have this AdTran registered as a Endpoint with my VoIP provider. Since I was originally unsure of how to do this otherwise I decided to pull two numbers out of that trunk and point them at our main gateway, and Asterisk server. I then set up two SIP users on the FXS ports to register to our asterisk box.

We can call out from those ports but inbound calls result in 486 BUSY HERE.

Looking over things again I'm starting to think there is probably a more elegant way to to point certain DIDs to the FXS ports I want from the PRI, instead of this ridiculous workaround that is not actually working. But I am unclear on how to do this....

If that's not the case, here is my current config:

Any help would be greatly appreciated!

!

! ADTRAN, Inc. OS version A4.11.00.E

! Boot ROM version 14.04.00

! Platform: Total Access 916 (2nd Gen), part number 4212916L1

! Serial number CFG1326470

!

hostname "XXXXXXXXXXXXXXXX"

enable password XXXXXXXXXXXXXXXX

!

clock timezone -5-Eastern-Time

!

ip subnet-zero

ip classless

ip default-gateway XXXXXXXXXXXXXXXX

ip routing

!

ip name-server XXXXXXXXXXXXXXXX XXXXXXXXXXXXXXXX

!

no auto-config

!

event-history on

no logging forwarding

no logging email

!

no service password-encryption

!

username "XXXXXXXXXXXXXXXX" password "XXXXXXXXXXXXXXXX"

!

no ip firewall alg msn

no ip firewall alg mszone

no ip firewall alg h323

!

no dot11ap access-point-control

!

interface eth 0/1

ip address XXXXXXXXXXXXXXXX 255.255.255.252

media-gateway ip primary

no awcp

no shutdown

!

interface t1 0/1

shutdown

!

interface t1 0/2

tdm-group 1 timeslots 1-24 speed 64

no shutdown

!

interface pri 1

description PRI to PBX

isdn name-delivery setup

connect t1 0/2 tdm-group 1

role network b-channel-restarts disable

no shutdown

!

interface fxs 0/1

no shutdown

!

interface fxs 0/2

no shutdown

!

interface fxs 0/3

no shutdown

!

interface fxs 0/4

no shutdown

!

interface fxs 0/5

no shutdown

!

interface fxs 0/6

no shutdown

!

interface fxs 0/7

no shutdown

!

interface fxs 0/8

no shutdown

!

interface fxs 0/9

no shutdown

!

interface fxs 0/10

no shutdown

!

interface fxs 0/11

no shutdown

!

interface fxs 0/12

no shutdown

!

interface fxs 0/13

no shutdown

!

interface fxs 0/14

no shutdown

!

interface fxs 0/15

no shutdown

!

interface fxs 0/16

no shutdown

!

isdn-group 1

connect pri 1

!

ip route 0.0.0.0 0.0.0.0 XXXXXXXXXXXXXXXX

!

no ip tftp server

no ip tftp server overwrite

ip http server

no ip http secure-server

no ip snmp agent

no ip ftp server

no ip scp server

no ip sntp server

!

ip sip

ip sip udp 5060

no ip sip tcp

!

voice feature-mode network

voice forward-mode network

!

voice dial-plan 1 local NXX-XXXX

voice dial-plan 3 international 011-$

voice dial-plan 4 long-distance 1-NXX-NXX-XXXX

!

voice codec-list "PRI to PBX"

codec g729

codec g711ulaw

!

voice trunk T01 type sip

description "vi1"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local"

sip-server primary XXXXXXXXXXXXXXXX

codec-group "PRI to PBX"

!

voice trunk T02 type sip

description "vi2"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local"

sip-server primary XXXXXXXXXXXXXXXX

!

voice trunk T03 type isdn

description "PRI to PBX"

resource-selection circular descending

connect isdn-group 1

modem-passthrough

t38

rtp delay-mode adaptive

rtp dtmf-relay inband

codec-group "PRI to PBX"

!

voice trunk T04 type sip

description "fxS15-SIP"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local prefix"

sip-server primary XXXXXXXXXXXXXXXX

registrar primary XXXXXXXXXXXXXXXX

outbound-proxy primary XXXXXXXXXXXXXXXX

authentication username "XXXXXXXXXXXXXXXX" password "XXXXXXXXXXXXXXXX"

!

voice trunk T05 type sip

description "fxS16-SIP"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local prefix"

sip-server primary XXXXXXXXXXXXXXXX

registrar primary XXXXXXXXXXXXXXXX

outbound-proxy primary XXXXXXXXXXXXXXXX

authentication username "XXXXXXXXXXXXXXXX" password "XXXXXXXXXXXXXXXX"

!

voice grouped-trunk "PRI TO PBX"

description "PRI to PBX"

trunk T03

accept $ cost 0

!

voice grouped-trunk "SIP VI"

description "SIP Trunk"

trunk T01

trunk T02

accept $ cost 0

!

voice grouped-trunk STANDALONE-PORTS

trunk T04

trunk T05

accept NXX-XXXX cost 0

accept 1-NXX-NXX-XXXX cost 0

accept 1-800-NXX-XXXX cost 0

accept 1-888-NXX-XXXX cost 0

accept 1-877-NXX-XXXX cost 0

accept 1-866-NXX-XXXX cost 0

accept 1-855-NXX-XXXX cost 0

accept 011-$ cost 0

accept 411 cost 0

accept 611 cost 0

accept 911 cost 0

accept 0-NXX-NXX-XXXX cost 0

accept 10-10-XXX-$ cost 0

reject 976-XXXX

reject 1-900-NXX-XXXX

reject 1-976-NXX-XXXX

!

voice user 9901

connect fxs 0/15

first-name "XXXXXXXXXXXXXXXX"

last-name "XXXXXXXXXXXXXXXX"

password "XXXXXXXXXXXXXXXX"

num-rings 9

sip-identity XXXXXXXXXXXXXXXX T04 register auth-name "XXXXXXXXXXXXXXXX" password "XXXXXXXXXXXXXXXX"

sip-authentication password "XXXXXXXXXXXXXXXX"

t38

codec-group "PRI to PBX"

!

voice user 9902

connect fxs 0/16

first-name "XXXXXXXXXXXXXXXX"

last-name "XXXXXXXXXXXXXXXX"

password "XXXXXXXXXXXXXXXX"

sip-identity XXXXXXXXXXXXXXXX T05 register auth-name "XXXXXXXXXXXXXXXX" password "XXXXXXXXXXXXXXXX"

sip-authentication password "XXXXXXXXXXXXXXXX"

t38

codec-group "PRI to PBX"

!

line con 0

login

!

line telnet 0 4

login

password XXXXXXXXXXXXXXXX

no shutdown

line ssh 0 4

login local-userlist

no shutdown

!

ntp source ethernet 0/1

ntp server XXXXXXXXXXXXXXXX version 3 source ethernet 0/1 prefer

!

end

Anonymous · ‎05-17-2016

Sam, direct TDM to TDM connection is not supported. You would need to route the numbers to a PBX before routing back to the 900. Thanks

Jay

skirschplex · ‎05-26-2016

Hello Jay, thanks for the reply!

Can you elaborate on that?

I have gotten things working a bit better and more streamlined. I can call into all the FXS ports I just cannot call out, I guess a 'Busy Here' message. This is my current configuration;

!

! ADTRAN, Inc. OS version A4.11.00.E

! Boot ROM version 14.04.00

! Platform: Total Access 916 (2nd Gen), part number 4212916L1

! Serial number CFG1326470

!

hostname "XXXXXXXXXXXXXXXXXXXX"

enable password XXXXXXXXXXXXXXXXXXXX

!

clock timezone -5-Eastern-Time

!

ip subnet-zero

ip classless

ip default-gateway XXXXXXXXXXXXXXXXXXXX

ip routing

!

ip name-server XXXXXXXXXXXXXXXXXXXX XXXXXXXXXXXXXXXXXXXX

!

no auto-config

!

event-history on

no logging forwarding

no logging email

!

no service password-encryption

!

username "admin" password "XXXXXXXXXXXXXXXXXXXX"

!

no ip firewall alg msn

no ip firewall alg mszone

no ip firewall alg h323

!

no dot11ap access-point-control

!

interface eth 0/1

ip address XXXXXXXXXXXXXXXXXXXX 255.255.255.252

media-gateway ip primary

no awcp

no shutdown

!

interface t1 0/1

shutdown

!

interface t1 0/2

tdm-group 1 timeslots 1-24 speed 64

no shutdown

!

interface pri 1

description PRI to PBX

isdn name-delivery setup

connect t1 0/2 tdm-group 1

role network b-channel-restarts disable

no shutdown

!

interface fxs 0/1

no shutdown

!

interface fxs 0/2

no shutdown

!

interface fxs 0/3

no shutdown

!

interface fxs 0/4

no shutdown

!

interface fxs 0/5

no shutdown

!

interface fxs 0/6

no shutdown

!

interface fxs 0/7

no shutdown

!

interface fxs 0/8

no shutdown

!

interface fxs 0/9

no shutdown

!

interface fxs 0/10

no shutdown

!

interface fxs 0/11

no shutdown

!

interface fxs 0/12

no shutdown

!

interface fxs 0/13

no shutdown

!

interface fxs 0/14

no shutdown

!

interface fxs 0/15

no shutdown

!

interface fxs 0/16

no shutdown

!

isdn-group 1

connect pri 1

!

ip route 0.0.0.0 0.0.0.0 XXXXXXXXXXXXXXXXXXXX

!

no ip tftp server

no ip tftp server overwrite

ip http server

no ip http secure-server

no ip snmp agent

no ip ftp server

no ip scp server

no ip sntp server

!

ip sip

ip sip udp 5060

no ip sip tcp

!

voice feature-mode network

voice forward-mode network

!

voice dial-plan 1 local NXX-XXXX

voice dial-plan 3 international 011-$

voice dial-plan 4 long-distance 1-NXX-NXX-XXXX

!

voice codec-list "PRI to PBX"

codec g729

codec g711ulaw

!

voice trunk T01 type sip

description "vi1"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local"

sip-server primary XXXXXXXXXXXXXXXXXXXX

codec-group "PRI to PBX"

!

voice trunk T02 type sip

description "vi2"

match dnis "NXX-XXXX" substitute "1-607-NXX-XXXX" name "local"

sip-server primary XXXXXXXXXXXXXXXXXXXX

!

voice trunk T03 type isdn

description "PRI to PBX"

resource-selection circular descending

connect isdn-group 1

modem-passthrough

t38

rtp delay-mode adaptive

rtp dtmf-relay inband

codec-group "PRI to PBX"

!

voice grouped-trunk "PRI TO PBX"

description "PRI to PBX"

trunk T03

accept $ cost 0

!

voice grouped-trunk "SIP VI"

description "SIP Trunk"

trunk T01

trunk T02

accept $ cost 0

!

voice user 9901

connect fxs 0/15

first-name "XXXX"

last-name "XXXX"

password "1234"

caller-id-override external-number nnnnnn7603

caller-id-override internal-name "XXXX"

caller-id-override internal-number nnnnnn7603

did "nnnnnn7603"

sip-authentication password "1234"

modem-passthrough

t38

codec-group "PRI to PBX"

!

voice user 9902

connect fxs 0/16

first-name "XXXX"

last-name "XXXX"

password "1234"

caller-id-override external-number nnnnnn7439

caller-id-override internal-name "XXXX"

caller-id-override internal-number nnnnnn7439

did "nnnnnn7439"

sip-authentication password "1234"

modem-passthrough

t38

codec-group "PRI to PBX"

!

voice user 9903

connect fxs 0/14

first-name "XXXX"

last-name "pXXXX"

password "1234"

caller-id-override external-number nnnnnn4673

caller-id-override internal-name "XXXX"

caller-id-override internal-number nnnnnn4673

did "nnnnnn4673"

sip-authentication password "1234"

modem-passthrough

t38

codec-group "PRI to PBX"

!

line con 0

login

!

line telnet 0 4

login

password XXXXXXXXXXXXXXXXXXXX

no shutdown

line ssh 0 4

login local-userlist

no shutdown

!

ntp source ethernet 0/1

ntp server XXXXXXXXXXXXXXXXXXXX version 3 source ethernet 0/1 prefer

!

end

skirschplex · ‎05-26-2016

It does look like its trying to route the call from the FXS port into the ISDN PRI port. Is that what you were referring to with TDM to TDM?

I have a few options for how I can do this. I'd like to make it all work through the Adtran but if its not possible we can drop some smaller Cisco SPA122s. But here is my setup and what I have at my disposal:

This Adtran is setup as an Endpoint through my VoIP Wholesaler. You've seen the rest of the config.

I have an asterisk server set up as a separate endpoint through the same wholesaler. Would I be able to set up the FXS ports as entirely stand alone SIP users? I can take the three DIDs they want on the FXS ports and point them at the other endpoint. I tried this before with one of the lines and it didn't seem to work properly, but maybe I flubbed something up there. What would be the best way to have these two separate trunks working in tandem on the same unit?

Here is my debug info:

16:14:40.814 PM.0:14 Idle	Processed OFFHOOK
16:14:40.815 PM.0:14 State change	>> Idle->Requesting Dialtone
16:14:40.815 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(ON) from PM
16:14:40.816 SA.9903 Ca:0 State change	>> Idle->DigitGathering
16:14:40.816 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_ReqDigits) to PM

16:14:40.817 PM.0:14 Requesting Dialtone CACHG:ReqDigits on primary CA

16:14:40.817 PM.0:14 State change

>> Requesting Dialtone->SendingDigits

16:14:40.819 TONESERVICES.EVENTS fxs 0/14 - empty - Tone Detection: Request resource

16:14:40.820 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: DSP channel allocated for the resource

16:14:40.821 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: constructed

16:14:40.821 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: starting

16:14:40.822 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM map

16:14:41.321 TONESERVICES.EVENTS fxs 0/14 - empty - DialTone Generation: Request resource

16:14:41.322 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: DSP channel allocated for the resource

16:14:41.322 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: constructed

16:14:41.323 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: starting

16:14:41.323 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM map

16:14:44.079 PM.0:14 SendingDigits

Digit 2 processed

16:14:44.080 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:14:44.081 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: stopping

16:14:44.082 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM unmap

16:14:44.082 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - DialTone Generation: releasing RTP resource

16:14:44.083 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: release

16:14:44.084 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:14:44.084 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 second(s) for more digits
16:14:44.369 PM.0:14 SendingDigits	Digit 2 processed

16:14:44.371 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:14:44.371 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:14:44.372 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:44.589 PM.0:14 SendingDigits	Digit 2 processed

16:14:44.591 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:14:44.591 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:14:44.592 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:44.969 PM.0:14 SendingDigits	Digit 5 processed

16:14:44.970 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (5) event

16:14:44.971 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(5) from PM
16:14:44.972 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:45.309 PM.0:14 SendingDigits	Digit 3 processed

16:14:45.311 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:14:45.311 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:14:45.312 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:45.770 PM.0:14 SendingDigits	Digit 9 processed

16:14:45.771 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (9) event

16:14:45.771 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(9) from PM
16:14:45.772 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:46.189 PM.0:14 SendingDigits	Digit 3 processed

16:14:46.190 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:14:46.191 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:14:46.192 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:14:50.192 SA.9903 Ca:0 State change	>> DigitGathering->DigitGathering
16:14:50.193 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_Active) to PM
16:14:50.194 PM.0:14 State change	>> SendingDigits->Call Pending
16:14:50.195 SA.9903 Ca:0 DigitGathering	sent: call to SB
16:14:50.195 SA.9903 Ca:0 State change	>> DigitGathering->CallPending

16:14:50.196 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: stopping

16:14:50.197 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM unmap

16:14:50.198 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Tone Detection: releasing RTP resource

16:14:50.198 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: release

16:14:50.199 SA.9903 rcvd: AcctPhoneMgr_billingCode from PM

16:14:50.199 SB.CALL 184 Idle

Called the call routine with 2225393

16:14:50 SB.TGMgr For dialed number 2225393, against template $, on TrunkGroup PRI TO PBX, the score is 500

16:14:50 SB.TGMgr For dialed number 2225393, against template $, on TrunkGroup SIP VI, the score is 500

16:14:50.201 SB.CCM isMappable:

16:14:50.202 SB.CCM : Call Struct 0x2952e10 : Call-ID = 184

16:14:50.202 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:14:50.203 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:14:50.203 SB.CCM : Org TID = Fxs Dst TID =

16:14:50.204 SB.CCM isMappable: Call Connection Type is TDM_TO_TDM

16:14:50.205 SB.CALL 184 Idle	Call sent from 9903 to T03 (2225393)
16:14:50.205 SB.CALL 184 State change	>> Idle->Delivering
16:14:50.206 SA.9903 Ca:0 CallPending	rcvd: callResponse from SB
16:14:50.206 SA.9903 Ca:0 State change	>> CallPending->Calling
16:14:50.207 TA.T03 07 State change	>> TAIdle->TAOutGoing (TAS_Delivering)

16:14:50.208 TM.T03 07 tachg_Delivering

16:14:50.209 TM.T03 07 IsdnTmStateIdle->IsdnTmStateOutboundDeliver

16:14:50.210 TM.T03 07 IsdnTmStateOutboundDeliver::enter()

16:14:50.211 ISDN.L2_FMT PRI 1 ==============================================

16:14:50.212 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:57 Nr:5 P:0

16:14:50.212 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:006C
16:14:50.212 ISDN.L2_FMT PRI 1	M - 05 SETUP
16:14:50.213 ISDN.L2_FMT PRI 1	IE - 04 BEARER CAPABILITY Len=3
16:14:50.213 ISDN.L2_FMT PRI 1	80 Xfer Cap.:SPEECH
16:14:50.213 ISDN.L2_FMT PRI 1	90 Xfer Rate:64k
16:14:50.214 ISDN.L2_FMT PRI 1	A2 Layer 1:u-Law
16:14:50.214 ISDN.L2_FMT PRI 1	IE - 18 CHANNEL ID	Len=3
16:14:50.214 ISDN.L2_FMT PRI 1	A1 Primary Rate
16:14:50.215 ISDN.L2_FMT PRI 1	Intfc ID:IMPLICIT
16:14:50.215 ISDN.L2_FMT PRI 1	Pref/Excl:PREFERRED
16:14:50.215 ISDN.L2_FMT PRI 1	D-Chan Indicated:NO
16:14:50.216 ISDN.L2_FMT PRI 1	Chan. Sel:FOLLOWS
16:14:50.216 ISDN.L2_FMT PRI 1	83 Numb/Map:NUMBER
16:14:50.216 ISDN.L2_FMT PRI 1	87 Channel:7
16:14:50.217 ISDN.L2_FMT PRI 1	IE - 1C FACILITY	Len=28
16:14:50.217 ISDN.L2_FMT PRI 1	Calling Name: XXXXXXXXXXXXXXXXXXX
16:14:50.218 ISDN.L2_FMT PRI 1	IE - 6C CALLING PARTY #	Len=12
16:14:50.218 ISDN.L2_FMT PRI 1	00 Numb. Type:UNKNOWN
16:14:50.218 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:14:50.219 ISDN.L2_FMT PRI 1	80 Presentation:ALLOWED
16:14:50.219 ISDN.L2_FMT PRI 1	Ph.# 6073044673
16:14:50.220 ISDN.L2_FMT PRI 1	IE - 70 CALLED PARTY #	Len=8
16:14:50.220 ISDN.L2_FMT PRI 1	80 Numb. Type:UNKNOWN
16:14:50.220 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:14:50.221 ISDN.L2_FMT PRI 1	Ph.# 2225393
16:14:50.221 SB.CALL 184 Delivering	Called the deliverResponse routine from Delivering
16:14:50.222 SB.CALL 184 Delivering	DeliverResponse(accept) sent from T03 to 9903
16:14:50.223 SA.9903 Ca:0 Calling	rcvd: deliverResponse from SB
16:14:50.223 SA.9903 Ca:0 Calling	sent: AcctPhoneMgr_info to PM

16:14:50.224 PM.0:14 ERROR! APM_Info ignored

16:14:50.336 ISDN.L2_FMT PRI 1 ==============================================

16:14:50.337 ISDN.L2_FMT PRI 1 Recd Sapi:00 C/R:R Tei:00 INFO Ns:5 Nr:58 P:0

16:14:50.337 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:806C
16:14:50.337 ISDN.L2_FMT PRI 1	M - 5A RELEASE_CMP
16:14:50.338 ISDN.L2_FMT PRI 1	IE - 08 CAUSE	Len=2
16:14:50.338 ISDN.L2_FMT PRI 1	80 Location:U
16:14:50.339 ISDN.L2_FMT PRI 1	91 Cause:17 (USER_BUSY)

16:14:50.349 TM.T03 07 IsdnTmStateOutboundDeliver->IsdnTmStateTrunkClearing

16:14:50.349 TM.T03 07 IsdnTmStateTrunkClearing::enter()

16:14:50.350 TM.T03 07 IsdnTmStateTrunkClearing->IsdnTmStateIdling

16:14:50.351 TM.T03 07 IsdnTmStateIdling::enter()

16:14:50.352 TM.T03 07 IsdnTmStateIdling - send appearance off

16:14:50.352 TM.T03 07 IsdnTmStateIdling->IsdnTmStateIdle

16:14:50.353 TM.T03 07 IsdnAppearanceChannel::releaseChannel

16:14:50.353 TM.T03 07 IsdnTmStateIdle::enter()

16:14:50.354 TA.T03 07 TAOutGoing	rcvd: clear from TM
16:14:50.355 TA.T03 07 State change	>> TAOutGoing->TATrunkClearing (TAS_Clearing)

16:14:50.355 TM.T03 07 IsdnTmStateIdle::tachgClearing - send appearance off

16:14:50.356 TA.T03 07 TATrunkClearing	rcvd: clear from TM
16:14:50.357 TA.T03 07 State change	>> TATrunkClearing->TAClearingComplete (TAS_Clearing)

16:14:50.357 TA.T03 07 TAClearingComplete rcvd: appearance off from TM

16:14:50.358 TA.T03 07 TAClearingComplete Clear Local Variables

16:14:50.358 TA.T03 07 State change	>> TAClearingComplete->TAIdle (TAS_Idle)
16:14:50.359 SB.CALL 184 Delivering	Called the clearCall routine from Delivering
16:14:50.360 SB.CALL 184 Delivering	Clearing due to Trunk Clear Reason on call from T03 to 9903
16:14:50.361 SB.CALL 184 State change	>> Delivering->Clearing
16:14:50.361 TA.T03 07 TAIdle	rcvd: appearance off from TM
16:14:50.362 TA.T03 07 TAIdle	Processing an appearance OFF
16:14:50.363 SA.9903 Ca:0 Calling	rcvd: clearCall from SB
16:14:50.363 SA.9903 Ca:0 Calling	sent: clearResponse(pass) to SB
16:14:50.364 SA.9903 Ca:0 State change	>> Calling->Idle
16:14:50.364 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_faxModem to PM
16:14:50.365 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:14:50.365 PM.0:14 Call Pending	Pri CA, Idle response, App OFF sent
16:14:50.366 PM.0:14 State change	>> Call Pending->Clearing Busy
16:14:50.367 SB.CALL 184 Clearing	Called the clearResponse routine
16:14:50.368 SB.CALL 184 State change	>> Clearing->CallIdlePending

16:14:50.369 SB.CCM release:

16:14:50.369 SB.CCM : Call Struct 0x2952e10 : Call-ID = 184

16:14:50.370 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:14:50.371 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:14:50.371 SB.CCM : Org TID = Fxs Dst TID = Isdn Group Table

16:14:50.372 SB.CCM release: Call Connection Type is TDM_TO_TDM

16:14:50.372 SB.CALL 184 CallIdlePending	ClearResponse sent from 9903 to T03
16:14:50.373 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(OFF) from PM
16:14:50.374 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:14:50.374 PM.0:14 Clearing Busy	Dropped CACHG w/Call State not RINGING

16:14:50.375 TONESERVICES.EVENTS fxs 0/14 - empty - Busy Generation: Request resource

16:14:50.376 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: DSP channel allocated for the resource

16:14:50.376 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: constructed

16:14:50.377 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: starting

16:14:50.377 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM map

16:14:50.378 TA.T03 clearResponse event rejected, no matching CallID

2016.05.26 16:14:50 TM.T03 07 IsdnTmStateIdling - clear trunk appearance

2016.05.26 16:14:51 SMDR 184	05/26/2016 16:14:50	0.0 0	I 00/14 XXXXXXXXXXXXXXXXXXX 6073044673	00/00 T03	2225393	0 N
16:14:55.642 PM.0:14 Clearing Busy	Processed OnHook
16:14:55.643 PM.0:14 State change	>> Clearing Busy->Idle

16:14:55.644 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: stopping

16:14:55.645 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM unmap

16:14:55.645 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Busy Generation: releasing RTP resource

16:14:55.646 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: release

16:14:55.647 SA.9903 rcvd: AcctPhoneMgr_COSOverride from PM

16:14:58.525 PM.0:14 Idle	Processed OFFHOOK
16:14:58.525 PM.0:14 State change	>> Idle->Requesting Dialtone
16:14:58.526 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(ON) from PM
16:14:58.526 SA.9903 Ca:0 State change	>> Idle->DigitGathering
16:14:58.527 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_ReqDigits) to PM

16:14:58.527 PM.0:14 Requesting Dialtone CACHG:ReqDigits on primary CA

16:14:58.528 PM.0:14 State change

>> Requesting Dialtone->SendingDigits

16:14:58.530 TONESERVICES.EVENTS fxs 0/14 - empty - Tone Detection: Request resource

16:14:58.531 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: DSP channel allocated for the resource

16:14:58.531 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: constructed

16:14:58.532 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: starting

16:14:58.533 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM map

16:14:59.031 TONESERVICES.EVENTS fxs 0/14 - empty - DialTone Generation: Request resource

16:14:59.032 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: DSP channel allocated for the resource

16:14:59.033 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: constructed

16:14:59.033 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: starting

16:14:59.034 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM map

16:14:59.591 PM.0:14 SendingDigits	Processed ONHOOK
16:14:59.592 PM.0:14 State change	>> SendingDigits->Idle
16:14:59.594 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_appearance(OFF) from PM
16:14:59.594 SA.9903 Ca:0 State change	>> DigitGathering->Idle
16:14:59.595 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_faxModem to PM
16:14:59.595 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:14:59.596 PM.0:14 Idle	Dropped CACHG w/Call State not RINGING

16:14:59.597 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: stopping

16:14:59.597 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM unmap

16:14:59.598 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - DialTone Generation: releasing RTP resource

16:14:59.599 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: release

16:14:59.599 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: stopping

16:14:59.600 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM unmap

16:14:59.600 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Tone Detection: releasing RTP resource

16:14:59.601 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: release

16:14:59.602 SA.9903 rcvd: AcctPhoneMgr_COSOverride from PM

16:15:10.647 PM.0:14 Idle	Processed OFFHOOK
16:15:10.648 PM.0:14 State change	>> Idle->Requesting Dialtone
16:15:10.648 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(ON) from PM
16:15:10.649 SA.9903 Ca:0 State change	>> Idle->DigitGathering
16:15:10.649 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_ReqDigits) to PM

16:15:10.650 PM.0:14 Requesting Dialtone CACHG:ReqDigits on primary CA

16:15:10.651 PM.0:14 State change

>> Requesting Dialtone->SendingDigits

16:15:10.653 TONESERVICES.EVENTS fxs 0/14 - empty - Tone Detection: Request resource

16:15:10.653 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: DSP channel allocated for the resource

16:15:10.654 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: constructed

16:15:10.655 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: starting

16:15:10.655 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM map

16:15:11.154 TONESERVICES.EVENTS fxs 0/14 - empty - DialTone Generation: Request resource

16:15:11.155 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: DSP channel allocated for the resource

16:15:11.155 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: constructed

16:15:11.156 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: starting

16:15:11.156 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM map

16:15:12.631 PM.0:14 SendingDigits

Digit 1 processed

16:15:12.632 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (1) event

16:15:12.632 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: stopping

16:15:12.633 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM unmap

16:15:12.633 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - DialTone Generation: releasing RTP resource

16:15:12.634 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: release

16:15:12.635 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(1) from PM
16:15:12.635 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 second(s) for more digits
16:15:13.370 PM.0:14 SendingDigits	Digit 6 processed

16:15:13.371 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (6) event

16:15:13.372 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(6) from PM
16:15:13.372 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:13.800 PM.0:14 SendingDigits	Digit 0 processed

16:15:13.801 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (0) event

16:15:13.801 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(0) from PM
16:15:13.802 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:14.160 PM.0:14 SendingDigits	Digit 7 processed

16:15:14.161 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (7) event

16:15:14.161 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(7) from PM
16:15:14.162 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:14.740 PM.0:14 SendingDigits	Digit 2 processed

16:15:14.741 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:14.741 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:14.742 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:15.070 PM.0:14 SendingDigits	Digit 2 processed

16:15:15.071 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:15.072 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:15.072 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:15.350 PM.0:14 SendingDigits	Digit 2 processed

16:15:15.351 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:15.352 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:15.352 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:16.130 PM.0:14 SendingDigits	Digit 5 processed

16:15:16.131 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (5) event

16:15:16.132 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(5) from PM
16:15:16.132 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:17.940 PM.0:14 SendingDigits	Processed ONHOOK
16:15:17.941 PM.0:14 State change	>> SendingDigits->Idle
16:15:17.942 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_appearance(OFF) from PM
16:15:17.942 SA.9903 Ca:0 State change	>> DigitGathering->Idle
16:15:17.943 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_faxModem to PM
16:15:17.943 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:15:17.944 PM.0:14 Idle	Dropped CACHG w/Call State not RINGING

16:15:17.945 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: stopping

16:15:17.945 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM unmap

16:15:17.946 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Tone Detection: releasing RTP resource

16:15:17.947 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: release

16:15:17.947 SA.9903 rcvd: AcctPhoneMgr_COSOverride from PM

16:15:18.072 PM.0:14 Idle	Processed OFFHOOK
16:15:18.073 PM.0:14 State change	>> Idle->Requesting Dialtone
16:15:18.073 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(ON) from PM
16:15:18.074 SA.9903 Ca:0 State change	>> Idle->DigitGathering
16:15:18.074 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_ReqDigits) to PM

16:15:18.075 PM.0:14 Requesting Dialtone CACHG:ReqDigits on primary CA

16:15:18.075 PM.0:14 State change

>> Requesting Dialtone->SendingDigits

16:15:18.077 TONESERVICES.EVENTS fxs 0/14 - empty - Tone Detection: Request resource

16:15:18.078 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: DSP channel allocated for the resource

16:15:18.078 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: constructed

16:15:18.079 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: starting

16:15:18.080 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM map

16:15:18.579 TONESERVICES.EVENTS fxs 0/14 - empty - DialTone Generation: Request resource

16:15:18.579 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: DSP channel allocated for the resource

16:15:18.580 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: constructed

16:15:18.581 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: starting

16:15:18.582 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM map

16:15:19.408 ISDN.L2_FMT PRI 1 ==============================================

16:15:19.408 ISDN.L2_FMT PRI 1 Recd Sapi:00 C/R:R Tei:00 INFO Ns:6 Nr:58 P:0

16:15:19.408 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:02F2
16:15:19.409 ISDN.L2_FMT PRI 1	M - 05 SETUP
16:15:19.409 ISDN.L2_FMT PRI 1	IE - 04 BEARER CAPABILITY Len=3
16:15:19.410 ISDN.L2_FMT PRI 1	80 Xfer Cap.:SPEECH
16:15:19.410 ISDN.L2_FMT PRI 1	90 Xfer Rate:64k
16:15:19.411 ISDN.L2_FMT PRI 1	A2 Layer 1:u-Law
16:15:19.411 ISDN.L2_FMT PRI 1	IE - 18 CHANNEL ID	Len=3
16:15:19.412 ISDN.L2_FMT PRI 1	A9 Primary Rate
16:15:19.412 ISDN.L2_FMT PRI 1	Intfc ID:IMPLICIT
16:15:19.412 ISDN.L2_FMT PRI 1	Pref/Excl:EXCLUSIVE
16:15:19.413 ISDN.L2_FMT PRI 1	D-Chan Indicated:NO
16:15:19.413 ISDN.L2_FMT PRI 1	Chan. Sel:FOLLOWS
16:15:19.413 ISDN.L2_FMT PRI 1	83 Numb/Map:NUMBER
16:15:19.414 ISDN.L2_FMT PRI 1	96 Channel:22
16:15:19.414 ISDN.L2_FMT PRI 1	IE - 1E PROGRESS INDICATOR Len=2
16:15:19.415 ISDN.L2_FMT PRI 1	81 Location:LPN
16:15:19.415 ISDN.L2_FMT PRI 1	83 Description:ORIG. NOT ISDN
16:15:19.415 ISDN.L2_FMT PRI 1	IE - 6C CALLING PARTY #	Len=12
16:15:19.416 ISDN.L2_FMT PRI 1	21 Numb. Type:NATIONAL
16:15:19.416 ISDN.L2_FMT PRI 1	Numb. Plan:E.164
16:15:19.416 ISDN.L2_FMT PRI 1	80 Presentation:ALLOWED
16:15:19.417 ISDN.L2_FMT PRI 1	Ph.# 6072387077
16:15:19.417 ISDN.L2_FMT PRI 1	IE - 70 CALLED PARTY #	Len=8
16:15:19.418 ISDN.L2_FMT PRI 1	80 Numb. Type:UNKNOWN
16:15:19.418 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:15:19.418 ISDN.L2_FMT PRI 1	Ph.# 2395254
16:15:19.419 ISDN.L2_FMT PRI 1	IE - 7C LOW LAYER COMPAT. Len=3
16:15:19.419 ISDN.L2_FMT PRI 1	80 Info Xfer Cap:Speech.
16:15:19.419 ISDN.L2_FMT PRI 1	90 Octet 4
16:15:19.420 ISDN.L2_FMT PRI 1	A2 Octet 5
16:15:19.420 ISDN.L2_FMT PRI 1	IE - 7D HIGH LAYER COMPAT. Len=2
16:15:19.420 ISDN.L2_FMT PRI 1	91 Coding Standard:CCITT.
16:15:19.421 ISDN.L2_FMT PRI 1	Interpretation:Octet 4 only.
16:15:19.421 ISDN.L2_FMT PRI 1	Pres Method:High Layer Prot Profile
16:15:19.422 ISDN.L2_FMT PRI 1	81 High Layer Char ID:Telephony.

16:15:19.423 ISDN.L2_FMT PRI 1 ==============================================

16:15:19.424 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:58 Nr:7 P:0

16:15:19.424 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:82F2
16:15:19.425 ISDN.L2_FMT PRI 1	M - 02 CALL_PROC
16:15:19.425 ISDN.L2_FMT PRI 1	IE - 18 CHANNEL ID	Len=3
16:15:19.426 ISDN.L2_FMT PRI 1	A9 Primary Rate
16:15:19.426 ISDN.L2_FMT PRI 1	Intfc ID:IMPLICIT
16:15:19.426 ISDN.L2_FMT PRI 1	Pref/Excl:EXCLUSIVE
16:15:19.427 ISDN.L2_FMT PRI 1	D-Chan Indicated:NO
16:15:19.427 ISDN.L2_FMT PRI 1	Chan. Sel:FOLLOWS
16:15:19.427 ISDN.L2_FMT PRI 1	83 Numb/Map:NUMBER
16:15:19.428 ISDN.L2_FMT PRI 1	96 Channel:22

16:15:19.428 TM.T03 22 en bloc sending call rec'd

16:15:19.429 TM.T03 22 IsdnTmStateIdle->IsdnTmStateInboundAccept

16:15:19.430 TM.T03 22 IsdnTmStateInboundAccept::enter() send call to acct

16:15:19.431 TA.T03 22 TAIdle	rcvd: inboundCall from TM
16:15:19.431 TA.T03 22 State change	>> TAIdle->TAInboundCall (TAS_Calling)

16:15:19.432 TA.T03 22 Failed - DID translation: no match for 2395254, using 2395254

16:15:19.433 TA.T03 22 TAIdle

sent: call to SB

16:15:19.434 TM.T03 22 tachg_TAInboundCall do nothing

16:15:19.434 SB.CALL 185 Idle

Called the call routine with 2395254

16:15:19 SB.TGMgr For dialed number 2395254, against template $, on TrunkGroup PRI TO PBX, the score is 500

16:15:19 SB.TGMgr For dialed number 2395254, against template $, on TrunkGroup SIP VI, the score is 500

16:15:19.437 SB.CCM isMappable:

16:15:19.437 SB.CCM : Call Struct 0x2984c10 : Call-ID = 185

16:15:19.438 SB.CCM : Org Acct = T03

Dst Acct = T01

16:15:19.438 SB.CCM : Org Port ID = 0/2.22 Dst Port ID = 0/0.0

16:15:19.439 SB.CCM : Org TID = Ds1Ds0Usage Dst TID =

16:15:19.439 SB.CCM isMappable: Call Connection Type is TDM_TO_RTP

16:15:19.440 SB.CCM isMappable: Reserving RTP Channel 0/1.3

16:15:19.441 SB.CCM isMappable: Creating SDP Offer

16:15:19.443 SB.CCM getBestMatchCodecList: G729 PCMU

16:15:19.445 SB.CALL 185 Idle	Call sent from T03 to T01 (2395254)
16:15:19.446 SB.CALL 185 State change	>> Idle->Delivering

16:15:19.446 RTP.MANAGER t1 0/2.0.22 - empty - RTP: Request resource

16:15:19.447 RTP.MANAGER t1 0/2.0.22 - dsp 0/1.3 - RTP: DSP channel allocated for the resource

16:15:19.448 RTP.PROVIDER t1 0/2.0.22 - dsp 0/1.3 - RTP: providing already allocated RTP channel

16:15:19.448 TA.T03 22 TAInboundCall	CallResp event accepted
16:15:19.449 TA.T03 22 State change	>> TAInboundCall->TAConnectWaitIn (TAS_Calling)
16:15:19.450 TA.T01 33 State change	>> TAIdle->TAOutGoing (TAS_Delivering)
16:15:19.451 TM.T01 33 SipTM_Idle	State change	>> SipTM_Idle->SipTM_Delivering

16:15:19.452 TA.T01 33 DNIS Substitution: dialed number 2395254 -> 16072395254

16:15:19.452 TA.T01 33 DNIS Substitution: dialed number 2395254 -> dialed name "local"

16:15:19.454 TM.T01 33 call-leg (0x259eac0) -> src-ip: XXX.XXX.XXX.XXX dst-ip: 64.136.174.30

16:15:19.456 TM.T01 33 Undo of previous operation not required (RTP NAT Entry for 127.0.0.2:10004 not found)

16:15:19.456 TM.T01 33 Checking for internal Media Gateway IP Address

16:15:19.457 TM.T01 33 Using RTP Channel 0/1.3

16:15:19.457 TM.T01 33 Inserting XXX.XXX.XXX.XXX into SDP for Media Gateway on interface eth 0/1

16:15:19.459 TM.T01 33 Adding RTP Media Gateway Entry: 127.0.0.2:10004 -> XXX.XXX.XXX.XXX:10004

16:15:19.459 TM.T01 33 No action taken, firewall traversal is not enabled

16:15:19.464 SIP.STACK MSG

Tx: UDP src=XXX.XXX.XXX.XXX:5060 dst=64.136.174.30:5060

16:15:19.464 SIP.STACK MSG	INVITE sip:16072395254@64.136.174.30:5060 SIP/2.0
16:15:19.465 SIP.STACK MSG	From: <sip:6072387077@64.136.174.30:5060;transport=UDP>;tag=25843b0-7f000001-13c4-d092-512dd0d9-d092
16:15:19.465 SIP.STACK MSG	To: "local" <sip:16072395254@64.136.174.30:5060>
16:15:19.466 SIP.STACK MSG	Call-ID: 259eac0-7f000001-13c4-d092-390708b9-d092@64.136.174.30
16:15:19.466 SIP.STACK MSG	CSeq: 1 INVITE
16:15:19.467 SIP.STACK MSG	Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK-d092-32ebe19-1b069291
16:15:19.467 SIP.STACK MSG	Max-Forwards: 70
16:15:19.468 SIP.STACK MSG	Supported: 100rel,replaces
16:15:19.468 SIP.STACK MSG	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
16:15:19.469 SIP.STACK MSG	User-Agent: ADTRAN_Total_Access_916_2nd_Gen/A4.11.00.E
16:15:19.470 SIP.STACK MSG	Contact: <sip:6072387077@XXX.XXX.XXX.XXX:5060;transport=UDP>
16:15:19.470 SIP.STACK MSG	Content-Type: application/sdp
16:15:19.471 SIP.STACK MSG	Content-Length: 208
16:15:19.471 SIP.STACK MSG
16:15:19.472 SIP.STACK MSG	v=0
16:15:19.472 SIP.STACK MSG	o=- 1464293719 1464293719 IN IP4 XXX.XXX.XXX.XXX
16:15:19.473 SIP.STACK MSG	s=-
16:15:19.473 SIP.STACK MSG	c=IN IP4 XXX.XXX.XXX.XXX
16:15:19.474 SIP.STACK MSG	t=0 0
16:15:19.474 SIP.STACK MSG	m=audio 10004 RTP/AVP 18 0
16:15:19.475 SIP.STACK MSG	a=rtpmap:18 G729/8000
16:15:19.475 SIP.STACK MSG	a=fmtp:18 annexb=no
16:15:19.476 SIP.STACK MSG	a=rtpmap:0 PCMU/8000
16:15:19.476 SIP.STACK MSG	a=silenceSupp:off - - - -
16:15:19.477 SIP.STACK MSG
16:15:19.478 TM.T01 33 SipTM_Delivering	call-leg -> Inviting
16:15:19.479 TM.T01 33 SipTM_Delivering	sent: INVITE
16:15:19.480 SB.CALL 185 Delivering	Called the deliverResponse routine from Delivering
16:15:19.481 SB.CALL 185 Delivering	DeliverResponse(accept) sent from T01 to T03
16:15:19.482 TA.T03 22 TAConnectWaitIn	deliverResponse event accepted
16:15:19.482 TA.T03 22 TAConnectWaitIn	ERROR! deliverResponse ignored
16:15:19.641 SIP.STACK MSG	Rx: UDP src=64.136.174.30:5060 dst=XXX.XXX.XXX.XXX:5060

16:15:19.641 SIP.STACK MSG	SIP/2.0 100 Trying
16:15:19.642 SIP.STACK MSG	Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK-d092-32ebe19-1b069291
16:15:19.642 SIP.STACK MSG	To: "local" <sip:16072395254@64.136.174.30:5060>
16:15:19.643 SIP.STACK MSG	From: <sip:6072387077@64.136.174.30:5060;transport=UDP>;tag=25843b0-7f000001-13c4-d092-512dd0d9-d092
16:15:19.643 SIP.STACK MSG	Call-ID: 259eac0-7f000001-13c4-d092-390708b9-d092@64.136.174.30
16:15:19.644 SIP.STACK MSG	CSeq: 1 INVITE
16:15:19.644 SIP.STACK MSG	Content-Length: 0
16:15:19.645 SIP.STACK MSG
16:15:19.648 TM.T01 33 SipTM_Delivering	rcvd SIP call-leg response: 100 Trying
16:15:19.649 TM.T01 33 SipTM_Delivering	No Supported header in message
16:15:19.649 TM.T01 33 SipTM_Delivering	rcvd: Provisional Response 100 Trying
16:15:19.650 TM.T01 33 SipTM_Delivering	call-leg -> Proceeding
16:15:21.517 SIP.STACK MSG	Rx: UDP src=64.136.174.30:5060 dst=XXX.XXX.XXX.XXX:5060

16:15:21.517 SIP.STACK MSG	SIP/2.0 183 Session Progress
16:15:21.518 SIP.STACK MSG	Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK-d092-32ebe19-1b069291
16:15:21.518 SIP.STACK MSG	Record-Route: <sip:sansay2589032518rdb16371@64.136.174.30:5060;lr;transport=udp>
16:15:21.519 SIP.STACK MSG	To: "local" <sip:16072395254@64.136.174.30:5060>;tag=sansay2589032518rdb16371
16:15:21.520 SIP.STACK MSG	From: <sip:6072387077@64.136.174.30:5060;transport=UDP>;tag=25843b0-7f000001-13c4-d092-512dd0d9-d092
16:15:21.520 SIP.STACK MSG	Call-ID: 259eac0-7f000001-13c4-d092-390708b9-d092@64.136.174.30
16:15:21.521 SIP.STACK MSG	CSeq: 1 INVITE
16:15:21.521 SIP.STACK MSG	Contact: <sip:16072395254@64.136.174.30:5060>
16:15:21.522 SIP.STACK MSG	Content-Type: application/sdp
16:15:21.522 SIP.STACK MSG	Content-Length: 170
16:15:21.523 SIP.STACK MSG
16:15:21.523 SIP.STACK MSG	v=0
16:15:21.524 SIP.STACK MSG	o=Sansay-VSXi 188 1 IN IP4 64.136.174.30
16:15:21.524 SIP.STACK MSG	s=Session Controller
16:15:21.525 SIP.STACK MSG	c=IN IP4 69.85.181.73
16:15:21.525 SIP.STACK MSG	t=0 0
16:15:21.526 SIP.STACK MSG	m=audio 20958 RTP/AVP 0
16:15:21.526 SIP.STACK MSG	a=rtpmap:0 PCMU/8000
16:15:21.527 SIP.STACK MSG	a=sendrecv
16:15:21.527 SIP.STACK MSG	a=ptime:20
16:15:21.528 SIP.STACK MSG
16:15:21.532 TM.T01 33 SipTM_Delivering	rcvd SIP call-leg response: 183 Session Progress
16:15:21.533 TM.T01 33 SipTM_Delivering	No Supported header in message
16:15:21.534 TM.T01 33 SipTM_Delivering	rcvd: Provisional Response 183 Session Progress (with SDP)
16:15:21.535 TM.T01 33 SipTM_Delivering	sent: TA->PreConnect
16:15:21.536 TA.T01 33 TAOutGoing	rcvd: preConnect from TM
16:15:21.536 SB.CALL 185 State change	>> Delivering->PreConnecting
16:15:21.537 SB.CALL 185 PreConnecting	Call PreConnecting from T01 to T03
16:15:21.538 TA.T03 22 TAConnectWaitIn	preConnect event accepted

16:15:21.539 TM.T03 22 IsdnTmStateInboundAccept->IsdnTmStateInboundPreconnect

16:15:21.539 TM.T03 22 IsdnTmStateInboundPreconnect::enter()

16:15:21.540 TA.T03 22 TAConnectWaitIn

rcvd: preConnectResponse from TM

16:15:21.541 SB.CCM connect:

16:15:21.541 SB.CCM : Call Struct 0x2984c10 : Call-ID = 185

16:15:21.542 SB.CCM : Org Acct = T03

Dst Acct = T01

16:15:21.542 SB.CCM : Org Port ID = 0/2.22 Dst Port ID = 0/0.32

16:15:21.543 SB.CCM : Org TID = Ds1Ds0Usage Dst TID =

16:15:21.543 SB.CCM : SDP Transaction = CallID: 185

16:15:21.544 SB.CCM : SDP Offer = 0x02991d10, (127.0.0.2:10004)

16:15:21.545 SB.CCM : SDP Answer = 0x0298a610, (69.85.181.73:20958)

16:15:21.545 SB.CCM : RTP Channel = 0/1.3

16:15:21.546 SB.CCM connect: Call Connection Type is TDM_TO_RTP

16:15:21.546 SB.CCM offer ip 127.0.0.2, 10004, answer ip 69.85.181.73, 20958

16:15:21.548 SB.CCM Looking up outbound interface to 69.85.181.73

16:15:21.549 ISDN.L2_FMT PRI 1 ==============================================

16:15:21.549 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:59 Nr:7 P:0

16:15:21.550 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:82F2
16:15:21.550 ISDN.L2_FMT PRI 1	M - 03 PROGRESS
16:15:21.551 ISDN.L2_FMT PRI 1	IE - 1E PROGRESS INDICATOR Len=2
16:15:21.551 ISDN.L2_FMT PRI 1	82 Location:LN
16:15:21.551 ISDN.L2_FMT PRI 1	88 Description:INBAND AUDIO AVAIL
16:15:21.553 PM.0:14 SendingDigits	Digit 6 processed

16:15:21.553 SB.CCM Changing RTP Channel source IP Address to XXX.XXX.XXX.XXX for Media Gateway on interface eth 0/1

16:15:21.554 SB.CCM connect: TDM streams: Table Ds1Ds0Usage 0/2.22 to Table 12372 0/1.3

16:15:21.555 SB.CCM not tdm only, call setupRtpChannel

16:15:21.555 SB.CCM setupRtpChannel, type 3, source 1, silence 0

16:15:21.556 SB.CCM TheCallConnectionManager::setupRtpChannel, IP addr = 69.85.181.73, port = 20958

16:15:21.557 SB.CCM connect: Undo of previous operation not required

16:15:21.558 SB.CCM getFinalCodec: PCMU

16:15:21.559 SB.CCM connect: Configuring RTP Channel 0/1.3 to 69.85.181.73:20958 via PCMU

16:15:21.560 SB.CCM connect:

fpp=2 echo=on dtmf=0/0 dscp=46 vad=off

16:15:21.561 SB.CCM connect: Starting RTP Channel

16:15:21.561 RTP.CHANNEL Channel 0/1.3 session statistics cleared.

16:15:21.562 RTP.CHANNEL Channel 0/1.3 started sucessfully.

16:15:21.562 SB.CCM firewallConnectCall: No action taken, firewall traversal is not enabled

16:15:21.563 SB.CALL 185 State change	>> PreConnecting->PreConnected
16:15:21.564 SB.CALL 185 PreConnected	Call PreConnecting from T03 to T01

16:15:21.565 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (6) event

16:15:21.565 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: stopping

16:15:21.566 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM unmap

16:15:21.567 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - DialTone Generation: releasing RTP resource

16:15:21.567 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: release

16:15:21.568 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(6) from PM
16:15:21.569 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 second(s) for more digits

16:15:21.570 RTP.CHANNEL t1 0/2.0.22 - dsp 0/1.3 - RTP: starting

16:15:21.573 TA.T01 ERROR! preConnectResponse ignored

16:15:22.440 PM.0:14 SendingDigits

Digit 0 processed

16:15:22.441 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (0) event

16:15:22.441 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(0) from PM
16:15:22.442 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:23.010 PM.0:14 SendingDigits	Digit 7 processed

16:15:23.011 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (7) event

16:15:23.012 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(7) from PM
16:15:23.013 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:23.770 PM.0:14 SendingDigits	Digit 2 processed

16:15:23.771 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:23.772 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:23.773 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:24.120 PM.0:14 SendingDigits	Digit 2 processed

16:15:24.121 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:24.121 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:24.122 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:24.400 PM.0:14 SendingDigits	Digit 2 processed

16:15:24.401 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:24.401 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:24.402 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:24.870 PM.0:14 SendingDigits	Digit 5 processed

16:15:24.871 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (5) event

16:15:24.872 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(5) from PM
16:15:24.873 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:25.230 PM.0:14 SendingDigits	Digit 3 processed

16:15:25.231 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:15:25.232 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:15:25.232 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:25.660 PM.0:14 SendingDigits	Digit 9 processed

16:15:25.661 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (9) event

16:15:25.661 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(9) from PM
16:15:25.662 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:26.050 PM.0:14 SendingDigits	Digit 3 processed

16:15:26.051 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:15:26.052 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:15:26.052 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:26.053 SA.9903 Ca:0 State change	>> DigitGathering->DigitGathering
16:15:26.054 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_Active) to PM
16:15:26.054 PM.0:14 State change	>> SendingDigits->Call Pending
16:15:26.056 SA.9903 Ca:0 DigitGathering	sent: call to SB
16:15:26.056 SA.9903 Ca:0 State change	>> DigitGathering->CallPending

16:15:26.057 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: stopping

16:15:26.058 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM unmap

16:15:26.059 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Tone Detection: releasing RTP resource

16:15:26.059 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: release

16:15:26.060 SA.9903 rcvd: AcctPhoneMgr_billingCode from PM

16:15:26.060 SB.CALL 186 Idle

Called the call routine with 6072225393

16:15:26 SB.TGMgr For dialed number 6072225393, against template $, on TrunkGroup PRI TO PBX, the score is 500

16:15:26 SB.TGMgr For dialed number 6072225393, against template $, on TrunkGroup SIP VI, the score is 500

16:15:26.062 SB.CCM isMappable:

16:15:26.063 SB.CCM : Call Struct 0x2983c10 : Call-ID = 186

16:15:26.063 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:15:26.064 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:15:26.065 SB.CCM : Org TID = Fxs Dst TID =

16:15:26.065 SB.CCM isMappable: Call Connection Type is TDM_TO_TDM

16:15:26.066 SB.CALL 186 Idle	Call sent from 9903 to T03 (6072225393)
16:15:26.066 SB.CALL 186 State change	>> Idle->Delivering
16:15:26.067 SA.9903 Ca:0 CallPending	rcvd: callResponse from SB
16:15:26.067 SA.9903 Ca:0 State change	>> CallPending->Calling
16:15:26.068 TA.T03 06 State change	>> TAIdle->TAOutGoing (TAS_Delivering)

16:15:26.069 TM.T03 06 tachg_Delivering

16:15:26.070 TM.T03 06 IsdnTmStateIdle->IsdnTmStateOutboundDeliver

16:15:26.071 TM.T03 06 IsdnTmStateOutboundDeliver::enter()

16:15:26.072 ISDN.L2_FMT PRI 1 ==============================================

16:15:26.073 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:60 Nr:7 P:0

16:15:26.073 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:006D
16:15:26.074 ISDN.L2_FMT PRI 1	M - 05 SETUP
16:15:26.074 ISDN.L2_FMT PRI 1	IE - 04 BEARER CAPABILITY Len=3
16:15:26.074 ISDN.L2_FMT PRI 1	80 Xfer Cap.:SPEECH
16:15:26.075 ISDN.L2_FMT PRI 1	90 Xfer Rate:64k
16:15:26.075 ISDN.L2_FMT PRI 1	A2 Layer 1:u-Law
16:15:26.075 ISDN.L2_FMT PRI 1	IE - 18 CHANNEL ID	Len=3
16:15:26.076 ISDN.L2_FMT PRI 1	A1 Primary Rate
16:15:26.076 ISDN.L2_FMT PRI 1	Intfc ID:IMPLICIT
16:15:26.076 ISDN.L2_FMT PRI 1	Pref/Excl:PREFERRED
16:15:26.077 ISDN.L2_FMT PRI 1	D-Chan Indicated:NO
16:15:26.077 ISDN.L2_FMT PRI 1	Chan. Sel:FOLLOWS
16:15:26.077 ISDN.L2_FMT PRI 1	83 Numb/Map:NUMBER
16:15:26.078 ISDN.L2_FMT PRI 1	86 Channel:6
16:15:26.078 ISDN.L2_FMT PRI 1	IE - 1C FACILITY	Len=28
16:15:26.078 ISDN.L2_FMT PRI 1	Calling Name: XXXXXXXXXXXXXXXXXXX
16:15:26.079 ISDN.L2_FMT PRI 1	IE - 6C CALLING PARTY #	Len=12
16:15:26.079 ISDN.L2_FMT PRI 1	00 Numb. Type:UNKNOWN
16:15:26.080 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:15:26.080 ISDN.L2_FMT PRI 1	80 Presentation:ALLOWED
16:15:26.081 ISDN.L2_FMT PRI 1	Ph.# 6073044673
16:15:26.081 ISDN.L2_FMT PRI 1	IE - 70 CALLED PARTY #	Len=11
16:15:26.081 ISDN.L2_FMT PRI 1	80 Numb. Type:UNKNOWN
16:15:26.082 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:15:26.082 ISDN.L2_FMT PRI 1	Ph.# 6072225393
16:15:26.083 SB.CALL 186 Delivering	Called the deliverResponse routine from Delivering
16:15:26.083 SB.CALL 186 Delivering	DeliverResponse(accept) sent from T03 to 9903
16:15:26.084 SA.9903 Ca:0 Calling	rcvd: deliverResponse from SB
16:15:26.085 SA.9903 Ca:0 Calling	sent: AcctPhoneMgr_info to PM

16:15:26.085 PM.0:14 ERROR! APM_Info ignored

16:15:26.163 ISDN.L2_FMT PRI 1 ==============================================

16:15:26.164 ISDN.L2_FMT PRI 1 Recd Sapi:00 C/R:R Tei:00 INFO Ns:7 Nr:61 P:0

16:15:26.164 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:806D
16:15:26.164 ISDN.L2_FMT PRI 1	M - 5A RELEASE_CMP
16:15:26.165 ISDN.L2_FMT PRI 1	IE - 08 CAUSE	Len=2
16:15:26.165 ISDN.L2_FMT PRI 1	80 Location:U
16:15:26.166 ISDN.L2_FMT PRI 1	91 Cause:17 (USER_BUSY)

16:15:26.169 TM.T03 06 IsdnTmStateOutboundDeliver->IsdnTmStateTrunkClearing

16:15:26.170 TM.T03 06 IsdnTmStateTrunkClearing::enter()

16:15:26.171 TM.T03 06 IsdnTmStateTrunkClearing->IsdnTmStateIdling

16:15:26.171 TM.T03 06 IsdnTmStateIdling::enter()

16:15:26.172 TM.T03 06 IsdnTmStateIdling - send appearance off

16:15:26.172 TM.T03 06 IsdnTmStateIdling->IsdnTmStateIdle

16:15:26.173 TM.T03 06 IsdnAppearanceChannel::releaseChannel

16:15:26.173 TM.T03 06 IsdnTmStateIdle::enter()

16:15:26.174 TA.T03 06 TAOutGoing	rcvd: clear from TM
16:15:26.175 TA.T03 06 State change	>> TAOutGoing->TATrunkClearing (TAS_Clearing)

16:15:26.175 TM.T03 06 IsdnTmStateIdle::tachgClearing - send appearance off

16:15:26.176 TA.T03 06 TATrunkClearing	rcvd: clear from TM
16:15:26.177 TA.T03 06 State change	>> TATrunkClearing->TAClearingComplete (TAS_Clearing)

16:15:26.177 TA.T03 06 TAClearingComplete rcvd: appearance off from TM

16:15:26.178 TA.T03 06 TAClearingComplete Clear Local Variables

16:15:26.178 TA.T03 06 State change	>> TAClearingComplete->TAIdle (TAS_Idle)
16:15:26.179 SB.CALL 186 Delivering	Called the clearCall routine from Delivering
16:15:26.180 SB.CALL 186 Delivering	Clearing due to Trunk Clear Reason on call from T03 to 9903
16:15:26.181 SB.CALL 186 State change	>> Delivering->Clearing
16:15:26.181 TA.T03 06 TAIdle	rcvd: appearance off from TM
16:15:26.182 TA.T03 06 TAIdle	Processing an appearance OFF
16:15:26.182 SA.9903 Ca:0 Calling	rcvd: clearCall from SB
16:15:26.183 SA.9903 Ca:0 Calling	sent: clearResponse(pass) to SB
16:15:26.183 SA.9903 Ca:0 State change	>> Calling->Idle
16:15:26.184 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_faxModem to PM
16:15:26.185 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:15:26.186 PM.0:14 Call Pending	Pri CA, Idle response, App OFF sent
16:15:26.186 PM.0:14 State change	>> Call Pending->Clearing Busy
16:15:26.188 SB.CALL 186 Clearing	Called the clearResponse routine
16:15:26.188 SB.CALL 186 State change	>> Clearing->CallIdlePending

16:15:26.189 SB.CCM release:

16:15:26.189 SB.CCM : Call Struct 0x2983c10 : Call-ID = 186

16:15:26.191 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:15:26.191 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:15:26.192 SB.CCM : Org TID = Fxs Dst TID = Isdn Group Table

16:15:26.192 SB.CCM release: Call Connection Type is TDM_TO_TDM

16:15:26.193 SB.CALL 186 CallIdlePending	ClearResponse sent from 9903 to T03
16:15:26.194 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(OFF) from PM
16:15:26.194 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:15:26.195 PM.0:14 Clearing Busy	Dropped CACHG w/Call State not RINGING

16:15:26.196 TONESERVICES.EVENTS fxs 0/14 - empty - Busy Generation: Request resource

16:15:26.196 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: DSP channel allocated for the resource

16:15:26.197 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: constructed

16:15:26.197 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: starting

16:15:26.198 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM map

16:15:26.199 TA.T03 clearResponse event rejected, no matching CallID

2016.05.26 16:15:26 TM.T03 06 IsdnTmStateIdling - clear trunk appearance

2016.05.26 16:15:27 SMDR 186	05/26/2016 16:15:26	0.0 0	I 00/14 XXXXXXXXXXXXXXXXXXX 6073044673	00/00 T03	6072225393	0 N
16:15:28.725 SIP.STACK MSG	Rx: UDP src=64.136.174.30:5060 dst=XXX.XXX.XXX.XXX:5060

16:15:28.726 SIP.STACK MSG	SIP/2.0 200 OK
16:15:28.727 SIP.STACK MSG	Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK-d092-32ebe19-1b069291
16:15:28.727 SIP.STACK MSG	Record-Route: <sip:sansay2589032518rdb16371@64.136.174.30:5060;lr;transport=udp>
16:15:28.728 SIP.STACK MSG	To: "local" <sip:16072395254@64.136.174.30:5060>;tag=sansay2589032518rdb16371
16:15:28.728 SIP.STACK MSG	From: <sip:6072387077@64.136.174.30:5060;transport=UDP>;tag=25843b0-7f000001-13c4-d092-512dd0d9-d092
16:15:28.729 SIP.STACK MSG	Call-ID: 259eac0-7f000001-13c4-d092-390708b9-d092@64.136.174.30
16:15:28.729 SIP.STACK MSG	CSeq: 1 INVITE
16:15:28.730 SIP.STACK MSG	Contact: <sip:16072395254@64.136.174.30:5060>
16:15:28.730 SIP.STACK MSG	Content-Type: application/sdp
16:15:28.732 SIP.STACK MSG	Content-Length: 170
16:15:28.732 SIP.STACK MSG
16:15:28.732 SIP.STACK MSG	v=0
16:15:28.733 SIP.STACK MSG	o=Sansay-VSXi 188 1 IN IP4 64.136.174.30
16:15:28.733 SIP.STACK MSG	s=Session Controller
16:15:28.734 SIP.STACK MSG	c=IN IP4 69.85.181.73
16:15:28.734 SIP.STACK MSG	t=0 0
16:15:28.735 SIP.STACK MSG	m=audio 20958 RTP/AVP 0
16:15:28.735 SIP.STACK MSG	a=rtpmap:0 PCMU/8000
16:15:28.736 SIP.STACK MSG	a=sendrecv
16:15:28.736 SIP.STACK MSG	a=ptime:20
16:15:28.737 SIP.STACK MSG
16:15:28.741 TM.T01 33 SipTM_Delivering	rcvd SIP call-leg response: 200 OK
16:15:28.741 TM.T01 33 SipTM_Delivering	No Supported header in message
16:15:28.742 TM.T01 33 SipTM_Delivering	call-leg -> RemoteAccepted
16:15:28.743 TM.T01 33 SipTM_Delivering	Loose Routing detected in Record-Route header URL.
16:15:28.744 TM.T01 33 SipTM_Delivering	Using addr spec from Record-Route instead of Contact.
16:15:28.745 TM.T01 33 SipTM_Delivering	State change	>> SipTM_Delivering->SipTM_Answering
16:15:28.746 TM.T01 33 SipTM_Answering	rcvd: SIP 200 OK
16:15:28.747 TM.T01 33 SipTM_Answering	sent: TA->Connect
16:15:28.748 TA.T01 33 TAOutGoing	rcvd: connect from TM
16:15:28.749 TA.T01 33 State change	>> TAOutGoing->TAConnectWaitOut (TAS_Delivering)
16:15:28.750 SB.CALL 185 PreConnected	Called the connect routine
16:15:28.750 SB.CALL 185 State change	>> PreConnected->Connecting
16:15:28.751 SB.CALL 185 Connecting	Connect sent from T01 to T03
16:15:28.752 TA.T03 22 TAConnectWaitIn	connect event accepted
16:15:28.752 TA.T03 22 State change	>> TAConnectWaitIn->TAConnectPending (TAS_Connected)

16:15:28.753 TM.T03 22 IsdnTmStateInboundPreconnect->IsdnTmStateInboundConnecting

16:15:28.754 TM.T03 22 IsdnTmStateInboundConnecting::enter()

16:15:28.755 TM.T03 22 IsdnTmStateInboundConnecting->IsdnTmStateInboundConnected

16:15:28.755 TM.T03 22 IsdnTmStateInboundConnected::enter()

16:15:28.756 TA.T03 22 TAConnectPending

rcvd: connect from TM

16:15:28.756 TM.T03 22 IsdnAppearanceChannel::enableFaxModemToneDetect - delayFaxEnableEvent enqueued

16:15:28.757 TA.T03 22 State change	>> TAConnectPending->TAConnected (TAS_Connected)
16:15:28.758 SB.CALL 185 Connecting	Called the connectResponse routine

16:15:28.758 SB.CCM connect:

16:15:28.759 SB.CCM : Call Struct 0x2984c10 : Call-ID = 185

16:15:28.759 SB.CCM : Org Acct = T03

Dst Acct = T01

16:15:28.760 SB.CCM : Org Port ID = 0/2.22 Dst Port ID = 0/0.32

16:15:28.760 SB.CCM : Org TID = Ds1Ds0Usage Dst TID =

16:15:28.761 SB.CCM : SDP Transaction = CallID: 185

16:15:28.762 SB.CCM : SDP Offer = 0x02991d10, (127.0.0.2:10004)

16:15:28.762 SB.CCM : SDP Answer = 0x028b2310, (69.85.181.73:20958)

16:15:28.763 SB.CCM : RTP Channel = 0/1.3

16:15:28.763 SB.CCM connect: Call Connection Type is TDM_TO_RTP

16:15:28.764 SB.CCM offer ip 127.0.0.2, 10004, answer ip 69.85.181.73, 20958

16:15:28.766 SB.CCM Looking up outbound interface to 69.85.181.73

16:15:28.767 SB.CCM Changing RTP Channel source IP Address to XXX.XXX.XXX.XXX for Media Gateway on interface eth 0/1

16:15:28.767 SB.CCM connect: TDM streams: Table Ds1Ds0Usage 0/2.22 to Table 12372 0/1.3

16:15:28.768 SB.CCM not tdm only, call setupRtpChannel

16:15:28.768 SB.CCM setupRtpChannel, type 3, source 2, silence 0

16:15:28.769 ISDN.L2_FMT PRI 1 ==============================================

16:15:28.770 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:61 Nr:8 P:0

16:15:28.770 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:82F2
16:15:28.771 ISDN.L2_FMT PRI 1	M - 07 CONNECT

16:15:28.772 SB.CCM TheCallConnectionManager::setupRtpChannel, IP addr = 69.85.181.73, port = 20958

16:15:28.772 SB.CCM connect: Undo of previous operation not required

16:15:28.773 SB.CCM getFinalCodec: PCMU

16:15:28.774 SB.CCM connect: Configuring RTP Channel 0/1.3 to 69.85.181.73:20958 via PCMU

16:15:28.775 SB.CCM connect:

fpp=2 echo=on dtmf=0/0 dscp=46 vad=off

16:15:28.776 RTP.CHANNEL Channel 0/1.3 stopped sucessfully.

16:15:28.777 SB.CCM connect: Starting RTP Channel

16:15:28.777 RTP.CHANNEL Channel 0/1.3 session statistics cleared.

16:15:28.778 RTP.CHANNEL Channel 0/1.3 started sucessfully.

16:15:28.778 SB.CCM firewallConnectCall: No action taken, firewall traversal is not enabled

16:15:28.779 SB.CALL 185 State change	>> Connecting->Connected
16:15:28.780 SB.CALL 185 Connected	ConnectResponse sent from T03 to T01

16:15:28.780 RTP.CHANNEL t1 0/2.0.22 - dsp 0/1.3 - RTP: stopping

16:15:28.781 RTP.CHANNEL t1 0/2.0.22 - dsp 0/1.3 - RTP: starting

16:15:28.782 TA.T01 33 TAConnectWaitOut	connectResponse event accepted
16:15:28.782 TA.T01 33 State change	>> TAConnectWaitOut->TAConnected (TAS_Connected)
16:15:28.783 TM.T01 33 SipTM_Answering	tachg -> TAConnected
16:15:28.784 TM.T01 33 SipTM_Answering	State change	>> SipTM_Answering->SipTM_Acking
16:15:28.787 SIP.STACK MSG	Tx: UDP src=XXX.XXX.XXX.XXX:5060 dst=64.136.174.30:5060

16:15:28.788 SIP.STACK MSG	ACK sip:16072395254@64.136.174.30:5060;transport=UDP SIP/2.0
16:15:28.788 SIP.STACK MSG	From: <sip:6072387077@64.136.174.30:5060;transport=UDP>;tag=25843b0-7f000001-13c4-d092-512dd0d9-d092
16:15:28.789 SIP.STACK MSG	To: "local" <sip:16072395254@64.136.174.30:5060>;tag=sansay2589032518rdb16371
16:15:28.790 SIP.STACK MSG	Call-ID: 259eac0-7f000001-13c4-d092-390708b9-d092@64.136.174.30
16:15:28.790 SIP.STACK MSG	CSeq: 1 ACK
16:15:28.791 SIP.STACK MSG	Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK-d09c-32ee284-7160fc46
16:15:28.792 SIP.STACK MSG	Max-Forwards: 70
16:15:28.792 SIP.STACK MSG	Supported: 100rel,replaces
16:15:28.793 SIP.STACK MSG	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
16:15:28.793 SIP.STACK MSG	User-Agent: ADTRAN_Total_Access_916_2nd_Gen/A4.11.00.E
16:15:28.794 SIP.STACK MSG	Contact: <sip:6072387077@XXX.XXX.XXX.XXX:5060;transport=UDP>
16:15:28.794 SIP.STACK MSG	Route: <sip:sansay2589032518rdb16371@64.136.174.30:5060;transport=udp;lr>
16:15:28.795 SIP.STACK MSG	Content-Length: 0
16:15:28.795 SIP.STACK MSG
16:15:28.797 TM.T01 33 SipTM_Acking	call-leg -> Connected
16:15:28.798 TM.T01 33 SipTM_Acking	State change	>> SipTM_Acking->SipTM_Connected
16:15:28.798 TM.T01 33 SipTM_Connected	call-leg-mod -> Modify Idle
16:15:28.799 TM.T01 33 SipTM_Acking	sent: ACK

16:15:29.007 TM.T03 22 IsdnApperanceChannel::delayFax

16:15:29.008 TM.T03 22 faxModemDetResource - startResource Passed TA-21 dspRes-295f090

16:15:29.009 TONESERVICES.EVENTS t1 0/2.0.22 - empty - Modem/FAX Detection: Request resource

16:15:29.010 TONESERVICES.EVENTS t1 0/2.0.22 - dsp 0/1.3 - Modem/FAX Detection: DSP channel allocated for the resource

16:15:29.011 TONESERVICES.EVENTS t1 0/2.0.22 - dsp 0/1.3 - Modem/FAX Detection: constructed

16:15:29.011 TONESERVICES.EVENTS t1 0/2.0.22 - dsp 0/1.3 - Modem/FAX Detection: starting

16:15:29.012 TONESERVICES.EVENTS t1 0/2.0.22 - dsp 0/1.3 - Modem/FAX Detection: TDM map

16:15:30.326 PM.0:14 Clearing Busy	Processed OnHook
16:15:30.327 PM.0:14 State change	>> Clearing Busy->Idle

16:15:30.328 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: stopping

16:15:30.329 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM unmap

16:15:30.330 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Busy Generation: releasing RTP resource

16:15:30.330 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: release

16:15:30.331 SA.9903 rcvd: AcctPhoneMgr_COSOverride from PM

16:15:33.462 PM.0:14 Idle	Processed OFFHOOK
16:15:33.462 PM.0:14 State change	>> Idle->Requesting Dialtone
16:15:33.463 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(ON) from PM
16:15:33.463 SA.9903 Ca:0 State change	>> Idle->DigitGathering
16:15:33.464 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_ReqDigits) to PM

16:15:33.465 PM.0:14 Requesting Dialtone CACHG:ReqDigits on primary CA

16:15:33.465 PM.0:14 State change

>> Requesting Dialtone->SendingDigits

16:15:33.467 TONESERVICES.EVENTS fxs 0/14 - empty - Tone Detection: Request resource

16:15:33.468 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: DSP channel allocated for the resource

16:15:33.468 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: constructed

16:15:33.469 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: starting

16:15:33.470 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM map

16:15:33.968 TONESERVICES.EVENTS fxs 0/14 - empty - DialTone Generation: Request resource

16:15:33.969 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: DSP channel allocated for the resource

16:15:33.970 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: constructed

16:15:33.971 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: starting

16:15:33.972 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM map

16:15:35.591 PM.0:14 SendingDigits

Digit 1 processed

16:15:35.592 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (1) event

16:15:35.593 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: stopping

16:15:35.593 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: TDM unmap

16:15:35.594 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - DialTone Generation: releasing RTP resource

16:15:35.595 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - DialTone Generation: release

16:15:35.596 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(1) from PM
16:15:35.596 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 second(s) for more digits
16:15:36.261 PM.0:14 SendingDigits	Digit 6 processed

16:15:36.262 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (6) event

16:15:36.262 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(6) from PM
16:15:36.263 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:36.731 PM.0:14 SendingDigits	Digit 0 processed

16:15:36.732 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (0) event

16:15:36.732 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(0) from PM
16:15:36.733 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:37.160 PM.0:14 SendingDigits	Digit 7 processed

16:15:37.161 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (7) event

16:15:37.162 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(7) from PM
16:15:37.163 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:37.840 PM.0:14 SendingDigits	Digit 2 processed

16:15:37.841 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:37.842 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:37.843 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:38.200 PM.0:14 SendingDigits	Digit 2 processed

16:15:38.201 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:38.202 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:38.203 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:38.511 PM.0:14 SendingDigits	Digit 2 processed

16:15:38.512 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (2) event

16:15:38.512 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(2) from PM
16:15:38.513 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:38.991 PM.0:14 SendingDigits	Digit 5 processed

16:15:38.992 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (5) event

16:15:38.992 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(5) from PM
16:15:38.993 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:39.371 PM.0:14 SendingDigits	Digit 3 processed

16:15:39.372 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:15:39.372 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:15:39.373 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:39.840 PM.0:14 SendingDigits	Digit 9 processed

16:15:39.842 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (9) event

16:15:39.842 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(9) from PM
16:15:39.843 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:40.260 PM.0:14 SendingDigits	Digit 3 processed

16:15:40.261 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: received digit (3) event

16:15:40.262 SA.9903 Ca:0 DigitGathering	rcvd: AcctPhoneMgr_dialDigit(3) from PM
16:15:40.263 SA.9903 Ca:0 DigitGathering	Named-digit-timeout waiting 0 seconds for more digits
16:15:40.263 SA.9903 Ca:0 State change	>> DigitGathering->DigitGathering
16:15:40.264 SA.9903 Ca:0 DigitGathering	sent: AcctPhoneMgr_cachg(CAS_Active) to PM
16:15:40.265 PM.0:14 State change	>> SendingDigits->Call Pending
16:15:40.266 SA.9903 Ca:0 DigitGathering	sent: call to SB
16:15:40.267 SA.9903 Ca:0 State change	>> DigitGathering->CallPending

16:15:40.268 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: stopping

16:15:40.268 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: TDM unmap

16:15:40.269 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Tone Detection: releasing RTP resource

16:15:40.270 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Tone Detection: release

16:15:40.271 SA.9903 rcvd: AcctPhoneMgr_billingCode from PM

16:15:40.271 SB.CALL 187 Idle

Called the call routine with 16072225393

16:15:40 SB.TGMgr For dialed number 16072225393, against template $, on TrunkGroup PRI TO PBX, the score is 500

16:15:40 SB.TGMgr For dialed number 16072225393, against template $, on TrunkGroup SIP VI, the score is 500

16:15:40.273 SB.CCM isMappable:

16:15:40.274 SB.CCM : Call Struct 0x280bc10 : Call-ID = 187

16:15:40.274 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:15:40.275 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:15:40.275 SB.CCM : Org TID = Fxs Dst TID =

16:15:40.276 SB.CCM isMappable: Call Connection Type is TDM_TO_TDM

16:15:40.277 SB.CALL 187 Idle	Call sent from 9903 to T03 (16072225393)
16:15:40.277 SB.CALL 187 State change	>> Idle->Delivering
16:15:40.278 SA.9903 Ca:0 CallPending	rcvd: callResponse from SB
16:15:40.278 SA.9903 Ca:0 State change	>> CallPending->Calling
16:15:40.279 TA.T03 05 State change	>> TAIdle->TAOutGoing (TAS_Delivering)

16:15:40.280 TM.T03 05 tachg_Delivering

16:15:40.281 TM.T03 05 IsdnTmStateIdle->IsdnTmStateOutboundDeliver

16:15:40.282 TM.T03 05 IsdnTmStateOutboundDeliver::enter()

16:15:40.283 ISDN.L2_FMT PRI 1 ==============================================

16:15:40.283 ISDN.L2_FMT PRI 1 Sent Sapi:00 C/R:C Tei:00 INFO Ns:62 Nr:8 P:0

16:15:40.284 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:006E
16:15:40.284 ISDN.L2_FMT PRI 1	M - 05 SETUP
16:15:40.284 ISDN.L2_FMT PRI 1	IE - 04 BEARER CAPABILITY Len=3
16:15:40.285 ISDN.L2_FMT PRI 1	80 Xfer Cap.:SPEECH
16:15:40.285 ISDN.L2_FMT PRI 1	90 Xfer Rate:64k
16:15:40.286 ISDN.L2_FMT PRI 1	A2 Layer 1:u-Law
16:15:40.286 ISDN.L2_FMT PRI 1	IE - 18 CHANNEL ID	Len=3
16:15:40.286 ISDN.L2_FMT PRI 1	A1 Primary Rate
16:15:40.287 ISDN.L2_FMT PRI 1	Intfc ID:IMPLICIT
16:15:40.287 ISDN.L2_FMT PRI 1	Pref/Excl:PREFERRED
16:15:40.287 ISDN.L2_FMT PRI 1	D-Chan Indicated:NO
16:15:40.288 ISDN.L2_FMT PRI 1	Chan. Sel:FOLLOWS
16:15:40.288 ISDN.L2_FMT PRI 1	83 Numb/Map:NUMBER
16:15:40.288 ISDN.L2_FMT PRI 1	85 Channel:5
16:15:40.289 ISDN.L2_FMT PRI 1	IE - 1C FACILITY	Len=28
16:15:40.289 ISDN.L2_FMT PRI 1	Calling Name: XXXXXXXXXXXXXXXXXXX
16:15:40.289 ISDN.L2_FMT PRI 1	IE - 6C CALLING PARTY #	Len=12
16:15:40.290 ISDN.L2_FMT PRI 1	00 Numb. Type:UNKNOWN
16:15:40.290 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:15:40.291 ISDN.L2_FMT PRI 1	80 Presentation:ALLOWED
16:15:40.291 ISDN.L2_FMT PRI 1	Ph.# 6073044673
16:15:40.291 ISDN.L2_FMT PRI 1	IE - 70 CALLED PARTY #	Len=12
16:15:40.292 ISDN.L2_FMT PRI 1	80 Numb. Type:UNKNOWN
16:15:40.292 ISDN.L2_FMT PRI 1	Numb. Plan:UNKNOWN
16:15:40.293 ISDN.L2_FMT PRI 1	Ph.# 16072225393
16:15:40.294 SB.CALL 187 Delivering	Called the deliverResponse routine from Delivering
16:15:40.295 SB.CALL 187 Delivering	DeliverResponse(accept) sent from T03 to 9903
16:15:40.295 SA.9903 Ca:0 Calling	rcvd: deliverResponse from SB
16:15:40.296 SA.9903 Ca:0 Calling	sent: AcctPhoneMgr_info to PM

16:15:40.296 PM.0:14 ERROR! APM_Info ignored

16:15:40.402 ISDN.L2_FMT PRI 1 ==============================================

16:15:40.402 ISDN.L2_FMT PRI 1 Recd Sapi:00 C/R:R Tei:00 INFO Ns:8 Nr:63 P:0

16:15:40.403 ISDN.L2_FMT PRI 1	Prot:08 CRL:2 CRV:806E
16:15:40.403 ISDN.L2_FMT PRI 1	M - 5A RELEASE_CMP
16:15:40.404 ISDN.L2_FMT PRI 1	IE - 08 CAUSE	Len=2
16:15:40.404 ISDN.L2_FMT PRI 1	80 Location:U
16:15:40.405 ISDN.L2_FMT PRI 1	91 Cause:17 (USER_BUSY)

16:15:40.410 TM.T03 05 IsdnTmStateOutboundDeliver->IsdnTmStateTrunkClearing

16:15:40.410 TM.T03 05 IsdnTmStateTrunkClearing::enter()

16:15:40.411 TM.T03 05 IsdnTmStateTrunkClearing->IsdnTmStateIdling

16:15:40.412 TM.T03 05 IsdnTmStateIdling::enter()

16:15:40.412 TM.T03 05 IsdnTmStateIdling - send appearance off

16:15:40.413 TM.T03 05 IsdnTmStateIdling->IsdnTmStateIdle

16:15:40.414 TM.T03 05 IsdnAppearanceChannel::releaseChannel

16:15:40.414 TM.T03 05 IsdnTmStateIdle::enter()

16:15:40.415 TA.T03 05 TAOutGoing	rcvd: clear from TM
16:15:40.415 TA.T03 05 State change	>> TAOutGoing->TATrunkClearing (TAS_Clearing)

16:15:40.416 TM.T03 05 IsdnTmStateIdle::tachgClearing - send appearance off

16:15:40.417 TA.T03 05 TATrunkClearing	rcvd: clear from TM
16:15:40.417 TA.T03 05 State change	>> TATrunkClearing->TAClearingComplete (TAS_Clearing)

16:15:40.418 TA.T03 05 TAClearingComplete rcvd: appearance off from TM

16:15:40.418 TA.T03 05 TAClearingComplete Clear Local Variables

16:15:40.419 TA.T03 05 State change	>> TAClearingComplete->TAIdle (TAS_Idle)
16:15:40.420 SB.CALL 187 Delivering	Called the clearCall routine from Delivering
16:15:40.420 SB.CALL 187 Delivering	Clearing due to Trunk Clear Reason on call from T03 to 9903
16:15:40.421 SB.CALL 187 State change	>> Delivering->Clearing
16:15:40.422 TA.T03 05 TAIdle	rcvd: appearance off from TM
16:15:40.423 TA.T03 05 TAIdle	Processing an appearance OFF
16:15:40.423 SA.9903 Ca:0 Calling	rcvd: clearCall from SB
16:15:40.424 SA.9903 Ca:0 Calling	sent: clearResponse(pass) to SB
16:15:40.424 SA.9903 Ca:0 State change	>> Calling->Idle
16:15:40.425 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_faxModem to PM
16:15:40.426 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:15:40.427 PM.0:14 Call Pending	Pri CA, Idle response, App OFF sent
16:15:40.427 PM.0:14 State change	>> Call Pending->Clearing Busy
16:15:40.429 SB.CALL 187 Clearing	Called the clearResponse routine
16:15:40.429 SB.CALL 187 State change	>> Clearing->CallIdlePending

16:15:40.430 SB.CCM release:

16:15:40.431 SB.CCM : Call Struct 0x280bc10 : Call-ID = 187

16:15:40.431 SB.CCM : Org Acct = 9903

Dst Acct = T03

16:15:40.432 SB.CCM : Org Port ID = 0/14.0 Dst Port ID = 0/0.0

16:15:40.432 SB.CCM : Org TID = Fxs Dst TID = Isdn Group Table

16:15:40.433 SB.CCM release: Call Connection Type is TDM_TO_TDM

16:15:40.433 SB.CALL 187 CallIdlePending	ClearResponse sent from 9903 to T03
16:15:40.434 SA.9903 Ca:0 Idle	rcvd: AcctPhoneMgr_appearance(OFF) from PM
16:15:40.435 SA.9903 Ca:0 Idle	sent: AcctPhoneMgr_cachg(CAS_Idle) to PM
16:15:40.435 PM.0:14 Clearing Busy	Dropped CACHG w/Call State not RINGING

16:15:40.436 TONESERVICES.EVENTS fxs 0/14 - empty - Busy Generation: Request resource

16:15:40.437 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: DSP channel allocated for the resource

16:15:40.437 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: constructed

16:15:40.438 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: starting

16:15:40.439 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM map

16:15:40.439 TA.T03 clearResponse event rejected, no matching CallID

2016.05.26 16:15:40 TM.T03 05 IsdnTmStateIdling - clear trunk appearance

2016.05.26 16:15:41 SMDR 187	05/26/2016 16:15:40	0.0 0	I 00/14 XXXXXXXXXXXXXXXXXXX 6073044673	00/00 T03	16072225393	0 N
16:15:44.582 PM.0:14 Clearing Busy	Processed OnHook
16:15:44.583 PM.0:14 State change	>> Clearing Busy->Idle

16:15:44.584 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: stopping

16:15:44.585 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: TDM unmap

16:15:44.586 RTP.CHANNEL fxs 0/14 - dsp 0/1.2 - Busy Generation: releasing RTP resource

16:15:44.587 TONESERVICES.EVENTS fxs 0/14 - dsp 0/1.2 - Busy Generation: release

16:15:44.587 SA.9903 rcvd: AcctPhoneMgr_COSOverride from PM

Anonymous · ‎05-27-2016

Sam, the 900 is designed to act as a gateway between an IP source and a TDM source, whether it be one direction or another. It is not designed to route calls from one TDM interface to another and as such, that application has not been tested and can not be guaranteed to work. Thanks

Jay

ADTRAN TSE

Support
Community

Push ISDN/T1 Number to FXS ports?

Administration & Management

PRI

SIP

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

SupportCommunity

Push ISDN/T1 Number to FXS ports?

Administration & Management

PRI

SIP

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

Re: Push ISDN/T1 Number to FXS ports?

Support
Community