Phone Keep rining even after PSTN disconnected..

anilkumar.cisco · ‎12-26-2017

Error: of Debug ISDN Q931

Cause i = 0x80E6 - Recovery on timer expiry

Phone mode: 3905 and 7821

CUCM : System version: 10.0.1.10000-24

Cisco IOS XE Software, Version 03.16.04b.S - Extended Support Release
Cisco IOS Software, ISR Software (X86_64_LINUX_IOSD-UNIVERSALK9-M), Version 15.5(3)S4b, RELEASE SOFTWARE (fc1)

cisco ISR4331/K9 (1RU) processor with 1648789K/6147K bytes of memory.
Processor board ID FDO2132A0QV
3 Gigabit Ethernet interfaces
31 Serial interfaces
1 Channelized E1/PRI port
32768K bytes of non-volatile configuration memory.
4194304K bytes of physical memory.
3207167K bytes of flash memory at bootflash:.

Gateway is configured as H.323

VG configuration..

!

ip dhcp excluded-address X.X.29.1
ip dhcp excluded-address X.X.29.6
ip dhcp excluded-address X.X.29.2
!
ip dhcp pool VOICE
network X.X.29.0 255.255.255.0
default-router X.X.29.1
option 150 ip X.X.10.62 X.X.10.72 X.X.29.1
!
!
!
!
!
!
!
!
!
!
subscriber templating
multilink bundle-name authenticated
!
!
!
!
isdn switch-type primary-net5
!
!
!
!
!
voice service voip
no ip address trusted authenticate
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none
sip
bind control source-interface GigabitEthernet0/0/0
bind media source-interface GigabitEthernet0/0/0
registrar server expires max 600 min 60
!
voice class codec 1
codec preference 1 g722-64
codec preference 2 g729r8
!
voice class h323 2
!
voice class h323 1
h225 timeout tcp establish 3
h225 timeout setup 3
!
!
!
!
!
voice register global
mode esrst
source-address X.X.29.1 port 5060
no allow-hash-in-dn
timeouts interdigit 5
max-dn 400
max-pool 110
timezone 39
date-format D/M/Y
dialplan-pattern 1 666... extension-length 4
ntp-server X.X.8.1 mode directedbroadcast
conference hardware
!
voice register pool 1
id network X.X.29.0 mask 255.255.255.0
type 3905
dtmf-relay rtp-nte sip-kpml
codec g711ulaw
no vad
!
voice register pool 2
busy-trigger-per-button 2
id network X.X.29.0 mask 255.255.255.0
type 7821
dtmf-relay rtp-nte sip-kpml
codec g711ulaw
no vad
!
!
!
!
!
!
voice-card 0/1
dsp services dspfarm
no watchdog
!
voice-card 0/4
dsp services dspfarm
no watchdog
!
license udi pid ISR4331/K9 sn FDO21310YDH
!
spanning-tree extend system-id
!
!
redundancy
mode none
!
controller E1 0/1/0
pri-group timeslots 1-31
!
!
vlan internal allocation policy ascending
!
!
!
!
!
!
interface GigabitEthernet0/0/0
ip address X.X.29.1 255.255.255.0
negotiation auto
h323-gateway voip interface
h323-gateway voip bind srcaddr X.X.29.1
!
interface GigabitEthernet0/0/1
no ip address
shutdown
negotiation auto
!
interface GigabitEthernet0/0/2
no ip address
shutdown
negotiation auto
!
interface Service-Engine0/1/0
!
interface Serial0/1/0:15
encapsulation hdlc
isdn switch-type primary-net5
isdn incoming-voice voice
!
interface Service-Engine0/4/0
!
interface GigabitEthernet0
vrf forwarding Mgmt-intf
no ip address
shutdown
negotiation auto
!
interface Vlan1
no ip address
shutdown
!
ip default-gateway X.X.29.6
ip forward-protocol nd
no ip http server
no ip http secure-server
ip tftp source-interface GigabitEthernet0
ip route 0.0.0.0 0.0.0.0 X.X.29.6
!
!
!
!
!
control-plane
!
!
voice-port 0/1/0:15
!
!
!
!
!
mgcp call-agent X.X.10.62 service-type mgcp version 0.1
mgcp behavior rsip-range tgcp-only
mgcp behavior comedia-role none
mgcp behavior comedia-check-media-src disable
mgcp behavior comedia-sdp-force disable
!
mgcp profile default
!
sccp local GigabitEthernet0/0/0
sccp ccm X.X.10.72 identifier 1 priority 1 version 7.0
sccp ccm X.X.10.62 identifier 2 priority 2 version 7.0
sccp ccm X.X.29.1 identifier 3 priority 3 version 7.0
sccp
!
sccp ccm group 1
bind interface GigabitEthernet0/0/0
associate ccm 1 priority 1
associate ccm 2 priority 2
associate ccm 3 priority 3
associate profile 2 register TRANSC
associate profile 1 register CONFER
!
!
ccm-manager config server X.X.10.62
ccm-manager config
!
telephony-service
sdspfarm units 2
sdspfarm transcode sessions 3
sdspfarm tag 1 CONFER
sdspfarm tag 2 TRANSC
conference hardware
max-ephones 10
max-dn 10
max-conferences 8 gain -6
transfer-system full-consult
!
!
dspfarm profile 2 transcode
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 11
associate application SCCP
!
dspfarm profile 1 conference
codec g729br8
codec g729r8
codec g729abr8
codec g729ar8
codec g711alaw
codec g711ulaw
maximum sessions 3
associate application SCCP
!
dial-peer voice 1 pots
destination-pattern .T
incoming called-number .
direct-inward-dial
port 0/1/0:15
!
dial-peer voice 10 voip
preference 1
destination-pattern 666...
session target ipv4:X.X.10.62
incoming called-number 666...
voice-class codec 1
voice-class h323 1
dtmf-relay h245-alphanumeric
no vad
!
dial-peer voice 11 voip
destination-pattern 666...
session target ipv4:X.X.10.72
incoming called-number 666...
voice-class codec 1
voice-class h323 1
dtmf-relay h245-alphanumeric
no vad
!
!
sip-ua
!
!
ephone-dn 1 octo-line
number DDDD
conference ad-hoc
!
!
line con 0
stopbits 1
line aux 0
stopbits 1
line vty 0 4
password P@ssw0rd
login
!
!
end

anilkumar.cisco · ‎12-26-2017

out put of Debug voice ccapi inout

*Dec 27 07:55:52.806: ISDN Se0/1/0:15 Q931: RX <- SETUP pd = 8 callref = 0x014B
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA98381
                Exclusive, Channel 1
        Calling Party Number i = 0x2183, '01147811216'
                Plan:ISDN, Type:National
        Called Party Number i = 0xC1, '666299'
                Plan:ISDN, Type:Subscriber(local)
        Sending Complete
*Dec 27 07:55:52.807: ISDN Se0/1/0:15 Q931: Received SETUP callref = 0x814B callID = 0x0190 switch = primary-net5 interface = User
*Dec 27 07:55:52.808: //-1/320C557A8216/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=01147811216
   cisco-anitype=2
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=3
   dest=666299
   cisco-desttype=4
   cisco-destplan=1
   cisco-rdie=FFFFFFFFFFFFFFFF
   cisco-rdn=
   cisco-rdntype=-1
   cisco-rdnplan=-1
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

*Dec 27 07:55:52.808: //-1/320C557A8216/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x7FE93BEB0CA0, Call Info(
   Calling Number=01147811216,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=666299(TON=Subscriber, NPI=ISDN),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
*Dec 27 07:55:52.808: //-1/320C557A8216/CCAPI/ccCheckClipClir:
   In: Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
*Dec 27 07:55:52.808: //-1/320C557A8216/CCAPI/ccCheckClipClir:
   Out: Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
*Dec 27 07:55:52.808: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.808: :cc_get_feature_vsa malloc success
*Dec 27 07:55:52.808: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.808: cc_get_feature_vsa count is 1
*Dec 27 07:55:52.808: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.808: :FEATURE_VSA attributes are: feature_name:0,feature_time:140639638784620,feature_id:1478
*Dec 27 07:55:52.808: //2105/320C557A8216/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=666299(TON=Subscriber, NPI=ISDN))
*Dec 27 07:55:52.809: //2105/320C557A8216/CCAPI/cc_process_call_setup_ind:
   Event=0x7FE937B81BE8
*Dec 27 07:55:52.809: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 666299
*Dec 27 07:55:52.809: //2105/320C557A8216/CCAPI/ccCallSetContext:
   Context=0x7FE944E75448
*Dec 27 07:55:52.809: //2105/320C557A8216/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 2105 with tag 1 to app "_ManagedAppProcess_Default"
*Dec 27 07:55:52.809: //2105/320C557A8216/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
*Dec 27 07:55:52.809: //2105/320C557A8216/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=11, Params=0x7FE93C742FB8, Progress Indication=NULL(0)
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccCheckClipClir:
   In: Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccCheckClipClir:
   Out: Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed)
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccCallSetupRequest:
   Destination Pattern=666..., Called Number=666299, Digit Strip=FALSE
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccCallSetupRequest:
   Calling Number=01147811216(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=666299(TON=Subscriber, NPI=ISDN),
   Redirect Number=, Display Info=
   Account Number=, Final Destination Flag=TRUE,
   Guid=320C557A-EA12-11E7-8216-74860BB98030, Outgoing Dial-peer=11
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=01147811216
   cisco-anitype=2
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=3
   dest=666299
   cisco-desttype=4
   cisco-destplan=1
   cisco-rdie=FFFFFFFFFFFFFFFF
   cisco-rdn=
   cisco-rdntype=-1
   cisco-rdnplan=-1
   cisco-rdnpi=-1
   cisco-rdnsi=-1
   cisco-redirectreason=-1   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x7FE933AFD258, Interface Type=1, Destination=, Mode=0x0,
   Call Params(Calling Number=01147811216,(Calling Name=)(TON=National, NPI=ISDN, Screening=Network, Presentation=Allowed),
   Called Number=666299(TON=Subscriber, NPI=ISDN), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=11, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
*Dec 27 07:55:52.810: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.810: :cc_get_feature_vsa malloc success
*Dec 27 07:55:52.810: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.810: cc_get_feature_vsa count is 2
*Dec 27 07:55:52.810: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

*Dec 27 07:55:52.810: :FEATURE_VSA attributes are: feature_name:0,feature_time:140639638787308,feature_id:1479
*Dec 27 07:55:52.810: //2106/320C557A8216/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
*Dec 27 07:55:52.810: //2106/320C557A8216/CCAPI/ccCallSetContext:
   Context=0x7FE93C742F38
*Dec 27 07:55:52.810: //2105/320C557A8216/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=11
*Dec 27 07:55:52.810: //2106/320C557A8216/CCAPI/ccGetMediaClassTag:
   media class tag 0
*Dec 27 07:55:52.810: //2106/320C557A8216/CCAPI/ccSetMediaclassIp2ipTags:
   media class tags set: NR 0, ASP 0
*Dec 27 07:55:52.811: //2105/320C557A8216/CCAPI/ccSetMediaclassIp2ipTags:
   media class tags set: NR 0, ASP 0
*Dec 27 07:55:52.811: //2106/320C557A8216/CCAPI/ccGet_xc_nr_asp_info:
   media class tags: NR 0, ASP 0
*Dec 27 07:55:52.811: //2105/320C557A8216/CCAPI/ccGet_xc_nr_asp_info:
   media class tags: NR 0, ASP 0
*Dec 27 07:55:52.811: ISDN Se0/1/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0x814B
        Channel ID i = 0xA98381
                Exclusive, Channel 1
*Dec 27 07:55:53.068: //2106/320C557A8216/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
*Dec 27 07:55:53.068: //2106/320C557A8216/CCAPI/cc_api_call_proceeding:
   Interface=0x7FE933AFD258, Progress Indication=NULL(0)
*Dec 27 07:55:53.364: //2106/320C557A8216/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
*Dec 27 07:55:53.364: //2106/320C557A8216/CCAPI/cc_api_set_delay_xport:
   CallInfo(delay xport=TRUE)
*Dec 27 07:55:53.364: //2106/320C557A8216/CCAPI/cc_api_call_alert:
   Interface=0x7FE933AFD258, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Dec 27 07:55:53.364: //2106/320C557A8216/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
*Dec 27 07:55:53.364: //2105/320C557A8216/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
*Dec 27 07:55:53.365: //2105/320C557A8216/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
*Dec 27 07:55:53.365: //2105/320C557A8216/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Ring Back,
   Tone Direction=Network, Params=0x0, Call Id=2105
*Dec 27 07:55:53.368: ISDN Se0/1/0:15 Q931: TX -> ALERTING pd = 8 callref = 0x814B
*Dec 27 07:55:53.368: //2106/320C557A8216/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
*Dec 27 07:55:53.368: //2106/320C557A8216/CCAPI/cc_api_call_notify:
   Data Bitmask=0x5, Interface=0x7FE933AFD258, Call Id=2106
*Dec 27 07:55:53.369: //2106/320C557A8216/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
   CallInfo(ssCTreRoutingNotSupported=FALSE)
*Dec 27 07:55:53.369: //2106/320C557A8216/CCAPI/cc_api_get_ccm_detected:
   CallInfo(ccm detected=TRUE)
*Dec 27 07:55:53.369: //2105/320C557A8216/CCAPI/ccCallNotify:
   Data Bitmask=0x5, Call Id=2105
*Dec 27 07:55:57.103: //2106/320C557A8216/CCAPI/ccIsInfoRingback:
   Returning dpRingBack=0
*Dec 27 07:55:57.386: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.386: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.386: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.386: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.386: //2106/320C557A8216/CCAPI/cc_api_call_connected:
   Interface=0x7FE933AFD258, Data Bitmask=0x0, Progress Indication=NULL(0),
   Connection Handle=0
*Dec 27 07:55:57.386: //2106/320C557A8216/CCAPI/cc_api_call_connected:
   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
*Dec 27 07:55:57.386: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.386: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.386: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.386: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.387: //2106/320C557A8216/CCAPI/cc_api_set_called_ccm_detected:
   CallInfo(called ccm detected=TRUE ccmVersion 3)
*Dec 27 07:55:57.387: //2106/320C557A8216/CCAPI/cc_api_call_notify:
   Data Bitmask=0x5, Interface=0x7FE933AFD258, Call Id=2106
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Network, Params=0x0, Call Id=2105
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/ccConferenceCreate:
   (confID=0xFFFFFFFFFFFFFFFF, callID1=0x839, gcid=320C557A-EA1211E7-82167486-BB98030, tag=0x0)
*Dec 27 07:55:57.387: //2106/320C557A8216/CCAPI/ccConferenceCreate:
   (confID=0xFFFFFFFFFFFFFFFF, callID2=0x83A, gcid=320C557A-EA1211E7-82167486-BB98030, tag=0x0)
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/ccConferenceCreate:
   Conference Id=0xFFFFFFFFFFFFFFFF, Call Id1=2105, Call Id2=2106, Tag=0x0
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/cc_api_bridge_done:
   Conference Id=0x1D3, Source Interface=0x7FE93BEB0CA0, Source Call Id=2105,
   Destination Call Id=2106, Disposition=0x0, Tag=0xFFFFFFFFFFFFFFFF
*Dec 27 07:55:57.387: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.387: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.387: //2106/320C557A8216/CCAPI/cc_api_bridge_done:
   Conference Id=0x1D3, Source Interface=0x7FE933AFD258, Source Call Id=2106,
   Destination Call Id=2105, Disposition=0x0, Tag=0x0
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/cc_generic_bridge_done:
   Conference Id=0x1D3, Source Interface=0x7FE933AFD258, Source Call Id=2106,
   Destination Call Id=2105, Disposition=0x0, Tag=0x0
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x1D3, Destination Call Id=2106)
*Dec 27 07:55:57.387: //2106/320C557A8216/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x1D3, Destination Call Id=2105)
*Dec 27 07:55:57.387: //2105/320C557A8216/CCAPI/ccConferenceCreate:

*Dec 27 07:55:57.387: confID:0x1D3; callEntry1 callID1:0x839, type:6; callEntry2 callID2:0x83A, type:1

*Dec 27 07:55:57.388: //2105/320C557A8216/CCAPI/cc_api_caps_ind:
   Destination Interface=0x7FE933AFD258, Destination Call Id=2106, Source Call Id=2105,
   Caps(Codec=0x1, Fax Rate=0x1, Fax Version:=0, Vad=0x1,
   Modem=0x2, Codec Bytes=20, Signal Type=3)
*Dec 27 07:55:57.388: //2105/320C557A8216/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))
*Dec 27 07:55:57.388: //2106/320C557A8216/CCAPI/cc_api_get_ssCTreRoutingNotSupported:
   CallInfo(ssCTreRoutingNotSupported=FALSE)
*Dec 27 07:55:57.388: //2106/320C557A8216/CCAPI/cc_api_get_ccm_detected:
   CallInfo(ccm detected=TRUE)
*Dec 27 07:55:57.388: //2105/320C557A8216/CCAPI/ccCallNotify:
   Data Bitmask=0x5, Call Id=2105
*Dec 27 07:55:57.389: //2105/320C557A8216/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x1D3, Call Id1=2105, Call Id2=2106
*Dec 27 07:55:57.389: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.389: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.389: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.389: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.390: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.390: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.390: //2106/320C557A8216/CCAPI/cc_api_caps_ind:
   Destination Interface=0x7FE93BEB0CA0, Destination Call Id=2105, Source Call Id=2106,
   Caps(Codec=0x10000000, Fax Rate=0x80, Fax Version:=0, Vad=0x1,
   Modem=0x0, Codec Bytes=160, Signal Type=2)
*Dec 27 07:55:57.390: //2106/320C557A8216/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))
*Dec 27 07:55:57.390: //2106/320C557A8216/CCAPI/cc_api_caps_ack:
   Destination Interface=0x7FE93BEB0CA0, Destination Call Id=2105, Source Call Id=2106,
   Caps(Codec=g722-64(0x10000000), Fax Rate=FAX_RATE_14400(0x80), Fax Version:=0, Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5042)
*Dec 27 07:55:57.390: //2105/320C557A8216/CCAPI/cc_api_caps_ack:
   Destination Interface=0x7FE933AFD258, Destination Call Id=2106, Source Call Id=2105,
   Caps(Codec=g722-64(0x10000000), Fax Rate=FAX_RATE_14400(0x80), Fax Version:=0, Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5042)
*Dec 27 07:55:57.390: //2105/320C557A8216/CCAPI/ccCallConnect:
   Progress Indication=NULL(0), Data Bitmask=0x1
*Dec 27 07:55:57.390: //2105/320C557A8216/CCAPI/ccCallConnect:
   Call Entry(Connected=TRUE, Responsed=TRUE)
*Dec 27 07:55:57.393: //2105/320C557A8216/CCAPI/cc_api_voice_mode_event:
   Call Id=2105
*Dec 27 07:55:57.393: //2105/320C557A8216/CCAPI/cc_api_voice_mode_event:
   Call Entry(Context=0x7FE944E75448)
*Dec 27 07:55:57.393: ISDN Se0/1/0:15 Q931: TX -> CONNECT pd = 8 callref = 0x814B
*Dec 27 07:55:57.723: ISDN Se0/1/0:15 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x014B
*Dec 27 07:55:57.845: ISDN Se0/1/0:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x014B
        Cause i = 0x8290 - Normal call clearing
        Progress Ind i = 0x8288 - In-band info or appropriate now available
*Dec 27 07:55:57.846: ISDN Se0/1/0:15 Q931: call_disc: PI received in disconnect; Postpone sending RELEASE for callid 0x190
*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/cc_api_call_disc_prog_ind:
   Cause Value=16, Progress Indication=INBAND(8), Interface=0x7FE93BEB0CA0
*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/cc_api_call_disc_prog_ind:
   Event(Progress Indication=0x0), Call Entry(Responsed=TRUE)
*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/ccConferenceDestroy:
   Conference Id=0x1D3, Tag=0x0
*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/ccConferenceDestroy:

*Dec 27 07:55:57.846: confID:0x1D3; callEntry1 callID1:0x839, type:6; callEntry2 callID2:0x83A, type:1

*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x1D3, Source Interface=0x7FE93BEB0CA0, Source Call Id=2105,
Destination Call Id=2106, Disposition=0x0, Tag=0x0
*Dec 27 07:55:57.846: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.846: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.846: //2106/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

*Dec 27 07:55:57.846: cc_api_get_xcode_stream : 4983
*Dec 27 07:55:57.846: //2106/320C557A8216/CCAPI/cc_api_bridge_drop_done:
   Conference Id=0x1D3, Source Interface=0x7FE933AFD258, Source Call Id=2106,
   Destination Call Id=2105, Disposition=0x0, Tag=0x0
*Dec 27 07:55:57.846: //2105/320C557A8216/CCAPI/cc_generic_bridge_done:
   Conference Id=0x1D3, Source Interface=0x7FE933AFD258, Source Call Id=2106,
   Destination Call Id=2105, Disposition=0x0, Tag=0x0
*Dec 27 07:55:57.847: //2105/320C557A8216/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
*Dec 27 07:55:57.847: //2105/320C557A8216/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Dec 27 07:55:57.847: //2105/320C557A8216/CCAPI/cc_api_get_transfer_info:
   Transfer Number=NULL
*Dec 27 07:55:57.847: //2106/320C557A8216/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
*Dec 27 07:55:57.847: //2106/320C557A8216/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
*Dec 27 07:55:57.847: //2106/320C557A8216/CCAPI/cc_api_get_transfer_info:
   Transfer Number=NULL
*Dec 27 07:55:57.867: //2106/320C557A8216/CCAPI/cc_api_get_transfer_info:
   Transfer Number=NULL
*Dec 27 07:55:57.868: //2106/320C557A8216/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x7FE933AFD258, Tag=0x0, Call Id=2106,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Dec 27 07:55:57.868: //2106/320C557A8216/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
*Dec 27 07:55:57.868: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Dec 27 07:55:57.868: :cc_free_feature_vsa freeing 7FE937B614E0
*Dec 27 07:55:57.868: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Dec 27 07:55:57.868: vsacount in free is 1
*Dec 27 07:55:57.886: //2105/320C557A8216/CCAPI/cc_api_call_disc_cause_update:
   Cause Value=16
*Dec 27 07:55:57.887: //2105/320C557A8216/CCAPI/cc_api_call_disc_cause_update:
   Call Entry(Disconnect Cause=16)
*Dec 27 07:55:57.887: ISDN Se0/1/0:15 Q931: TX -> RELEASE pd = 8 callref = 0x814B
*Dec 27 07:55:57.889: //2105/320C557A8216/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x7FE93BEB0CA0, Tag=0x0, Call Id=2105,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
*Dec 27 07:55:57.889: //2105/320C557A8216/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
*Dec 27 07:55:57.889: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Dec 27 07:55:57.889: :cc_free_feature_vsa freeing 7FE937B60A60
*Dec 27 07:55:57.889: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

*Dec 27 07:55:57.889: vsacount in free is 0
*Dec 27 07:55:58.051: ISDN Se0/1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x014B

Mohammed al Baqari · ‎12-27-2017

Check the link to your telco. Seems that you aren't receiving response for
setup message. This is the meaning of recovery timer expired