Avaya Support Forums

Avaya Support Forums (http://support.avaya.com/forums/index.php)
-   IP Telephony and Convergence (http://support.avaya.com/forums/forumdisplay.php?f=8)
-   -   Call rings once then nothing (http://support.avaya.com/forums/showthread.php?t=12226)

rfreil 12-02-2016 08:40 AM

Call rings once then nothing
 
Outside call comes in rings once the seems to drop.

This happens sporadically but often enough to be a nuance.
Below is the Sys Log showing the time and number when it happened.

IP 500 V2 9.0.7.0 build 983




IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPrivateNetLocalUser (1), pd=CMPDInbandPattern (8)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=815633829673
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=5 channel=20 features=0x0 rx_gain=32 tx_gain=32 ep_callid=25685 ipaddr=10.1.50.10 apps=8 loc=0 em_loc=0 features2=0x0
Timed: 01/12/16 14:18
14:18:06 508697419mS CD: CALL: 283.25683.0 BState=Ringing Cut=3 Music=0.0 Aend="bhageman(227)" (0.0) Bend="Line 5" [Line 5] (3.21) CalledNum=15633829673 () CallingNum=227 (bhageman) Internal=0 Time=11535 AState=Ringing
14:18:06 508697420mS CMMap: a=3.21 b=0.0 PCGS CPReserveCodec (pcp[216]b0r1) true
14:18:06 508697421mS CMMap: a=3.21 b=0.0 PCGS CPReserveCodec (pcp[216]b0r1) true
14:18:06 508697421mS CMMap: PCG::MapBChan pcp[216]b0r1 cp_b f5413b48 other_cp_b f53c1580 type CGTypeSimple
14:18:06 508697421mS CMMap: a=3.21 b=0.2 M12
14:18:06 508697839mS RES: Thu 1/12/2016 14:18:06 FreeMem=46350328 44079144(2) CachedMem=2271184 CMMsg=13(13) Buff=5200 1356 1000 7409 5 Links=25503 BTree=12859 CPU=09.33% CPUStats=4/11/920/17078/22765/1/0 MCR=0 MCW=0
14:18:06 508697839mS RES2: IP 500 V2 9.0.7.0 build 983 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=160 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=182(TLS=5) TAPI=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:18:06 508697839mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
14:18:10 508701049mS CMExtnEvt: Hospitality200: No user activity
14:18:10 508701235mS H323Evt: Recv: RegistrationRequest 10.1.50.27; Endpoints registered: 81; Endpoints in registration: 0
14:18:11 508702153mS H323Evt: Recv: RegistrationRequest 10.1.50.44; Endpoints registered: 81; Endpoints in registration: 0
14:18:11 508702959mS CMTARGET: ISDN BChannel 1: in-service check = 1
14:18:11 508702959mS ISDNL3Evt: v=5 stacknum=5 State, new=Present, old=NullState id=15
14:18:11 508702960mS CMLineRx: v=5
CMSetup
Line: type=Q931Line 5 Call: lid=5 id=15 in=1
Called[9673] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[815633875230] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
14:18:11 508702961mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 3.2
14:18:11 508702961mS CMCallEvt: 0.25686.0 -1 BaseEP: NEW CMEndpoint f466e2d8 TOTAL NOW=13 CALL_LIST=6
14:18:11 508702961mS CMTARGET: ISDN BChannel 1: in-service check = 1
14:18:11 508702962mS CMTARGET: ISDN BChannel 1: in-service check = 1
14:18:11 508702962mS CMCallEvt: CREATE CALL:6018 (f45fe810)
14:18:11 508702962mS CMCallEvt: 0.25687.0 -1 BaseEP: NEW CMEndpoint f44ac850 TOTAL NOW=14 CALL_LIST=6
14:18:11 508702965mS CD: CALL: 5.15.1 BState=Idle Cut=1 Music=0.0 Aend="Line 5" (3.2) Bend="" [] (0.0) CalledNum=9673 () CallingNum=815633875230 () Internal=0 Time=3 AState=Idle
14:18:11 508702965mS CMCallEvt: 5.15.1 6018 Q931 Trunk:5 CHAN=1: StateChange: END=A CMCSIdle->CMCSDialInitiated
14:18:11 508702965mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=9673 sub= calling=815633875230 dir=in complete=1 ses=0
14:18:11 508702965mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: SET BESTMATCH: length 0 vs -1 match= dest=HospitalityHG
14:18:11 508702965mS CMCallEvt: Priority hike: call 6018 priority 0->1
14:18:11 508702965mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: LOOKUP ICR: DDI= CGPN=815633875230 (Destination HospitalityHG ) => CDPN=HospitalityHG
14:18:11 508702966mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: ADD TARGET (N): number=HospitalityHG type=0 depth=1 nobar=1 setorig=1 ses=0
14:18:11 508702966mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: HG(HospitalityHG,700,10.1.50.10) Requires Routing To Master(1). IsLocalExecutive(1)
14:18:11 508702966mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: HG call targeting occuring here
14:18:11 508702966mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: PrimeForHGTarget: HospitalityHG setorig=1 recall=0 resetExtnVars 1
14:18:11 508702966mS CMCallEvt: Priority hike: call 6018 priority 1->5
14:18:11 508702966mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: AddHGTarget HospitalityHG (depth=1) allowq=1 type=CMNTypeUnknown
14:18:11 508702967mS CMCallEvt: 0.25688.0 -1 BaseEP: NEW CMEndpoint f463c1d0 TOTAL NOW=15 CALL_LIST=7
14:18:11 508702967mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: AddHGTargetRingGroup HospitalityHG starting at 1
14:18:11 508702967mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: ADD USER: Hospitality201 depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
14:18:11 508702968mS CMMap: a=0.18 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f5410838) resource busy 6, total 64
14:18:11 508702968mS CMCallEvt: 0.25689.0 -1 BaseEP: NEW CMEndpoint f45c4c10 TOTAL NOW=16 CALL_LIST=7
14:18:11 508702968mS CMCallEvt: 0.25689.0 -1 Hospitality201.-1: NEW CMExtnEndpoint f45c4c10, Name=Hospitality201, Extn=201, Phys Extn=201
14:18:11 508702970mS CMTARGET: 256.25689.0 6018 Hospitality201.0: ADD PRIMARY
14:18:11 508702970mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: ADD USER: Hospitality200 depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
14:18:11 508702970mS CMCallEvt: 0.25690.0 -1 BaseEP: NEW CMEndpoint f4589838 TOTAL NOW=17 CALL_LIST=7
14:18:11 508702970mS CMCallEvt: 0.25690.0 -1 Hospitality200.-1: NEW CMExtnEndpoint f4589838, Name=Hospitality200, Extn=200, Phys Extn=200
14:18:11 508702972mS CMTARGET: 313.25690.0 6018 Hospitality200.0: ADD PRIMARY
14:18:11 508702972mS CMTARGET: FoundKnownSystemTargets ICR cache hit
14:18:11 508702972mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: INITIAL TARGETING SUCCEEDED
14:18:11 508702973mS CMTARGET: 5.15.1 6018 Q931 Trunk:5 CHAN=1: GetNoAnswerTimer:15
14:18:11 508702973mS CMCallEvt: 5.15.1 6018 Q931 Trunk:5 CHAN=1: StateChange: END=A CMCSDialInitiated->CMCSDialled
14:18:11 508702973mS CMLineTx: v=5
CMProceeding
Line: type=Q931Line 5 Call: lid=5 id=15 in=1
BChan: slot=0 chan=1
14:18:11 508702974mS CMCallEvt: 0.25687.0 6018 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
14:18:11 508702975mS CMCallEvt: 256.25689.0 6018 Hospitality201.0: StateChange: END=T CMCSIdle->CMCSOffering
14:18:11 508702976mS CMExtnEvt: Hospitality201: CMExtnHandler::SetCurrent( id: 0->25689 )

rfreil 12-02-2016 08:41 AM

14:18:11 508702977mS CMExtnTx: v=201, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=256 id=25689 in=0
Called[201] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[815633875230] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 8 item(s)
IE CMIECallSignalChannelData (8) unknown
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=HospitalityHG
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:1 R:4) number=815633875230
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=5 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=15 ipaddr=10.1.50.10 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HospitalityHG
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=700
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=HospitalityHG
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=700
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=9673
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIESecurityData (253) 2 item(s)
UUI type=User2User [700..] [0x37 0x30 0x30 0xff 0xff ]
Display [815633875230>HospitalityHG]
Timed: 01/12/16 14:18
Locale: enu
14:18:11 508702977mS CMExtnRx: v=201, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=256 id=25689 in=0
14:18:11 508702977mS CMCallEvt: 256.25689.0 6018 Hospitality201.0: StateChange: END=T CMCSOffering->CMCSRinging
14:18:11 508702977mS CMExtnEvt: v=1 State, new=Ringing old=Idle,0,0,Hospitality201
14:18:11 508702978mS CMCallEvt: 0.25687.0 6018 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
14:18:11 508702978mS CMCallEvt: 5.15.1 6018 Q931 Trunk:5 CHAN=1: StateChange: END=A CMCSDialled->CMCSRingBack
14:18:11 508702979mS CMLineTx: v=5
CMAlerting
Line: type=Q931Line 5 Call: lid=5 id=15 in=1
BChan: slot=0 chan=1
14:18:11 508702984mS CMCallEvt: 313.25690.0 6018 Hospitality200.0: StateChange: END=T CMCSIdle->CMCSOffering
14:18:11 508702985mS CMExtnEvt: Hospitality200: CMExtnHandler::SetCurrent( id: 0->25690 )
14:18:11 508702985mS CMExtnTx: v=200, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=313 id=25690 in=0
Called[200] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[815633875230] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 8 item(s)
IE CMIECallSignalChannelData (8) unknown
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=HospitalityHG
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:1 R:4) number=815633875230
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=5 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=15 ipaddr=10.1.50.10 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HospitalityHG
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=700
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=HospitalityHG
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=700
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=9673
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIESecurityData (253) 2 item(s)
UUI type=User2User [700..] [0x37 0x30 0x30 0xff 0xff ]
Display [815633875230>HospitalityHG]
Timed: 01/12/16 14:18
Locale: enu
14:18:11 508702986mS CMExtnRx: v=200, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=313 id=25690 in=0
14:18:11 508702986mS CMCallEvt: 313.25690.0 6018 Hospitality200.0: StateChange: END=T CMCSOffering->CMCSRinging
14:18:11 508702986mS CMExtnEvt: v=2 State, new=Ringing old=Idle,0,0,Hospitality200
14:18:11 508702991mS CD: CALL: 5.15.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 5" (3.2) Bend="HospitalityHG(700)" [Hospitality201(201),Hospitality200(200)] (0.0) CalledNum=700 (HospitalityHG) CallingNum=815633875230 () Internal=0 Time=29 AState=Ringing
14:18:11 508702997mS CMMap: a=3.2 b=0.0 R1
14:18:11 508702997mS ISDNL3Evt: v=5 stacknum=5 State, new=ICProceeding, old=Present id=15
14:18:11 508702998mS ISDNL3Evt: v=5 stacknum=5 State, new=Received, old=ICProceeding id=15
14:18:12 508703027mS ISDNL3Evt: v=5 stacknum=5 State, new=Delivered, old=Proceeding id=25685
14:18:12 508703028mS CMLineRx: v=5
CMAlerting
Line: type=Q931Line 5 Call: lid=0 id=25685 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
14:18:12 508703043mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,228: Feature Key Pressed 10https://support.avaya.com/forums/ima...es/biggrin.gifCPLFeatureCallLog
14:18:12 508703044mS CMCallEvt: DCPLFieldBasedDisplayScreen::SetLine - Invalid line number (255(line_num) >= 8(op_line_count)) or Null buffer (text=f07c9fe0)
14:18:12 508703048mS PRN: Config Write Wake Up
14:18:12 508703348mS RES: Thu 1/12/2016 14:18:11 FreeMem=46271288 44079144(2) CachedMem=2192144 CMMsg=13(13) Buff=5200 1355 999 7410 5 Links=25389 BTree=12854 CPU=07.62% CPUStats=1/4/920/17355/22765/1/0 MCR=0 MCW=0
14:18:12 508703348mS RES2: IP 500 V2 9.0.7.0 build 983 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=167 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=182(TLS=5) TAPI=2 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:18:12 508703348mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
14:18:12 508703548mS PRN: Updates IO list size 1 updated list size 1
14:18:12 508703548mS PRN: Sending Updates out to f47e1c50 started
14:18:12 508703549mS PRN: Sending Updates out to f47e1c50 finished
14:18:12 508703549mS PRN: Config Write Completed
14:18:14 508705325mS CMExtnTx: v=227, p1=8032
CMFacility

morri234 01-12-2017 12:52 PM

What model phone is this occurring on?
It sounds like the behavior of a second call to that extension were it indeed rings the phone once then projects a silent appearance.
Also, in the programming, has the ring time been limited to that particular extension?


All times are GMT -7. The time now is 05:22 AM.