Issues with B169 receiving direct calls - 604 Does Not Exist Anywhere

[chavz7796]

I have two B169 IP Wireless DECT stations. Both are experiencing issues with receiving calls when I call them direct via a DID or Auto Attendant.
Calls are coming via a SIP Line.
The phones are able to make outbound calls with no issues.
I set the destination on the ICR to another extension, a J169 phone, the call goes through with no issues.
So looks like like it is a config issue with the IP Dect station.
I do see the call in System Status for a split second and then it drops.
Monitor shows that calls are reaching the phone and dropping. Found the following logs.

SIP/2.0 604 Does Not Exist Anywhere
Via: SIP/2.0/TCP 10.10.142.17:5060;rport=5060;branch=z9hG4bKd29558a4e7b786a9ee4324198b6423fc
Max-Forwards: 70
From: "6808" <sip:19152172500@10.10.142.17>;tag=d6632cf1c44b5a8a
To: <sip:2620@10.10.142.17;transport=TCP;line=26733>;tag=i.mfzrxga
Call-ID: 182c9173331fe7fafd8b62b482912acf
CSeq: 1278338862 INVITE
Contact: <sip:2620@xxx.xxx.xxx:51215;transport=TCP;line=26733>
User-Agent: Avaya IP DECT SC/04.50.0007 (MAC=00087B195802; SER= 00000; HW=1)
Content-Length: 0

Has anyone ran into this issue?

Thanks for the help!

 

[Bucky101]

Can you call them internally?
Any conflicting shortcode or huntgroup extensions?
Any strange characters in the name of the user?
System Status > Extensions - shows extension 2620 logged in as user 2620 ?

 

[icet500]

You may need to post a full trace. Do you have the call details or sip uri set as internal data or auto?

 

[chavz7796]

@Bucky101
Can you call them internally?
Yes, but I can only call another extension. I cannot receive a call. Looks like the issue is with inbound calls.
Any conflicting shortcode or huntgroup extensions?
There is no conflicting shortcode or huntgroup.
Any strange characters in the name of the user?
No
System Status > Extensions - shows extension 2620 logged in as user 2620 ?
I do see both extensions listed in System Status.

@icet500

Here is a call sample from an internal call. Im getting the same error.

Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
14:38:32 396321330mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: Setting Hard Timer 4000
14:38:32 396321330mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: LOOKUP CALL ROUTE: GID=0 type=100 called_party=2634 sub= calling=2601 calling_sub= dir=out complete=0 ses=0
14:38:32 396321330mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: ADD TARGET (N): number=2634 type=100 depth=1 nobar=1 setorig=1 ses=0
14:38:32 396321330mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: SET USER: Conference orig=1
14:38:32 396321330mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: ADD USER: Conference depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0) r_lic=T
14:38:32 396321331mS CMCallEvt: 0000000000000000 0.9703.0 -1 BaseEP: NEW CMEndpoint f1767324 TOTAL NOW=5 CALL_LIST=2
14:38:32 396321331mS CMCallEvt: 0000000000000000 0.9703.0 -1 Conference.-1: NEW CMExtnEndpoint f1767324, Name=Conference, Extn=2634, Phys Extn=2634
14:38:32 396321333mS CMTARGET: 0a0a8e11000025e7 276.9703.0 2271 Conference.0: ADD PRIMARY
14:38:32 396321333mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: CancelTimer CMTCDelayedProcessing
14:38:32 396321333mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: INITIAL TARGETING SUCCEEDED
14:38:32 396321334mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: GetNoAnswerTimer:15
14:38:32 396321334mS CMCallEvt: 0000000000000000 0.9702.0 2271 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
14:38:32 396321335mS CMCallEvt: 0a0a8e11000025e7 276.9703.0 2271 Conference.0: StateChange: END=T CMCSIdle->CMCSOffering
14:38:32 396321337mS CMExtnEvt: Conference: CMExtnHandler::SetCurrent( id: 0->9703 )
14:38:32 396321337mS CMExtnTx: v=2634, p1=0
CMSetup
Line: type=SIPLine 260 Call: lid=276 id=9703 in=0
Called[2634] Type=Default (100) Reason=CMDRdirect Calling[2601] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 16969
user 'Jane' presentation Allowed
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIECallingPartyName (110)(Type=CMNameInternalUser) name=Jane
IE CMIECalledPartyName (224)(Type=CMNameInternalUser) name=Conference
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Conference
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Jane Enriquez
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Jane
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Jane Enriquez
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2601
IE CMIEDeviceDetail (231) 0a0a8e11000025e5 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPPhone type=184 number=31 channel=0 features=0x10110420 rx_gain=32 tx_gain=32
ep_callid=9701 ipaddr=10.10.142.17 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x20 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Display [Jane>Conference]
Timed: 06/10/20 14:38
Locale: enu
14:38:32 396321337mS Sip: SIPDialog f16babf0 created, dialogs 22 txn_keys 0 video 1 presentation 1 camera 1 unsupp audio 0
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) SetUnIntTransactionCondition to UnInt_None
14:38:32 396321338mS Sip: SipTCPUser 374 has 1 dialog open (AttachDialogToSipTCPUser)
14:38:32 396321338mS Sip: SipTCPUser 374 has 2 dialogs open (AttachDialogToSipTCPUser)
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) CMSetup received, owner f16bfbd0, dialog f16babf0, dialling 2634
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) OnCmMessage Setup is_call_diverted 0 is_call_twinned 0 is_vmail_leave 0 out device 10 in device 10 full_name_supp 1
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) OnCmMessage Setup responding sets caller
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) OnCmMessage Setup user <2601> orig_user <> orig_hg <>
14:38:32 396321338mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) OnCmMessage Setup (after aligning with user data) number <2601> name <Jane Enriquez>
14:38:32 396321339mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) OnCmMessage Setup calling_party number <2601> name <Jane Enriquez>
14:38:32 396321339mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) InsertIdentityHeader caller ID method <CallerIDPAID>
14:38:32 396321340mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) InsertPAID number <2601> and name <Jane Enriquez> and restricted <F>
14:38:32 396321340mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) SIPDialog::BuildSDPFromFastStart
14:38:32 396321340mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) SetLocalRTPAddress to 10.241.2.52:40750
14:38:32 396321341mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) SdpClone
14:38:32 396321341mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) UpdateSDPState SIPDialog::IDLE(0) -> SIPDialog::OFFER_SENT(1)
14:38:32 396321341mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) INVITE SENT TO 10.241.2.94:51272 (reg required 0 registered 0)

396321342mS SIP Call Tx: phone
INVITE sip:2634@10.241.2.94;transport=TCP;line=40356 SIP/2.0
Via: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Contact: "Jane Enriquez" <sip:2601@10.10.142.17:5060;transport=tcp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: 100rel
User-Agent: IP Office 11.0.4.2.0 build 58
P-Asserted-Identity: "Jane Enriquez" <sip:2601@10.10.142.17:5060>
Content-Type: application/sdp
Content-Length: 272

v=0
o=UserA 1600401482 278615536 IN IP4 10.241.2.52
s=Session SDP
c=IN IP4 10.241.2.52
t=0 0
m=audio 40750 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
14:38:32 396321343mS SIP Tx: TCP 10.10.142.17:5060 -> 10.241.2.94:51272
INVITE sip:2634@10.241.2.94;transport=TCP;line=40356 SIP/2.0
Via: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Contact: "Jane Enriquez" <sip:2601@10.10.142.17:5060;transport=tcp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: 100rel
User-Agent: IP Office 11.0.4.2.0 build 58
P-Asserted-Identity: "Jane Enriquez" <sip:2601@10.10.142.17:5060>
Content-Type: application/sdp
Content-Length: 272

v=0
o=UserA 1600401482 278615536 IN IP4 10.241.2.52
s=Session SDP
c=IN IP4 10.241.2.52
t=0 0
m=audio 40750 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
14:38:32 396321343mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) UpdateSIPCallState SIPDialog::INITIAL(0) -> SIPDialog::INVITE_SENT(1)
14:38:32 396321446mS SIP Rx: TCP 10.241.2.94:51272 -> 10.10.142.17:5060
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.10.142.17:5060;rport=5060;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Content-Length: 0

14:38:32 396321448mS Sip: TCP packet known set owner
14:38:32 396321448mS SIP Call Rx: phone
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.10.142.17:5060;rport=5060;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Content-Length: 0

14:38:32 396321448mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) Process SIP response dialog f16babf0, method INVITE, CodeNum 100 in state SIPDialog::INVITE_SENT(1)
14:38:32 396321448mS CMExtnRx: v=2634, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=276 id=9703 in=0
14:38:32 396321449mS CMCallEvt: 0a0a8e11000025e7 276.9703.0 2271 Conference.0: StateChange: END=T CMCSOffering->CMCSAccept
14:38:32 396321449mS CMCallEvt: 0000000000000000 0.9702.0 2271 TargetingEP: StateChange: END=B CMCSOffering->CMCSAccept
14:38:32 396321449mS CMCallEvt: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: StateChange: END=A CMCSDialling->CMCSDialled
14:38:32 396321449mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,Jane
14:38:32 396321451mS CMExtnTx: v=2601, p1=0
CMFacility
Line: type=SIPLine 260 Call: lid=273 id=9701 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 101, rxp 101, fmtp = <null>, misc 21 (misc oob 0 misc rfc2833 1 prio oob 1 prio rfc2833 0)
Timed: 06/10/20 14:38
14:38:32 396321452mS Sip: (f526a430) SIPDialog::BuildSDPFromFastStart
14:38:32 396321452mS Sip: (f526a430) INVITE SENT TO 10.241.2.52:50753 (reg required 0 registered 0)
14:38:32 396321453mS SIP Call Tx: phone
INVITE sip:2601@10.241.2.52:50753;transport=tcp SIP/2.0
v: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKec763f60df9e5418057b96631b097091
f: <sip:CCMSInterface@10.10.142.17>;tag=591af35d32955a54
t: <sip:2601@xxxxx.voip.xxxxx.cloud>;tag=5f79574d3ec6494d3g40335d4o701c4o1w7p_F2601
i: 18_5f79574d44e9cc636i2f1sux2g58mi64ma_I2601
CSeq: 2244 INVITE
m: <sip:CCMSInterface@10.10.142.17:5060;transport=tcp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
User-Agent: IP Office 11.0.4.2.0 build 58
c: application/sdp
l: 125

v=0
o=UserA 992233135 3419237254 IN IP4 10.10.142.17
s=Session SDP
c=IN IP4 10.10.142.17
t=0 0
m=audio 0 RTP/AVP 0 101
14:38:32 396321453mS SIP Tx: TCP 10.10.142.17:5060 -> 10.241.2.52:50753
INVITE sip:2601@10.241.2.52:50753;transport=tcp SIP/2.0
v: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKec763f60df9e5418057b96631b097091
f: <sip:CCMSInterface@10.10.142.17>;tag=591af35d32955a54
t: <sip:2601@xxxxx.voip.xxxxx.cloud>;tag=5f79574d3ec6494d3g40335d4o701c4o1w7p_F2601
i: 18_5f79574d44e9cc636i2f1sux2g58mi64ma_I2601
CSeq: 2244 INVITE
m: <sip:CCMSInterface@10.10.142.17:5060;transport=tcp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
User-Agent: IP Office 11.0.4.2.0 build 58
c: application/sdp
l: 125

v=0
o=UserA 992233135 3419237254 IN IP4 10.10.142.17
s=Session SDP
c=IN IP4 10.10.142.17
t=0 0
m=audio 0 RTP/AVP 0 101
14:38:32 396321454mS Sip: (f526a430) UpdateSIPCallState SIPDialog::CALL_UP(16) -> SIPDialog::REINVITE_SENT(20)
14:38:32 396321454mS Sip: (f526a430) UpdateSDPState SIPDialog::COMPLETE(7) -> SIPDialog::OFFER_SENT(1)
14:38:32 396321455mS CMExtnTx: v=2601, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=273 id=9701 in=0
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Conference
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Conference
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=2634
IE CMIEDeviceDetail (231) 0a0a8e11000025e7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPPhone type=201 number=34 channel=0 features=0x10110000 rx_gain=32 tx_gain=32
ep_callid=9703 ipaddr=10.10.142.17 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
Timed: 06/10/20 14:38
14:38:32 396321455mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) UpdateSIPCallState SIPDialog::INVITE_SENT(1) -> SIPDialog::INV_PROV_RESP_RCVD(5)
14:38:32 396321491mS SIP Rx: TCP 10.241.2.94:51272 -> 10.10.142.17:5060
SIP/2.0 604 Does Not Exist Anywhere
Via: SIP/2.0/TCP 10.10.142.17:5060;rport=5060;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
Max-Forwards: 70
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>;tag=0x4gx2j
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Contact: <sip:2634@10.241.2.94:51272;transport=TCP;line=40356>
User-Agent: Avaya IP DECT SC/04.50.0007 (MAC=00087B19561F; SER= 00000; HW=1)
Content-Length: 0

14:38:32 396321493mS Sip: TCP packet known set owner
14:38:32 396321493mS SIP Call Rx: phone
SIP/2.0 604 Does Not Exist Anywhere
Via: SIP/2.0/TCP 10.10.142.17:5060;rport=5060;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
Max-Forwards:
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: <sip:2634@10.10.142.17;transport=TCP;line=40356>;tag=0x4gx2j
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 INVITE
Contact: <sip:2634@10.241.2.94:51272;transport=TCP;line=40356>
User-Agent: Avaya IP DECT SC/04.50.0007 (MAC=00087B19561F; SER= 00000; HW=1)
Content-Length: 0

14:38:32 396321493mS Sip: FindContactParameters mFarDisplayString <> mFarDisplayNumber <2634> restricted 0
14:38:32 396321493mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) Process SIP response dialog f16babf0, method INVITE, CodeNum 604 in state SIPDialog::INV_PROV_RESP_RCVD(5)
14:38:32 396321493mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) UpdateSIPCallState SIPDialog::INV_PROV_RESP_RCVD(5) -> SIPDialog::INV_FINAL_RESP_RCVD(17)
14:38:32 396321494mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) ACK SENT TO 10.241.2.94 51272
14:38:32 396321495mS SIP Call Tx: phone
ACK sip:2634@10.241.2.94:51272;transport=TCP;line=40356 SIP/2.0
Via: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: "Conference" <sip:2634@10.10.142.17;transport=TCP;line=40356>;tag=0x4gx2j
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 ACK
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
User-Agent: IP Office 11.0.4.2.0 build 58
Content-Length: 0

14:38:32 396321495mS SIP Tx: TCP 10.10.142.17:5060 -> 10.241.2.94:51272
ACK sip:2634@10.241.2.94:51272;transport=TCP;line=40356 SIP/2.0
Via: SIP/2.0/TCP 10.10.142.17:5060;rport;branch=z9hG4bKc741c8c7f8f753f0a17e5e847f432cf9
From: "Jane Enriquez" <sip:2601@10.10.142.17>;tag=0617e32d2d271244
To: "Conference" <sip:2634@10.10.142.17;transport=TCP;line=40356>;tag=0x4gx2j
Call-ID: ff5cfdae5b33a7f7d8f09c7fd8493274
CSeq: 1016275978 ACK
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
User-Agent: IP Office 11.0.4.2.0 build 58
Content-Length: 0

14:38:32 396321496mS Sip: 0a0a8e11000025e7 276.9703.0 2271 Conference.0(f16babf0) UpdateSIPCallState SIPDialog::INV_FINAL_RESP_RCVD(17) -> SIPDialog::FINAL(28)
14:38:32 396321496mS CMTARGET: 0a0a8e11000025e7 276.9703.0 2271 Conference.0: AttemptStopRinging
14:38:32 396321496mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: Retarget NOANSWER EXCEPTED=00000001 INCLUDED=00000001 ValidTargets=0
14:38:32 396321496mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: Retarget on target_cfg_user=Conference
14:38:32 396321496mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: ADD USER: Conference depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x1) excpt(0x1), allow_redir(1) remote=00000000 simult 0 (0) r_lic=T
14:38:32 396321497mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: SELECT: TRY VOICEMAIL orig_hg() orig_user(2634)
14:38:32 396321497mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: VM targeting failed. Remaining on final target Conference
14:38:32 396321497mS CMTARGET: 0a0a8e11000025e5 273.9701.0 2271 Jane.1: GetNoAnswerTimer:15
14:38:32 396321498mS CMLOGGING: CALL:2020/10/0614:38,00:00:00,000,2601,O,2634,2634,Jane,,,1,,"",0,n/a
14:38:32 396321498mS CMExtnEvt: Jane: CALL LOST (CMCauseUnallocatedNumber)
14:38:32 396321498mS CMExtnEvt: Jane: Extn(2601) Calling Party Number(2601) Type(CMNTypeInternal)
14:38:32 396321499mS CMCallEvt: 0a0a8e11000025e5 273.9701.0 -1 Jane.1: StateChange: END=X CMCSDialled->CMCSCompletedTone
14:38:32 396321501mS CMExtnTx: v=2601, p1=0
CMFacility
Line: type=SIPLine 260 Call: lid=273 id=9701 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 101, rxp 101, fmtp = <null>, misc 21 (misc oob 0 misc rfc2833 1 prio oob 1 prio rfc2833 0)
Timed: 06/10/20 14:38
14:38:32 396321501mS Sip: PortmPhoneDelayFacMsg(2601)- Set CM msg for debouncing
14:38:32 396321502mS CMTARGET: 0a0a8e11000025e5 273.9701.0 -1 Jane.1: CancelTimer CMTCNoAnswerTimeout
14:38:32 396321502mS CMExtnEvt: v=0 State, new=CMESCompleted old=Proceeding,0,0,Jane
14:38:32 396321502mS CMCallEvt: 0000000000000000 0.9702.0 -1 BaseEP: DELETE CMEndpoint f173aed4 TOTAL NOW=4 CALL_LIST=1
14:38:32 396321503mS CMExtnEvt: Conference: CALL LOST (CMCauseUnallocatedNumber)
14:38:32 396321503mS CMExtnEvt: Conference: Extn(2634) Calling Party Number(2601) Type(CMNTypeInternal)
14:38:32 396321504mS CMCallEvt: 0a0a8e11000025e7 276.9703.0 -1 Conference.0: StateChange: END=X CMCSAccept->CMCSCompleted
14:38:32 396321504mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=Idle,0,0,Conference
14:38:32 396321504mS CMExtnTx: v=2634, p1=0
CMReleaseComp
Line: type=SIPLine 260 Call: lid=276 id=9703 in=0
Called[2634] Type=Default (100) Reason=CMDRdirect Calling[2601] Type=Internal Plan=Default
Cause=1, Unallocated (unassigned) number
Timed: 06/10/20 14:38
14:38:32 396321504mS Sip: 0a0a8e11000025e7 276.9703.0 -1 Conference.0(f16babf0) Terminating dialog f16babf0, state SIPDialog::FINAL(28) for cause CMCauseUnallocatedNumber
14:38:32 396321504mS CMExtnEvt: Conference: CMExtnHandler::SetCurrent( id: 9703->0 )
14:38:32 396321505mS CMCallEvt: 0a0a8e11000025e7 276.9703.0 -1 Conference.-1: StateChange: END=X CMCSCompleted->CMCSDelete
14:38:32 396321505mS CMCallEvt: END CALL:2271 (f1783a8c)
14:38:32 396321505mS Sip: (f16babf0) KeepDlgOnCmCallLost SIPDialog::FINAL
14:38:32 396321506mS Sip: (f16babf0) SetUnIntTransactionCondition to UnInt_None
14:38:32 396321506mS Sip: SIPDialog f16babf0 deleted, dialogs 21 txn_keys 1
14:38:32 396321506mS Sip: SipTCPUser 374 has 2 dialogs open (Close)
14:38:32 396321506mS Sip: SipTCPUser 374 has 1 dialog open (Close)
14:38:32 396321506mS CMCallEvt: 0a0a8e11000025e7 276.9703.0 -1 BaseEP: DELETE CMEndpoint f1767324 TOTAL NOW=3 CALL_LIST=1
14:38:32 396321509mS CMMap: PCG::AddToneGenerator g B3[6] for cp[728]b0r1 append pcp[731]b0r1 (total 1)
14:38:32 396321509mS CMMap: a=2.17 b=0.0 IP::ReserveCodec pcp[731]b0r1 allocated CMRTVocoder(f537c8f8) resource busy 2, total 42, codec type 5
14:38:32 396321510mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail(f5371890) resource busy -1523, total 45
14:38:32 396321510mS CMMap: a=21.11 b=1.65535 T
14:38:32 396321510mS CMMap: a=21.11 b=2.17 M22
14:38:32 396321510mS CMMap: PlatformConnectionAudioSAP::Bind pcp[731]b0r1 to 2.17
14:38:32 396321510mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[731]b0r1 echo_cancellation set to 0
14:38:32 396321511mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[731]b0r1 Configure 2.17
14:38:32 396321511mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[731]b0r1 ConnectIndication 2.17
14:38:32 396321511mS CMMap: a=0.0 b=0.0 pcp[728]b0r1 RTPB3
14:38:32 396321511mS H323Evt: SESS aa3 SetOperational local 10.10.142.17:40750 remote 10.241.2.52:40750 to 1
14:38:32 396321514mS SIP Rx: TCP 10.241.2.52:50753 -> 10.10.142.17:5060

 

[critchey]

If you can't call the extension internally you can stop doing external calls and focus on that first. It looks like in your trace you tried to call extn2601 but it can't be reached so you get Unallocated (unassigned) number. Most likely the user is not logged into the phone correctly or you have a different user logged into the phone. In system status find extension 2601 and click on it. What does it say is the user logged into this extension?

The truth is just an excuse for lack of imagination.

 

[icet500]

seems like IPO does not find that Ext. Make sure you have set this ext as SIP-Dect. If you go to the SIP dect line, do you see that ext in the associated extension tab?

 

[Bucky101]

hey Icet, it needs to be a SIP Extension not DECT. The B169 communicates with the basestation via DECT but the basestation communicates with IPO via standard SIP setup

Perhaps start over by deleting the user and extension and start here -

https://ipofficekb.avaya.com/businesspartner/ipoffice/mergedProjects/sipphoneinst/notes.htm

setup with a different user / extension incase there is some conflict somewhere