cant dial 896-xxxx numbers

[RINGINnBLINGIN]

any ideas???
here is the monitor capture- if you think the whole capture would help, let me know. i have the entire call if nessesary. i thought the time expired was the start of the important part.


4084982234mS PRN: 8178: Digit Pressed 8
4084982235mS CMARS: FORM: [9] - Received Number: 8
4084982235mS CMARS: CMARSTargetingPending
4084982475mS PRN: 8178: Digit Pressed 9
4084982476mS CMARS: FORM: [9] - Received Number: 89
4084982476mS CMARS: CMARSTargetingPending
4084982745mS PRN: 8178: Digit Pressed 6
4084982746mS CMARS: FORM: [9] - Received Number: 896
4084982746mS CMARS: CMARSTargetingPending
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: TimerExpired cause=CMTCNoAnswerTimeout
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: Retarget NOANSWER EXCEPTED=00000000 ValidTargets=0
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: RetargetNoAnswer on HUNTGROUP=AlarmTraqHG. Ring Attempt 0 All Extensions Attempted
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTarget AlarmTraqHG (depth=1) allowq=0 type=CMNTypeUnknown
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: OV visable. VM NOT visable:Group VM off
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: FindNextInServiceHgInHgIncludedList: No Alternative!
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: PrimeForHGTarget: AlarmTraqHG setorig=0 recall=0 resetExtnVars 1
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTarget AlarmTraqHG (depth=2) allowq=0 type=CMNTypeUnknown
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTargetRingRotary(Sequential) AlarmTraqHG ring_attempt_count 0 index 0
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: ADD USER: AlarmTraqModem2 depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0

 

[intrigrant]

You are using overlap dialing on the trunk which needs overlap sending.
Do a google on both terms and learn something.

In ARS create a shortcoe like this:
SC = 896N;
TN = .
Action = Dial
Line ID = the required line ID for this number to use

A simple mind delivers great solutions

 

[RINGINnBLINGIN]

maybe i need to be more clear. i can dial any other prefix except 896xxxx. so when i dial 9-896-xxxx, it gives me a busy signal. after doing tests with the service provider, they say they never even see the call go out! and the system status says current state-ringback, and is apparently always using channel 23 on the pri, even when i was on channel 23 with the service provider doing test calls. creating that short code didnt not help. thanks

 

[CarGoSki]

You should post a complete failed call. Right now, from the traces, it appears that ARS quits collecting digits after 896 then it times out and likely sends only those digits.

Turn on isdn\layer 2 and call\short code messages in sysmon

 

[RINGINnBLINGIN]

4084981277mS PRN: 8178: Digit Pressed 9
4084981278mS CMCallEvt: 0.181403.0 -1 BaseEP: NEW CMEndpoint f4aaf340 TOTAL NOW=5 CALL_LIST=1
4084981279mS CMCallEvt: 0.181403.0 -1 WestynWilhelm.-1: NEW CMExtnEndpoint f4aaf340, Name=WestynWilhelm, Extn=8178, Phys Extn=8178
4084981279mS CMCallEvt: CREATE CALL:31784 (f4976b50)
4084981279mS CMCallEvt: 0.181404.0 -1 BaseEP: NEW CMEndpoint f4b2a66c TOTAL NOW=6 CALL_LIST=1
4084981282mS CMExtnEvt: WestynWilhelm: CMExtnHandler::SetCurrent( id: 0->181403 )
4084981282mS CMCallEvt: 0.181403.0 31784 WestynWilhelm.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
4084981282mS CMExtnEvt: v=27 State, new=Dialling old=Idle,0,0,WestynWilhelm
4084981283mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=8178 dir=out complete=0 ses=0
4084981283mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4084981284mS CMCallEvt: 0.181403.0 31784 WestynWilhelm.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
4084981286mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: Setting Hard Timer 4000
4084981286mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: LOOKUP CALL ROUTE: type=100 called_party=9 sub= calling=8178 dir=out complete=0 ses=0
4084981286mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: ADD TARGET (N): number=9 type=100 depth=1 nobar=1 setorig=1 ses=0
4084981287mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: SYS SC: 9 2 sc=type=Dial code=9N, num=N callinfop->sending_complete=0 secondary_dialtone=
4084981287mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
4084981287mS CMARS: MakeCallTarget - Called Number:
4084981287mS CMCallEvt: 0.181405.0 -1 BaseEP: NEW CMEndpoint f4b0bf6c TOTAL NOW=7 CALL_LIST=2
4084981288mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: CancelTimer CMTCDelayedProcessing
4084981288mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: INITIAL TARGETING SUCCEEDED
4084981288mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: GetNoAnswerTimer:17
4084981289mS CMCallEvt: 0.181404.0 31784 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
4084981290mS CMCallEvt: 0.181405.0 31784 ARS for [9]: StateChange: END=T CMCSIdle->CMCSOffering
4084981290mS CMCallEvt: 0.181404.0 31784 TargetingEP: RequestEnd 0.181405.0 31784 ARS for [9]
4084981291mS CMTARGET: 0.181403.0 31784 WestynWilhelm.0: CancelTimer CMTCNoAnswerTimeout
4084981292mS CMCallEvt: 0.181405.0 31784 ARS for [9]: StateChange: END=B CMCSOffering->CMCSOverlapRecv
4084981293mS CMARS: FORM: [9] - Received Number:
4084981293mS CMARS: CMARSTargetingPending
4084981293mS CMCallEvt: 0.181404.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
4084981293mS CMCallEvt: 0.181404.0 -1 BaseEP: DELETE CMEndpoint f4b2a66c TOTAL NOW=6 CALL_LIST=2
4084981294mS CD: CALL: 0.181403.0 BState=Idle Cut=2 Music=3.0 Aend="WestynWilhelm(8178)" (8.29) Bend="" [] (0.0) CalledNum=9 () CallingNum=8178 (WestynWilhelm) Internal=1 Time=14 AState=Dialling
4084981294mS CD2: CALL:S 0.181403.0,0.181404.0,7,0,0,3,1,0,WestynWilhelm(8178),,,8.29,0.0,100.100,,100.101,8178,,,100,,100,,0,16,0,0,0.0,,,,,,,1,15,0,0,0,0,enu,,0,,0,0,0,0,,31784,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
4084982234mS PRN: 8178: Digit Pressed 8
4084982235mS CMARS: FORM: [9] - Received Number: 8
4084982235mS CMARS: CMARSTargetingPending
4084982475mS PRN: 8178: Digit Pressed 9
4084982476mS CMARS: FORM: [9] - Received Number: 89
4084982476mS CMARS: CMARSTargetingPending
4084982745mS PRN: 8178: Digit Pressed 6
4084982746mS CMARS: FORM: [9] - Received Number: 896
4084982746mS CMARS: CMARSTargetingPending
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: TimerExpired cause=CMTCNoAnswerTimeout
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: Retarget NOANSWER EXCEPTED=00000000 ValidTargets=0
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: RetargetNoAnswer on HUNTGROUP=AlarmTraqHG. Ring Attempt 0 All Extensions Attempted
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTarget AlarmTraqHG (depth=1) allowq=0 type=CMNTypeUnknown
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: OV visable. VM NOT visable:Group VM off
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: FindNextInServiceHgInHgIncludedList: No Alternative!
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: PrimeForHGTarget: AlarmTraqHG setorig=0 recall=0 resetExtnVars 1
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTarget AlarmTraqHG (depth=2) allowq=0 type=CMNTypeUnknown
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: AddHGTargetRingRotary(Sequential) AlarmTraqHG ring_attempt_count 0 index 0
4084983064mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: ADD USER: AlarmTraqModem2 depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(1) remote=00000000 simult 0 (0)
4084983064mS CMCallEvt: 0.181406.0 -1 BaseEP: NEW CMEndpoint f492b2d4 TOTAL NOW=7 CALL_LIST=2
4084983065mS CMCallEvt: 0.181406.0 -1 AlarmTraqModem2.-1: NEW CMExtnEndpoint f492b2d4, Name=AlarmTraqModem2, Extn=434, Phys Extn=434
4084983066mS CMTARGET: 0.181406.0 31783 AlarmTraqModem2.0: ADD PRIMARY
4084983067mS CMExtnEvt: AlarmtraqModem: CALL LOST (CMCauseRetargetNoAnswer)
4084983067mS CMExtnEvt: AlarmtraqModem: Extn(432) Calling Party Number(2523317440) Type(CMNTypeUnknown)
4084983067mS CMCallEvt: 0.181401.0 -1 AlarmtraqModem.0: StateChange: END=X CMCSRinging->CMCSCompleted
4084983068mS CMExtnEvt: v=14 State, new=PortRecoverDelay old=Ringing,0,0,AlarmtraqModem
4084983068mS CMExtnEvt: AlarmtraqModem: CMExtnHandler::SetCurrent( id: 181401->0 )
4084983068mS CMCallEvt: 0.181401.0 -1 AlarmtraqModem.-1: StateChange: END=X CMCSCompleted->CMCSDelete
4084983069mS CMCallEvt: 0.181401.0 -1 BaseEP: DELETE CMEndpoint f4abeb38 TOTAL NOW=6 CALL_LIST=2
4084983069mS CD2: CALL:M 0.181407.0,0.181407.0,31783
4084983069mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: GetNoAnswerTimer:17
4084983069mS CMACD: 0.181397.0 31783 ACDTep(AlarmTraqHG) ProcessOutboundMsg CMSetup
4084983070mS CMExtnRxC: v=5555
CMSetup
Line: type=NoLine 0 Call: lid=0 id=181397 in=0
Called[8510] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[2523317440] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=AlarmTraqHG
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=AlarmTraqHG
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=8510
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=AlarmTraqHG
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=8510
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [.] [0xff ]
Display [2523317440>AlarmTraqHG]
Locale: enu
4084983070mS CMCallEvt: 0.181406.0 31783 AlarmTraqModem2.0: StateChange: END=T CMCSIdle->CMCSOffering
4084983071mS CMExtnEvt: AlarmTraqModem2: CMExtnHandler::SetCurrent( id: 0->181406 )
4084983072mS CD: CALL: 1.11.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.24) Bend="AlarmTraqHG(8510)" [AlarmTraqModem2(434)] (0.0) CalledNum=8510 (AlarmTraqHG) CallingNum=2523317440 () Internal=0 Time=34024 AState=Ringing
4084983072mS CD2: CALL:S 1.11.1,0.181407.0,1,1,1,2,0,0,Line 1,,AlarmTraqModem2(434),1.24,0.0,100.0,8510,0.0,2523317440,,,0,6481501,100,,0,16,0,1,16.0,,AlarmTraqHG,,AlarmTraqHG,,,0,34025,0,33919,0,0,enu,,0,,0,0,0,0,,31783,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,
,,,,,,,,,
4084983079mS CMExtnEvt: AlarmtraqModem: Recover Timer reason=CMTRWrapUp
4084983079mS CMExtnEvt: v=14 State, new=Idle old=PortRecoverDelay,0,0,AlarmtraqModem
4084983188mS CMCallPkt: v=0
CMAlerting
Line: type=AnalogueExtn 9 Call: lid=0 id=16 in=0
4084983188mS CMCallEvt: 0.181406.0 31783 AlarmTraqModem2.0: StateChange: END=T CMCSOffering->CMCSRinging
4084983188mS CMExtnEvt: v=16 State, new=Ringing old=Idle,0,0,AlarmTraqModem2
4084983189mS CD: CALL: 1.11.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (1.24) Bend="AlarmTraqHG(8510)" [AlarmTraqModem2(434)] (0.0) CalledNum=8510 (AlarmTraqHG) CallingNum=2523317440 () Internal=0 Time=34142 AState=Ringing
4084983190mS CD2: CALL:S 1.11.1,0.181407.0,1,1,1,2,0,0,Line 1,,AlarmTraqModem2(434),1.24,0.0,100.0,8510,0.0,2523317440,,,0,6481501,100,,0,16,0,1,16.0,,AlarmTraqHG,,AlarmTraqHG,,,0,34142,0,34036,0,0,enu,,0,,0,0,0,0,,31783,0,0,,0,,3,0,0,0,0,0,0,1,618,1,,,,
,,,,,,,,,
4084983259mS PRN: 8178: Digit Pressed 6
4084983260mS CMARS: FORM: [9] - Received Number: 8966
4084983260mS CMARS: CMARSTargetingPending
4084983585mS PRN: 8178: Digit Pressed 4
4084983586mS CMARS: FORM: [9] - Received Number: 89664
4084983586mS CMARS: CMARSTargetingPending
4084983882mS PRN: 8178: Digit Pressed 6
4084983883mS CMARS: FORM: [9] - Received Number: 896646
4084983883mS CMARS: CMARSTargetingPending
4084984183mS PRN: 8178: Digit Pressed 1
4084984184mS CMARS: FORM: [9] - Received Number: 8966461
4084984184mS CMARS: CMARSTargetingPending
4084984764mS SIP Rx: UDP 192.168.101.42:5060 -> 192.168.100.20:5060
0000 53 55 42 53 43 52 49 42 45 20 73 69 70 3a 22 3b SUBSCRIBE sip:";
0010 53 49 50 34 31 31 30 22 3b 40 31 39 32 2e 31 36 SIP4110";@192.16
0020 38 2e 31 30 30 2e 32 30 3a 35 30 36 30 20 53 49 8.100.20:5060 SI
0030 50 2f 32 2e 30 0d 0a 56 69 61 3a 20 53 49 50 2f P/2.0..Via: SIP/
0040 32 2e 30 2f 55 44 50 20 31 39 32 2e 31 36 38 2e 2.0/UDP 192.168.
0050 31 30 31 2e 34 32 3b 62 72 61 6e 63 68 3d 7a 39 101.42;branch=z9
0060 68 47 34 62 4b 65 34 33 35 36 61 62 38 43 35 36 hG4bKe4356ab8C56
0070 45 37 37 34 33 0d 0a 46 72 6f 6d 3a 20 22 53 49 E7743..From: "SI
0080 50 34 31 31 30 22 20 3c 73 69 70 3a 34 31 31 30 P4110" <sip:4110
0090 40 31 39 32 2e 31 36 38 2e 31 30 30 2e 32 30 3e @192.168.100.20>
00a0 3b 74 61 67 3d 34 44 41 43 38 30 44 46 2d 36 38 ;tag=4DAC80DF-68
00b0 34 39 34 46 45 32 0d 0a 54 6f 3a 20 3c 73 69 70 494FE2..To: <sip
00c0 3a 22 3b 53 49 50 34 31 31 30 22 3b 40 31 39 32 :";SIP4110";@192
00d0 2e 31 36 38 2e 31 30 30 2e 32 30 3e 0d 0a 43 53 .168.100.20>..CS
00e0 65 71 3a 20 31 20 53 55 42 53 43 52 49 42 45 0d eq: 1 SUBSCRIBE.
00f0 0a 43 61 6c 6c 2d 49 44 3a 20 31 39 37 64 66 34 .Call-ID: 197df4
0100 36 36 2d 61 61 62 62 66 38 65 31 2d 39 38 36 62 66-aabbf8e1-986b
0110 36 31 37 34 40 31 39 32 2e 31 36 38 2e 31 30 31 6174@192.168.101
0120 2e 34 32 0d 0a 43 6f 6e 74 61 63 74 3a 20 3c 73 .42..Contact: <s
0130 69 70 3a 34 31 31 30 40 31 39 32 2e 31 36 38 2e ip:4110@192.168.
0140 31 30 31 2e 34 32 3e 0d 0a 41 6c 6c 6f 77 3a 20 101.42>..Allow:
0150 49 4e 56 49 54 45 2c 20 41 43 4b 2c 20 42 59 45 INVITE, ACK, BYE
0160 2c 20 43 41 4e 43 45 4c 2c 20 4f 50 54 49 4f 4e , CANCEL, OPTION
0170 53 2c 20 49 4e 46 4f 2c 20 4d 45 53 53 41 47 45 S, INFO, MESSAGE
0180 2c 20 53 55 42 53 43 52 49 42 45 2c 20 4e 4f 54 , SUBSCRIBE, NOT
0190 49 46 59 2c 20 50 52 41 43 4b 2c 20 55 50 44 41 IFY, PRACK, UPDA
01a0 54 45 2c 20 52 45 46 45 52 0d 0a 45 76 65 6e 74 TE, REFER..Event
01b0 3a 20 6d 65 73 73 61 67 65 2d 73 75 6d 6d 61 72 : message-summar
01c0 79 0d 0a 55 73 65 72 2d 41 67 65 6e 74 3a 20 50 y..User-Agent: P
01d0 6f 6c 79 63 6f 6d 53 6f 75 6e 64 50 6f 69 6e 74 olycomSoundPoint
01e0 49 50 2d 53 50 49 50 5f 35 36 30 2d 55 41 2f 32 IP-SPIP_560-UA/2
01f0 2e 32 2e 32 2e 30 30 38 34 0d 0a 41 63 63 65 70 .2.2.0084..Accep
0200 74 3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 73 t: application/s
0210 69 6d 70 6c 65 2d 6d 65 73 73 61 67 65 2d 73 75 imple-message-su
0220 6d 6d 61 72 79 0d 0a 4d 61 78 2d 46 6f 72 77 61 mmary..Max-Forwa
0230 72 64 73 3a 20 37 30 0d 0a 45 78 70 69 72 65 73 rds: 70..Expires
0240 3a 20 33 36 30 30 0d 0a 43 6f 6e 74 65 6e 74 2d : 3600..Content-
0250 4c 65 6e 67 74 68 3a 20 30 0d 0a 0d 0a Length: 0....
4084987124mS CDR: TCP Communications are down (Client Destroyed)
4084988287mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 8966461 Addr: f4b0bf6c
4084988287mS CMARS: FORM: [9] - Received Number: 8966461
4084988287mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: Nsi7574907777 - Called_Party: 8966461 - Line Group Id: 0
4084988287mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
4084988288mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
4084988288mS CMCallEvt: 0.181408.0 -1 BaseEP: NEW CMEndpoint f4b9b974 TOTAL NOW=7 CALL_LIST=2
4084988289mS CMTARGET: ISDN BChannel 22: in-service check = 1
4084988289mS CMTARGET: ISDN BChannel 22: in-service check = 1
4084988291mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 8966461 - Calling Number: 7574907777
4084988291mS CMARS: SEND Setup TO LINE
4084988291mS CMCallEvt: 0.181408.0 31784 Q931 Trunk:1 CHAN=22: StateChange: END=child CMCSIdle->CMCSOffering
4084988291mS PRN: Setting configured voice gain for ch 22.
4084988291mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
Called[8966461] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[7574907777] Type=National Plan=ISDN
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=22
Locale: enu
4084988292mS CD: CALL: 0.181403.0 BState=Idle Cut=2 Music=0.0 Aend="WestynWilhelm(8178)" (8.29) Bend="" [Line 1] (0.0) CalledNum=98966461 () CallingNum=8178 (WestynWilhelm) Internal=1 Time=7013 AState=Dialling
4084988292mS CD2: CALL:S 0.181403.0,0.181404.0,7,0,0,0,1,0,WestynWilhelm(8178),,Line 1,8.29,0.0,100.100,,100.101,8178,,,100,,100,,0,16,0,1,0.0,,,,,,,1,7013,0,0,0,0,enu,,0,,0,0,0,0,,31784,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
4084988316mS CMCallPkt: v=0
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
BChan: slot=0 chan=22
4084988316mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
BChan: slot=0 chan=22
4084988316mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
4084988316mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
4084988316mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.181405.0 31784 ARS for [9] - Call State: CMCSOverlapRecv
4084988317mS CMCallEvt: 0.181405.0 -1 ARS for [9]: StateChange: END=X CMCSOverlapRecv->CMCSDelete
4084988318mS CMCallEvt: 0.181408.0 31784 Q931 Trunk:1 CHAN=22: StateChange: END=B CMCSOffering->CMCSAccept
4084988318mS CMCallEvt: 0.181403.0 31784 WestynWilhelm.0: StateChange: END=A CMCSDialling->CMCSDialled
4084988319mS CMExtnEvt: v=27 State, new=Proceeding old=Dialling,0,0,WestynWilhelm
4084988320mS CMCallEvt: 0.181405.0 -1 BaseEP: DELETE CMEndpoint f4b0bf6c TOTAL NOW=6 CALL_LIST=2
4084988321mS CD: CALL: 0.181403.0 BState=Ringing Cut=3 Music=0.0 Aend="WestynWilhelm(8178)" (8.29) Bend="Line 1" [Line 1] (1.23) CalledNum=8966461 () CallingNum=8178 (WestynWilhelm) Internal=0 Time=7041 AState=Dialled
4084988321mS CD2: CALL:S 0.181403.0,0.181404.0,8,1,1,0,1,0,WestynWilhelm(8178),Line 1,,8.29,1.23,100.100,8966461,100.101,8178,,,100,98966461,100,,0,16,0,1,16.0,,,,,,,0,7042,0,0,0,0,enu,,0,,0,0,0,0,,31784,,,,,,,,,,,,,,,,0,0,WestynWilhelm,0,,3,0,0,0,0,0,0,
,618,10
4084988323mS CMCallPkt: v=0
CMProgress
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Cause=31, Normal, unspecified
4084988323mS CMLineRx: v=1
CMProgress
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Cause=31, Normal, unspecified
4084988323mS CMCallEvt: 0.181408.0 31784 Q931 Trunk:1 CHAN=22: StateChange: END=B CMCSAccept->CMCSRinging
4084988324mS CMCallEvt: 0.181403.0 31784 WestynWilhelm.0: StateChange: END=A CMCSDialled->CMCSRingBack
4084988324mS CMExtnEvt: v=27 State, new=Alerting old=Proceeding,0,0,WestynWilhelm
4084988325mS CD: CALL: 0.181403.0 BState=Ringing Cut=3 Music=0.0 Aend="WestynWilhelm(8178)" (8.29) Bend="Line 1" [Line 1] (1.23) CalledNum=8966461 () CallingNum=8178 (WestynWilhelm) Internal=0 Time=7046 AState=Ringing
4084988326mS CD2: CALL:S 0.181403.0,0.181404.0,1,1,1,0,1,0,WestynWilhelm(8178),Line 1,,8.29,1.23,100.100,8966461,100.101,8178,,,100,98966461,100,,0,16,0,1,16.0,,,,,,,0,7047,0,0,0,0,enu,,0,,0,0,0,0,,31784,,,,,,,,,,,,,,,,0,0,WestynWilhelm,0,,3,0,0,0,0,0,0,
,618,10
4084988764mS SIP Rx: UDP 192.168.101.42:5060 -> 192.168.100.20:5060
0000 53 55 42 53 43 52 49 42 45 20 73 69 70 3a 22 3b SUBSCRIBE sip:";
0010 53 49 50 34 31 31 30 22 3b 40 31 39 32 2e 31 36 SIP4110";@192.16
0020 38 2e 31 30 30 2e 32 30 3a 35 30 36 30 20 53 49 8.100.20:5060 SI
0030 50 2f 32 2e 30 0d 0a 56 69 61 3a 20 53 49 50 2f P/2.0..Via: SIP/
0040 32 2e 30 2f 55 44 50 20 31 39 32 2e 31 36 38 2e 2.0/UDP 192.168.
0050 31 30 31 2e 34 32 3b 62 72 61 6e 63 68 3d 7a 39 101.42;branch=z9
0060 68 47 34 62 4b 65 34 33 35 36 61 62 38 43 35 36 hG4bKe4356ab8C56
0070 45 37 37 34 33 0d 0a 46 72 6f 6d 3a 20 22 53 49 E7743..From: "SI
0080 50 34 31 31 30 22 20 3c 73 69 70 3a 34 31 31 30 P4110" <sip:4110
0090 40 31 39 32 2e 31 36 38 2e 31 30 30 2e 32 30 3e @192.168.100.20>
00a0 3b 74 61 67 3d 34 44 41 43 38 30 44 46 2d 36 38 ;tag=4DAC80DF-68
00b0 34 39 34 46 45 32 0d 0a 54 6f 3a 20 3c 73 69 70 494FE2..To: <sip
00c0 3a 22 3b 53 49 50 34 31 31 30 22 3b 40 31 39 32 :";SIP4110";@192
00d0 2e 31 36 38 2e 31 30 30 2e 32 30 3e 0d 0a 43 53 .168.100.20>..CS
00e0 65 71 3a 20 31 20 53 55 42 53 43 52 49 42 45 0d eq: 1 SUBSCRIBE.
00f0 0a 43 61 6c 6c 2d 49 44 3a 20 31 39 37 64 66 34 .Call-ID: 197df4
0100 36 36 2d 61 61 62 62 66 38 65 31 2d 39 38 36 62 66-aabbf8e1-986b
0110 36 31 37 34 40 31 39 32 2e 31 36 38 2e 31 30 31 6174@192.168.101
0120 2e 34 32 0d 0a 43 6f 6e 74 61 63 74 3a 20 3c 73 .42..Contact: <s
0130 69 70 3a 34 31 31 30 40 31 39 32 2e 31 36 38 2e ip:4110@192.168.
0140 31 30 31 2e 34 32 3e 0d 0a 41 6c 6c 6f 77 3a 20 101.42>..Allow:
0150 49 4e 56 49 54 45 2c 20 41 43 4b 2c 20 42 59 45 INVITE, ACK, BYE
0160 2c 20 43 41 4e 43 45 4c 2c 20 4f 50 54 49 4f 4e , CANCEL, OPTION
0170 53 2c 20 49 4e 46 4f 2c 20 4d 45 53 53 41 47 45 S, INFO, MESSAGE
0180 2c 20 53 55 42 53 43 52 49 42 45 2c 20 4e 4f 54 , SUBSCRIBE, NOT
0190 49 46 59 2c 20 50 52 41 43 4b 2c 20 55 50 44 41 IFY, PRACK, UPDA
01a0 54 45 2c 20 52 45 46 45 52 0d 0a 45 76 65 6e 74 TE, REFER..Event
01b0 3a 20 6d 65 73 73 61 67 65 2d 73 75 6d 6d 61 72 : message-summar
01c0 79 0d 0a 55 73 65 72 2d 41 67 65 6e 74 3a 20 50 y..User-Agent: P
01d0 6f 6c 79 63 6f 6d 53 6f 75 6e 64 50 6f 69 6e 74 olycomSoundPoint
01e0 49 50 2d 53 50 49 50 5f 35 36 30 2d 55 41 2f 32 IP-SPIP_560-UA/2
01f0 2e 32 2e 32 2e 30 30 38 34 0d 0a 41 63 63 65 70 .2.2.0084..Accep
0200 74 3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 73 t: application/s
0210 69 6d 70 6c 65 2d 6d 65 73 73 61 67 65 2d 73 75 imple-message-su
0220 6d 6d 61 72 79 0d 0a 4d 61 78 2d 46 6f 72 77 61 mmary..Max-Forwa
0230 72 64 73 3a 20 37 30 0d 0a 45 78 70 69 72 65 73 rds: 70..Expires
0240 3a 20 33 36 30 30 0d 0a 43 6f 6e 74 65 6e 74 2d : 3600..Content-
0250 4c 65 6e 67 74 68 3a 20 30 0d 0a 0d 0a Length: 0....
4084991001mS CMCallEvt: 0.181403.0 31784 WestynWilhelm.0: StateChange: END=A CMCSRingBack->CMCSCompleted
4084991002mS CMExtnEvt: v=27 State, new=PortRecoverDelay old=Alerting,0,0,WestynWilhelm
4084991002mS CDR: Initialising communications [IP Address = 0.0.0.0, port 12000 [TCP]]
4084991003mS PRN: CDR - ResetQueueSize=548
4084991004mS CDR: SMDR OUTPUT '2012/12/26 15:37:08,00:00:00,0,8178,O,98966461,98966461,,0,1031783,0,E8178,WestynWilhelm,T9001,Line 1.22,0,0,,,,,,,,,,,U,WestynWilhelm,
'
4084991004mS PRN: CDR - TCPSend maxqueuesize=548 operational=0
4084991005mS CMLOGGING: CALL:2012/12/2615:37,00:00:00,000,8178,O,8966461,98966461,WestynWilhelm,,,0,,""n/a,0
4084991005mS CD: CALL: 0.181403.0 BState=Ringing Cut=2 Music=0.0 Aend="WestynWilhelm(8178)" (8.29) Bend="Line 1" [Line 1] (1.23) CalledNum=8966461 () CallingNum=8178 (WestynWilhelm) Internal=0 Time=9726 AState=Idle
4084991005mS CD2: CALL:S 0.181403.0,0.181404.0,0,1,0,0,1,0,WestynWilhelm(8178),Line 1,,8.29,1.23,100.100,8966461,100.101,8178,,,100,98966461,100,,0,16,0,1,16.0,,,,,,,0,9726,0,0,0,0,enu,,0,,0,0,0,0,,31784,,,,,,,,,,,,,,,,0,0,WestynWilhelm,0,,3,0,0,0,0,0,0,
,618,10
4084991005mS CD: CALL: 0.181403.0 Deleted
4084991006mS CMExtnEvt: WestynWilhelm: CALL LOST (CMCauseNormal)
4084991006mS CMExtnEvt: WestynWilhelm: Extn(8178) Calling Party Number(8178) Type(CMNTypeInternal)
4084991006mS CMExtnEvt: WestynWilhelm: CMExtnHandler::SetCurrent( id: 181403->0 )
4084991007mS CMCallEvt: 0.181403.0 -1 WestynWilhelm.-1: StateChange: END=X CMCSCompleted->CMCSDelete
4084991008mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
Cause=16, Normal call clearing
4084991008mS CMCallEvt: 0.181408.0 -1 Q931 Trunk:1 CHAN=22: StateChange: END=X CMCSRinging->CMCSCompleted
4084991009mS CMCallEvt: END CALL:31784 (f4976b50)
4084991009mS CMTARGET: 0.181403.0 -1 BaseEP: ~CMTargetHandler f48dba1c ep f4aaf340
4084991010mS CMCallEvt: 0.181403.0 -1 BaseEP: DELETE CMEndpoint f4aaf340 TOTAL NOW=5 CALL_LIST=1
4084991028mS CMCallPkt: v=0
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
BChan: slot=0 chan=22
4084991028mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=181408 in=0
BChan: slot=0 chan=22
4084991028mS CMCallEvt: 0.181408.0 -1 Q931 Trunk:1 CHAN=22: StateChange: END=X CMCSCompleted->CMCSDelete
4084991029mS CMTARGET: ISDN BChannel 22: in-service check = 1
4084991029mS CMTARGET: ISDN BChannel 22: in-service check = 1
4084991029mS CD2: CALL 0.181403.0,0.181404.0,31784,,,,,,,,,,,,,,,,0,0,WestynWilhelm,0,,3,0,0,0,0,0,0,1,618,100
4084991030mS CMCallEvt: 0.181408.0 -1 BaseEP: DELETE CMEndpoint f4b9b974 TOTAL NOW=4 CALL_LIST=1
4084992764mS SIP Rx: UDP 192.168.101.42:5060 -> 192.168.100.20:5060
0000 53 55 42 53 43 52 49 42 45 20 73 69 70 3a 22 3b SUBSCRIBE sip:";
0010 53 49 50 34 31 31 30 22 3b 40 31 39 32 2e 31 36 SIP4110";@192.16
0020 38 2e 31 30 30 2e 32 30 3a 35 30 36 30 20 53 49 8.100.20:5060 SI
0030 50 2f 32 2e 30 0d 0a 56 69 61 3a 20 53 49 50 2f P/2.0..Via: SIP/
0040 32 2e 30 2f 55 44 50 20 31 39 32 2e 31 36 38 2e 2.0/UDP 192.168.
0050 31 30 31 2e 34 32 3b 62 72 61 6e 63 68 3d 7a 39 101.42;branch=z9
0060 68 47 34 62 4b 65 34 33 35 36 61 62 38 43 35 36 hG4bKe4356ab8C56
0070 45 37 37 34 33 0d 0a 46 72 6f 6d 3a 20 22 53 49 E7743..From: "SI
0080 50 34 31 31 30 22 20 3c 73 69 70 3a 34 31 31 30 P4110" <sip:4110
0090 40 31 39 32 2e 31 36 38 2e 31 30 30 2e 32 30 3e @192.168.100.20>
00a0 3b 74 61 67 3d 34 44 41 43 38 30 44 46 2d 36 38 ;tag=4DAC80DF-68
00b0 34 39 34 46 45 32 0d 0a 54 6f 3a 20 3c 73 69 70 494FE2..To: <sip
00c0 3a 22 3b 53 49 50 34 31 31 30 22 3b 40 31 39 32 :";SIP4110";@192
00d0 2e 31 36 38 2e 31 30 30 2e 32 30 3e 0d 0a 43 53 .168.100.20>..CS
00e0 65 71 3a 20 31 20 53 55 42 53 43 52 49 42 45 0d eq: 1 SUBSCRIBE.
00f0 0a 43 61 6c 6c 2d 49 44 3a 20 31 39 37 64 66 34 .Call-ID: 197df4
0100 36 36 2d 61 61 62 62 66 38 65 31 2d 39 38 36 62 66-aabbf8e1-986b
0110 36 31 37 34 40 31 39 32 2e 31 36 38 2e 31 30 31 6174@192.168.101
0120 2e 34 32 0d 0a 43 6f 6e 74 61 63 74 3a 20 3c 73 .42..Contact: <s
0130 69 70 3a 34 31 31 30 40 31 39 32 2e 31 36 38 2e ip:4110@192.168.
0140 31 30 31 2e 34 32 3e 0d 0a 41 6c 6c 6f 77 3a 20 101.42>..Allow:
0150 49 4e 56 49 54 45 2c 20 41 43 4b 2c 20 42 59 45 INVITE, ACK, BYE
0160 2c 20 43 41 4e 43 45 4c 2c 20 4f 50 54 49 4f 4e , CANCEL, OPTION
0170 53 2c 20 49 4e 46 4f 2c 20 4d 45 53 53 41 47 45 S, INFO, MESSAGE
0180 2c 20 53 55 42 53 43 52 49 42 45 2c 20 4e 4f 54 , SUBSCRIBE, NOT
0190 49 46 59 2c 20 50 52 41 43 4b 2c 20 55 50 44 41 IFY, PRACK, UPDA
01a0 54 45 2c 20 52 45 46 45 52 0d 0a 45 76 65 6e 74 TE, REFER..Event
01b0 3a 20 6d 65 73 73 61 67 65 2d 73 75 6d 6d 61 72 : message-summar
01c0 79 0d 0a 55 73 65 72 2d 41 67 65 6e 74 3a 20 50 y..User-Agent: P
01d0 6f 6c 79 63 6f 6d 53 6f 75 6e 64 50 6f 69 6e 74 olycomSoundPoint
01e0 49 50 2d 53 50 49 50 5f 35 36 30 2d 55 41 2f 32 IP-SPIP_560-UA/2
01f0 2e 32 2e 32 2e 30 30 38 34 0d 0a 41 63 63 65 70 .2.2.0084..Accep
0200 74 3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 73 t: application/s
0210 69 6d 70 6c 65 2d 6d 65 73 73 61 67 65 2d 73 75 imple-message-su
0220 6d 6d 61 72 79 0d 0a 4d 61 78 2d 46 6f 72 77 61 mmary..Max-Forwa
0230 72 64 73 3a 20 37 30 0d 0a 45 78 70 69 72 65 73 rds: 70..Expires
0240 3a 20 33 36 30 30 0d 0a 43 6f 6e 74 65 6e 74 2d : 3600..Content-
0250 4c 65 6e 67 74 68 3a 20 30 0d 0a 0d 0a Length: 0....

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

 

[CarGoSki]

What number does your ARS - Form 9 - Dial Delay Time contain?
What numbers do your System/telephony Dial Delay Time and Dial Delay Count contain?

 

[RINGINnBLINGIN]

ars-system default (4)
system/telephony tab- 4 second delay 0 dial delay count

i have tried changing the count to 4 and that doesnt make it any better

 

[CarGoSki]

List the ARS form short codes

 

[RINGINnBLINGIN]

there is nothing in there that relates to 896...at all

 

[intrigrant]

Unsuccesfull call shows this
4084982746mS CMARS: FORM: [9] - Received Number: 896
4084982746mS CMARS: CMARSTargetingPending
4084983063mS CMTARGET: 1.11.1 31783 Q931 Trunk:1 CHAN=23: TimerExpired cause=CMTCNoAnswerTimeout

A succesfull call show this:
4084984184mS CMARS: FORM: [9] - Received Number: 8966461
4084984184mS CMARS: CMARSTargetingPending
4084988287mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 8966461 Addr: f4b0bf6c

I would expect that after 896 ARS would wait for further digits but here it time-out on channel 23 of your PRI. It looks like ARS wants to send the number direct to the channel 23 which I suspect has a line number 896 ... true?

A simple mind delivers great solutions

 

[Dasheen]

Display your ARS Table.