FNE Service fail and mobile twinning 1

[martinnystrom]

Hi, we recently upgraded our pbx to 9.1.300 and after that upgrade SOME users are experiencing issues with the FNE Service.

When a user call the FNE they just recieve Busy tone. Also, when some extensions try to activate twinning they recieve "Twinning function blocked" in their 9608 display.
This is a monitor trace for the FNE FAIL

What struck me was this line
10:29:48 50468562mS PRN: CMGhostHandler : No mobility licence for Anna B-S

I have no idea what it means. Twinning is ACTIVATED with the correct number!

10:29:48 50468556mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=7231 in=1
Called[0101025008] Type=Default (100) Reason=CMDRdirect SndComp Calling[xxxx@xxxx] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 4 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=xxxx@xxxx
IE CMIEDeviceDetail (231) 0afe021500001c3f LOCALE=sve HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=6 features=0x0 rx_gain=32 tx_gain=32 ep_callid=7231 ipaddr=xxxx apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=1
10:29:48 50468557mS CD: CALL: 17.7231.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=xxxx() CallingNum=xxxx@xxxx () Internal=0 Time=4 AState=Idle
10:29:48 50468557mS CMCallEvt: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
10:29:48 50468557mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: LOOKUP CALL ROUTE: GID=0 type=100 called_party=xxxx sub= calling=xxxx@xxxxdir=in complete=1 ses=0
10:29:48 50468559mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=
10:29:48 50468559mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: SET BESTMATCH: length 9 vs 0 match=xxxx dest=77701
10:29:48 50468560mS CMCallEvt: Priority hike: call 1065 priority 0->1
10:29:48 50468560mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: LOOKUP ICR: DDI=xxxx CGPN=xxxx@xxxx (Destination 77701 ) => CDPN=77701
10:29:48 50468560mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: ADD TARGET (N): number=77701 type=100 depth=1 nobar=1 setorig=1 ses=0
10:29:48 50468561mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: SYS SC: 77701 5 01 sc=type=FeatureNameExtensions code=777XX, num=N callinfop->sending_complete=1 secondary_dialtone=
10:29:48 50468562mS PRN: CMGhostHandler : No mobility licence for Anna B-S
10:29:48 50468562mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: NO INITIAL TARGETS: ??
10:29:48 50468563mS CMTARGET: 0afe021500001c3f 17.7231.1 1065 SIPTrunk Endpoint: Fallback() targeting failed
10:29:48 50468564mS PRN: CDR - TCPSend maxqueuesize=500 framecount=0 operational=1
10:29:48 50468566mS CMLOGGING: CALL:2015/07/1010:29,00:00:00,000,xxxx@xxxx,I,77701,xxxx,,,,0,,"",0,n/a
10:29:48 50468566mS CD: CALL: 17.7231.1 BState=Idle Cut=0 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=77701 () CallingNum=xxxx@xxxx () Internal=0 Time=13 AState=Dialling
10:29:48 50468566mS CD: CALL: 17.7231.1 Deleted
10:29:48 50468567mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=7231 in=1
Cause=17, User busy

 

[EmilP]

No mather the FNE twin calls still fails on our 9.1 systems of no mobile worker license is present. I tried all 9.1 releases but it´s the same behavior. After downgrade to 9.0 it works again.
I can´t believe Avaya haven´t fixed it since the first release of 9.1. Its not just a bugg in manager because even if it´s ticket in manager beforehand it does not work.

 

[janni78]

Ok, I just tested this on 9.1.200.91 works like a charm.
I'm using FNE00 and no Mobile Worker licenses present.

11:57:10 742514078mS CMCallEvt: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: StateChange: END=A CMCSIdle->CMCSDialInitiated
11:57:10 742514078mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: LOOKUP CALL ROUTE: GID=0 type=4 called_party=219 sub= calling=07xxxxxxxx dir=in complete=1 ses=0
11:57:10 742514078mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: SET BESTMATCH: length 3 vs -1 match=0-------- dest=*7700
11:57:10 742514078mS CMCallEvt: Priority hike: call 6862 priority 0->1
11:57:10 742514079mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: LOOKUP ICR: DDI= CGPN=07xxxxxxxx (Destination *7700 ) => CDPN=*7700
11:57:10 742514079mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: ADD TARGET (N): number=*7700 type=4 depth=1 nobar=1 setorig=1 ses=0
11:57:10 742514079mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: SYS SC: *7700 5 sc=type=FeatureNameExtensions code=*7700, num=00 callinfop->sending_complete=1 secondary_dialtone=
11:57:10 742514080mS CMCallEvt: 0000000000000000 0.24549.0 -1 BaseEP: NEW CMEndpoint f1769494 TOTAL NOW=9 CALL_LIST=4
11:57:10 742514080mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: INITIAL TARGETING SUCCEEDED
11:57:10 742514080mS CMTARGET: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: GetNoAnswerTimer:40
11:57:10 742514080mS CMCallEvt: c0a839d20000000d 13.13.1 6862 Q931 Trunk:13 CHAN=25: StateChange: END=A CMCSDialInitiated->CMCSDialled


"Trying is the first step to failure..." - Homer