SIP Extension outbound trouble

[stuzzy]

I have a SIP phone registered, internal calls work fine, and inbound did calls direct no problem. Talk path both ways good quality. But I can't dial outbound. I'm using the same routing as all the other phones in the system. Here is the the monitor output for it. Help please?



568587455mS SIP Rx: UDP 192.168.101.97:5060 -> 192.168.101.70:5060
jaK
3568607544mS SIP Rx: UDP 192.168.101.97:5060 -> 192.168.101.70:5060
jaK
3568613060mS SIP Rx: UDP 192.168.101.97:5060 -> 192.168.101.70:5060
INVITE sip:912062466110@192.168.101.70 SIP/2.0
Via: SIP/2.0/UDP 192.168.101.97:5060;rport;branch=z9hG4bK899196237
From: <sip:605@192.168.101.70>;tag=1445664652
To: <sip:912062466110@192.168.101.70>
Call-ID: 1123662423
CSeq: 20 INVITE
Contact: <sip:605@192.168.101.97:5060>
Content-Type: application/sdp
Max-Forwards: 70
User-Agent: Cyberdata Intercom v1.0.4
Subject: Phone call
Expires: 120
Content-Length: 320

v=0
o=605 123456 654321 IN IP4 192.168.101.97
s=A conversation
c=IN IP4 192.168.101.97
t=0 0
m=audio 50000 RTP/AVP 111 110 0 8 18 101
a=rtpmap:111 speex/16000/1
a=rtpmap:110 speex/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:18 G729/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
3568613066mS CMCallEvt: 0.195622.0 -1 BaseEP: NEW CMEndpoint fe62a5c4 TOTAL NOW=5 CALL_LIST=2
3568613066mS CMCallEvt: 0.195622.0 -1 SIPphone.-1: NEW CMExtnEndpoint fe62a5c4, Name=SIPphone, Extn=605, Phys Extn=605
3568613067mS CMCallEvt: CREATE CALL:39089 (fe6f3b70)
3568613067mS CMCallEvt: 0.195623.0 -1 BaseEP: NEW CMEndpoint fe6ede88 TOTAL NOW=6 CALL_LIST=2
3568613075mS CMExtnEvt: SIPphone: CMExtnHandler::SetCurrent( id: 0->195622 )
3568613078mS SIP Tx: UDP 192.168.101.70:5060 -> 192.168.101.97:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.101.97:5060;rport;branch=z9hG4bK899196237
From: <sip:605@192.168.101.70>;tag=1445664652
To: <sip:912062466110@192.168.101.70>;tag=400259274a92db7d
Call-ID: 1123662423
CSeq: 20 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Content-Length: 0

3568613082mS CMExtnRx: v=605, p1=0
CMSetup
Line: type=IPLine 260 Call: lid=259 id=195622 in=0
Called[912062466110] Type=Default (100) Reason=CMDRdirect SndComp Calling[605] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SIPphone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=605
IE CMIEFastStartInfoData (6)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
3568613083mS CMCallEvt: 259.195622.0 39089 SIPphone.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
3568613083mS CMExtnEvt: v=0 State, new=Connected old=Idle,0,0,SIPphone
3568613084mS CMTARGET: 259.195622.0 39089 SIPphone.0: LOOKUP CALL ROUTE: type=100 called_party=912062466110 sub= calling=605 dir=out complete=1 ses=0
3568613084mS CMTARGET: 259.195622.0 39089 SIPphone.0: ADD TARGET (N): number=912062466110 type=100 depth=1 nobar=1 setorig=1 ses=0
3568613085mS CMTARGET: 259.195622.0 39089 SIPphone.0: SYS SC: 912062466110 2 12062466110 sc=type=Dial code=9N, num=N callinfop->sending_complete=1 secondary_dialtone=
3568613086mS CMARS: FindActiveARSByGroupID GroupID=52 - Found
3568613086mS CMARS: MakeCallTarget - Called Number: 12062466110
3568613086mS CMCallEvt: 0.195624.0 -1 BaseEP: NEW CMEndpoint fe6ad344 TOTAL NOW=7 CALL_LIST=3
3568613087mS CMTARGET: 259.195622.0 39089 SIPphone.0: INITIAL TARGETING SUCCEEDED
3568613087mS CMTARGET: 259.195622.0 39089 SIPphone.0: GetNoAnswerTimer:15
3568613088mS CMCallEvt: 259.195622.0 39089 SIPphone.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
3568613089mS CMExtnEvt: v=0 State, new=Proceeding old=Connected,0,0,SIPphone
3568613090mS CMExtnTx: v=605, p1=0
CMProceeding
Line: type=IPLine 260 Call: lid=259 id=195622 in=0
Timed: 04/11/11 12:20
3568613091mS CMCallEvt: 0.195623.0 39089 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
3568613093mS CMCallEvt: 0.195624.0 39089 ARS for [9]: StateChange: END=T CMCSIdle->CMCSOffering
3568613093mS CMCallEvt: 0.195623.0 39089 TargetingEP: RequestEnd 0.195624.0 39089 ARS for [9]
3568613093mS CMTARGET: 259.195622.0 39089 SIPphone.0: CancelTimer CMTCNoAnswerTimeout
3568613094mS CMCallEvt: 0.195624.0 39089 ARS for [9]: StateChange: END=B CMCSOffering->CMCSOverlapRecv
3568613094mS CMARS: FORM: [9] - Received Number: 12062466110
3568613095mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: Ns4252780e - Called_Party: 12062466110 - Line Group Id: 1
3568613095mS CMARS: FindActiveARSByGroupID GroupID=1 - Not Found
3568613095mS CMLRQ: FindActiveLRQByGroupID GroupID=1 - Not Found
3568613096mS CMTARGET: Group Id: 1 has NOT been found in Line: 1
3568613097mS CMMap: a=3.14 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 20
3568613097mS CMCallEvt: 0.195625.0 -1 BaseEP: NEW CMEndpoint fe62bed4 TOTAL NOW=8 CALL_LIST=3
3568613104mS CMARS: FOUND LINE - Line Id: 5 - using line group id: 1 - Called Number: 12062466110 - Calling Number: 4252780605
3568613105mS CMARS: SEND Setup TO LINE
3568613108mS CMCallEvt: 0.195625.0 39089 Q931 Trunk:5 CHAN=3: StateChange: END=child CMCSIdle->CMCSOffering
3568613108mS PRN: Setting configured voice gain for ch 3.
3568613109mS CMLineTx: v=5
CMSetup
Line: type=Q931Line 5 Call: lid=0 id=195625 in=0
Called[12062466110] Type=National (2) Reason=CMDRdirect Calling[4252780605] Type=National Plan=ISDN Pres=AllowedIndSend (101)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=3
Locale: enu
3568613109mS CMCallEvt: 0.195623.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
3568613110mS CMCallEvt: 0.195623.0 -1 BaseEP: DELETE CMEndpoint fe6ede88 TOTAL NOW=7 CALL_LIST=3
3568613115mS CD: CALL: 259.195622.0 BState=Idle Cut=3 Music=0.0 Aend="SIPphone(605)" (0.0) Bend="" [Line 5] (0.0) CalledNum=912062466110 () CallingNum=605 (SIPphone) Internal=1 Time=47 AState=Dialled
3568613148mS CMLineRx: v=5
CMProceeding
Line: type=Q931Line 5 Call: lid=0 id=195625 in=0
BChan: slot=0 chan=3
3568613149mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
3568613149mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
3568613149mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.195624.0 39089 ARS for [9] - Call State: CMCSOverlapRecv
3568613150mS CMCallEvt: 0.195624.0 -1 ARS for [9]: StateChange: END=X CMCSOverlapRecv->CMCSDelete
3568613151mS CMCallEvt: 0.195625.0 39089 Q931 Trunk:5 CHAN=3: StateChange: END=B CMCSOffering->CMCSAccept
3568613152mS CMCallEvt: 0.195624.0 -1 BaseEP: DELETE CMEndpoint fe6ad344 TOTAL NOW=6 CALL_LIST=3
3568613155mS CD: CALL: 259.195622.0 BState=Ringing Cut=3 Music=0.0 Aend="SIPphone(605)" (0.0) Bend="Line 5" [Line 5] (7.4) CalledNum=12062466110 () CallingNum=605 (SIPphone) Internal=0 Time=87 AState=Dialled
3568613205mS CMLineRx: v=5
CMReleaseComp
Line: type=Q931Line 5 Call: lid=0 id=195625 in=0
BChan: slot=0 chan=3
Cause=1, Unallocated (unassigned) number
3568613205mS CMCallEvt: 0.195625.0 39089 Q931 Trunk:5 CHAN=3: StateChange: END=B CMCSAccept->CMCSCompleted
3568613210mS CMLOGGING: CALL:2011/11/0412:20,00:00:00,000,605,O,12062466110,912062466110,SIPphone,,,0,,""n/a,0
3568613210mS CD: CALL: 259.195622.0 BState=Disconnecting Cut=1 Music=0.0 Aend="SIPphone(605)" (0.0) Bend="Line 5" [Line 5] (7.4) CalledNum=12062466110 () CallingNum=605 (SIPphone) Internal=0 Time=143 AState=Dialled
3568613212mS CD: CALL: 259.195622.0 Deleted
3568613215mS CMExtnEvt: SIPphone: CALL LOST (CMCauseUnallocatedNumber)
3568613215mS CMExtnEvt: SIPphone: Extn(605) Calling Party Number(605) Type(CMNTypeInternal)
3568613215mS CMCallEvt: 259.195622.0 -1 SIPphone.0: StateChange: END=X CMCSDialled->CMCSCompleted
3568613216mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=Proceeding,0,0,SIPphone
3568613216mS CMExtnTx: v=605, p1=0
CMReleaseComp
Line: type=IPLine 260 Call: lid=259 id=195622 in=0
Called[912062466110] Type=Default (100) Reason=CMDRdirect Calling[605] Type=Internal Plan=Default
Cause=1, Unallocated (unassigned) number
Timed: 04/11/11 12:20
3568613218mS SIP Tx: UDP 192.168.101.70:5060 -> 192.168.101.97:5060
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.101.97:5060;rport;branch=z9hG4bK899196237
From: <sip:605@192.168.101.70>;tag=1445664652
To: <sip:912062466110@192.168.101.70>;tag=400259274a92db7d
Call-ID: 1123662423
CSeq: 20 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, SUBSCRIBE, REGISTER, PUBLISH
Content-Length: 0

3568613219mS CMExtnEvt: SIPphone: CMExtnHandler::SetCurrent( id: 195622->0 )
3568613220mS CMCallEvt: 259.195622.0 -1 SIPphone.-1: StateChange: END=X CMCSCompleted->CMCSDelete
3568613221mS CMTARGET: 259.195622.0 -1 BaseEP: ~CMTargetHandler
3568613222mS CMCallEvt: 259.195622.0 -1 BaseEP: DELETE CMEndpoint fe62a5c4 TOTAL NOW=5 CALL_LIST=2
3568613222mS CMCallEvt: 0.195625.0 -1 Q931 Trunk:5 CHAN=3: StateChange: END=X CMCSCompleted->CMCSDelete
3568613223mS CMCallEvt: END CALL:39089 (fe6f3b70)
3568613226mS CMCallEvt: 0.195625.0 -1 BaseEP: DELETE CMEndpoint fe62bed4 TOTAL NOW=4 CALL_LIST=2
3568613229mS CMMap: a=3.14 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 20
3568613230mS SIP Rx: UDP 192.168.101.97:5060 -> 192.168.101.70:5060
ACK sip:912062466110@192.168.101.70 SIP/2.0
Via: SIP/2.0/UDP 192.168.101.97:5060;rport;branch=z9hG4bK899196237
From: <sip:605@192.168.101.70>;tag=1445664652
To: <sip:912062466110@192.168.101.70>;tag=400259274a92db7d
Call-ID: 1123662423
CSeq: 20 ACK
Content-Length: 0

3568613327mS RES: Fri 4/11/2011 12:20:47 FreeMem=35098852(1) CMMsg=6 (6) Buff=100 522 498 1028 3 Links=1022
3568613327mS RES2: IP 406 DS 5.0(15) Tasks=24 RTEngine=0 CMRTEngine=0 Timer=69 Poll=0 Ready=2 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2
3568615220mS CMExtnEvt: SIPphone: Recover Timer reason=CMTRWrapUp
3568615221mS CMExtnEvt: v=0 State, new=Idle old=PortRecoverDelay,0,0,SIPphone
3568618328mS RES: Fri 4/11/2011 12:20:52 FreeMem=35106880(1) CMMsg=6 (6) Buff=100 522 499 1028 3 Links=1022
3568618328mS RES2: IP 406 DS 5.0(15) Tasks=24 RTEngine=0 CMRTEngine=0 Timer=66 Poll=0 Ready=1 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2
3568634232mS SIP Rx: UDP 192.168.101.97:5060 -> 192.168.101.70:5060

 

[tlpeter]

Sip needs unblock dialing, are you?

BAZINGA!

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

 

[amriddle01]

en-bloc works better

http://www.ipoffice.tel

 

[stuzzy]

Hrmm excuse my ignorance, but what is unblock dialing or en-bloc?

 

[amriddle01]

En-bloc means all digits are dialled together to the system/line when the full number has been entered (Peter meant this when he said unblock), the other method is overlap and this is where each digit is passed to the system/line one at a time as the user presses it, SIP requires the first of these two methods..En-bloc

http://www.ipoffice.tel

 

[amriddle01]

From that trace it's because the number you dialled is unallocated (doesn't yet exist effectivly)....

Cause=1, Unallocated (unassigned) number
Timed: 04/11/11 12:20

http://www.ipoffice.tel

 

[stuzzy]

I can dial it from other digital extensions

 

[amriddle01]

So 912062466110 (including the 9) is a valid number? In that case is 4252780605 that handsets DDI or a valid number on this trunk? As that is what it is sending out

http://www.ipoffice.tel

 

[amriddle01]

no sorry it is sending 12062466110 (the 9 is being stripped)

http://www.ipoffice.tel