martinnystrom
Programmer
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
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