Outgoing calls do not work Avaya

[System97]

Hello, i need help

I have
Avaya IP Office 11.1.1 with VoiceMail

Outgoing calls don't work, when I run a test it sounds like they hang up on me,
The problem generally happens with all extensions.
When I see the record in voicemail it never goes through the ringing state, it goes to the connected state, but it sounds like the phone is busy or they hang up.

And the incoming calls are working fine.


Here's a monitor trace (not sure if I got the filters right)

09:23:23 1589272mS CMMap: a=5.2 b=0.0 R0
09:23:23 1589273mS CMMap: PCG::MapBChan pcp[123]b1r0 cp_b f567cd5c other_cp_b 0 type CGTypeSimple
09:23:23 1589273mS H323Evt: SESS 19 SetOperational local 192.168.1.201:0 remote 0.0.0.0:0 to 0
09:23:23 1589273mS H323Evt: SESS 19 Configure: Alaw64K packet size 160
09:23:23 1589273mS H323Evt: SESS 19 SetRemUDP 0 -> 46750, remote IP 0.0.0.0 -> 192.168.1.238
09:23:23 1589274mS CMMap: a=5.2 b=0.0 PCGS CPReserveCodec (pcp[177]b0r1) true
09:23:23 1589274mS CMMap: PCG::MapBChan pcp[177]b0r1 cp_b f56b0794 other_cp_b f567cd5c type CGTypeSimple
09:23:23 1589274mS CMMap: a=5.2 b=4.17 M12
09:23:23 1589274mS CMMap: PlatformConnectionAudioSAP::Bind pcp[177]b0r1 to 4.17
09:23:23 1589274mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[177]b0r1 Configure 4.17
09:23:23 1589274mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelayId: 2
09:23:23 1589274mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success rtpRelayId: 2
09:23:23 1589275mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.1.201:46750, Remote IP addr 192.168.1.238:46750
09:23:23 1589275mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.5:32768, telogyHost IP addr 1.1.1.1:42
09:23:23 1589275mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success rtpRelayId: 2
09:23:23 1589275mS CMMap: pcp[177]b0r1 rtpRelayId: 2 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
09:23:23 1589275mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[177]b0r1 ConnectIndication 4.17
09:23:23 1589275mS H323Evt: SESS 19 SetOperational local 192.168.1.201:46750 remote 192.168.1.238:46750 to 1


If you need some monitor filters or more information, tell me which ones so I can pass the information on to you.
I hope you can help me with this problem.

 

[vfotineas]

Hello ,
Please send more details :
What type of lines do you have?
Make some test calls and take from System Status Application the trace All output from the extension & from the line.

Best regards,

 

[System97]

Here is all the monitoring of the test I performed,
I hope this helps:

INVITE sip:444xxxxxx@192.168.1.201 SIP/2.0
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
To: <sip:444xxxxxx@192.168.1.201>
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 15 INVITE
Max-Forwards: 70
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bKf_65eb331618eb20d74hx1w2i5x4za5s3v276v4l_I206;keep
Supported: 100rel,eventlist,feature-ref,replaces,tdialog,vnd.avaya.ipo
Allow: INVITE,ACK,BYE,CANCEL,SUBSCRIBE,NOTIFY,MESSAGE,REFER,INFO,PRACK,PUBLISH,UPDATE
User-Agent: Avaya J129 IP Phone 4.0.7.0.7 c81feabdc7bf
Contact: <sip:206@192.168.1.36:14517;transport=tcp>
Accept-Language: es
Content-Type: application/sdp
Content-Length: 341

v=0
o=sip:206@192.168.1.36 1 16 IN IP4 192.168.1.36
s=sip:206@192.168.1.36
c=IN IP4 192.168.1.36
b=TIAS:1920000
t=0 0
m=audio 46750 RTP/AVP 0 8 18 101
c=IN IP4 192.168.1.36
b=TIAS:64000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:18 G729/8000/1
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000/1
a=maxptime:60
09:47:34 86829698mS CMCallEvt: 0000000000000000 0.2379.0 -1 BaseEP: NEW CMEndpoint f1a4fdb8 TOTAL NOW=1 CALL_LIST=0
09:47:34 86829698mS CMCallEvt: 0000000000000000 0.2379.0 -1 Extn206.-1: NEW CMExtnEndpoint f1a4fdb8, Name=Extn206, Extn=206, Phys Extn=206
09:47:34 86829699mS CMCallEvt: CREATE CALL:328 (f1a322e4)
09:47:34 86829699mS CMCallEvt: 0000000000000000 0.2380.0 -1 BaseEP: NEW CMEndpoint f1a07e78 TOTAL NOW=2 CALL_LIST=0
09:47:34 86829702mS CMExtnEvt: Extn206: CMExtnHandler::SetCurrent( id: 0->2379 )
09:47:34 86829702mS Sip: SIPDialog f5285d8c created, dialogs 10 txn_keys 2 video 1 presentation 1 camera 1 unsupp audio 0
09:47:34 86829702mS Sip: SipTCPUser 18 has 1 dialog open (AttachDialogToSipTCPUser)
09:47:34 86829703mS Sip: SipTCPUser 18 has 2 dialogs open (AttachDialogToSipTCPUser)
09:47:34 86829703mS Sip: c0a801c90000094b 268.2379.0 328 Extn206.0(f5285d8c) mPRACK is supported, PreProcessMsg
09:47:34 86829706mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.36:14517
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bKf_65eb331618eb20d74hx1w2i5x4za5s3v276v4l_I206;keep
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 15 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: 100rel
Server: IP Office 11.1.1.0.0 build 209
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Content-Length: 0

09:47:34 86829708mS Sip: c0a801c90000094b 268.2379.0 328 Extn206.0(f5285d8c) SetRfc2833TxPayload: use RFC2833 for dtmf
09:47:34 86829709mS CMExtnRx: v=206, p1=0
CMSetup
Line: type=SIPLine 260 Call: lid=268 id=2379 in=0
Called[444xxxxxx] Type=Default (100) Reason=CMDRdirect SndComp Calling[206] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Extn206
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=206
IE CMIEFastStartInfoData (6) 6 item(s)
09:47:34 86829710mS CMCallEvt: c0a801c90000094b 268.2379.0 328 Extn206.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
09:47:34 86829710mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,Extn206
09:47:34 86829711mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=444xxxxxx sub= calling=206 calling_sub= dir=out complete=1 ses=0
09:47:34 86829711mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: ADD TARGET (N): number=444xxxxxx type=100 depth=1 nobar=1 setorig=1 ses=0
09:47:34 86829712mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: DEF SC: 444xxxxxx0 sc=type=Dial code=?, num=.
09:47:34 86829712mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
09:47:34 86829712mS CMARS: MakeCallTarget - Called Number: 444xxxxxx
09:47:34 86829712mS CMCallEvt: 0000000000000000 0.2381.0 -1 BaseEP: NEW CMEndpoint f19b2830 TOTAL NOW=3 CALL_LIST=1
09:47:34 86829713mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: INITIAL TARGETING SUCCEEDED
09:47:34 86829713mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: GetNoAnswerTimer:15
09:47:34 86829713mS CMCallEvt: c0a801c90000094b 268.2379.0 328 Extn206.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
09:47:34 86829714mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,Extn206
09:47:34 86829715mS CMExtnTx: v=206, p1=0
CMProceeding
Line: type=SIPLine 260 Call: lid=268 id=2379 in=0
Timed: 08/03/24 09:47
09:47:34 86829715mS CMCallEvt: 0000000000000000 0.2380.0 328 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
09:47:34 86829716mS CMCallEvt: 0000000000000000 0.2381.0 328 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
09:47:34 86829717mS CMCallEvt: 0000000000000000 0.2380.0 328 TargetingEP: RequestEnd 0000000000000000 0.2381.0 328 ARS for Main
09:47:34 86829717mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: CancelTimer CMTCNoAnswerTimeout
09:47:34 86829718mS CMCallEvt: 0000000000000000 0.2381.0 328 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
09:47:34 86829718mS CMARS: FORM: Main - Received Number: 444xxxxxx
09:47:34 86829718mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 444xxxxxx- Line Group Id: 0
09:47:34 86829719mS CMARS: SetCurrentTarget: Short_Code: ? - Line_Group_ID: 0
09:47:34 86829719mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
09:47:34 86829719mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
09:47:34 86829719mS CMMap: a=4.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f56621c0) resource busy 1, total 64
09:47:34 86829719mS CMCallEvt: 0000000000000000 0.2382.0 -1 BaseEP: NEW CMEndpoint f1a6a5cc TOTAL NOW=4 CALL_LIST=1
09:47:34 86829721mS CMARS: ModifyCMARSTarget: Short_Code: ? - Line_Group_ID: 0 set line status to CMARS_TRYING
09:47:34 86829721mS CMARS: FOUND LINE - Line Id: 16 - using line group id: 0 (code ?, line 0) - Called Number: 444xxxxxx - Calling Number: 206
09:47:34 86829721mS CMARS: SEND Setup TO LINE
09:47:34 86829721mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=child CMCSIdle->CMCSOffering
09:47:34 86829722mS CMCallEvt: 0000000000000000 0.2380.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
09:47:34 86829722mS CMCallEvt: 0000000000000000 0.2380.0 -1 BaseEP: DELETE CMEndpoint f1a07e78 TOTAL NOW=3 CALL_LIST=1
09:47:34 86829770mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
09:47:34 86829770mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
09:47:34 86829770mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: c0a801c90000094b 0.2381.0 328 ARS for Main - Call State: CMCSOverlapRecv
09:47:34 86829771mS CMCallEvt: c0a801c90000094b 0.2381.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
09:47:34 86829772mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=B CMCSOffering->CMCSOverlapRecv
09:47:34 86829772mS CMCallEvt: c0a801c90000094b 0.2381.0 -1 BaseEP: DELETE CMEndpoint f19b2830 TOTAL NOW=2 CALL_LIST=1
09:47:34 86829773mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=B CMCSOverlapRecv->CMCSAccept
09:47:35 86830076mS RES: Fri 8/3/2024 09:47:36 FreeMem=59290812 Heap=58675972(1) Cache=614840 MemObjs=10239(Max 12759) CMMsg=7(9) ASN=0 Buff=5200 1356 1000 7444 5 Links=53469(53767) BTree=692(1092) CB=6053 MCT=0 CPU=09.66% CPUStats=05.63%/1/3/2486/2353
3/25292/00.05%/0/02.48% MCR=0 MCW=0 DEV=0
09:47:35 86830077mS RES2: IP 500 V2 11.1.1.0.0 build 209 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+110 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=50(TLS=6 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:47:35 86830077mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
09:47:35 86830077mS RES5: CLog MemObjs=973 FreePoolMem(Objs)=7112(127) TotalMem=61600 StringsTotalMem=103600
09:47:37 86832649mS SIP Rx: TCP 192.168.1.225:12948 -> 192.168.1.201:5060
REGISTER sip:192.168.1.201 SIP/2.0
From: <sip:301@192.168.1.201>;tag=65e5fc9d7907ca6c4n6b623h7udc206v3m4d_F301
To: <sip:301@192.168.1.201>
Call-ID: 1_65e5fc9d-7efaa5f21d4q3k646i2t3w838406xv_R301
CSeq: 5387 REGISTER
Max-Forwards: 70
Via: SIP/2.0/TCP 192.168.1.225:12948;branch=z9hG4bKa86_65eb331a75fcc585224p5j3z2s1z54b575g2t39_R301;keep
Supported: eventlist,feature-ref,replaces,tdialog,vnd.avaya.ipo
Allow: INVITE,ACK,BYE,CANCEL,SUBSCRIBE,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya J129 IP Phone 4.0.7.0.7 c81feabbd6fe
Contact: <sip:301@192.168.1.225:12948;transport=tcp;avaya-sc-enabled>;q=1;expires=900;avaya-actions="presence.initiate-pubsub,presence.redirect";+avaya.gmtoffset="-6:00";+avaya.js-ver="1.0";+avaya.model="J129";+avaya.sn="19WZ436008HC";
+avaya.firmware="4.0.7.0.7";+av.ip.mode=4;+av.sdp.anat;+av.sip.sig=4;+av.sip.media=4;+av.sip.iptolerance;+sip.instance="<urn:uuid:00000000-0000-1000-8000-c81feabbd6fe>";reg-id=1
Content-Length: 0

09:47:37 86832652mS Sip: SIPDialog f555cebc created, dialogs 11 txn_keys 3 video 1 presentation 1 camera 1 unsupp audio 0
09:47:37 86832655mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.225:12948
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TCP 192.168.1.225:12948;branch=z9hG4bKa86_65eb331a75fcc585224p5j3z2s1z54b575g2t39_R301;keep
From: <sip:301@192.168.1.201>;tag=65e5fc9d7907ca6c4n6b623h7udc206v3m4d_F301
Call-ID: 1_65e5fc9d-7efaa5f21d4q3k646i2t3w838406xv_R301
CSeq: 5387 REGISTER
User-Agent: IP Office 11.1.1.0.0 build 209
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH

http://WWW-Authenticate:

Digest nonce="d217658a0865cb224b52",realm="ipoffice",algorithm=MD5
Supported: vnd.avaya.ipo
Server: IP Office 11.1.1.0.0 build 209
To: <sip:301@192.168.1.201>;tag=e03d1ff30501ba88
Content-Length: 0

09:47:37 86832673mS SIP Rx: TCP 192.168.1.225:12948 -> 192.168.1.201:5060
REGISTER sip:192.168.1.201 SIP/2.0
From: <sip:301@192.168.1.201>;tag=65e5fc9d7907ca6c4n6b623h7udc206v3m4d_F301
To: <sip:301@192.168.1.201>
Call-ID: 1_65e5fc9d-7efaa5f21d4q3k646i2t3w838406xv_R301
CSeq: 5388 REGISTER
Max-Forwards: 70
Via: SIP/2.0/TCP 192.168.1.225:12948;branch=z9hG4bKa87_65eb331a7864c6031x251l6g1z4c5q5l4y6a2f2g_R301;keep
Supported: eventlist,feature-ref,replaces,tdialog,vnd.avaya.ipo
Allow: INVITE,ACK,BYE,CANCEL,SUBSCRIBE,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya J129 IP Phone 4.0.7.0.7 c81feabbd6fe
Contact: <sip:301@192.168.1.225:12948;transport=tcp;avaya-sc-enabled>;q=1;expires=900;avaya-actions="presence.initiate-pubsub,presence.redirect";+avaya.gmtoffset="-6:00";+avaya.js-ver="1.0";+avaya.model="J129";+avaya.sn="19WZ436008HC";
+avaya.firmware="4.0.7.0.7";+av.ip.mode=4;+av.sdp.anat;+av.sip.sig=4;+av.sip.media=4;+av.sip.iptolerance;+sip.instance="<urn:uuid:00000000-0000-1000-8000-c81feabbd6fe>";reg-id=1
Authorization: Digest realm="ipoffice",nonce="d217658a0865cb224b52",uri="sip:192.168.1.201",response="7498e848fbc2a4b43682f5d245c14b92",username="301"
Content-Length: 0

09:47:37 86832680mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.225:12948
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.1.225:12948;branch=z9hG4bKa87_65eb331a7864c6031x251l6g1z4c5q5l4y6a2f2g_R301;keep
From: <sip:301@192.168.1.201>;tag=65e5fc9d7907ca6c4n6b623h7udc206v3m4d_F301
Call-ID: 1_65e5fc9d-7efaa5f21d4q3k646i2t3w838406xv_R301
CSeq: 5388 REGISTER
User-Agent: IP Office 11.1.1.0.0 build 209
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Contact: <sip:301@192.168.1.225:12948;transport=tcp;avaya-sc-enabled>
Content-Type: application/vnd.avaya.ipo
Date: Fri, 08 Mar 2024 15:47:38 GMT
Expires: 180
Supported: vnd.avaya.ipo
Server: IP Office 11.1.1.0.0 build 209
To: <sip:301@192.168.1.201>;tag=5a867dbbb551cc88
Content-Length: 92

<ipo>
backup_ipoffice_server="0.0.0.0";
GUID="b3aa03807e4711e48a0a00e00709413f";
</ipo>
09:47:37 86832681mS Sip: SIPDialog f555cebc deleted, dialogs 10 txn_keys 2
09:47:39 86834393mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=B CMCSAccept->CMCSRinging
09:47:39 86834394mS CMCallEvt: c0a801c90000094b 268.2379.0 328 Extn206.0: StateChange: END=A CMCSDialled->CMCSRingBack
09:47:39 86834396mS CMExtnTx: v=206, p1=0
CMFacility
Line: type=SIPLine 260 Call: lid=268 id=2379 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: 08/03/24 09:47
09:47:39 86834397mS CMMap: IP::SetCodec pcp[721]b0r0 0 -> f56621c0
09:47:39 86834397mS CMExtnEvt: v=0 State, new=Alerting old=Proceeding,0,0,Extn206
09:47:39 86834401mS CMExtnTx: v=206, p1=0
CMProgress
Line: type=SIPLine 260 Call: lid=268 id=2379 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=444xxxxxx
IE CMIEDeviceDetail (231) c0a801c90000094e LOCALE=esm HW=15 VER=11 class=CMDeviceAlogTrunk type=0 number=16 channel=0 features=0x1 rx_gain=32 tx_gain=32
ep_callid=2382 ipaddr=192.168.1.201 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0
avgsid=<>
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: 08/03/24 09:47
09:47:39 86834401mS Sip: c0a801c90000094b 268.2379.0 328 Extn206.0(f5285d8c) SetLocalRTPAddress to 192.168.1.201:46750
09:47:39 86834402mS Sip: SetName() Old outbound_name.name: New name:
09:47:39 86834405mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.36:14517
SIP/2.0 183 Session Progress
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bKf_65eb331618eb20d74hx1w2i5x4za5s3v276v4l_I206;keep
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 15 INVITE
Contact: <sip:444xxxxxx@192.168.1.201:5060;transport=tcp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
P-Asserted-Identity: <sip:444xxxxxx@192.168.1.201:5060>
Supported: 100rel
Server: IP Office 11.1.1.0.0 build 209
RSeq: 2147065754
Require: 100rel
Content-Type: application/sdp
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Content-Length: 206

v=0
o=UserA 1303037031 3031418835 IN IP4 192.168.1.201
s=Session SDP
c=IN IP4 192.168.1.201
t=0 0
m=audio 46750 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
09:47:39 86834407mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=B CMCSRinging->CMCSConnReq
09:47:39 86834408mS CMCallEvt: c0a801c90000094b 268.2379.0 328 Extn206.0: StateChange: END=A CMCSRingBack->CMCSOGConnReq
09:47:39 86834408mS CMExtnEvt: v=0 State, new=Connected old=Alerting,0,0,Extn206
09:47:39 86834411mS CMExtnTx: v=206, p1=0
CMConnect
Line: type=SIPLine 260 Call: lid=268 id=2379 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=444xxxxxx
IE CMIEDeviceDetail (231) c0a801c90000094e LOCALE=esm HW=15 VER=11 class=CMDeviceAlogTrunk type=0 number=16 channel=0 features=0x1 rx_gain=32 tx_gain=32
ep_callid=2382 ipaddr=192.168.1.201 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0
avgsid=<>
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: 08/03/24 09:47
09:47:39 86834412mS CMExtnRx: v=206, p1=0
CMConnectAck
Line: type=SIPLine 260 Call: lid=268 id=2379 in=0
09:47:39 86834412mS CMCallEvt: c0a801c90000094b 268.2379.0 328 Extn206.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
09:47:39 86834412mS CMTARGET: c0a801c90000094b 268.2379.0 328 Extn206.0: ~CMTargetHandler f1a61aec ep f1a4fdb8
09:47:39 86834414mS CMCallEvt: c0a801c90000094e 0.2382.0 328 Alog Trunk:16: StateChange: END=B CMCSConnReq->CMCSConnected
09:47:39 86834419mS H323Evt: SESS d9 SetOperational local 192.168.1.201:0 remote 0.0.0.0:0 to 0
09:47:39 86834419mS H323Evt: SESS d9 Configure: Alaw64K packet size 160
09:47:39 86834419mS H323Evt: SESS d9 SetRemUDP 0 -> 46750, remote IP 0.0.0.0 -> 192.168.1.36
09:47:39 86834419mS H323Evt: SESS d9 SetRfc2833: (1) rx payload 101 tx payload 101
09:47:39 86834420mS CMMap: PCG::MapBChan pcp[721]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
09:47:39 86834420mS CMMap: PCGS CPReserveCodec for the other end (pcp[721]b0r1) true
09:47:39 86834420mS CMMap: PCG::MapBChan pcp[129]b1r0 cp_b f566ca3c other_cp_b f56b3b50 type CGTypeSimple
09:47:39 86834420mS CMMap: a=4.1 b=7.4 M12
09:47:39 86834420mS CMMap: PlatformConnectionAudioSAP::Bind pcp[721]b0r1 to 4.1
09:47:39 86834420mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[721]b0r1 Configure 4.1
09:47:39 86834420mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelayId: 1
09:47:39 86834421mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success rtpRelayId: 1
09:47:39 86834421mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.1.201:46750, Remote IP addr 192.168.1.36:46750
09:47:39 86834421mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.4:32768, telogyHost IP addr 1.1.1.1:10
09:47:39 86834421mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success rtpRelayId: 1
09:47:39 86834421mS CMMap: pcp[721]b0r1 rtpRelayId: 1 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
09:47:39 86834421mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[721]b0r1 ConnectIndication 4.1
09:47:39 86834421mS H323Evt: SESS d9 SetOperational local 192.168.1.201:46750 remote 192.168.1.36:46750 to 1
09:47:39 86834426mS SIP Rx: TCP 192.168.1.36:14517 -> 192.168.1.201:5060
PRACK sip:444xxxxxx@192.168.1.201:5060;transport=tcp SIP/2.0
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 16 PRACK
Max-Forwards: 70
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bK-4a3262a0_65eb331b-18ce137602d6q2855b5d6p5m1k51_P206
Supported: 100rel,eventlist,feature-ref,replaces,tdialog,vnd.avaya.ipo
User-Agent: Avaya J129 IP Phone 4.0.7.0.7 c81feabdc7bf
Rack: 2147065754 15 INVITE
Content-Length: 0

09:47:39 86834430mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.36:14517
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bK-4a3262a0_65eb331b-18ce137602d6q2855b5d6p5m1k51_P206
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 16 PRACK
Supported: 100rel
Server: IP Office 11.1.1.0.0 build 209
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Content-Length: 0

09:47:39 86834432mS Sip: SetName() Old outbound_name.name: New name:
09:47:39 86834434mS SIP Tx: TCP 192.168.1.201:5060 -> 192.168.1.36:14517
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bKf_65eb331618eb20d74hx1w2i5x4za5s3v276v4l_I206;keep
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 15 INVITE
Contact: <sip:444xxxxxx@192.168.1.201:5060;transport=tcp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
P-Asserted-Identity: <sip:444xxxxxx@192.168.1.201:5060>
Supported: 100rel
Server: IP Office 11.1.1.0.0 build 209
Content-Type: application/sdp
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Content-Length: 206

v=0
o=UserA 1303037031 3031418835 IN IP4 192.168.1.201
s=Session SDP
c=IN IP4 192.168.1.201
t=0 0
m=audio 46750 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
09:47:39 86834454mS SIP Rx: TCP 192.168.1.36:14517 -> 192.168.1.201:5060
ACK sip:444xxxxxx@192.168.1.201:5060;transport=tcp SIP/2.0
From: <sip:206@192.168.1.201>;tag=65eb331646a32bad1u6bf3j4o6s5w4qp4tu6b_F206
To: <sip:444xxxxxx@192.168.1.201>;tag=3db7cdab2ec57358
Call-ID: f_65eb3316-45c81089415k545u70b1v2l6i171i6r_I206
CSeq: 15 ACK
Max-Forwards: 70
Via: SIP/2.0/TCP 192.168.1.36:14517;branch=z9hG4bK-4a3262a0_65eb331b-7b93375b186kv63t463o3s6g5r1a3q_A206
User-Agent: Avaya J129 IP Phone 4.0.7.0.7 c81feabdc7bf
Content-Length: 0

 

[vfotineas]

Hello ,
It will be more usefull if you use System Status Application to collect the call flow of the test calls.
Best regards

 

[sizbut]

STOP. Don't dump any more traces on us. Instead explain the scenario in more detail. Why are you focusing on voicemail? Is this voicemail making outgoing calls? What type of lines? What does "The problem generally happens with all extensions" mean, 'generally' implies its not always happening.

Stuck in a never ending cycle of file copying.