DECT R4 - incompatible on dialing external 1

[telesonic]

Hi

Grateful if anyone can help please.
IP500V2 on 9.1
DECT R4 units from older system.
All connected up ok, can receive calls, can make internal calls.
But making an external call the phone comes up with 'incompatible' on the screen.
They have BT HIPCOM SIP trunks.
Sysmon shows the dial event to the SIP line. Looks ok and drops.
this bit of trace appears most relevant:

CALL:2015/03/0212:39,00:00:00,000,256,O,07********7,07********7,Tina,,,1,,""n/a,0
12:39:40 362792054mS CD: CALL: 272.7390.0 BState=Disconnecting Cut=0 Music=0.0 Aend="Tina(256)" (0.0) Bend="" [] (0.0) CalledNum=07********7 () CallingNum=256 (Tina) Internal=1 Time=9148 AState=Dialling
12:39:40 362792054mS CD: CALL: 272.7390.0 Deleted
12:39:40 362792055mS CMExtnEvt: Tina: CALL LOST ([highlight #FCE94F]CMCauseUnallocatedNumber)[/highlight]
12:39:40 362792055mS CMExtnEvt: Tina: Extn(256) Calling Party Number(256) Type(CMNTypeInternal)
12:39:40 362792056mS CMCallEvt: 240.44.1 -1 Tina.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
12:39:40 362792057mS CMExtnEvt: v=0 State, new=CMESCompleted old=Dialling,0,0,Tina
12:39:40 362792057mS CMARS: CMARSEndpoint::[highlight #FCE94F]CallLost(cause=1)[/highlight] - Address: 0.7392.0 -1 ARS for BT_ IPVS_SIP-T - Call State: CMCSCompleted
12:39:40 362792057mS CMCallEvt: 0.7392.0 -1 ARS for BT_ IPVS_SIP-T: StateChange: END=X CMCSCompleted->CMCSDelete

If anyone can steer me a little that would be great
thank you
Paul

 

[tlpeter]

This sounds like the prefixes are set in the dect system too.


BAZINGA!

I'm not insane, my mother had me tested!

 

[janni78]

You should include the complete monitor trace from the call, but it looks like the number you're trying to dial doesn't exist or the CLI you're sending aren't allowed to dial out.
The SIP part of the trace should give the answer.

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

 

[telesonic]

The DECT handsets are members of the same user rights group as the other IP deskphones.
They follow the same routing.
The number can be dialled by the deskphones.
The trace shows the dial string to the SIP Trunk. It looks the same as the others, number dialled @ BTSIP-HIPCOM blah.


Does not look like any prefix is being applied?? Anyway to check and confirm tlpeter?

 

[tlpeter]

Login to the master and go to the section DECT -> master.

There is an option to put in a prefix.
You do not need that as this is arranged in the IPOffice itself

BAZINGA!

I'm not insane, my mother had me tested!

 

[telesonic]

Just found it.
They are clear.
ARS Prefix
International CPN Prefix
National CPN Prefix
all blank

 

[tlpeter]

OK so you can rule out the dect system.
Can you check if the option "inhibit oof-switch forward/transfer" is turned off?
Although it should not matter, this option is now turned off by default.


BAZINGA!

I'm not insane, my mother had me tested!

 

[telesonic]

Good call, it was on in the user rights group.
I copied the group, removed preset buttons, took out that option just in case as it sounded like a 'maybe'.
Added my test extension to that new group.

Same result.

 

[tlpeter]

Can you post a trace form the moment start dialing till the end?
Make a default trace and i suggest not to use SIP in it for now.

BAZINGA!

I'm not insane, my mother had me tested!

 

[telesonic]

The client only has SIP trunks.
Naturally a little concerned at the data in a public forum.

 

[tlpeter]

Just make the trace but without the SIP filter as i think it is not a SIP issue.
Remove the data you do not want on the public forum.

BAZINGA!

I'm not insane, my mother had me tested!

 

[telesonic]

ok, one trace

thanks

********** Warning: Logging to Screen Started **********
14:06:09 367981362mS RES: Mon 2/3/2015 14:06:09 FreeMem=53452016 51635208(7) CachedMem=1816808 CMMsg=9(9) Buff=5200 1355 1000 7419 4 Links=32739 BTree=14289 CPU=03.40% CPUStats=1/4/2092/18668/22234/0/1 MCR=0 MCW=0
14:06:09 367981363mS RES2: IP 500 V2 9.0.4.0 build 965 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=78 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=64(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:06:09 367981363mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
14:06:09 367981580mS H323Evt: v=0 stacknum=240 State, new=NullState, old=NullState id=-1
14:06:09 367981580mS H323Evt: v=0 stacknum=240 State, new=Present, old=NullState id=52
14:06:09 367981581mS CMCallEvt: 0.7510.0 -1 BaseEP: NEW CMEndpoint f4d88590 TOTAL NOW=1 CALL_LIST=0
14:06:09 367981582mS CMCallEvt: 0.7510.0 -1 Tina.-1: NEW CMExtnEndpoint f4d88590, Name=Tina, Extn=256, Phys Extn=256
14:06:09 367981582mS CMCallEvt: CREATE CALL:131 (f4d9b620)
14:06:09 367981582mS CMCallEvt: 0.7511.0 -1 BaseEP: NEW CMEndpoint f4d99d50 TOTAL NOW=2 CALL_LIST=0
14:06:09 367981585mS CMExtnEvt: Tina: CMExtnHandler::SetCurrent( id: 0->7510 )
14:06:09 367981587mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:09 367981588mS CMExtnRx: v=256, p1=0
CMSetup
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[256] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Tina
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Tina
14:06:09 367981588mS CMCallEvt: 240.52.1 131 Tina.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
14:06:09 367981588mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,Tina
14:06:09 367981589mS CMTARGET: 240.52.1 131 Tina.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=256 dir=out complete=0 ses=0
14:06:09 367981589mS CMTARGET: 240.52.1 131 Tina.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
14:06:09 367981589mS CMCallEvt: 240.52.1 131 Tina.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
14:06:09 367981591mS CMExtnTx: v=256, p1=0
CMSetupAck
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Timed: 02/03/15 14:06
14:06:09 367981591mS CD: CALL: 272.7510.0 BState=Idle Cut=1 Music=3.0 Aend="Tina(256)" (0.0) Bend="" [] (0.0) CalledNum= () CallingNum=256 (Tina) Internal=1 Time=9 AState=DialInitiated
14:06:09 367981593mS H323Evt: v=0 stacknum=240 State, new=OverlapRecv, old=Present id=52
14:06:09 367981593mS H323Evt: v=0 stacknum=240 State, new=Active, old=OverlapRecv id=52
14:06:09 367981603mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981604mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:09 367981605mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981606mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:09 367981618mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981620mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981621mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:09 367981622mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:06:09 367981624mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
14:06:09 367981626mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
14:06:09 367981626mS CMTARGET: 240.52.1 131 Tina.0: CancelTimer CMTCDelayedProcessing
14:06:09 367981627mS CMTARGET: 240.52.1 131 Tina.0: LOOKUP CALL ROUTE: type=100 called_party=079 sub= calling=256 dir=out complete=0 ses=0
14:06:09 367981627mS CMTARGET: 240.52.1 131 Tina.0: ADD TARGET (N): number=079 type=100 depth=1 nobar=1 setorig=1 ses=0
14:06:09 367981628mS CMTARGET: 240.52.1 131 Tina.0: DEF SC: 079 0 sc=type=Dial code=?, num=.
14:06:09 367981628mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
14:06:09 367981628mS CMARS: MakeCallTarget - Called Number: 079
14:06:09 367981629mS CMCallEvt: 0.7512.0 -1 BaseEP: NEW CMEndpoint f4d81918 TOTAL NOW=3 CALL_LIST=1
14:06:09 367981630mS CMTARGET: 240.52.1 131 Tina.0: CancelTimer CMTCDialToneTimeout
14:06:09 367981630mS CMTARGET: 240.52.1 131 Tina.0: INITIAL TARGETING SUCCEEDED
14:06:09 367981630mS CMTARGET: 240.52.1 131 Tina.0: GetNoAnswerTimer:20
14:06:09 367981630mS CMCallEvt: 0.7511.0 131 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
14:06:09 367981631mS CMCallEvt: 0.7512.0 131 ARS for BT_ IPVS_SIP-T: StateChange: END=T CMCSIdle->CMCSOffering
14:06:09 367981631mS CMCallEvt: 0.7511.0 131 TargetingEP: RequestEnd 0.7512.0 131 ARS for BT_ IPVS_SIP-T
14:06:09 367981632mS CMTARGET: 240.52.1 131 Tina.0: CancelTimer CMTCNoAnswerTimeout
14:06:09 367981633mS CMCallEvt: 0.7512.0 131 ARS for BT_ IPVS_SIP-T: StateChange: END=B CMCSOffering->CMCSOverlapRecv
14:06:09 367981634mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079
14:06:09 367981634mS CMARS: CMARSTargetingPending
14:06:09 367981634mS CMCallEvt: 0.7511.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
14:06:09 367981634mS CMCallEvt: 0.7511.0 -1 BaseEP: DELETE CMEndpoint f4d99d50 TOTAL NOW=2 CALL_LIST=1
14:06:09 367981635mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981635mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079*
14:06:09 367981635mS CMARS: CMARSTargetingPending
14:06:09 367981637mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981637mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079**
14:06:09 367981637mS CMARS: CMARSTargetingPending
14:06:09 367981638mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981639mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079***
14:06:09 367981639mS CMARS: CMARSTargetingPending
14:06:09 367981640mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981641mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079****
14:06:09 367981641mS CMARS: CMARSTargetingPending
14:06:09 367981642mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981642mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079*****
14:06:09 367981643mS CMARS: CMARSTargetingPending
14:06:09 367981644mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[8] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981644mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079******
14:06:09 367981644mS CMARS: CMARSTargetingPending
14:06:09 367981645mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981646mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079*******
14:06:09 367981646mS CMARS: CMARSTargetingPending
14:06:09 367981647mS CMExtnRx: v=256, p1=0
CMInformation
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[*]
14:06:09 367981648mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 079*******6
14:06:09 367981648mS CMARS: CMARSTargetingPending
14:06:09 367981648mS CD: CALL: 272.7510.0 BState=Idle Cut=2 Music=0.0 Aend="Tina(256)" (0.0) Bend="" [] (0.0) CalledNum=07********6 () CallingNum=256 (Tina) Internal=1 Time=66 AState=Dialling
14:06:09 367981660mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981661mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:09 367981666mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:09 367981669mS H323Evt: SetOperational 171 192.168.*.*:0 to 0
14:06:10 367982834mS H323Evt: Recv: RegistrationRequest 192.168.*.***; Endpoints registered: 18; Endpoints in registration: 0
14:06:12 367984402mS H323Evt: Recv: RegistrationRequest 192.168.*.***; Endpoints registered: 18; Endpoints in registration: 0
14:06:12 367984629mS CMARS: Case 1 - dial_delay_time: 2000 has expired - Resolve what we might have - Called Number: 07********6 Addr: f4d81918
14:06:12 367984629mS CMARS: FORM: BT_ IPVS_SIP-T - Received Number: 07*******6
14:06:12 367984629mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07********6@uk.ic.sipconnect.hipcom.co.uk - Line Group Id: 30
14:06:12 367984629mS CMARS: FindActiveARSByGroupID GroupID=30 - Not Found
14:06:12 367984630mS CMLRQ: FindActiveLRQByGroupID GroupID=30 - Not Found
14:06:12 367984630mS CMTARGET: Group Id: 30 has NOT been found in Line: 1
14:06:12 367984630mS CMTARGET: Group Id: 30 has NOT been found in Line: 2
14:06:12 367984630mS CMTARGET: Problem with Line Id: 24 - check_DNS 0 OperationalTest: 0, IP address: 0.0.0.0
14:06:12 367984631mS CMCallEvt: 0.7513.0 -1 BaseEP: NEW CMEndpoint f4d74dd0 TOTAL NOW=3 CALL_LIST=1
14:06:12 367984632mS Stun: Info: Line 30: Not using STUN for media in this case.
14:06:12 367984633mS CMARS: FOUND LINE - Line Id: 30 - using line group id: 30 - Called Number: 07*******96@uk.ic.sipconnect.hipcom.co.uk - Calling Number: 256
14:06:12 367984633mS CMARS: SEND Setup TO LINE
14:06:12 367984634mS CMCallEvt: 30.7513.0 131 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
14:06:12 367984636mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 30 Call: lid=30 id=7513 in=0
Called[07*******6@uk.ic.sipconnect.hipcom.co.uk] Type=Default (100) Reason=CMDRdirect SndComp Calling[256] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 14745
user 'Tina' presentation Allowed
IE CMIEFastStartInfoData (6) 12 item(s)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Tina
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Tina
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Tina
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Tina
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=256
IE CMIEDeviceDetail (231) c0a8040200001d56 LOCALE=eng HW=15 VER=9 class=CMDeviceMobilityPhone type=52 number=8021 channel=0 features=0x10000020 rx_gain=32 tx_gain=32 ep_callid=7510 ipaddr=192.168.*.* apps=0 loc=0 em_loc=0 features2=0x0 is_spca
ll=0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 6)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 6)
}
Locale: eng
14:06:12 367984639mS CD: CALL: 272.7510.0 BState=Idle Cut=2 Music=0.0 Aend="Tina(256)" (0.0) Bend="" [Line 30] (0.0) CalledNum=07*******6 () CallingNum=256 (Tina) Internal=1 Time=3057 AState=Dialling
14:06:12 367984653mS CMLineRx: v=0
CMProceeding
Line: type=SIPLine 30 Call: lid=30 id=7513 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[256] Type=Internal Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDirectoryMatch) name=Operator
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07*******6
IE CMIEDeviceDetail (231) c0a8040200001d59 LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=30 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=7513 ipaddr=192.168.*.* apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=0
14:06:12 367984653mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
14:06:12 367984659mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 30 Call: lid=30 id=7513 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[256] Type=Internal Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDirectoryMatch) name=Operator
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07*******6
IE CMIEDeviceDetail (231) c0a8040200001d59 LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=30 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=7513 ipaddr=192.168.*.*

​

apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=0
Cause=1, Unallocated (unassigned) number
14:06:12 367984659mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
14:06:12 367984660mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 30 Call: lid=30 id=7513 in=0
Cause=16, Normal call clearing
14:06:12 367984660mS CMCallEvt: 30.7513.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSOffering->CMCSDelete
14:06:12 367984661mS CMARS: Target: Short_Code: N; - Line_Group_ID: 30 has been set to: CMARS_OUTOFSERVICE_ALL
14:06:12 367984661mS CMARS: 0.7512.0 131 ARS for BT_ IPVS_SIP-T: DropCall() - ABOUT TO SEND CMReleaseComp
14:06:12 367984661mS CMCallEvt: 0.7512.0 131 ARS for BT_ IPVS_SIP-T: StateChange: END=B CMCSOverlapRecv->CMCSCompleted
14:06:12 367984662mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984663mS PRN: CDR - ResetQueueSize=500
14:06:12 367984664mS PRN: CDR - TCPSend maxqueuesize=500 framecount=303 operational=0
14:06:12 367984665mS CMLOGGING: CALL:2015/03/0214:06,00:00:00,000,256,O,07********6,079********6,Tina,,,1,,""n/a,0
14:06:12 367984665mS CD: CALL: 272.7510.0 BState=Disconnecting Cut=0 Music=0.0 Aend="Tina(256)" (0.0) Bend="" [] (0.0) CalledNum=079*******6 () CallingNum=256 (Tina) Internal=1 Time=3083 AState=Dialling
14:06:12 367984665mS CD: CALL: 272.7510.0 Deleted
14:06:12 367984667mS CMExtnEvt: Tina: CALL LOST (CMCauseUnallocatedNumber)
14:06:12 367984667mS CMExtnEvt: Tina: Extn(256) Calling Party Number(256) Type(CMNTypeInternal)
14:06:12 367984667mS CMCallEvt: 240.52.1 -1 Tina.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
14:06:12 367984668mS CMExtnEvt: v=0 State, new=CMESCompleted old=Dialling,0,0,Tina
14:06:12 367984669mS CMARS: CMARSEndpoint::CallLost(cause=1) - Address: 0.****.0 -1 ARS for BT_ IPVS_SIP-T - Call State: CMCSCompleted
14:06:12 367984669mS CMCallEvt: 0.****.0 -1 ARS for BT_ IPVS_SIP-T: StateChange: END=X CMCSCompleted->CMCSDelete
14:06:12 367984669mS CMCallEvt: END CALL:131 (f4d9b620)
14:06:12 367984670mS CMCallEvt: 0.7512.0 -1 BaseEP: DELETE CMEndpoint f4d81918 TOTAL NOW=2 CALL_LIST=0
14:06:12 367984671mS CMCallEvt: 30.7513.0 -1 BaseEP: DELETE CMEndpoint f4d74dd0 TOTAL NOW=1 CALL_LIST=0
14:06:12 367984673mS CMMap: PCG::AddToneGenerator g B3[6] for cp[438]b0r1 append pcp[440]b0r1 (total 1)
14:06:12 367984673mS CMMap: PlatformConnectionAudioSAP::Bind pcp[440]b0r1 to 0.0
14:06:12 367984673mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[440]b0r1 Configure 0.0
14:06:12 367984673mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[440]b0r1 ConnectIndication 0.0
14:06:12 367984674mS CMMap: a=0.0 b=0.0 pcp[438]b0r1 RTPB3
14:06:12 367984674mS H323Evt: SetOperational 171 192.168.*.*:4**** to 1
14:06:12 367984680mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984684mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984685mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984687mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984687mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984699mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984701mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984702mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984702mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984704mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984704mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984705mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984706mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:12 367984708mS CMMap: a=0.0 b=0.0 pcp[438]b0r1 RTPB3
14:06:12 367984716mS CMExtnRx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
14:06:12 367984718mS CMMap: a=0.0 b=0.0 pcp[438]b0r1 RTPB3
14:06:12 367984982mS H323Evt: Shared tcp socket for line 20 disconnected
14:06:13 367985789mS H323Evt: RTP(50r): 192.168.*.*/49152 192.168.*.**/***** CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=4120 SENT=55 (avg size=164) RECV=50 (avg size=160)
14:06:14 367986388mS CMCallEvt: 0.7514.0 -1 BaseEP: NEW CMEndpoint f4d74dd0 TOTAL NOW=2 CALL_LIST=0
14:06:14 367986388mS Stun: Info: Line 30: Not using STUN for media in this case.
14:06:14 367986405mS CMCallEvt: 0.7514.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
14:06:14 367986405mS CMCallEvt: 0.7514.0 -1 BaseEP: DELETE CMEndpoint f4d74dd0 TOTAL NOW=1 CALL_LIST=0
14:06:14 367986778mS RES: Mon 2/3/2015 14:06:14 FreeMem=53342112 51569592(5) CachedMem=1772520 CMMsg=9(9) Buff=5200 1356 1000 7419 4 Links=32658 BTree=14286 CPU=04.84% CPUStats=1/3/2092/18169/22234/0/1 MCR=0 MCW=0
14:06:14 367986778mS RES2: IP 500 V2 9.0.4.0 build 965 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=86 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=65(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:06:14 367986778mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
14:06:15 367987375mS H323Evt: v=0 stacknum=240 State, new=NullState, old=Active id=52
14:06:15 367987377mS CMExtnTx: v=256, p1=0
CMFacility
Line: type=Mobility 272 Call: lid=240 id=52 in=1
IE CMIEPrimitiveData (7)
Timed: 02/03/15 14:06
14:06:15 367987378mS CMExtnRx: v=256, p1=0
CMReleaseComp
Line: type=Mobility 272 Call: lid=240 id=52 in=1
Cause=16, Normal call clearing
14:06:15 367987378mS CMCallEvt: 240.52.1 -1 Tina.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
14:06:15 367987378mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=CMESCompleted,0,0,Tina
14:06:15 367987379mS CMExtnEvt: Tina: CALL LOST (CMCauseForceClear)
14:06:15 367987379mS CMExtnEvt: Tina: Extn(256) Calling Party Number(256) Type(CMNTypeInternal)
14:06:15 367987379mS CMExtnEvt: Tina: CMExtnHandler::SetCurrent( id: 52->0 )
14:06:15 367987379mS CMCallEvt: 240.52.1 -1 Tina.-1: StateChange: END=X CMCSCompleted->CMCSDelete
14:06:15 367987380mS CMTARGET: 240.52.1 -1 BaseEP: ~CMTargetHandler f4d69b60 ep f4d88590
14:06:15 367987381mS CMCallEvt: 240.52.1 -1 BaseEP: DELETE CMEndpoint f4d88590 TOTAL NOW=0 CALL_LIST=0
14:06:15 367987382mS CMMap: a=0.0 b=0.0 pcp[438]b0r1 RTPB0
14:06:15 367987382mS H323Evt: SetOperational 171 192.168.*.*:*****to 0
14:06:15 367987383mS H323Evt: RTP(END): 192.168.*.*/49152 192.168.*.*/16586 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=5714 SENT=135 RECV=128 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0

********** Warning: Logging to Screen Stopped **********

 

[janni78]

You're recieving "Unallocated number" from the SIP provider so if you can call from a deskphone but not from a DECT phone something is different in the outgoing SIP invite

14:06:12 367984659mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 30 Call: lid=30 id=7513 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[256] Type=Internal Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDirectoryMatch) name=Operator
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07*******6
IE CMIEDeviceDetail (231) c0a8040200001d59 LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=30 channel=1 features=0x1 rx_gain=32 tx_gain=32 ep_callid=7513 ipaddr=192.168.*.*
apps=0 loc=999 em_loc=999 features2=0x0 is_spcall=0
Cause=1, Unallocated (unassigned) number

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

 

[tlpeter]

SIP URI for the dect users incorrect?

BAZINGA!

I'm not insane, my mother had me tested!

 

[telesonic]

thanks tlpeter,
that was it, SIP set up. So focused on the DECT being the issue I did not manage to take my mind out to another area of programming...

thank you!!
Paul

 

[telesonic]

And Janni!!! Sorry - thank you too