Fax in FXS port not working

[aherraez77]

Hello:

I am not able to make a fax machine working.

I have a FXS port conected to a gw with a sip trunk to the ISP. I have configure the DID at the voice port and the call is arriving to the port but the fax doesn´t hang up.

I have this output when I make a call to the fax machine:

#show voice call

0/0/0 1 - - -
0/0/0 2 - - -
0/0/1 1 - - -
0/0/1 2 - - -
0/3/0
vtsp level 0 state = S_SETUP_REQ_PROCvpm level 1 state = FXSLS_WAIT_OFFHOOK
vpm level 0 state = S_UP

#show voice call summary
PORT CODEC VAD VTSP STATE VPM STATE
============== ========= === ==================== ======================
0/0/0.1 - - -
0/0/0.2 - - -
0/0/1.1 - - -
0/0/1.2 - - -
0/3/0 g711alaw y S_SETUP_REQ_PROC FXSLS_WAIT_OFFHOOK
0/3/1 - - - FXSLS_ONHOOK

#show voice port summary
IN OUT
PORT CH SIG-TYPE ADMIN OPER STATUS STATUS EC
=============== == ============ ===== ==== ======== ======== ==
0/0/0 1 bri-voice up dorm none none y
0/0/0 2 bri-voice up dorm none none y
0/0/1 1 bri-voice up dorm none none y
0/0/1 2 bri-voice up dorm none none y
0/3/0 -- fxs-ls up up on-hook ringing y
0/3/1 -- fxs-ls up dorm on-hook idle y

The outgoing calls though the sip trunk doesn´t work neither

What can I test to find the problem?

Best Regards

[Ayodeji Okanlawon]

Can we start by looking at the outgoing call through the sip trunk. What happens when you try to make an outbound call?

Can you also describe the call flow

Can you also provide a debug ccsip messages from the gateway when you do a test call. Please include calling and called number

Finally please send the sh run of your gateway

[aherraez77]

Hi Ayodeji:

I have conected an analog phone to the fxs port and this is what happen:

- Outgoing calls: I can´t do a outbound call. I have a busy tone after hang up the fax or the analog phone

- incoming calls: The calling phone rings all the time and the destination analog phone doesn´t ring. But if I hang up the phone, the call is stablished and works.

As I have said you, I can´t do a outgoing call because I have only a busy signal.

The call flow is this: The fsx port is instaled in the same cube as the conexion for the sip trunk with the provider. The call starts at the port, matches the outcoming dial-peer to the sip trunk and goes out to the provider.

For incoming calls, the call matches the dial peer pots associate to the voice port of the fxs.

Here you are the debug ccsi messages from the gw in a incoming call:

*Jan 13 2016 17:43:56.137 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:916619510@192.168.5.20 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
Max-Forwards: 70
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>
Contact: <sip:627559040@90.169.25.195:5060>
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 INVITE
User-Agent: FPBX-2.11.0(11.13.0)
Date: Wed, 13 Jan 2016 16:46:01 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 1311

v=0
o=root 1460088905 1460088905 IN IP4 90.169.25.195
s=Asterisk PBX 11.13.0
c=IN IP4 90.169.25.195
t=0 0
m=audio 10410 RTP/AVP 8 4 3 0 112 5 7 18 110 97 111 9 102 115 116 117 96 100 107 108 10 118 119 96
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=fmtp:4 annexa=no
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:7 LPC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:110 speex/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:111 G726-32/8000
a=rtpmap:9 G722/8000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:116 G719/48000
a=fmtp:116 bitrate=64000
a=rtpmap:117 speex/16000
a=rtpmap:96 SILK/8000
a=fmtp:96 maxaveragebitrate=10000
a=fmtp:96 usedtx=0
a=fmtp:96 useinbandfec=1
a=rtpmap:100 SILK/12000
a=fmtp:100 maxaveragebitrate=12000
a=fmtp:100 usedtx=0
a=fmtp:100 useinbandfec=1
a=rtpmap:107 SILK/16000
a=fmtp:107 maxaveragebitrate=20000
a=fmtp:107 usedtx=0
a=fmtp:107 useinbandfec=1
a=rtpmap:108 SILK/24000
a=fmtp:108 maxaveragebitrate=30000
a=fmtp:108 usedtx=0
a=fmtp:108 useinbandfec=1
a=rtpmap:10 L16/8000
a=rtpmap:118 L16/16000
a=rtpmap:119 speex/32000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ptime:20
a=sendrecv

*Jan 13 2016 17:43:56.153 UTC: //11003/ABD75507AD3B/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>
Date: Wed, 13 Jan 2016 16:43:56 GMT
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Content-Length: 0

*Jan 13 2016 17:43:56.157 UTC: //11003/ABD75507AD3B/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>;tag=60C2F18-1EC3
Date: Wed, 13 Jan 2016 16:43:56 GMT
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Contact: <sip:916619510@192.168.5.20:5060>
Supported: sdp-anat
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 244

v=0
o=CiscoSystemsSIP-GW-UserAgent 7723 8431 IN IP4 192.168.5.20
s=SIP Call
c=IN IP4 192.168.5.20
t=0 0
m=audio 17086 RTP/AVP 8 96
c=IN IP4 192.168.5.20
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ptime:20

*Jan 13 2016 17:44:06.401 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:916619510@192.168.5.20 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
Max-Forwards: 70
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 CANCEL
User-Agent: FPBX-2.11.0(11.13.0)
Content-Length: 0

*Jan 13 2016 17:44:06.401 UTC: //11003/ABD75507AD3B/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>
Date: Wed, 13 Jan 2016 16:44:06 GMT
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 CANCEL
Content-Length: 0

*Jan 13 2016 17:44:06.405 UTC: //11003/ABD75507AD3B/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 487 Request Cancelled
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>;tag=60C2F18-1EC3
Date: Wed, 13 Jan 2016 16:44:06 GMT
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-15.4.3.M1
Reason: Q.850;cause=16
Content-Length: 0

*Jan 13 2016 17:44:06.413 UTC: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:916619510@192.168.5.20:49414 SIP/2.0
Via: SIP/2.0/UDP 90.169.25.195:5060;branch=z9hG4bK7810e58a;rport
Max-Forwards: 70
From: "627559040" <sip:627559040@90.169.25.195>;tag=as5f3125fc
To: <sip:916619510@192.168.5.20>;tag=60C2F18-1EC3
Contact: <sip:627559040@90.169.25.195:5060>
Call-ID: 6b96cb3a3ada79165b3b603b7d4ef4b5@90.169.25.195:5060
CSeq: 102 ACK
User-Agent: FPBX-2.11.0(11.13.0)
Content-Length: 0

my show run:

#show run
Building configuration...
!
isdn switch-type basic-net3
!
cts logging verbose
!

voice-card 0
codec complexity high
dspfarm
dsp services dspfarm
!
!
!
voice service voip
ip address trusted list
ipv4 90.169.25.195
ipv4 213.97.72.7
ipv4 192.168.5.6
ipv4 192.168.5.7
ipv4 192.168.5.8
ipv4 192.168.5.10
ipv4 192.168.5.20
dtmf-interworking rtp-nte
mode border-element
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
supplementary-service h450.12
no supplementary-service sip moved-temporarily
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711alaw
h323
h225 connect-passthru
sip
bind control source-interface GigabitEthernet0/1
bind media source-interface GigabitEthernet0/1
no update-callerid
early-offer forced
no silent-discard untrusted
midcall-signaling passthru
privacy-policy passthru
!
voice class codec 10
codec preference 1 g711ulaw
codec preference 2 g729r8
codec preference 3 g711alaw
!
!

!
!
!
!
voice translation-rule 2
rule 1 /^911091538/ /627559040/
!
!
voice translation-profile DDI_PromocionMadrid
translate called 2
!
!
!
license udi pid CISCO2901/K9 sn FGL1916216U
hw-module pvdm 0/0
!
hw-module pvdm 0/1
!
!
!
redundancy
!
!
!
translation-rule 1
Rule 0 ^08 8
Rule 1 ^09 9
!
!
!
!
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
!
interface GigabitEthernet0/1
description VLAN Voz
ip address 192.168.5.20 255.255.255.0
duplex auto
speed auto
h323-gateway voip interface
h323-gateway voip bind srcaddr 192.168.5.20
!
interface BRI0/0/0
description Track 683700932 683700955
no ip address
isdn switch-type basic-net3
isdn point-to-point-setup
isdn incoming-voice voice
isdn sending-complete
isdn static-tei 0
!
interface BRI0/0/1
no ip address
isdn switch-type basic-net3
isdn point-to-point-setup
isdn incoming-voice voice
isdn sending-complete
!

!
control-plane
!
!
voice-port 0/0/0
compand-type a-law
cptone ES
connection plar 201
!
voice-port 0/0/1
compand-type a-law
cptone ES
!
voice-port 0/3/0
cptone ES
station-id number 916619510
caller-id enable
!
voice-port 0/3/1
!
!
!
!
!

!
sccp local GigabitEthernet0/1
sccp ccm 192.168.5.6 identifier 10 version 7.0
sccp
!
sccp ccm group 1
associate ccm 10 priority 1
associate profile 3 register RFS-MTP
associate profile 2 register RFS-CON
associate profile 1 register RFS-TRA
keepalive retries 5
switchover method immediate
switchback method immediate
switchback interval 5
!
!
!
dspfarm profile 1 transcode
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
codec g729br8
codec pass-through
maximum sessions 3
associate application SCCP
!
dspfarm profile 2 conference
codec g729br8
codec g729r8
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 1
associate application SCCP
!
dspfarm profile 3 mtp
description recursos_mtp_soft
codec g711ulaw
codec pass-through
maximum sessions hardware 10
maximum sessions software 500
associate application SCCP
!
dial-peer voice 6001 pots
preference 1
destination-pattern 6........
direct-inward-dial
port 0/0/0
prefix 6
no sip-register
!
dial-peer voice 7001 pots
preference 1
destination-pattern 7........
direct-inward-dial
port 0/0/0
prefix 7
no sip-register
!
dial-peer voice 4 voip
incoming called-number .
voice-class codec 10
dtmf-relay rtp-nte
!
dial-peer voice 5 voip
destination-pattern 9T
signaling forward unconditional
session protocol sipv2
session target ipv4:192.168.5.6
session transport udp
voice-class codec 10
dtmf-relay rtp-nte
!
dial-peer voice 9000 voip
destination-pattern 0[89]........
translate-outgoing called 1
session protocol sipv2
session target ipv4:90.169.25.195
session transport udp
voice-class codec 10
voice-class sip options-keepalive up-interval 12 down-interval 65 retry 3
dtmf-relay rtp-nte
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback pass-through g711alaw
authentication username 2155 password 7 143F145A3A2E0E0411212B17343B010F5027605A
!
dial-peer voice 6 voip
translation-profile incoming DDI_PromocionMadrid
shutdown
incoming called-number 911091538
voice-class codec 10
!
dial-peer voice 5000 pots
destination-pattern 916619510
direct-inward-dial
port 0/3/0
!
dial-peer voice 60 voip
shutdown
destination-pattern 627559040
session protocol sipv2
session target ipv4:90.169.25.195
session transport udp
voice-class codec 10
voice-class sip options-keepalive up-interval 12 down-interval 65 retry 3
dtmf-relay rtp-nte
authentication username 2155 password 7 143F145A3A2E0E0411212B17343B010F5027605A
!
!

sip-ua
credentials username 2155 password 7 10660F48333D3624390D32093200352C71353115 realm sip1.sietevoz.com
authentication username 2155 password 7 032C5D5A302505637B000127013A0D15570C1D27
no remote-party-id
retry invite 2
retry register 10
retry subscribe 10
retry options 10
timers connect 100
registrar ipv4:90.169.25.195 expires 3600
sip-server dns:sip1.sietevoz.com
host-registrar
!
!
!
gatekeeper
shutdown
!
!
telephony-service
sdspfarm units 3
sdspfarm transcode sessions 10
sdspfarm tag 1 RFS-TRA
sdspfarm tag 2 RFS-CON
sdspfarm tag 3 RFS-MTP
max-ephones 1
max-dn 1
ip source-address 192.168.5.20 port 2000
max-conferences 8 gain -6
transfer-system full-consult

!

end

[aherraez77]

As you can see the incoming call matches incoming dial-peer 4 and outgoing dial-peer 5000 for the call and the fxs port receives the call as I show in the first post.

*Jan 13 2016 17:52:56.256 UTC: //-1/EDC67CBFAD7E/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=627559040
----- ccCallInfo IE subfields -----
cisco-ani=627559040
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=916619510
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Jan 13 2016 17:52:56.260 UTC: //-1/EDC67CBFAD7E/CCAPI/cc_api_call_setup_ind_common:
Interface=0x22556880, Call Info(
Calling Number=627559040,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916619510(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=4, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=11065
*Jan 13 2016 17:52:56.260 UTC: //-1/EDC67CBFAD7E/CCAPI/ccCheckClipClir:
In: Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 17:52:56.260 UTC: //-1/EDC67CBFAD7E/CCAPI/ccCheckClipClir:
Out: Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 17:52:56.260 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.260 UTC: :cc_get_feature_vsa malloc success
*Jan 13 2016 17:52:56.260 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.260 UTC: cc_get_feature_vsa count is 1
*Jan 13 2016 17:52:56.260 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.260 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:563333744,feature_id:489
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916619510(TON=Unknown, NPI=Unknown))
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_process_call_setup_ind:
Event=0x3EA4B898
*Jan 13 2016 17:52:56.260 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 916619510
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallSetContext:
Context=0x2101ADA4
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 11065 with tag 4 to app "_ManagedAppProcess_Default"
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=5000, Params=0x2101C39C, Progress Indication=NULL(0)
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCheckClipClir:
In: Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 17:52:56.260 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCheckClipClir:
Out: Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 17:52:56.264 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallSetupRequest:
Destination Pattern=916619510, Called Number=916619510, Digit Strip=TRUE
*Jan 13 2016 17:52:56.264 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallSetupRequest:
Calling Number=627559040(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916619510(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=627559040
Account Number=627559040, Final Destination Flag=TRUE,
Guid=EDC67CBF-B94C-11E5-AD7E-B1B643910E91, Outgoing Dial-peer=5000
*Jan 13 2016 17:52:56.264 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=627559040
----- ccCallInfo IE subfields -----
cisco-ani=627559040
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=916619510
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Jan 13 2016 17:52:56.264 UTC: //11065/EDC67CBFAD7E/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x3EAE7F0C, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=627559040,(Calling Name=627559040)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=916619510(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=5000, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Jan 13 2016 17:52:56.264 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.264 UTC: :cc_get_feature_vsa malloc success
*Jan 13 2016 17:52:56.264 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.264 UTC: cc_get_feature_vsa count is 2
*Jan 13 2016 17:52:56.264 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 17:52:56.264 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:563335536,feature_id:490
*Jan 13 2016 17:52:56.264 UTC: //11066/EDC67CBFAD7E/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
*Jan 13 2016 17:52:56.264 UTC: //11066/EDC67CBFAD7E/CCAPI/ccCallSetContext:
Context=0x2101C34C
*Jan 13 2016 17:52:56.264 UTC: //11065/EDC67CBFAD7E/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=5000
*Jan 13 2016 17:52:56.268 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_call_proceeding:
Interface=0x3EAE7F0C, Progress Indication=NULL(0)
*Jan 13 2016 17:52:56.268 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_call_cut_progress:
Interface=0x3EAE7F0C, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2),
Cause Value=0
*Jan 13 2016 17:52:56.268 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_call_cut_progress:
Call Entry(Responsed=TRUE)
*Jan 13 2016 17:52:56.268 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallCutProgress:
Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0
Voice Call Send Alert=FALSE, Call Entry(Alert Sent=FALSE)
*Jan 13 2016 17:52:56.268 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallCutProgress:
Call Entry(Responsed=TRUE)
*Jan 13 2016 17:52:56.268 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID1=0x2B39, gcid=EDC67CBF-B94C11E5-AD81B1B6-43910E91, tag=0x0)
*Jan 13 2016 17:52:56.268 UTC: //11066/EDC67CBFAD7E/CCAPI/ccConferenceCreate:
(confID=0xFFFFFFFF, callID2=0x2B3A, gcid=EDC67CBF-B94C11E5-AD81B1B6-43910E91, tag=0x0)
*Jan 13 2016 17:52:56.268 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceCreate:
Conference Id=0xFFFFFFFF, Call Id1=11065, Call Id2=11066, Tag=0x0
*Jan 13 2016 17:52:56.268 UTC: //11065/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:52:56.268 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:52:56.268 UTC: //11065/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:52:56.268 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_bridge_done:
Conference Id=0xDA, Source Interface=0x22556880, Source Call Id=11065,
Destination Call Id=11066, Disposition=0x0, Tag=0x0
*Jan 13 2016 17:52:56.272 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_bridge_done:
Conference Id=0xDA, Source Interface=0x3EAE7F0C, Source Call Id=11066,
Destination Call Id=11065, Disposition=0x0, Tag=0xFFFFFFFF
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_generic_bridge_done:
Conference Id=0xDA, Source Interface=0x3EAE7F0C, Source Call Id=11066,
Destination Call Id=11065, Disposition=0x0, Tag=0xFFFFFFFF
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xDA, Destination Call Id=11066)
*Jan 13 2016 17:52:56.272 UTC: //11066/EDC67CBFAD7E/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0xDA, Destination Call Id=11065)
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceCreate:

*Jan 13 2016 17:52:56.272 UTC: confID:0xDA; callEntry1 callID1:0x2B39, type:3; callEntry2 callID2:0x2B3A, type:6

*Jan 13 2016 17:52:56.272 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_caps_ind:
Destination Interface=0x22556880, Destination Call Id=11065, Source Call Id=11066,
Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1,
Modem=0x2, Codec Bytes=20, Signal Type=3)
*Jan 13 2016 17:52:56.272 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_caps_ind:
Destination Interface=0x3EAE7F0C, Destination Call Id=11066, Source Call Id=11065,
Caps(Codec=0x2, Fax Rate=0x2, Fax Version:=0, Vad=0x2,
Modem=0x0, Codec Bytes=160, Signal Type=2)
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=1000(ms), Fax Nom=300(ms))
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_caps_ack:
Destination Interface=0x3EAE7F0C, Destination Call Id=11066, Source Call Id=11065,
Caps(Codec=g711alaw(0x2), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=ON(0x2),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=3693)
*Jan 13 2016 17:52:56.272 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_caps_ack:
Destination Interface=0x22556880, Destination Call Id=11065, Source Call Id=11066,
Caps(Codec=g711alaw(0x2), Fax Rate=FAX_RATE_VOICE(0x2), Fax Version:=0, Vad=ON(0x2),
Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=3693)
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=11065
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=11065
*Jan 13 2016 17:52:56.272 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_process_notify_bridge_done:
Conference Id=0xDA, Call Id1=11065, Call Id2=11066
*Jan 13 2016 17:52:56.276 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_voice_mode_event:
Call Id=11066
*Jan 13 2016 17:52:56.276 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_voice_mode_event:
Call Entry(Context=0x2101C34C)
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_call_disconnected:
Cause Value=16, Interface=0x22556880, Call Id=11065
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceDestroy:
Conference Id=0xDA, Tag=0x0
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/ccConferenceDestroy:

*Jan 13 2016 17:53:01.864 UTC: confID:0xDA; callEntry1 callID1:0x2B39, type:3; callEntry2 callID2:0x2B3A, type:6

*Jan 13 2016 17:53:01.864 UTC: //11065/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:53:01.864 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:53:01.864 UTC: //11066/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:53:01.864 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:53:01.864 UTC: //11065/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:53:01.864 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:53:01.864 UTC: //11065/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Jan 13 2016 17:53:01.864 UTC: cc_api_get_xcode_stream : 4982
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xDA, Source Interface=0x22556880, Source Call Id=11065,
Destination Call Id=11066, Disposition=0x0, Tag=0x0
*Jan 13 2016 17:53:01.864 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_bridge_drop_done:
Conference Id=0xDA, Source Interface=0x3EAE7F0C, Source Call Id=11066,
Destination Call Id=11065, Disposition=0x0, Tag=0x0
*Jan 13 2016 17:53:01.864 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_generic_bridge_done:
Conference Id=0xDA, Source Interface=0x3EAE7F0C, Source Call Id=11066,
Destination Call Id=11065, Disposition=0x0, Tag=0x0
*

*Jan 13 2016 17:53:01.868 UTC: //11066/EDC67CBFAD7E/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 13 2016 17:53:01.868 UTC: //11066/EDC67CBFAD7E/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Jan 13 2016 17:53:01.868 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
*Jan 13 2016 17:53:01.868 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallDisconnect:
Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
*Jan 13 2016 17:53:01.868 UTC: //11065/EDC67CBFAD7E/CCAPI/ccCallDisconnect:
Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Jan 13 2016 17:53:01.868 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EAE7F0C, Tag=0x0, Call Id=11066,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Jan 13 2016 17:53:01.868 UTC: //11066/EDC67CBFAD7E/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 13 2016 17:53:01.868 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 13 2016 17:53:01.868 UTC: :cc_free_feature_vsa freeing 2193D168
*Jan 13 2016 17:53:01.868 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 13 2016 17:53:01.868 UTC: vsacount in free is 1ter
*Jan 13 2016 17:53:01.888 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x22556880, Tag=0x0, Call Id=11065,
Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Jan 13 2016 17:53:01.888 UTC: //11065/EDC67CBFAD7E/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 13 2016 17:53:01.888 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 13 2016 17:53:01.888 UTC: :cc_free_feature_vsa freeing 2193CA68
*Jan 13 2016 17:53:01.888 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

[Ayodeji Okanlawon]

The first question is how is this fxs port configured ? I suggest you use SCCP to control the fxs port on cucm. Do you know how to do this?

[aherraez77]

Hi, Ayodeji

As you can see in the Show Run I have the gw configure to use sscp. 

I have configured the FXS port :

dial-peer voice 5000 pots
service stcapp
direct-inward-dial
port 0/3/0

I have configured the gw in the cm. And I have the voice port registered in the cm.

Now I need to change the conexion to the fax machine to test the configuration.

Mainwhile I have the fax connected to an ATA 186 and although I can send a fax and I have a OK in the report status, the destination doens´t receive anything. I think that I have algo something wrong in the gw configuration. What do you think?

thanks for your help

Best Regards. 

[Ayodeji Okanlawon]

OK. So now you have configured the device to use sccp. Great. Before you plug a fax machine to it, test the port with an analogue phone, ensure you can make and receive calls through it.

I don't like ATA186 devices, so I can't help you there but when you have the fax connected to the analog port then we can look at it.

You also need to ensure and check that your provider supports t. 38

[aherraez77]

Hi Ayodeji:

I have the fax connected to the fxs port and the result is the same as the beginning:

If I call the fax the port is ringing you don´t hear the destination ringing.

I am going to talk with the provider to confirm that support T.38

Best Regards

[Ayodeji Okanlawon]

What happens when you connect an analogue port to the port and you call the other fax line? Do you hear the fax tone?

[Ryan Huff]

Honestly, for analog faxing in a gateway like this, you shouldn't be using skinny (SCCP). Ideally you should use h.323 or SIP. If you must use gateway controlled ports, MGCP is a much better option for analog faxing.

If you are planning on using protocol-based T.38 then SCCP is out of the picture as it only supports NSE based T.38.

Thanks,

Ryan

[Ayodeji Okanlawon]

Hi Ryan,

May I ask why sccp shouldn't be used to control gateway ports that will be used for faxes. I have over 200 analogue ports using sccp that work perfectly. I do understand limitation with T.38 but aside that I don't see why not.

[Ryan Huff]

Not that skinny doesn't work, it does; but in my experience causes more issues than not for analog faxing; and it has a fairly decent limitation (especially when doing T.38 over SIP).

[aherraez77]

this is from a "debug voice ccapi inout" in a outgoing call:

*Jan 13 2016 18:01:02.940 UTC: //-1/0FDC9025ADBD/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=
----- ccCallInfo IE subfields -----
cisco-ani=916619510
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=0
dest=
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=0
cisco-rdnsi=0
cisco-redirectreason=0 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

*Jan 13 2016 18:01:02.940 UTC: //-1/0FDC9025ADBD/CCAPI/cc_api_call_setup_ind_common:
Interface=0x3EAE7F0C, Call Info(
Calling Number=916619510,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
Incoming Dial-peer=5000, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
*Jan 13 2016 18:01:02.940 UTC: //-1/0FDC9025ADBD/CCAPI/ccCheckClipClir:
In: Calling Number=916619510(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 18:01:02.940 UTC: //-1/0FDC9025ADBD/CCAPI/ccCheckClipClir:
Out: Calling Number=916619510(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
*Jan 13 2016 18:01:02.940 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 18:01:02.940 UTC: :cc_get_feature_vsa malloc success
*Jan 13 2016 18:01:02.940 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 18:01:02.940 UTC: cc_get_feature_vsa count is 1
*Jan 13 2016 18:01:02.940 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Jan 13 2016 18:01:02.940 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:563333744,feature_id:493
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=916619510(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
Called Number=(TON=Unknown, NPI=Unknown))
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/cc_process_call_setup_ind:
Event=0x3EA4D9B0
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/ccCallSetContext:
Context=0x21029BDC
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 11120 with tag 5000 to app "_ManagedAppProcess_Default"
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/ccCallDisconnect:
Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/ccCallDisconnect:
Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)
*Jan 13 2016 18:01:02.940 UTC: //11120/0FDC9025ADBD/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
*Jan 13 2016 18:01:17.240 UTC: //11120/0FDC9025ADBD/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EAE7F0C, Tag=0x0, Call Id=11120,
Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)
*Jan 13 2016 18:01:17.240 UTC: //11120/0FDC9025ADBD/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*Jan 13 2016 18:01:17.240 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Jan 13 2016 18:01:17.240 UTC: :cc_free_feature_vsa freeing 2193CA68
*Jan 13 2016 18:01:17.240 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: