Please I am trying to forward calls from an Avaya extension 3112 with DID 015010112 to a mobile phone 07088605214 when not picked or busy. but the call goes to voice mail after several rings and drop. So I called from another mobile to the DID- 015010112 to go to 07088605214 with prefix 8. Kindly look at the logs below from system monitor and help explain what's wrong and possible solution.
Logs:
Call-ID: feef12ce78bac3fb8b783cc38b3a56a1
From: "Anonymous"<sip:15010112@10.200.15.27>;tag=775dfbbe9eff2148
To: <sip:07088605214@10.200.15.27>;tag=6ujkkvbu-CC-1004
CSeq: 2040162106 INVITE
Warning: 399 CGPV1R2.SIPCOM "3412 SIPAPP_CR_ReleaseRecv 3079 Receive Sipsl Relealse msg."
Reason: Q.850;cause=21;text="Call rejected"
Reason: SIP;cause=480;text="Temporarily unavailable"
Content-Length: 0
09:59:50 4242598335mS SIP Tx: UDP 192.168.120.7:5060 -> 10.200.15.27:5060
ACK sip:07088605214@10.200.15.27 SIP/2.0
Via: SIP/2.0/UDP 192.168.120.7:5060;rport;branch=z9hG4bKb46e2bce71a22789d112e2689419bd97
From: "Anonymous" <sip:15010112@10.200.15.27>;tag=775dfbbe9eff2148
To: <sip:07088605214@10.200.15.27>;tag=6ujkkvbu-CC-1004
Call-ID: feef12ce78bac3fb8b783cc38b3a56a1
CSeq: 2040162106 ACK
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY
User-Agent: IP Office 9.0.5.0 build 972
Content-Length: 0
09:59:50 4242598336mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 21 Call: lid=21 id=5436917 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[15010112] Type=Default Plan=Unknown
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=807088605214
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceSIPTrunk type=0 number=21 channel=3 features=0x1 rx_gain=32 tx_gain=32 ep_callid=5436917 ipaddr=192.168.120.7 apps=8 loc=999 em_loc=999 features2=0x0
Cause=21, Call rejected
09:59:50 4242598336mS CMCallEvt: 21.5436917.0 1606220 SIPTrunk Endpoint: StateChange: END=T CMCSAccept->CMCSCompleted
09:59:50 4242598339mS CMCallEvt: 21.5436917.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
09:59:50 4242598339mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: AdjustNoAnswerTimer 10ms
09:59:50 4242598340mS CMCallEvt: 21.5436917.0 -1 BaseEP: DELETE CMEndpoint f3677344 TOTAL NOW=16 CALL_LIST=7
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: TimerExpired cause=CMTCNoAnswerTimeout
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: Retarget NOANSWER EXCEPTED=00000045 ValidTargets=65
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: Retarget on target_cfg_user=IMS Test
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD USER: IMS Test depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeInternational) incl(0x41) excpt(0x45), allow_redir(1) remote=00000000 simult 0 (0)
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: SELECT: TRY VOICEMAIL orig_hg() orig_user(3112)
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD VM TARGET
09:59:50 4242598350mS CMTARGET: **** 21.5436906.1 1606220 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
09:59:50 4242598351mS CMMap: a=0.33 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f547904c) resource busy 8, total 128
09:59:50 4242598351mS CMCallEvt: 0.5436919.0 -1 BaseEP: NEW CMEndpoint f4914a74 TOTAL NOW=17 CALL_LIST=7
09:59:50 4242598351mS CMTARGET: 0.5436919.0 1606220 RAS.0: ADD PRIMARY
09:59:50 4242598351mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
09:59:50 4242598352mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: GetNoAnswerTimer:25
09:59:50 4242598353mS CMCallEvt: 0.5436919.0 1606220 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
09:59:50 4242598353mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->5436919 )
09:59:50 4242598353mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1015 in=0
Called[#IMS Test] Type=Voicemail (102) Reason=107 SndComp Calling[08036257758@10.200.15.27] Type=International Plan=Unknown
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=21 chan=44
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=IMS Test
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:1 N:0 R:4) number=08036257758@10.200.15.27
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceSIPTrunk type=0 number=21 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=5436906 ipaddr=192.168.120.7 apps=8 loc=999 em_loc=999 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=IMS Test
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=IMS Test
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=3112
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=+23415010112
IE CMIEMohSourceId (247) MOH Source = 1
Display [08036257758>IMS Test]
Timed: 01/10/15 09:59
Locale: enu
09:59:50 4242598354mS CMMap: a=21.44 b=1.65534 T
09:59:50 4242598357mS CMExtnRx: v=RAS, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#IMS Test
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=44 features=0x0 rx_gain=32 tx_gain=32 ep_callid=5436919 ipaddr=192.168.120.7 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
09:59:50 4242598357mS CMCallEvt: 0.5436919.0 1606220 RAS.0: StateChange: END=T CMCSOffering->CMCSRinging
09:59:50 4242598357mS CMExtnEvt: v=1015 State, new=Ringing old=Idle,0,0,RAS
09:59:50 4242598357mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
09:59:50 4242598358mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 21 Call: lid=21 id=5436906 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#IMS Test
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=44 features=0x0 rx_gain=32 tx_gain=32 ep_callid=5436919 ipaddr=192.168.120.7 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
09:59:50 4242598360mS CD: CALL: 21.5436906.1 BState=Ringing Cut=1 Music=0.0 Aend="Line 21" (0.0) Bend="IMS Test(3112)" [RAS] (0.0) CalledNum=3112 (IMS Test) CallingNum=08036257758@10.200.15.27 () Internal=0 Time=25665 AState=Ringing
09:59:50 4242598361mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[LeaveMessage] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [1.] [0x31 0x00 ]
09:59:50 4242598361mS CMCallEvt: 0.1001.0 -1 BaseEP CMEndpoint does nothing with msg - 38 CMVoiceMailMsg
Logs:
Call-ID: feef12ce78bac3fb8b783cc38b3a56a1
From: "Anonymous"<sip:15010112@10.200.15.27>;tag=775dfbbe9eff2148
To: <sip:07088605214@10.200.15.27>;tag=6ujkkvbu-CC-1004
CSeq: 2040162106 INVITE
Warning: 399 CGPV1R2.SIPCOM "3412 SIPAPP_CR_ReleaseRecv 3079 Receive Sipsl Relealse msg."
Reason: Q.850;cause=21;text="Call rejected"
Reason: SIP;cause=480;text="Temporarily unavailable"
Content-Length: 0
09:59:50 4242598335mS SIP Tx: UDP 192.168.120.7:5060 -> 10.200.15.27:5060
ACK sip:07088605214@10.200.15.27 SIP/2.0
Via: SIP/2.0/UDP 192.168.120.7:5060;rport;branch=z9hG4bKb46e2bce71a22789d112e2689419bd97
From: "Anonymous" <sip:15010112@10.200.15.27>;tag=775dfbbe9eff2148
To: <sip:07088605214@10.200.15.27>;tag=6ujkkvbu-CC-1004
Call-ID: feef12ce78bac3fb8b783cc38b3a56a1
CSeq: 2040162106 ACK
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY
User-Agent: IP Office 9.0.5.0 build 972
Content-Length: 0
09:59:50 4242598336mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 21 Call: lid=21 id=5436917 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[15010112] Type=Default Plan=Unknown
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=807088605214
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceSIPTrunk type=0 number=21 channel=3 features=0x1 rx_gain=32 tx_gain=32 ep_callid=5436917 ipaddr=192.168.120.7 apps=8 loc=999 em_loc=999 features2=0x0
Cause=21, Call rejected
09:59:50 4242598336mS CMCallEvt: 21.5436917.0 1606220 SIPTrunk Endpoint: StateChange: END=T CMCSAccept->CMCSCompleted
09:59:50 4242598339mS CMCallEvt: 21.5436917.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
09:59:50 4242598339mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: AdjustNoAnswerTimer 10ms
09:59:50 4242598340mS CMCallEvt: 21.5436917.0 -1 BaseEP: DELETE CMEndpoint f3677344 TOTAL NOW=16 CALL_LIST=7
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: TimerExpired cause=CMTCNoAnswerTimeout
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: Retarget NOANSWER EXCEPTED=00000045 ValidTargets=65
09:59:50 4242598349mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: Retarget on target_cfg_user=IMS Test
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD USER: IMS Test depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeInternational) incl(0x41) excpt(0x45), allow_redir(1) remote=00000000 simult 0 (0)
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: SELECT: TRY VOICEMAIL orig_hg() orig_user(3112)
09:59:50 4242598350mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD VM TARGET
09:59:50 4242598350mS CMTARGET: **** 21.5436906.1 1606220 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
09:59:50 4242598351mS CMMap: a=0.33 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f547904c) resource busy 8, total 128
09:59:50 4242598351mS CMCallEvt: 0.5436919.0 -1 BaseEP: NEW CMEndpoint f4914a74 TOTAL NOW=17 CALL_LIST=7
09:59:50 4242598351mS CMTARGET: 0.5436919.0 1606220 RAS.0: ADD PRIMARY
09:59:50 4242598351mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
09:59:50 4242598352mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: GetNoAnswerTimer:25
09:59:50 4242598353mS CMCallEvt: 0.5436919.0 1606220 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
09:59:50 4242598353mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->5436919 )
09:59:50 4242598353mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1015 in=0
Called[#IMS Test] Type=Voicemail (102) Reason=107 SndComp Calling[08036257758@10.200.15.27] Type=International Plan=Unknown
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=21 chan=44
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=IMS Test
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:1 N:0 R:4) number=08036257758@10.200.15.27
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceSIPTrunk type=0 number=21 channel=2 features=0x20 rx_gain=32 tx_gain=32 ep_callid=5436906 ipaddr=192.168.120.7 apps=8 loc=999 em_loc=999 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=IMS Test
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=IMS Test
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=3112
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=+23415010112
IE CMIEMohSourceId (247) MOH Source = 1
Display [08036257758>IMS Test]
Timed: 01/10/15 09:59
Locale: enu
09:59:50 4242598354mS CMMap: a=21.44 b=1.65534 T
09:59:50 4242598357mS CMExtnRx: v=RAS, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#IMS Test
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=44 features=0x0 rx_gain=32 tx_gain=32 ep_callid=5436919 ipaddr=192.168.120.7 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
09:59:50 4242598357mS CMCallEvt: 0.5436919.0 1606220 RAS.0: StateChange: END=T CMCSOffering->CMCSRinging
09:59:50 4242598357mS CMExtnEvt: v=1015 State, new=Ringing old=Idle,0,0,RAS
09:59:50 4242598357mS CMTARGET: 21.5436906.1 1606220 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
09:59:50 4242598358mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 21 Call: lid=21 id=5436906 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#IMS Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#IMS Test
IE CMIEDeviceDetail (231) LOCALE=enu HW=14 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=44 features=0x0 rx_gain=32 tx_gain=32 ep_callid=5436919 ipaddr=192.168.120.7 apps=8 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=IMS Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=3112
09:59:50 4242598360mS CD: CALL: 21.5436906.1 BState=Ringing Cut=1 Music=0.0 Aend="Line 21" (0.0) Bend="IMS Test(3112)" [RAS] (0.0) CalledNum=3112 (IMS Test) CallingNum=08036257758@10.200.15.27 () Internal=0 Time=25665 AState=Ringing
09:59:50 4242598361mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[LeaveMessage] Type=Default (100) Reason=CMDRdirect
Tag type=Text flags=0x1 [1.] [0x31 0x00 ]
09:59:50 4242598361mS CMCallEvt: 0.1001.0 -1 BaseEP CMEndpoint does nothing with msg - 38 CMVoiceMailMsg