forward unconditional to external not working 3

[zoomatomic]

I have an IPO server edition with a 500v2 as expansion for PRI. When I set a users forward unconditional to an external number I get number busy.

See trace below.

I have user 8060 forwarded to 0863848527. I dial 8060 from user 5120. Trace is from the 500v2 box and phones are 1608 registered on the server.

I can dial 90863848527 directly from any user no problem.

********** Warning: Logging to Screen Started **********
16:48:56 1510926mS H323Evt: v=0 stacknum=17 State, new=NullState, old=NullState id=-1
16:48:56 1510927mS H323Evt: v=0 stacknum=17 State, new=Present, old=NullState id=6
16:48:56 1510931mS CMLineRx: v=17
CMSetup
Line: type=IPLine 17 Call: lid=17 id=6 in=1
Called[90879635024] Type=Default (100) Reason=CMDRdirect SndComp Calling[15334840] Type=Default Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 10 item(s)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=0 (0000) id=1029 (0405) in=0 (0000)
IE CMIEOriginalCallingPartyName (194)(Type=CMNameDefault) name=SIMONP
IE CMIEOriginalCallingPartyKName (195)(Type=CMNameDefault) name=SIMON POWELL
IE CMIEOriginalCallingPartyNumber (196)(P:100 S:100 T:101 N:100 R:4) number=5120
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SIMONP
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=SIMON POWELL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=5120
IE CMIEDeviceDetail (231) LOCALE=eng HW=11 VER=9 class=CMDeviceH323Phone type=87 number=11213 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1026 ipaddr=172.26.129.26 apps=0 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:1) number=5121
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=ROBERT RYAN
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:1) number=5121
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
16:48:56 1510931mS CMCallEvt: 0.1023.0 -1 BaseEP: NEW CMEndpoint f4e2a080 TOTAL NOW=1 CALL_LIST=0
16:48:56 1510932mS CMCallEvt: CREATE CALL:6 (f4e28878)
16:48:56 1510932mS CMCallEvt: 0.1024.0 -1 BaseEP: NEW CMEndpoint f4e26cd0 TOTAL NOW=2 CALL_LIST=0
16:48:56 1510934mS CD: CALL: 17.6.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (267.1) Bend="" [] (0.0) CalledNum=90879635024 (ROBERT RYAN) CallingNum=15334840 () Internal=0 Time=2 AState=Idle
16:48:56 1510935mS CMCallEvt: 17.6.1 6 H323TrunkEP: StateChange: END=A CMCSIdle->CMCSDialInitiated
16:48:56 1510935mS CMTARGET: 17.6.1 6 H323TrunkEP: LOOKUP CALL ROUTE: type=100 called_party=90879635024 sub= calling=15334840 dir=out complete=1 ses=0
16:48:56 1510935mS CMTARGET: 17.6.1 6 H323TrunkEP: ADD TARGET (N): number=90879635024 type=100 depth=1 nobar=1 setorig=1 ses=0
16:48:56 1510935mS CMTARGET: 17.6.1 6 H323TrunkEP: SYS SC: 90879635024 2 0879635024 sc=type=Dial code=9N, num=N callinfop->sending_complete=1 secondary_dialtone=
16:48:56 1510936mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
16:48:56 1510936mS CMARS: MakeCallTarget - Called Number: 0879635024
16:48:56 1510936mS CMCallEvt: 0.1025.0 -1 BaseEP: NEW CMEndpoint f4e1d2b0 TOTAL NOW=3 CALL_LIST=1
16:48:56 1510936mS CMTARGET: 17.6.1 6 H323TrunkEP: INITIAL TARGETING SUCCEEDED
16:48:56 1510937mS CMTARGET: 17.6.1 6 H323TrunkEP: SetNoAnswerTimer Not Starting: 5121
16:48:56 1510937mS CMCallEvt: 17.6.1 6 H323TrunkEP: StateChange: END=A CMCSDialInitiated->CMCSDialled
16:48:56 1510938mS CMLineTx: v=17
CMProceeding
Line: type=IPLine 17 Call: lid=17 id=6 in=1
16:48:56 1510938mS CMCallEvt: 0.1024.0 6 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
16:48:56 1510940mS CMCallEvt: 0.1025.0 6 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
16:48:56 1510940mS CMCallEvt: 0.1025.0 6 ARS for Main: StateChange: END=T CMCSOffering->CMCSOverlapRecv
16:48:56 1510940mS CMCallEvt: 0.1024.0 6 TargetingEP: StateChange: END=B CMCSOffering->CMCSOverlapRecv
16:48:56 1510940mS CMARS: FORM: Main - Received Number: 0879635024
16:48:56 1510941mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 0879635024 - Line Group Id: 1
16:48:56 1510941mS CMARS: FindActiveARSByGroupID GroupID=1 - Not Found
16:48:56 1510941mS CMLRQ: FindActiveLRQByGroupID GroupID=1 - Not Found
16:48:56 1510941mS CMTARGET: Group Id: 1 has NOT been found in Line: 2
16:48:56 1510941mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f569dba0) resource busy 1, total 64
16:48:56 1510941mS CMCallEvt: 0.1026.0 -1 BaseEP: NEW CMEndpoint f4e18ec8 TOTAL NOW=4 CALL_LIST=1
16:48:56 1510943mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 1 - Called Number: 0879635024 - Calling Number: 15334840
16:48:56 1510943mS CMARS: SEND Setup TO LINE
16:48:56 1510943mS CMCallEvt: 0.1026.0 6 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
16:48:56 1510944mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
Called[0879635024] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[5120] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: eng
16:48:56 1510944mS CD: CALL: 17.6.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (267.1) Bend="" [] (0.0) CalledNum=90879635024 (ROBERT RYAN) CallingNum=15334840 () Internal=0 Time=12 AState=Dialled
16:48:56 1510946mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=1026
16:48:56 1510946mS H323Evt: v=0 stacknum=17 State, new=ICProceeding, old=Present id=6
16:48:56 1510957mS ISDNL3Evt: v=1 stacknum=1 State, new=Proceeding, old=Initiated id=1026
16:48:56 1510957mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
BChan: slot=0 chan=1
16:48:56 1510958mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
16:48:57 1511086mS ISDNL3Evt: v=1 stacknum=1 State, new=DiscInd, old=Proceeding id=1026
16:48:57 1511087mS CMLineRx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPrivateNetLocalUser (1), pd=CMPDInbandPattern (8)
Cause=1, Unallocated (unassigned) number
16:48:57 1511087mS CMARS: LINE ep Received: CMDisconnect - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
16:48:57 1511087mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
Cause=16, Normal call clearing
16:48:57 1511088mS CMCallEvt: 0.1026.0 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSOffering->CMCSCompleted
16:48:57 1511088mS CMARS: Target: Short_Code: ? - Line_Group_ID: 1 has been set to: CMARS_OUTOFSERVICE_ALL
16:48:57 1511088mS CMARS: 0.1025.0 6 ARS for Main: DropCall() - ABOUT TO SEND CMReleaseComp
16:48:57 1511088mS CMCallEvt: 0.1025.0 6 ARS for Main: StateChange: END=T CMCSOverlapRecv->CMCSCompleted
16:48:57 1511088mS CMARS: CMARSEndpoint::CallLost(cause=1) - Address: 0.1025.0 6 ARS for Main - Call State: CMCSCompleted
16:48:57 1511089mS CMCallEvt: 0.1025.0 -1 ARS for Main: StateChange: END=X CMCSCompleted->CMCSDelete
16:48:57 1511089mS CMLOGGING: CALL:2015/01/0816:48,00:00:00,000,15334840,I,90879635024,90879635024,SIMONP,,,0,,""n/a,0
16:48:57 1511089mS CD: CALL: 17.6.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (267.1) Bend="" [] (0.0) CalledNum=90879635024 (ROBERT RYAN) CallingNum=15334840 () Internal=0 Time=157 AState=Dialled
16:48:57 1511089mS CD: CALL: 17.6.1 Deleted
16:48:57 1511090mS CMLineTx: v=17
CMReleaseComp
Line: type=IPLine 17 Call: lid=17 id=6 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
BusyName.Invoke.CodePageUtf8String
invokeId 14627
user 'ROBERT RYAN' presentation Allowed
Cause=17, User busy
16:48:57 1511090mS CMCallEvt: 17.6.1 -1 H323TrunkEP: StateChange: END=X CMCSDialled->CMCSDelete
16:48:57 1511091mS CMTARGET: 17.6.1 -1 BaseEP: ~CMTargetHandler f4e203a8 ep f4e2a080
16:48:57 1511091mS CMCallEvt: 17.6.1 -1 BaseEP: DELETE CMEndpoint f4e2a080 TOTAL NOW=3 CALL_LIST=0
16:48:57 1511091mS CMCallEvt: 0.1024.0 -1 BaseEP: DELETE CMEndpoint f4e26cd0 TOTAL NOW=2 CALL_LIST=0
16:48:57 1511091mS CMCallEvt: END CALL:6 (f4e28878)
16:48:57 1511092mS CMCallEvt: 0.1025.0 -1 BaseEP: DELETE CMEndpoint f4e1d2b0 TOTAL NOW=1 CALL_LIST=0
16:48:57 1511093mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f569dba0) resource busy 0, total 64
16:48:57 1511094mS H323Evt: v=0 stacknum=17 State, new=ReleaseReq, old=ICProceeding id=6
16:48:57 1511095mS H323Evt: v=0 stacknum=17 State, new=NullState, old=ReleaseReq id=6
16:48:57 1511095mS ISDNL3Evt: v=1 stacknum=1 State, new=ReleaseReq, old=DiscInd id=1026
16:48:57 1511102mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=ReleaseReq id=1026
16:48:57 1511102mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
BChan: slot=0 chan=1
Cause=1, Unallocated (unassigned) number
16:48:57 1511103mS CMCallEvt: 0.1026.0 -1 Q931 Trunk:1 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
16:48:57 1511103mS CMCallEvt: 0.1026.0 -1 BaseEP: DELETE CMEndpoint f4e18ec8 TOTAL NOW=0 CALL_LIST=0

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

 

[Jerrberr]

check your dial out, you state you dial
"0863848527" then say
"I can dial 90863848527 directly from any user no problem." I always include the 9 in the forwarding number string because it gives line access direction.
hope it is that easy a fix.

Jerrberr

 

[zoomatomic]

I should have been clearer, I have the 9 in the forward unconditional box.

 

[intrigrant]

16:48:57 1511087mS CMLineRx: v=1 CMDisconnect
=Line: type=Q931Line 1 Call: lid=0 id=1026 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPrivateNetLocalUser (1), pd=CMPDInbandPattern (8)
Cause=1, Unallocated (unassigned) number

That is clear enough, the number is not recognized by your provider as a valid telephone number, can you dial the number from a cell phone?

 

[amriddle01]

You are sending the 9 to line not stripping it

 

[intrigrant]

I noticed but it would be nice if he discovered that part himself

 

[zoomatomic]

I dont see how it is not being stripped? If i dial 90863848527 from the handset i get out no problem so I don't see the difference if I put this user on forward unconditional to 90863848527

???

 

[zoomatomic]

Looks like the 9 is stripped to me?

16:48:56 1510940mS CMARS: FORM: Main - Received Number: 0879635024
16:48:56 1510941mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 0879635024 - Line Group Id: 1
16:48:56 1510941mS CMARS: FindActiveARSByGroupID GroupID=1 - Not Found
16:48:56 1510941mS CMLRQ: FindActiveLRQByGroupID GroupID=1 - Not Found
16:48:56 1510941mS CMTARGET: Group Id: 1 has NOT been found in Line: 2
16:48:56 1510941mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f569dba0) resource busy 1, total 64
16:48:56 1510941mS CMCallEvt: 0.1026.0 -1 BaseEP: NEW CMEndpoint f4e18ec8 TOTAL NOW=4 CALL_LIST=1
16:48:56 1510943mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 1 - Called Number: 0879635024 - Calling Number: 15334840
16:48:56 1510943mS CMARS: SEND Setup TO LINE
16:48:56 1510943mS CMCallEvt: 0.1026.0 6 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
16:48:56 1510944mS CMLineTx: v=1

 

[amriddle01]

The last to line call event before receiving the cause code shows you sending the 9

 

[intrigrant]

Indeed. taking a closer look the 9 is stripped, then there is a problem on ISDN but you have not all ISDN messages selected so it is unclear what is wrong, maybe it is the CLI you send along with the call is not allowed by the provider?

 

[Westi]

uncheck the "sending original caller ID" in the system as that might help

Joe W.

FHandw, ACSS (SME), ACIS (SME)


"This is the end of the world, make sure to buy your T-shirt before it is too late"
Original expression of my daughter

 

[vztech]

Post a trace on the 500V2 end. That might give us a better idea.

It's only dialtone!!!

 

[vztech]

Just checking but.....System/Telephony - Inhibit off switch calls is not ticked on the 500V2

It's only dialtone!!!

 

[zoomatomic]

Thanks Westi, it was "sending original caller ID" , cant believe I got stung by a tick the box!!

 

[intrigrant]

As I said "maybe it is the CLI you send along with the call is not allowed by the provider", that would be seen if you posted a full ISDN log.

 

[derfloh]

Strange behavior of the provider...

All German ISDN providers have the option to pay for the ability to send the number you want. If you are not allowed to send numbers you are not the owner of calls will not be blocked but the provider will change the number to send to your trunk's main number. That's far better than just blocking calls.

 

[tlpeter]

But your neighbor the Netherlands does not allow that and refuses the call (it is not blocking)


BAZINGA!

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

 

[derfloh]

Refuse... Block... Doesn't matter ;-)

Er habe refused calls only with SIP. But the ISDN way in Germany is a good one.

 

[tlpeter]

I don't like DISA
I never understood why the German use that.

BAZINGA!

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

 

[intrigrant]

It is like they build their telecom systems, it works but even they don't know how that is possible...