B179 Conference Phone cannot make outside calls 1

[TMMcN]

I have 3 B179 SIP sets. All can make and receive internal calls. I can direct a DID to the Extension and receive outside calls, however, I cannot make a outside call from the sets. In monitor it shows waiting for line. Service is a PRI. All is good on all the other 1616 phones
Thanks in advance

 

[snowman50]

Check you short code for the 3 Users.
what are they set to.

 

[TMMcN]

I'm using the default codes in the ARS.
This is where the trace ends...waiting for line

 

[snowman50]

Yes that's fine but how do the users get access to the ARS.

Is it in the user short code or is it a system short code pointing to the ARS

Basically if the 1416 phones can access the line the use the same method for the sip sets short codes.

 

[TMMcN]

I've tried both ways, with the same result.

 

[janni78]

Post a trace, much easier than to keep us guessing.

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

 

[infonaut]

What release are you on and what is the PCS level on IPO unit ?

 

[TMMcN]

Below is trace


10:39:12 425681456mS CMExtnRx: v=45, p1=0
CMSetup
Line: type=SIPLine 260 Call: lid=251 id=3910 in=0
Called[97681245] Type=Default (100) Reason=CMDRdirect SndComp Calling[45] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CVMA LCP
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=45
10:39:12 425681456mS CMCallEvt: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
10:39:12 425681456mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,CVMA LCP
10:39:12 425681456mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=97681245 sub= calling=45 calling_sub= dir=out complete=1 ses=0
10:39:12 425681456mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: ADD TARGET (N): number=97681245 type=100 depth=1 nobar=1 setorig=1 ses=0
10:39:12 425681457mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: SYS SC: 97681245 2 7681245 sc=type=Dial code=9N, num=N callinfop->sending_complete=1 secondary_dialtone=
10:39:12 425681457mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
10:39:12 425681457mS CMARS: MakeCallTarget - Called Number: 7681245
10:39:12 425681457mS CMCallEvt: 0000000000000000 0.3912.0 -1 BaseEP: NEW CMEndpoint f1969f80 TOTAL NOW=11 CALL_LIST=5
10:39:12 425681458mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: INITIAL TARGETING SUCCEEDED
10:39:12 425681458mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: GetNoAnswerTimer:15
10:39:12 425681458mS CMCallEvt: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
10:39:12 425681459mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,CVMA LCP
10:39:12 425681460mS CMExtnTx: v=45, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=251 id=3910 in=0
Timed: 23/08/16 10:39
10:39:12 425681460mS CMCallEvt: 0000000000000000 0.3911.0 691 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
10:39:12 425681461mS CMCallEvt: 0000000000000000 0.3912.0 691 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
10:39:12 425681461mS CMCallEvt: 0000000000000000 0.3911.0 691 TargetingEP: RequestEnd 0000000000000000 0.3912.0 691 ARS for Main
10:39:12 425681461mS CMTARGET: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: CancelTimer CMTCNoAnswerTimeout
10:39:12 425681462mS CMCallEvt: 0000000000000000 0.3912.0 691 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
10:39:12 425681463mS CMARS: FORM: Main - Received Number: 7681245
10:39:12 425681463mS CMARS: FOUND A SHORT CODE - short_code: XN; - Tel: N - Called_Party: 7681245 - Line Group Id: 0
10:39:12 425681463mS CMARS: SetCurrentTarget: Short_Code: XN; - Line_Group_ID: 0
10:39:12 425681463mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
10:39:12 425681463mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
10:39:12 425681463mS CMMap: a=2.17 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f5675074) resource busy 5, total 64
10:39:12 425681464mS CMCallEvt: 0000000000000000 0.3913.0 -1 BaseEP: NEW CMEndpoint f17b0ad4 TOTAL NOW=12 CALL_LIST=5
10:39:12 425681464mS CMTARGET: ISDN BChannel 17: in-service check = 1
10:39:12 425681464mS CMTARGET: ISDN BChannel 17: in-service check = 1
10:39:12 425681465mS CMARS: ModifyCMARSTarget: Short_Code: XN; - Line_Group_ID: 0 set line status to CMARS_TRYING
10:39:12 425681465mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 (code XN;, line 0) - Called Number: 7681245 - Calling Number: 45
10:39:12 425681465mS CMARS: SEND Setup TO LINE
10:39:12 425681466mS CMCallEvt: c0a801fa00000f49 0.3913.0 691 Q931 Trunk:1 CHAN=17: StateChange: END=child CMCSIdle->CMCSOffering
10:39:12 425681466mS CMARS: LINE ep Received: CMProgress - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
10:39:12 425681466mS CMCallEvt: c0a801fa00000f49 0.3913.0 691 Q931 Trunk:1 CHAN=17: StateChange: END=child CMCSOffering->CMCSRinging
10:39:12 425681466mS CMCallEvt: c0a801fa00000f46 0.3912.0 691 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
10:39:12 425681466mS CMCallEvt: c0a801fa00000f46 251.3910.0 691 CVMA LCP.0: StateChange: END=A CMCSDialled->CMCSRingBack
10:39:12 425681468mS CMExtnTx: v=45, p1=0
CMFacility
Line: type=SIPLine 260 Call: lid=251 id=3910 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Timed: 23/08/16 10:39
10:39:12 425681469mS CMExtnEvt: v=0 State, new=Alerting old=Proceeding,0,0,CVMA LCP
10:39:12 425681470mS CMExtnTx: v=45, p1=0
CMProgress
Line: type=SIPLine 260 Call: lid=251 id=3910 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Display [ENTER AUTHCODE]
Timed: 23/08/16 10:39
10:39:12 425681472mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPj4Tgptv7xgHlsDgyY.miiejr4ld7BJYMa;alias
From: <sip:45@192.168.1.250>;tag=3BfcXfErEK360tSy9knmkU94GLRm5wvT
Call-ID: 2yaE.coiznT8pmqcOuKaq8.S96aJ3zJF
CSeq: 12535 INVITE
Contact: <sip:97681245@192.168.1.250:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.4.0 build 137
To: <sip:97681245@192.168.1.250>;tag=1c43e23542785a07
RSeq: 1510252473
Require: 100rel
Content-Type: application/sdp
Content-Length: 205

v=0
o=UserA 3621885842 563697930 IN IP4 192.168.1.250
s=Session SDP
c=IN IP4 192.168.1.250
t=0 0
m=audio 46754 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
10:39:12 425681473mS CMCallEvt: 0000000000000000 0.3911.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
10:39:12 425681473mS CMCallEvt: 0000000000000000 0.3911.0 -1 BaseEP: DELETE CMEndpoint f197a5a0 TOTAL NOW=11 CALL_LIST=5
10:39:12 425681475mS H323Evt: SESS 317 SetOperational local 192.168.1.250:0 remote 0.0.0.0:0 to 0
10:39:12 425681475mS H323Evt: SESS 317 SetRemUDP 0 -> 4004
10:39:12 425681475mS H323Evt: SESS 317 SetRfc2833: (1) rx payload 101 tx payload 101
10:39:12 425681476mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail(f566acbc) resource busy 2, total 45
10:39:12 425681476mS CMMap: a=21.11 b=1.65535 T
10:39:12 425681476mS CMMap: PCG::MapBChan pcp[593]b0r1 cp_b f5674edc other_cp_b f566aba4 type CGTypeDTMF
10:39:12 425681476mS CMMap: a=21.11 b=2.17 M22
10:39:12 425681477mS CMMap: PlatformConnectionAudioSAP::Bind pcp[593]b0r1 to 2.17
10:39:12 425681477mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[593]b0r1 Configure 2.17
10:39:12 425681477mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[593]b0r1 ConnectIndication 2.17
10:39:12 425681477mS H323Evt: SESS 317 SetOperational local 192.168.1.250:46754 remote 192.168.1.118:4004 to 1
10:39:12 425681477mS CMMap: DTMF:layTones g A[11] Tones ',W,,,,,,,,'
10:39:12 425681479mS CD: CALL: 251.3910.0 BState=Ringing Cut=3 Music=0.0 Aend="CVMA LCP(45)" (0.0) Bend="" [Line 1] (0.0) CalledNum=97681245 () CallingNum=45 (CVMA LCP) Internal=1 Time=30 AState=Ringing
10:39:12 425681482mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
PRACK sip:97681245@192.168.1.250:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjuPCHplXLou8caxLR7tNev9vv5MzGDSpR;alias
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=3BfcXfErEK360tSy9knmkU94GLRm5wvT
To: <sip:97681245@192.168.1.250>;tag=1c43e23542785a07
Call-ID: 2yaE.coiznT8pmqcOuKaq8.S96aJ3zJF
CSeq: 12536 PRACK
RAck: 1510252473 12535 INVITE
Content-Length: 0

10:39:12 425681485mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjuPCHplXLou8caxLR7tNev9vv5MzGDSpR;alias
From: <sip:45@192.168.1.250>;tag=3BfcXfErEK360tSy9knmkU94GLRm5wvT
Call-ID: 2yaE.coiznT8pmqcOuKaq8.S96aJ3zJF
CSeq: 12536 PRACK
Supported: timer,100rel
Server: IP Office 9.1.4.0 build 137
To: <sip:97681245@192.168.1.250>;tag=1c43e23542785a07
Content-Length: 0

 

[amriddle01]

You can't use Auth codes with SIP phones, change the shortcode it uses/matches or create a user one

 

[TMMcN]

removed the auth requirement, now I get "service unavailable"

11:38:18 429229574mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
INVITE sip:97681245@192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjt92nVibGqrY4ql5OqN-xLhVIt5V11hH0;alias
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=xV117xGK5Njcp85PJ9iySLfhjTaVTBEP
To: <sip:97681245@192.168.1.250>
Contact: <sip:45@192.168.1.118:5060;ob>
Call-ID: PytXVwkTdljUlPadRosNSWudsvpdq5tV
CSeq: 3133 INVITE
Route: <sip:192.168.1.250;transport=udp;lr>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel
User-Agent: Avaya B179 2.4.1.5
Content-Type: application/sdp
Content-Length: 495

v=0
o=- 3680966299 3680966299 IN IP4 192.168.1.118
s=pjmedia
c=IN IP4 192.168.1.118
t=0 0
a=X-nat:0
m=audio 4002 RTP/AVP 9 8 0 18 101
a=rtcp:4003 IN IP4 192.168.1.118
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=tcap:1 RTP/SAVP
a=acap:1 crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Z1yzd8zMdrLOU6GQwAkS8F77GIkEmoSmMzAH1Ph9 UNENCRYPTED_SRTCP
a=pcfg:1 t=1 a=1
11:38:18 429229578mS CMCallEvt: 0000000000000000 0.4081.0 -1 BaseEP: NEW CMEndpoint f187d974 TOTAL NOW=5 CALL_LIST=2
11:38:18 429229578mS CMCallEvt: 0000000000000000 0.4081.0 -1 CVMA LCP.-1: NEW CMExtnEndpoint f187d974, Name=CVMA LCP, Extn=45, Phys Extn=45
11:38:18 429229579mS CMCallEvt: CREATE CALL:732 (f1923a74)
11:38:18 429229579mS CMCallEvt: 0000000000000000 0.4082.0 -1 BaseEP: NEW CMEndpoint f17c43b8 TOTAL NOW=6 CALL_LIST=2
11:38:18 429229581mS CMExtnEvt: CVMA LCP: CMExtnHandler::SetCurrent( id: 0->4081 )
11:38:18 429229583mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjt92nVibGqrY4ql5OqN-xLhVIt5V11hH0;alias
From: <sip:45@192.168.1.250>;tag=xV117xGK5Njcp85PJ9iySLfhjTaVTBEP
Call-ID: PytXVwkTdljUlPadRosNSWudsvpdq5tV
CSeq: 3133 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.4.0 build 137
To: <sip:97681245@192.168.1.250>;tag=93e715e5309f6baa
Content-Length: 0

11:38:18 429229586mS CMExtnRx: v=45, p1=0
CMSetup
Line: type=SIPLine 260 Call: lid=251 id=4081 in=0
Called[97681245] Type=Default (100) Reason=CMDRdirect SndComp Calling[45] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=CVMA LCP
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=45
11:38:18 429229586mS CMCallEvt: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
11:38:18 429229586mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,CVMA LCP
11:38:18 429229587mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=97681245 sub= calling=45 calling_sub= dir=out complete=1 ses=0
11:38:18 429229587mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: ADD TARGET (N): number=97681245 type=100 depth=1 nobar=1 setorig=1 ses=0
11:38:18 429229587mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: USER SC: 97681245 2 7681245 sc=type=Dial code=9N, num=
11:38:18 429229587mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
11:38:18 429229587mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
11:38:18 429229588mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f569c138) resource busy 3, total 64
11:38:18 429229588mS CMCallEvt: 0000000000000000 0.4083.0 -1 BaseEP: NEW CMEndpoint f1927148 TOTAL NOW=7 CALL_LIST=3
11:38:18 429229588mS CMTARGET: ISDN BChannel 20: in-service check = 1
11:38:18 429229589mS CMTARGET: ISDN BChannel 20: in-service check = 1
11:38:18 429229590mS CMTARGET: DIAL LINE: 732 GROUP = 0 SUCCESS = f1927148
11:38:18 429229590mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: INITIAL TARGETING SUCCEEDED
11:38:18 429229590mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: GetNoAnswerTimer:15
11:38:18 429229590mS CMCallEvt: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
11:38:18 429229591mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,CVMA LCP
11:38:18 429229592mS CMExtnTx: v=45, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=251 id=4081 in=0
Timed: 23/08/16 11:38
11:38:18 429229592mS CMCallEvt: 0000000000000000 0.4082.0 732 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
11:38:18 429229593mS CMCallEvt: c0a801fa00000ff3 0.4083.0 732 Q931 Trunk:1 CHAN=20: StateChange: END=T CMCSIdle->CMCSOffering
11:38:18 429229593mS PRN: Setting configured voice gain for ch 20.
11:38:18 429229593mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=4083 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=20
Timed: 23/08/16 11:38
Locale: enu
11:38:18 429229595mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=4083
11:38:18 429229596mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0014(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a2 .....
InformationElement = CHI
0000 18 03 a9 83 94 .....
11:38:18 429229598mS CD: CALL: 251.4081.0 BState=Idle Cut=1 Music=0.0 Aend="CVMA LCP(45)" (0.0) Bend="Line 1" [Line 1] (0.0) CalledNum=97681245 () CallingNum=45 (CVMA LCP) Internal=0 Time=19 AState=Dialled
11:38:18 429229603mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=0014(Local)
Message Type = ReleaseComplete
InformationElement = CAUSE
0000 08 03 82 e0 70 ....p
11:38:18 429229604mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=4083
11:38:18 429229604mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=4083 in=0
BChan: slot=0 chan=20
Cause=96, Mandatory information element missing
11:38:18 429229605mS CMCallEvt: 0000000000000000 0.4082.0 732 TargetingEP: RequestEnd c0a801fa00000ff3 0.4083.0 732 Q931 Trunk:1 CHAN=20
11:38:18 429229605mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: CancelTimer CMTCNoAnswerTimeout
11:38:18 429229605mS CMCallEvt: 0000000000000000 0.4082.0 -1 BaseEP: DELETE CMEndpoint f17c43b8 TOTAL NOW=6 CALL_LIST=3
11:38:18 429229605mS CMCallEvt: c0a801fa00000ff3 0.4083.0 732 Q931 Trunk:1 CHAN=20: StateChange: END=B CMCSOffering->CMCSCompleted
11:38:18 429229606mS CMLOGGING: CALL:2016/08/2311:38,00:00:00,000,45,O,,97681245,CVMALCP,,,0,,"",0,n/a
11:38:18 429229607mS CD: CALL: 251.4081.0 BState=Disconnecting Cut=1 Music=0.0 Aend="CVMA LCP(45)" (0.0) Bend="Line 1" [Line 1] (1.21) CalledNum= () CallingNum=45 (CVMA LCP) Internal=0 Time=28 AState=Dialled
11:38:18 429229607mS CD: CALL: 251.4081.0 Deleted
11:38:18 429229607mS CMExtnEvt: CVMA LCP: CALL LOST (CMCauseMissingMandatoryIE)
11:38:18 429229607mS CMExtnEvt: CVMA LCP: Extn(45) Calling Party Number(45) Type(CMNTypeInternal)
11:38:18 429229608mS CMCallEvt: c0a801fa00000ff1 251.4081.0 -1 CVMA LCP.0: StateChange: END=X CMCSDialled->CMCSCompleted
11:38:18 429229608mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=Proceeding,0,0,CVMA LCP
11:38:18 429229608mS CMExtnTx: v=45, p1=0
CMReleaseComp
Line: type=SIPLine 260 Call: lid=251 id=4081 in=0
Called[97681245] Type=Default (100) Reason=CMDRdirect Calling[45] Type=Internal Plan=Default
Cause=96, Mandatory information element missing
Timed: 23/08/16 11:38
11:38:18 429229609mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjt92nVibGqrY4ql5OqN-xLhVIt5V11hH0;alias
From: <sip:45@192.168.1.250>;tag=xV117xGK5Njcp85PJ9iySLfhjTaVTBEP
Call-ID: PytXVwkTdljUlPadRosNSWudsvpdq5tV
CSeq: 3133 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.4.0 build 137
Reason: Q.850;cause=47;text="Resource unavailable, unspecified"
To: <sip:97681245@192.168.1.250>;tag=93e715e5309f6baa
Content-Length: 0

11:38:18 429229610mS CMExtnEvt: CVMA LCP: CMExtnHandler::SetCurrent( id: 4081->0 )
11:38:18 429229610mS CMCallEvt: c0a801fa00000ff1 251.4081.0 -1 CVMA LCP.-1: StateChange: END=X CMCSCompleted->CMCSDelete
11:38:18 429229611mS CMTARGET: c0a801fa00000ff1 251.4081.0 -1 BaseEP: ~CMTargetHandler f1963ae0 ep f187d974
11:38:18 429229611mS CMCallEvt: c0a801fa00000ff1 251.4081.0 -1 BaseEP: DELETE CMEndpoint f187d974 TOTAL NOW=5 CALL_LIST=2
11:38:18 429229611mS CMCallEvt: c0a801fa00000ff3 0.4083.0 -1 Q931 Trunk:1 CHAN=20: StateChange: END=X CMCSCompleted->CMCSDelete
11:38:18 429229611mS CMCallEvt: END CALL:732 (f1923a74)
11:38:18 429229612mS CMTARGET: ISDN BChannel 20: in-service check = 1
11:38:18 429229612mS CMTARGET: ISDN BChannel 20: in-service check = 1
11:38:18 429229612mS CMCallEvt: c0a801fa00000ff3 0.4083.0 -1 BaseEP: DELETE CMEndpoint f1927148 TOTAL NOW=4 CALL_LIST=2
11:38:18 429229613mS CMMap: a=0.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f569c138) resource busy 2, total 64
11:38:18 429229614mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
ACK sip:97681245@192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjt92nVibGqrY4ql5OqN-xLhVIt5V11hH0;alias
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=xV117xGK5Njcp85PJ9iySLfhjTaVTBEP
To: <sip:97681245@192.168.1.250>;tag=93e715e5309f6baa
Call-ID: PytXVwkTdljUlPadRosNSWudsvpdq5tV
CSeq: 3133 ACK
Route: <sip:192.168.1.250;transport=udp;lr>
Content-Length: 0

11:38:18 429229617mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
REGISTER sip:192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjLLI4gfABg80VDv0s.RUbw9SUpcWL8Uc9;alias
Route: <sip:192.168.1.250;transport=udp;lr>
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=NYWcWTwIf1GI1r1p60TMUxVvHvkZNq9Z
To: <sip:45@192.168.1.250>
Call-ID: DsA1ncOYZFClYRrk2t5wt.zLrnm8pUVf
CSeq: 1333 REGISTER
User-Agent: Avaya B179 2.4.1.5
Contact: <sip:45@192.168.1.118:5060;ob>
Expires: 0
Content-Length: 0

11:38:18 429229621mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjLLI4gfABg80VDv0s.RUbw9SUpcWL8Uc9;alias
From: <sip:45@192.168.1.250>;tag=NYWcWTwIf1GI1r1p60TMUxVvHvkZNq9Z
Call-ID: DsA1ncOYZFClYRrk2t5wt.zLrnm8pUVf
CSeq: 1333 REGISTER
User-Agent: IP Office 9.1.4.0 build 137
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Date: Tue, 23 Aug 2016 18:38:20 GMT
Expires: 0
Supported: timer
Server: IP Office 9.1.4.0 build 137
To: <sip:45@192.168.1.250>;tag=87a6d7cb9400c7cf
Content-Length: 0

11:38:18 429229621mS CMExtnEvt: CVMA LCP:45 ExtnFault now 1
11:38:18 429229625mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
REGISTER sip:192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPj2xbM3o99dYMo1Z7bxieNJHuXRaF89jk3;alias
Route: <sip:192.168.1.250;transport=udp;lr>
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=TX2zrQusftRHuj7ZMmLCSAI3q2Zf9uQo
To: <sip:45@192.168.1.250>
Call-ID: sS0n5n2th.6d3hK0WvWdfvDpgBykiNWs
CSeq: 38834 REGISTER
User-Agent: Avaya B179 2.4.1.5
Contact: <sip:45@192.168.1.118:5060;ob>
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

11:38:18 429229629mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPj2xbM3o99dYMo1Z7bxieNJHuXRaF89jk3;alias
From: <sip:45@192.168.1.250>;tag=TX2zrQusftRHuj7ZMmLCSAI3q2Zf9uQo
Call-ID: sS0n5n2th.6d3hK0WvWdfvDpgBykiNWs
CSeq: 38834 REGISTER
User-Agent: IP Office 9.1.4.0 build 137
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH

http://WWW-Authenticate:

Digest nonce="8427f2862026fdd42046",realm="ipoffice",algorithm=MD5
Supported: timer
Server: IP Office 9.1.4.0 build 137
To: <sip:45@192.168.1.250>;tag=74b9055c5dd5611a
Content-Length: 0

11:38:18 429229702mS RES: Tue 23/8/2016 11:38:20 FreeMem=55300200 Heap=54643200(4) Cache=657000 MemObjs=14257(16806) CMMsg=8(8) ASN=0 Buff=5200 1360 1000 7419 5 Links=58558(60265) BTree=970(1889) CPU=02.27% CPUStats=06.29%/21/28/2377/21800/23655/00.81%/
0/03.12% MCR=0 MCW=0
11:38:18 429229702mS RES2: IP 500 V2 9.1.4.0 build 137 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=96 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=86(TLS=6) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
11:38:18 429229702mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
11:38:18 429229702mS RES5: CLog MemObjs=883 FreePoolMem(Objs)=6084(117) TotalMem=188500
11:38:18 429229833mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
REGISTER sip:192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjV7DurvML3cUT73NJmzm7CKldQXY5M9xj;alias
Route: <sip:192.168.1.250;transport=udp;lr>
Max-Forwards: 70
From: <sip:45@192.168.1.250>;tag=TX2zrQusftRHuj7ZMmLCSAI3q2Zf9uQo
To: <sip:45@192.168.1.250>
Call-ID: sS0n5n2th.6d3hK0WvWdfvDpgBykiNWs
CSeq: 38835 REGISTER
User-Agent: Avaya B179 2.4.1.5
Contact: <sip:45@192.168.1.118:5060;ob>
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="CVMA LCP", realm="ipoffice", nonce="8427f2862026fdd42046", uri="sip:192.168.1.250;transport=udp", response="d76cb311b081fe533f0f276cf7875942", algorithm=MD5
Content-Length: 0

11:38:18 429229838mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.118:5060;rport;branch=z9hG4bKPjV7DurvML3cUT73NJmzm7CKldQXY5M9xj;alias
From: <sip:45@192.168.1.250>;tag=TX2zrQusftRHuj7ZMmLCSAI3q2Zf9uQo
Call-ID: sS0n5n2th.6d3hK0WvWdfvDpgBykiNWs
CSeq: 38835 REGISTER
User-Agent: IP Office 9.1.4.0 build 137
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Contact: <sip:45@192.168.1.118:5060;ob>
Date: Tue, 23 Aug 2016 18:38:20 GMT
Expires: 180
Supported: timer
Server: IP Office 9.1.4.0 build 137
To: <sip:45@192.168.1.250>;tag=aea2e13e4104be92
Content-Length: 0

11:38:18 429229838mS CMExtnEvt: CVMA LCP:45 ExtnFault now 0
11:38:18 429229842mS Sip: SIP REG: New Registration for Extn:45 IP address: 192.168.1.118 UA:Avaya B179 2.4.1.5
11:38:18 429229843mS PRN: Config Write Wake Up
11:38:18 429229940mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.118:5060
OPTIONS sip:45@192.168.1.250 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.250:5060;rport;branch=z9hG4bKb04c73bafbac70a932f49eec19c488b5
From: <sip:Unknown@192.168.1.250>;tag=da85aedf35599693
To: <sip:45@192.168.1.250>
Call-ID: 6affca83bb6ba0e217e9579559dd4d31
CSeq: 1934894414 OPTIONS
Contact: <sip:Unknown@192.168.1.250:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
User-Agent: IP Office 9.1.4.0 build 137
Content-Length: 0

11:38:18 429229965mS SIP Rx: UDP 192.168.1.118:5060 -> 192.168.1.250:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.250:5060;rport=5060;received=192.168.1.250;branch=z9hG4bKb04c73bafbac70a932f49eec19c488b5;alias
Call-ID: 6affca83bb6ba0e217e9579559dd4d31
From: <sip:Unknown@192.168.1.250>;tag=da85aedf35599693
To: <sip:45@192.168.1.250>
CSeq: 1934894414 OPTIONS
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Accept: application/sdp, message/sipfrag;version=2.0, application/im-iscomposing+xml, text/plain
Supported: replaces, 100rel
Allow-Events: refer
User-Agent: Avaya B179 2.4.1.5
Content-Type: application/sdp
Content-Length: 338

v=0
o=- 3680966300 3680966300 IN IP4 192.168.1.118
s=pjmedia
c=IN IP4 192.168.1.118
t=0 0
m=audio 4000 RTP/AVP 9 8 0 18 101
a=rtcp:4001 IN IP4 192.168.1.118
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
11:38:19 429230343mS PRN: Updates IO list size 1 updated list size 1
11:38:19 429230343mS PRN: Sending Updates out to f4e9df3c started
11:38:19 429230344mS PRN: Sending Updates out to f4e9df3c finished
11:38:19 429230344mS PRN: Config Write Completed
11:38:21 429231610mS CMExtnEvt: CVMA LCP: Recover Timer reason=CMTRWrapUp
11:38:21 429231610mS CMExtnEvt: v=0 State, new=Idle old=PortRecoverDelay,0,0,CVMA LCP
11:38:24 429234702mS RES: Tue 23/8/2016 11:38:25 FreeMem=55298260 Heap=54644128(4) Cache=654132 MemObjs=14271(16806) CMMsg=8(8) ASN=0 Buff=5200 1360 1000 7419 5 Links=58560(60265) BTree=972(1889) CPU=02.54% CPUStats=01.70%/1/3/2377/22923/23655/00.72%/0/
03.01% MCR=0 MCW=0
11:38:24 429234702mS RES2: IP 500 V2 9.1.4.0 build 137 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=93 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=86(TLS=6) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
11:38:24 429234703mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
11:38:24 429234703mS RES5: CLog MemObjs=883 FreePoolMem(Objs)=6084(117) TotalMem=188500

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

 

[amriddle01]

You aren't sending a valid DDI/DID number by the looks of it

 

[joe2938]

Do you have incoming call routes built for these 3 extensions?

 

[TMMcN]

I have an incoming route for one of them, but not the others.

 

[TMMcN]

Still trying to resolve this issue.

I have a separate DID for each phone. All incoming calls, internal or external are fine. Outgoing internal calls are fine. I believe it is my short code on the extension. I am using
Code N;
Number
Feature Dial
Line Group 0

It now seizes a PRI channel, however, no call connects.

Any help would be greatly appreciated.

Thanks

 

[amriddle01]

You need something in the tel number field, you have it set to dial nothing at all, try N or . (just a dot)

 

[janni78]

You're not sending a number.

11:38:18 429229587mS CMTARGET: c0a801fa00000ff1 251.4081.0 732 CVMA LCP.0: USER SC: 97681245 2 7681245 sc=type=Dial code=9N, num=

11:38:18 429229604mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=4083 in=0
BChan: slot=0 chan=20
Cause=96, Mandatory information element missing

Your shortcode should be

SC: N
Number: N
Feature: Dial
Line Group: 0

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

 

[TMMcN]

Making headway....
It shows sending 9XXXXXXX to the Central Office.
How do I strip the 9 from the telephone #?

Thanks

 

[amriddle01]

Code 9N;
Tel number N

 

[janni78]

That's correct, I'm just used to not using access code here =)

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

 

[TMMcN]

Still now working, Below is trace.

16:35:47 3557749906mS SIP Rx: UDP 192.168.1.56:5060 -> 192.168.1.250:5060
INVITE sip:97681245@192.168.1.250;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.56:5060;rport;branch=z9hG4bKPjC3E085GM7v1SQxdCl9pi7TilGaKMD2ft;alias
Max-Forwards: 70
From: <sip:81@192.168.1.250>;tag=LckVlx7zz37lUvXqfF9IkizmwmXgstGT
To: <sip:97681245@192.168.1.250>
Contact: <sip:81@192.168.1.56:5060;ob>
Call-ID: ZNcUYnqsT52rEHzD6esKQUqwRbsAb4b2
CSeq: 30434 INVITE
Route: <sip:192.168.1.250;transport=udp;lr>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel
User-Agent: Avaya B179 2.4.1.5
Content-Type: application/sdp
Content-Length: 492

v=0
o=- 3684094547 3684094547 IN IP4 192.168.1.56
s=pjmedia
c=IN IP4 192.168.1.56
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 9 8 0 18 101
a=rtcp:4005 IN IP4 192.168.1.56
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=tcap:1 RTP/SAVP
a=acap:1 crypto:1 AES_CM_128_HMAC_SHA1_80 inline:8qA1/IOgueGS4kKHaB6RbGvYcASZ2cChz2MLZ05q UNENCRYPTED_SRTCP
a=pcfg:1 t=1 a=1
16:35:47 3557749910mS CMCallEvt: 0000000000000000 0.32931.0 -1 BaseEP: NEW CMEndpoint f1968068 TOTAL NOW=1 CALL_LIST=0
16:35:47 3557749910mS CMCallEvt: 0000000000000000 0.32931.0 -1 VISC CP.-1: NEW CMExtnEndpoint f1968068, Name=VISC CP, Extn=81, Phys Extn=81
16:35:47 3557749911mS CMCallEvt: CREATE CALL:7174 (f197be94)
16:35:47 3557749911mS CMCallEvt: 0000000000000000 0.32932.0 -1 BaseEP: NEW CMEndpoint f197a5a0 TOTAL NOW=2 CALL_LIST=0
16:35:47 3557749913mS CMExtnEvt: VISC CP: CMExtnHandler::SetCurrent( id: 0->32931 )
16:35:47 3557749915mS SIP Tx: UDP 192.168.1.250:5060 -> 192.168.1.56:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.56:5060;rport;branch=z9hG4bKPjC3E085GM7v1SQxdCl9pi7TilGaKMD2ft;alias
From: <sip:81@192.168.1.250>;tag=LckVlx7zz37lUvXqfF9IkizmwmXgstGT
Call-ID: ZNcUYnqsT52rEHzD6esKQUqwRbsAb4b2
CSeq: 30434 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.4.0 build 137
To: <sip:97681245@192.168.1.250>;tag=fc54727bf854b651
Content-Length: 0

16:35:47 3557749918mS CMExtnRx: v=81, p1=0
CMSetup
Line: type=SIPLine 260 Call: lid=253 id=32931 in=0
Called[97681245] Type=Default (100) Reason=CMDRdirect SndComp Calling[81] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=VISC CP
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=81
16:35:47 3557749918mS CMCallEvt: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
16:35:47 3557749918mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,VISC CP
16:35:47 3557749919mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=97681245 sub= calling=81 calling_sub= dir=out complete=1 ses=0
16:35:47 3557749919mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: ADD TARGET (N): number=97681245 type=100 depth=1 nobar=1 setorig=1 ses=0
16:35:47 3557749919mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: USER SC: 97681245 3 7681245 sc=type=Dial code=9N;, num=N
16:35:47 3557749920mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
16:35:47 3557749920mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
16:35:47 3557749920mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f569c138) resource busy 1, total 64
16:35:47 3557749920mS CMCallEvt: 0000000000000000 0.32933.0 -1 BaseEP: NEW CMEndpoint f1932228 TOTAL NOW=3 CALL_LIST=1
16:35:47 3557749921mS CMTARGET: ISDN BChannel 20: in-service check = 1
16:35:47 3557749921mS CMTARGET: ISDN BChannel 20: in-service check = 1
16:35:47 3557749922mS CMTARGET: DIAL LINE: 7174 GROUP = 0 SUCCESS = f1932228
16:35:47 3557749923mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: INITIAL TARGETING SUCCEEDED
16:35:47 3557749923mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: GetNoAnswerTimer:15
16:35:47 3557749923mS CMCallEvt: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
16:35:47 3557749923mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,VISC CP
16:35:47 3557749924mS CMExtnTx: v=81, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=253 id=32931 in=0
Timed: 28/09/16 16:35
16:35:47 3557749925mS CMCallEvt: 0000000000000000 0.32932.0 7174 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
16:35:47 3557749926mS CMCallEvt: c0a801fa000080a5 0.32933.0 7174 Q931 Trunk:1 CHAN=20: StateChange: END=T CMCSIdle->CMCSOffering
16:35:47 3557749926mS CMCallEvt: 0000000000000000 0.32932.0 7174 TargetingEP: RequestEnd c0a801fa000080a5 0.32933.0 7174 Q931 Trunk:1 CHAN=20
16:35:47 3557749926mS CMTARGET: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: CancelTimer CMTCNoAnswerTimeout
16:35:47 3557749926mS CMCallEvt: c0a801fa000080a3 253.32931.0 7174 VISC CP.0: StateChange: END=A CMCSDialled->CMCSRingBack
16:35:47 3557749929mS CMExtnTx: v=81, p1=0
CMFacility
Line: type=SIPLine 260 Call: lid=253 id=32931 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Timed: 28/09/16 16:35
16:35:47 3557749930mS CMExtnEvt: v=0 State, new=Alerting old=Proceeding,0,0,VISC CP
16:35:47 3557749931mS CMExtnTx: v=81, p1=0
CMProgress
Line: type=SIPLine 260 Call: lid=253 id=32931 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Display [ENTER AUTHCODE]