Dialling out - SIP trunk 1

[siplyconfused]

Hi all

I'm having a strange problem with dialling out on a SIP trunk, incoming and call routing works fine. Its outgoing that gives me issues.

The called number is having the SIP url added several times - see here 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
(I've replaced the number with x's for obvious reasons)

Monitor log below

Type=Default (100) Reason=CMDRdirect Keypad[6]
669210906mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
669210907mS CMARS: CMARSTargetingPending
669212959mS CMARS: Case 1 - dial_delay_time: 2000 has expired - Resolve what we might have - Called Number: 07880xxxxx6 Addr: f5177c00
669212959mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
669212960mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk - Line Group Id: 55
669212960mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212960mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk
669212961mS CMCallEvt: 0.16029.0 -1 BaseEP: NEW CMEndpoint f51300a0 TOTAL NOW=3 CALL_LIST=1
669212961mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk - Calling Number: 6400
669212961mS CMARS: SEND Setup TO LINE
669212961mS CMCallEvt: 0.16029.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212962mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212962mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212962mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16028.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212962mS CMCallEvt: 0.16028.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212963mS CMCallEvt: 0.16029.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212963mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk
669212963mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk - Line Group Id: 55
669212964mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212964mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk
669212964mS CMCallEvt: 0.16030.0 -1 BaseEP: NEW CMEndpoint f512d8dc TOTAL NOW=4 CALL_LIST=1
669212964mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk - Calling Number: 6400
669212965mS CMARS: SEND Setup TO LINE
669212965mS CMCallEvt: 0.16030.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212965mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212966mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212966mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16029.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212966mS CMCallEvt: 0.16029.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212967mS CMCallEvt: 0.16030.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212967mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk
669212967mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212967mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212967mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212968mS CMCallEvt: 0.16031.0 -1 BaseEP: NEW CMEndpoint f512a840 TOTAL NOW=5 CALL_LIST=1
669212968mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212968mS CMARS: SEND Setup TO LINE
669212969mS CMCallEvt: 0.16031.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212969mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212969mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212969mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16030.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212970mS CMCallEvt: 0.16030.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212970mS CMCallEvt: 0.16031.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212970mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212971mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212971mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212971mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212971mS CMCallEvt: 0.16032.0 -1 BaseEP: NEW CMEndpoint f51277a4 TOTAL NOW=6 CALL_LIST=1
669212972mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212972mS CMARS: SEND Setup TO LINE
669212972mS CMCallEvt: 0.16032.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212973mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212973mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212973mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16031.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212973mS CMCallEvt: 0.16031.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212974mS CMCallEvt: 0.16032.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212974mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212974mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212975mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212975mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212975mS CMCallEvt: 0.16033.0 -1 BaseEP: NEW CMEndpoint f5124708 TOTAL NOW=7 CALL_LIST=1
669212975mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212975mS CMARS: SEND Setup TO LINE
669212976mS CMCallEvt: 0.16033.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212976mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212976mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212977mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16032.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212977mS CMCallEvt: 0.16032.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212977mS CMCallEvt: 0.16033.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212978mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212978mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212978mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212978mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212979mS CMCallEvt: 0.16034.0 -1 BaseEP: NEW CMEndpoint f512166c TOTAL NOW=8 CALL_LIST=1
669212979mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212979mS CMARS: SEND Setup TO LINE
669212979mS CMCallEvt: 0.16034.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212980mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212980mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212980mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16033.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212980mS CMCallEvt: 0.16033.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212981mS CMCallEvt: 0.16034.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212981mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212982mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212982mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212982mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212982mS CMCallEvt: 0.16035.0 -1 BaseEP: NEW CMEndpoint f511e5d0 TOTAL NOW=9 CALL_LIST=1
669212983mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212983mS CMARS: SEND Setup TO LINE
669212983mS CMCallEvt: 0.16035.0 20 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
669212983mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
669212984mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
669212984mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.16034.0 20 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
669212984mS CMCallEvt: 0.16034.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
669212985mS CMCallEvt: 0.16035.0 20 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
669212985mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212985mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: ."@uk.ic.sipconnect.hipcom.co.uk" - Called_Party: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Line Group Id: 55
669212985mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
669212986mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s
669212986mS CMCallEvt: 0.16036.0 -1 BaseEP: NEW CMEndpoint f511b460 TOTAL NOW=10 CALL_LIST=1
669212986mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6@uk.ic.sipconnect.hipcom.co.uk@uk.ic.sipconnect.hipcom.co.uk@uk.ic.s - Calling Number: 6400
669212986mS CMARS: SEND Setup TO LINE

Thanks for any help

Rich

 

[amriddle01]

Take the "@uk.ic.sipconnect.hipcom.co.uk" away from the shortcode after the . it isn't required anymore (this changed at R6) and in your case it looks like its doubling up the info going to the line. Do you have that in both system and ARS... that is also wrong if you do.

So

N;
Dial
.
SIP Line gp

 

[siplyconfused]

Thanks for the help.

In the shortcode (Dialling 8 for routing via sip) I have N and I've taken the "@uk.ic.sipconnect.hipcom.co.uk" out of the ARS and left the full stop in

It now just has the number in the logs, the call still fails with incompatible displayed on the handset

Cheers

Rich

 

[siplyconfused]

Monitor output after change


759877573mS CMExtnRx: v=6400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
759877573mS CMARS: FORM: hipcom_itsp - Received Number: 078807156
759877573mS CMARS: CMARSTargetingPending
759877898mS PRN: 6400: Digit Pressed 9
759877899mS CMExtnRx: v=6400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
759877899mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx
759877899mS CMARS: CMARSTargetingPending
759878135mS PRN: 6400: Digit Pressed 6
759878135mS CMExtnRx: v=6400, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
Called[6] Type=Default (100) Reason=CMDRdirect Keypad[6]
759878136mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759878136mS CMARS: CMARSTargetingPending
759880390mS RES: Thu 6/12/2012 10:44:15 FreeMem=61141800(2) CMMsg=27 (28) Buff=5200 959 888 7445 5 Links=3737
759880391mS RES2: IP 500 V2 8.0(42) Tasks=46 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=63 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=41 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
759880706mS CMARS: Case 1 - dial_delay_time: 2000 has expired - Resolve what we might have - Called Number: 07880xxxxx6 Addr: f5177c00
759880706mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880707mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880707mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880707mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880707mS CMCallEvt: 0.18188.0 -1 BaseEP: NEW CMEndpoint f51300a0 TOTAL NOW=3 CALL_LIST=1
759880707mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880708mS CMARS: SEND Setup TO LINE
759880708mS CMCallEvt: 0.18188.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880708mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880708mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880709mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18187.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880709mS CMCallEvt: 0.18187.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880710mS CMCallEvt: 0.18188.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880710mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880710mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880710mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880710mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880711mS CMCallEvt: 0.18189.0 -1 BaseEP: NEW CMEndpoint f512d8dc TOTAL NOW=4 CALL_LIST=1
759880711mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880711mS CMARS: SEND Setup TO LINE
759880711mS CMCallEvt: 0.18189.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880712mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880712mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880712mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18188.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880712mS CMCallEvt: 0.18188.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880713mS CMCallEvt: 0.18189.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880713mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880713mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880714mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880714mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880714mS CMCallEvt: 0.18190.0 -1 BaseEP: NEW CMEndpoint f512a840 TOTAL NOW=5 CALL_LIST=1
759880714mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880715mS CMARS: SEND Setup TO LINE
759880715mS CMCallEvt: 0.18190.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880715mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880715mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880716mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18189.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880716mS CMCallEvt: 0.18189.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880716mS CMCallEvt: 0.18190.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880717mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880717mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880717mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880717mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880717mS CMCallEvt: 0.18191.0 -1 BaseEP: NEW CMEndpoint f51277a4 TOTAL NOW=6 CALL_LIST=1
759880718mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880718mS CMARS: SEND Setup TO LINE
759880718mS CMCallEvt: 0.18191.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880719mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880719mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880719mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18190.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880719mS CMCallEvt: 0.18190.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880720mS CMCallEvt: 0.18191.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880720mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880720mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880721mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880721mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880721mS CMCallEvt: 0.18192.0 -1 BaseEP: NEW CMEndpoint f5124708 TOTAL NOW=7 CALL_LIST=1
759880721mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880721mS CMARS: SEND Setup TO LINE
759880722mS CMCallEvt: 0.18192.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880722mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880722mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880722mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18191.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880723mS CMCallEvt: 0.18191.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880723mS CMCallEvt: 0.18192.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880724mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880724mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880724mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880724mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880724mS CMCallEvt: 0.18193.0 -1 BaseEP: NEW CMEndpoint f512166c TOTAL NOW=8 CALL_LIST=1
759880725mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880725mS CMARS: SEND Setup TO LINE
759880725mS CMCallEvt: 0.18193.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880726mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880726mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880726mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18192.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880726mS CMCallEvt: 0.18192.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880727mS CMCallEvt: 0.18193.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880727mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880727mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880727mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880728mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880728mS CMCallEvt: 0.18194.0 -1 BaseEP: NEW CMEndpoint f511e5d0 TOTAL NOW=9 CALL_LIST=1
759880728mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880728mS CMARS: SEND Setup TO LINE
759880729mS CMCallEvt: 0.18194.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880729mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880729mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880729mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18193.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880730mS CMCallEvt: 0.18193.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880730mS CMCallEvt: 0.18194.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880731mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880731mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880731mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880731mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880731mS CMCallEvt: 0.18195.0 -1 BaseEP: NEW CMEndpoint f511b460 TOTAL NOW=10 CALL_LIST=1
759880732mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880732mS CMARS: SEND Setup TO LINE
759880732mS CMCallEvt: 0.18195.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880733mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880733mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880733mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18194.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880733mS CMCallEvt: 0.18194.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880734mS CMCallEvt: 0.18195.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880734mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880734mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880734mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880735mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880735mS CMCallEvt: 0.18196.0 -1 BaseEP: NEW CMEndpoint f511828c TOTAL NOW=11 CALL_LIST=1
759880735mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880735mS CMARS: SEND Setup TO LINE
759880736mS CMCallEvt: 0.18196.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880736mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880736mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880736mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18195.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880737mS CMCallEvt: 0.18195.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880737mS CMCallEvt: 0.18196.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880737mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880738mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: . - Called_Party: 07880xxxxx6 - Line Group Id: 55
759880738mS CMARS: FindActiveARSByGroupID GroupID=55 - Found
759880738mS CMARS: MakeCallTarget - Called Number: 07880xxxxx6
759880738mS CMCallEvt: 0.18197.0 -1 BaseEP: NEW CMEndpoint f51150b8 TOTAL NOW=12 CALL_LIST=1
759880739mS CMARS: FOUND LINE - Line Id: 99 - using line group id: 55 - Called Number: 07880xxxxx6 - Calling Number: 6400
759880739mS CMARS: SEND Setup TO LINE
759880739mS CMCallEvt: 0.18197.0 32 ARS for hipcom_itsp: StateChange: END=child CMCSIdle->CMCSOffering
759880739mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
759880740mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
759880740mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.18196.0 32 ARS for hipcom_itsp - Call State: CMCSOverlapRecv
759880740mS CMCallEvt: 0.18196.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSOverlapRecv->CMCSDelete
759880741mS CMCallEvt: 0.18197.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOffering->CMCSOverlapRecv
759880741mS CMARS: FORM: hipcom_itsp - Received Number: 07880xxxxx6
759880741mS CMARS: ERROR IsARSFormValid()
759880741mS CMARS: CMARSTargetingFailed
759880741mS CMARS: FAIL CALL
759880741mS CMARS: 0.18197.0 32 ARS for hipcom_itsp: DropCall() - ABOUT TO SEND CMReleaseComp
759880742mS CMCallEvt: 0.18197.0 32 ARS for hipcom_itsp: StateChange: END=B CMCSOverlapRecv->CMCSCompleted
759880743mS CMExtnTx: v=6400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
IE CMIEFastStartInfoData (6)
Timed: 06/12/12 10:44
759880743mS CMExtnTx: v=6400, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=2 in=1
IE CMIEFastStartInfoData (6)
759880744mS PRN: CDR - ResetQueueSize=500
759880744mS PRN: CDR - TCPSend maxqueuesize=500 operational=0
759880745mS CMLOGGING: CALL:2012/12/0610:44,00:00:00,000,6400,O,807880xxxxx6,807880xxxxx6,Extn6400,,,1,,""n/a,0
759880745mS CD: CALL: 253.18185.0 BState=Disconnecting Cut=0 Music=0.0 Aend="Extn6400(6400)" (0.0) Bend="" [] (0.0) CalledNum=807880xxxxx6 () CallingNum=6400 (Extn6400) Internal=1 Time=6570 AState=Dialling
759880745mS CD: CALL: 253.18185.0 Deleted
759880747mS CMExtnEvt: Extn6400: CALL LOST (CMCauseUnallocatedNumber)
759880747mS CMExtnEvt: Extn6400: Extn(6400) Calling Party Number(6400) Type(CMNTypeInternal)
759880748mS CMCallEvt: 253.18185.0 -1 Extn6400.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
759880752mS CMMap: PCG::AddToneGenerator g B3[6] for cp[183]b0r1 append pcp[185]b0r1 (total 1)
759880752mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[185]b0r1 Configure 0.0
759880752mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[185]b0r1 ConnectIndication 0.0
759880752mS CMMap: a=0.0 b=0.0 pcp[183]b0r1 RTPB3
759880753mS CMExtnTx: v=6400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
IE CMIEFastStartInfoData (6)
Timed: 06/12/12 10:44
759880753mS CMExtnTx: v=6400, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=2 in=1
IE CMIEFastStartInfoData (6)
759880754mS CMExtnEvt: v=2 State, new=CMESCompleted old=Dialling,0,0,Extn6400
759880754mS CMARS: CMARSEndpoint::CallLost(cause=1) - Address: 0.18197.0 -1 ARS for hipcom_itsp - Call State: CMCSCompleted
759880754mS CMCallEvt: 0.18197.0 -1 ARS for hipcom_itsp: StateChange: END=X CMCSCompleted->CMCSDelete
759880755mS CMCallEvt: END CALL:32 (f51b712c)
759880755mS CMCallEvt: 0.18197.0 -1 BaseEP: DELETE CMEndpoint f51150b8 TOTAL NOW=11 CALL_LIST=0
759880756mS CMCallEvt: 0.18196.0 -1 BaseEP: DELETE CMEndpoint f511828c TOTAL NOW=10 CALL_LIST=0
759880756mS CMCallEvt: 0.18195.0 -1 BaseEP: DELETE CMEndpoint f511b460 TOTAL NOW=9 CALL_LIST=0
759880756mS CMCallEvt: 0.18194.0 -1 BaseEP: DELETE CMEndpoint f511e5d0 TOTAL NOW=8 CALL_LIST=0
759880756mS CMCallEvt: 0.18193.0 -1 BaseEP: DELETE CMEndpoint f512166c TOTAL NOW=7 CALL_LIST=0
759880757mS CMCallEvt: 0.18192.0 -1 BaseEP: DELETE CMEndpoint f5124708 TOTAL NOW=6 CALL_LIST=0
759880757mS CMCallEvt: 0.18191.0 -1 BaseEP: DELETE CMEndpoint f51277a4 TOTAL NOW=5 CALL_LIST=0
759880757mS CMCallEvt: 0.18190.0 -1 BaseEP: DELETE CMEndpoint f512a840 TOTAL NOW=4 CALL_LIST=0
759880757mS CMCallEvt: 0.18189.0 -1 BaseEP: DELETE CMEndpoint f512d8dc TOTAL NOW=3 CALL_LIST=0
759880758mS CMCallEvt: 0.18188.0 -1 BaseEP: DELETE CMEndpoint f51300a0 TOTAL NOW=2 CALL_LIST=0
759880758mS CMCallEvt: 0.18187.0 -1 BaseEP: DELETE CMEndpoint f5177c00 TOTAL NOW=1 CALL_LIST=0
759881229mS H323Evt: RTP(50t): 192.168.1.2/49152 192.168.1.11/50858 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=7043 SENT=50 (avg size=160) RECV=33 (avg size=160)
759886675mS CMExtnTx: v=6400, p1=8002
CMFacility
Line: type=IPLine 250 Call: lid=253 id=2 in=1
IE CMIEFastStartInfoData (6)
759886675mS CMExtnRx: v=6400, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
759886676mS CMCallEvt: 253.18185.0 -1 Extn6400.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
759886677mS CMExtnTx: v=6400, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=253 id=18185 in=0
IE CMIEFastStartInfoData (6)
Timed: 06/12/12 10:44
759886677mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=CMESCompleted,0,0,Extn6400
759886679mS CMExtnEvt: Extn6400: CALL LOST (CMCauseForceClear)
759886679mS CMExtnEvt: Extn6400: Extn(6400) Calling Party Number(6400) Type(CMNTypeInternal)
759886679mS CMExtnEvt: Extn6400: CMExtnHandler::SetCurrent( id: 18185->0 )
759886680mS CMCallEvt: 253.18185.0 -1 Extn6400.-1: StateChange: END=X CMCSCompleted->CMCSDelete
759886681mS CMTARGET: 253.18185.0 -1 BaseEP: ~CMTargetHandler f51afbec ep f51ae348
759886681mS CMCallEvt: 253.18185.0 -1 BaseEP: DELETE CMEndpoint f51ae348 TOTAL NOW=0 CALL_LIST=0
759886682mS CMMap: a=0.0 b=0.0 pcp[183]b0r1 RTPB0
759886683mS H323Evt: RTP(END): 192.168.1.2/49152 192.168.1.11/50858 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=12496 SENT=322 RECV=306 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
759886890mS RES: Thu 6/12/2012 10:44:21 FreeMem=61178608(2) CMMsg=27 (28) Buff=5200 956 888 7444 5 Links=3809
759886890mS RES2: IP 500 V2 8.0(42) Tasks=46 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=43 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
759886991mS H323Evt: Shared tcp socket for line 20 disconnected
759888680mS CMExtnEvt: Extn6400: Recover Timer reason=CMTRWrapUp
759888680mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Extn6400
759888681mS CMExtnTx: v=6400, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Extn6400 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Extn6400 Msgs=0]
Timed: 06/12/12 10:44

 

[amriddle01]

Then the trunk itself is not configured correctly, just to clarify I use Hipcom trunks too and I use the shortcodes below

System shortcode

SC - ?
Feat - Dial
Tel no - .
Line gp - 51:SIP Trunk

ARS form 51:Sip Trunk

SC - 0XXXXXXXXXX
Feat - Dial
Tel number - .
Line Gp - 1 (Hipcom trunk)

There are other shortcodes to prefix local area code to local numbers etc (as is required with SIP) but you get the idea, and it works fine so it isn't the shortcodes

 

[siplyconfused]

Hi

Well its working now, the changes I made were to remove the @uk.ic....... url from the ARS and then I relised that the ARS pointed to the line group rather than a line, so I changed it to the line and the system now dials

So thank you for all your help on this .... I was losing the will to live

Rich