AVAYA IP Office SIP trunk anonymous.invalid problem

[Swankytmc]

Hi,

This is first time I've had to ask for help so be gentle. We have configured a registered SIP trunk but are intermittently sending out calls with anonymous@anonymous.invalid in the from field as well as anonymous.invalid in the realm field interchangeably. I can't for the life of me figure out why it seems to happen randomly as users are configured with a SIP user and the trunk is set to use internal data. Due to this the ITSP will reject calls with a 403 reply. Software is release 11. Any ideas/suggestions?

09:11:01 18186446mS SIP Tx: UDP 192.168.1.249:5060 -> 178.255.63.75:5060
INVITE siputbound-number@blah.blah SIP/2.0
Via: SIP/2.0/UDP 192.168.1.249:5060;rport;branch=z9hG4bKb296f257e323accbc47538176a2f9a30
From: "user" <sip:user@blah.blah>;tag=fdf23f007344a9ad
To: <siputbound-number@blah.blah>
Call-ID: 7dbd1068906422b4b18b9c63d289d82a
CSeq: 2117248170 INVITE
Contact: "2000" <sip:user@192.168.1.249:5060;transport=udp>
Proxy-Authorization: Digest username="user",realm="[highlight #FCE94F]anonymous.invalid[/highlight]",nonce="6b3c188c-90cd-483c-bb5d-dc7da1f3ae46",response="9efcaababe06b2276307c665d8beeffe",uri="siputbound-number@blah.blah",algorithm=MD5,qop=auth,nc=0000
0004,cnonce="b832f157ec7d52bbf9e4"
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
User-Agent: IP Office 11.0.4.4.0 build 6
Content-Type: application/sdp
Content-Length: 301

v=0
o=UserA 3224913659 1783336038 IN IP4 192.168.1.249
s=Session SDP
c=IN IP4 192.168.1.249
t=0 0
m=audio 46750 RTP/AVP 8 0 18 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

Thanks,

Al

 

[janni78]

It would probably tell us more if you include what happens before the INVITE

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

 

[Swankytmc]

Hi,

As requested. I've provided a more complete trace.

09:10:57 18182735mS CMARS: FORM: FORMA - Received Number: XXXXXXXXXX
09:10:57 18182735mS CMARS: CMARSTargetingPending

********** SysMonitor v11.0.4.4.0 build 6 [connected to 192.168.1.249 (CAS)] **********
09:10:58 18183682mS PRN: Monitor Status IP 500 V2 11.0.4.4.0 build 6
09:10:58 18183682mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=1(TOT=7151)
09:10:59 18184144mS H323Evt: Recv: RegistrationRequest 10.130.35.12; Extn(210), time since last RRQ-KA 55004, Endpoints registered: 22; Endpoints in registration: 0
09:10:59 18184819mS H323Evt: Recv: RegistrationRequest 10.130.35.3; Extn(215), time since last RRQ-KA 55004, Endpoints registered: 22; Endpoints in registration: 0
09:11:00 18185111mS H323Evt: Recv: RegistrationRequest 10.130.35.6; Extn(214), time since last RRQ-KA 55005, Endpoints registered: 22; Endpoints in registration: 0
09:11:01 18186437mS CMARS: Case 1 - dial_delay_time: 3000 has expired - Resolve what we might have - Called Number: XXXXXXXXX Addr: f1884474
09:11:01 18186437mS CMARS: FORM: FORMA - Received Number: XXXXXXXXXX
09:11:01 18186437mS CMARS: FOUND A SHORT CODE - short_code: 0N; - Tel: 0N”@example.com - Called_Party: XXXXXXXXX@example.com- Line Group Id: 30
09:11:01 18186437mS CMARS: SetCurrentTarget: Short_Code: 0N; - Line_Group_ID: 30
09:11:01 18186437mS CMARS: FindActiveARSByGroupID GroupID=30 - Not Found
09:11:01 18186437mS CMLRQ: FindActiveLRQByGroupID GroupID=30 - Not Found
09:11:01 18186438mS CMTARGET: Group Id: 30 has NOT been found in Line: 13
09:11:01 18186438mS CMCallEvt: 0000000000000000 0.32362.0 -1 BaseEP: NEW CMEndpoint f18c04ec TOTAL NOW=3 CALL_LIST=1
09:11:01 18186440mS CMARS: ModifyCMARSTarget: Short_Code: 0N; - Line_Group_ID: 30 set line status to CMARS_TRYING
09:11:01 18186440mS CMARS: FOUND LINE - Line Id: 17 - using line group id: 30 (code 0N;, line 30) - Called Number: XXXXXXXXX@example - Calling Number: 214
09:11:01 18186441mS CMARS: SEND Setup TO LINE
09:11:01 18186441mS CMCallEvt: 0a8223fd00007e6a 17.32362.0 7151 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
09:11:01 18186443mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=32362 in=0
Called[XXXXXXXXX@example.com] Type=Default (100) Reason=CMDRdirect SndComp Calling[214] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 16608
user 'finance' presentation Allowed
IE CMIEFastStartInfoData (6) 14 item(s)
IE CMIECallingPartyName (110)(Type=CMNameInternalUser) name=finance
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Finance Manager
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=finance
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Finance Manager
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=214
IE CMIEDeviceDetail (231) 0a8223fd00007e67 LOCALE=eng HW=15 VER=11 class=CMDeviceH323Phone type=87 number=15 channel=0 features=0x10000420 rx_gain=32 tx_gain=32
ep_callid=32359 ipaddr=10.130.35.253 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x20 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Locale: eng
09:11:01 18186446mS SIP Tx: UDP 192.168.1.249:5060 -> x.x.x.x:5060
INVITE sip:XXXXXXXXX@example.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.249:5060;rport;branch=z9hG4bKb296f257e323accbc47538176a2f9a30
From: "2000" <sip:2000@example.com>;tag=fdf23f007344a9ad
To: <sip:XXXXXXXXX@example.com>
Call-ID: 7dbd1068906422b4b18b9c63d289d82a
CSeq: 2117248170 INVITE
Contact: "2000" <sip:2000@192.168.1.249:5060;transport=udp>
Proxy-Authorization: Digest username="2000",realm="anonymous.invalid",nonce="6b3c188c-90cd-483c-bb5d-dc7da1f3ae46",response="9efcaababe06b2276307c665d8beeffe",uri="sip:XXXXXXXXX@example.com”,algorithm=MD5,qop=auth,nc=0000
0004,cnonce="b832f157ec7d52bbf9e4"
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
User-Agent: IP Office 11.0.4.4.0 build 6
Content-Type: application/sdp
Content-Length: 301

v=0
o=UserA 3224913659 1783336038 IN IP4 192.168.1.249
s=Session SDP
c=IN IP4 192.168.1.249
t=0 0
m=audio 46750 RTP/AVP 8 0 18 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
09:11:01 18186468mS SIP Rx: UDP x.x.x.x:5060 -> 192.168.1.249:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.249:5060;rport=5060;branch=z9hG4bKb296f257e323accbc47538176a2f9a30;received=213.105.79.42
From: "2000" <sip:2000@example.com>;tag=fdf23f007344a9ad
To: <sip:XXXXXXXXX@example.com>
Call-ID: 7dbd1068906422b4b18b9c63d289d82a
CSeq: 2117248170 INVITE
User-Agent: BHC
Content-Length: 0

09:11:01 18186470mS CMLineRx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=32362 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[214] Type=Internal Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9XXXXXXXXX
IE CMIEDeviceDetail (231) 0a8223fd00007e6a LOCALE=eng HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32
ep_callid=32362 ipaddr=10.130.35.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
09:11:01 18186470mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
09:11:01 18186470mS CMCallEvt: 0a8223fd00007e6a 17.32362.0 7151 SIPTrunk Endpoint: StateChange: END=child CMCSOffering->CMCSAccept
09:11:01 18186470mS CMCallEvt: 0a8223fd00007e67 0.32361.0 7151 ARS for FORMA: StateChange: END=B CMCSOverlapRecv->CMCSAccept
09:11:01 18186471mS CMCallEvt: 0a8223fd00007e67 263.32359.0 7151 finance.0: StateChange: END=A CMCSDialling->CMCSDialled
09:11:01 18186471mS CMExtnEvt: v=12 State, new=Proceeding old=Dialling,0,0,finance
09:11:01 18186472mS CMExtnTx: v=214, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=263 id=32359 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 15/12/20 09:11
09:11:01 18186473mS CMExtnTx: v=214, p1=8012
CMFacility
Line: type=IPLine 250 Call: lid=263 id=12 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:11:01 18186473mS CMExtnTx: v=214, p1=0
CMProceeding
Line: type=IPLine 250 Call: lid=263 id=32359 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[214] Type=Internal Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9XXXXXXXXX
IE CMIEDeviceDetail (231) 0a8223fd00007e6a LOCALE=eng HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32
ep_callid=32362 ipaddr=10.130.35.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
Timed: 15/12/20 09:11
09:11:01 18186703mS SIP Rx: UDP x.x.x.x:5060 -> 192.168.1.249:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 192.168.1.249:5060;rport=5060;branch=z9hG4bKb296f257e323accbc47538176a2f9a30;received=213.105.79.42
From: "2000" <sip:2000@example.com>;tag=fdf23f007344a9ad
To: <sip:XXXXXXXXX@example.com>;tag=v4QSDQF2X9Uyr
Call-ID: 7dbd1068906422b4b18b9c63d289d82a
CSeq: 2117248170 INVITE
User-Agent: BHC
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Length: 0

09:11:01 18186707mS SIP Tx: UDP 192.168.1.249:5060 -> x.x.x.x:5060
ACK sip:XXXXXXXXX@example.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.249:5060;rport;branch=z9hG4bKb296f257e323accbc47538176a2f9a30
From: "2000" <sip:2000@example.com>;tag=fdf23f007344a9ad
To: <sip:XXXXXXXXX@example.com>;tag=v4QSDQF2X9Uyr
Call-ID: 7dbd1068906422b4b18b9c63d289d82a
CSeq: 2117248170 ACK
Max-Forwards: 70
Proxy-Authorization: Digest username="2000",realm="anonymous.invalid",nonce="6b3c188c-90cd-483c-bb5d-dc7da1f3ae46",response="5dec8223f2907053329703d25def199c",uri="sip:XXXXXXXXX@example.com”,algorithm=MD5,qop=auth,nc=0000
0005,cnonce="051aa07a739f6b4e2134"
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
User-Agent: IP Office 11.0.4.4.0 build 6
Content-Length: 0


Thanks,

Al

 

[janni78]

09:11:01 18186437mS CMARS: FOUND A SHORT CODE - short_code: 0N; - Tel: 0N”@example.com - Called_Party: XXXXXXXXX@example.com- Line Group Id: 30

Your shortcode should only be "0N", it's not supposed to include the domain part as it's set by the ITSP Domain setting on the Line.

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

 

[critchey]

Make sure that the anonymous checkbox is unchecked under all users. Also 96XX phones can have the number withheld through the phone itself which you won't see any evidence of in manager.

The truth is just an excuse for lack of imagination.

 

[Swankytmc]

Thanks Janni78 and Critchey. I don't have the anonymous box checked and SIP trunk was set to use internal data. I'm going to try Janni78's suggestion of using ITSP domain info and removing the @example.com from ARS short code.

Just to clarify, if i set ITSDP domain to example.com is this ok for shortcode?:

Dial: 0N;
Telephone number: 0N"
Feature: Dial
Line Group ID: SIP trunk outgoing group ID

Thanks,

Al

 

[janni78]

Remove the " from Telephone Number as well.

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

 

[janni78]

It should be

Dial: 0N;
Telephone number: 0N
Feature: Dial
Line Group ID: SIP trunk outgoing group ID

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

 

[Swankytmc]

I removed the config including and after the "

ITSP domain name was already set. Just a case if keeping an eye on it now. I will report back.

Thanks

 

[Swankytmc]

Hi,

Still been having an odd experience with this one. I made the changes to the ARS form and I thought that the anonymous.invalid from field problem had gone. The customer has reported intermittent bouts of this so I started looking at monitor trace again. Please see redacted output below. We have concluded that IPO is intermittently producing a 405 error as per the below output. The SIP provider requests that the method be updated as follows but cant see how to do this. Any advice or views on the matter would be appreciated. Message, register, subscribe, publish are being requested but again cant see how to do this

12:31:19 1931175009mS SIP Tx: UDP 192.168.1.249:5060 -> x.x.x.x:5060
SIP/2.0 405 Method Not Allowed
Via: SIP/2.0/UDP x.x.x.x ;rport;branch=z9hG4bK4g8ct2eBQNKHm
From: <sip:2000@blah.blah>;tag=0BKgBr7e2Sm3F
Call-ID: eb7d105c-cabd-1239-3ea1-0050568f81cf
CSeq: 30414347 NOTIFY
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.4.4.0 build 6
Content-Length: 0
To: <sip:2000@blah.blah>
Thanks,

Al

 

[critchey]

Personally I would have your customer contact the SIP provider. They are the ones sending you a 405 Method Not Allowed they "should" be able to tell you why they are sending that message.

The truth is just an excuse for lack of imagination.

 

[tac84]

I think I've seen this before. I remember seeing it when I changed my settings on the handset:

Features > Call Settings > Withhold Number to on - Some users may have enabled this?

When this was on, I couldn't make an outbound call via SIP lines as it was sending the call from anonymous@anonymous.invalid which was rejected by the SIP trunk provider. Likely similar to what others have said by ensuring that 'Anonymous' checkbox is clear under users too.

Thanks, Tim
Adelaide, Australia

 

[critchey]

I mentioned the above earlier that you can actually set withhold number through the phone itself and it will not show up anywhere in manager. I know 96XX phones have that option not sure about J series or 16XX phones. Hopefully the OP already checked this but just in case he should.

The truth is just an excuse for lack of imagination.

 

[tac84]

@Critchey sorry mate I overlooked that part of your post.

Thanks, Tim
Adelaide, Australia

 

[Swankytmc]

Hi Critchey, No it's the IPO system sending the 405. I checked with the provider and Message, register, subscribe, publish are missing from the IPO method list. As you can appreciate, it's been a tough one to nail down.

Just wondering if the association method plays a part and if it does for a registered trunk, which method to use.

 

[critchey]

You are right I missed it was a TX packet so the system sent it.

Have you checked the phone you are testing from, one that is failing, to make sure the with hold number is not turned on? This has been stated a few times and as I said earlier you would see NO evidence of it in manager. tac84 said he was getting the exact same error you are. You haven't specified for sure if you have checked this so just want to be sure its not something simple.

The truth is just an excuse for lack of imagination.

 

[G van Hamburg]

Hi Al,

I’d ask the provider for a complete interop document and read that a few times. There is nothing wrong with sending anonymous@anonymous.invalid in the From header, als long as the complete SIP message matches the expectations of the SIP provider.



Freelance Certified Avaya Aura Engineer

 

[janni78]

Again you're only showing part of the SIP trace, what is happening that causes the IPO to answer 405 ?

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

 

[Swankytmc]

critchey, checked and numbers not set to withheld.

Here is a longer set of output. Update is that this happens in spikes and affects a group of outbound attempts.

Called[9] Type=Default (100) Reason=CMDRdirect Keypad[9]
14:03:28 1850295718mS CMMap: a=0.0 b=0.0 pcp[932]b0r1 RTPD0
14:03:28 1850295718mS H323Evt: SESS 238f SetOperational local 10.130.35.253:46752 remote 10.130.35.13:48302 to 0
14:03:29 1850296006mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 0
14:03:29 1850296007mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:29 1850296260mS H323Evt: Recv: RegistrationRequest 10.130.35.5; Extn(212), time since last RRQ-KA 55004, Endpoints registered: 22; Endpoints in registration: 0
14:03:29 1850296905mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 7
14:03:29 1850296906mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
14:03:30 1850297788mS H323Evt: Recv: RegistrationRequest 10.130.35.9; Extn(207), time since last RRQ-KA 55003, Endpoints registered: 22; Endpoints in registration: 0
14:03:30 1850297906mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: TimerExpired cause=CMTCDelayedProcessing
14:03:30 1850297906mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: LOOKUP CALL ROUTE: GID=0 type=100 called_party=907 sub= calling=202 calling_sub= dir=out complete=0 ses=0
14:03:30 1850297906mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: ADD TARGET (N): number=907 type=100 depth=1 nobar=1 setorig=1 ses=0
14:03:30 1850297907mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: USER SC: 907 3 7 sc=type=Dial code=90N, num=0N
14:03:30 1850297907mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
14:03:30 1850297907mS CMARS: MakeCallTarget - Called Number: 07
14:03:30 1850297908mS CMCallEvt: 0000000000000000 0.46242.0 -1 BaseEP: NEW CMEndpoint f1879b10 TOTAL NOW=5 CALL_LIST=2
14:03:30 1850297909mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: CancelTimer CMTCDialToneTimeout
14:03:30 1850297909mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: INITIAL TARGETING SUCCEEDED
14:03:30 1850297909mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: GetNoAnswerTimer:15
14:03:30 1850297909mS CMCallEvt: 0000000000000000 0.46241.0 8239 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
14:03:30 1850297910mS CMCallEvt: 0000000000000000 0.46242.0 8239 ARS for ARSFORM: StateChange: END=T CMCSIdle->CMCSOffering
14:03:30 1850297911mS CMCallEvt: 0000000000000000 0.46241.0 8239 TargetingEP: RequestEnd 0000000000000000 0.46242.0 8239 ARS for ARSFORM
14:03:30 1850297911mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: CancelTimer CMTCNoAnswerTimeout
14:03:30 1850297912mS CMCallEvt: 0000000000000000 0.46242.0 8239 ARS for ARSFORM: StateChange: END=B CMCSOffering->CMCSOverlapRecv
14:03:30 1850297912mS CMARS: FORM: ARSFORM - Received Number: 07
14:03:30 1850297912mS CMARS: CMARSTargetingPending
14:03:30 1850297912mS CMCallEvt: 0000000000000000 0.46241.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
14:03:30 1850297912mS CMCallEvt: 0000000000000000 0.46241.0 -1 BaseEP: DELETE CMEndpoint f192d0b4 TOTAL NOW=4 CALL_LIST=2
14:03:31 1850298355mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 7
14:03:31 1850298355mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
14:03:31 1850298356mS CMARS: FORM: ARSFORM - Received Number: 070
14:03:31 1850298356mS CMARS: CMARSTargetingPending
14:03:31 1850298975mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 1
14:03:31 1850298976mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
14:03:31 1850298977mS CMARS: FORM: ARSFORM - Received Number: 0771
14:03:31 1850298977mS CMARS: CMARSTargetingPending
14:03:32 1850299177mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 4
14:03:32 1850299177mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:32 1850299178mS CMARS: FORM: ARSFORM - Received Number: 0700
14:03:32 1850299178mS CMARS: CMARSTargetingPending
14:03:32 1850299455mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 9
14:03:32 1850299456mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[9] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:32 1850299456mS CMARS: FORM: ARSFORM - Received Number: 070000
14:03:32 1850299456mS CMARS: CMARSTargetingPending
14:03:32 1850299727mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 2
14:03:32 1850299727mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:32 1850299728mS CMARS: FORM: ARSFORM - Received Number: 0700000
14:03:32 1850299728mS CMARS: CMARSTargetingPending
14:03:32 1850299937mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 4
14:03:32 1850299937mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:32 1850299938mS CMARS: FORM: ARSFORM - Received Number: 07000000
14:03:32 1850299938mS CMARS: CMARSTargetingPending
14:03:33 1850300098mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 1
14:03:33 1850300098mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:33 1850300099mS CMARS: FORM: ARSFORM - Received Number: 0700000000 14:03:33 1850300099mS CMARS: CMARSTargetingPending
14:03:33 1850300346mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 0
14:03:33 1850300347mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
14:03:33 1850300347mS CMARS: FORM: ARSFORM - Received Number: 0700000000
14:03:33 1850300347mS CMARS: CMARSTargetingPending
14:03:33 1850300381mS RES: Tue 5/1/2021 14:03:32 FreeMem=59599144 Heap=58908876(4) Cache=690268 MemObjs=10313(Max 12943) CMMsg=7(7) ASN=1 Buff=5200 1361 1000 7417 6 Links=54246(54666) BTree=698(1585) CB=6050 MCT=0 CPU=09.90% CPUStats=09.08%/2/7/1962/2036
3/24404/00.35%/0/02.43% MCR=0
14:03:33 1850300381mS RES2: IP 500 V2 11.0.4.4.0 build 6 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=14+103 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=41(TLS=6 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:03:33 1850300381mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
14:03:33 1850300381mS RES5: CLog MemObjs=679 FreePoolMem(Objs)=6776(121) TotalMem=44800 StringsTotalMem=103600
14:03:33 1850300547mS CMExtnEvt: v=(null) State, new=Idle old=Idle,0,0,202: Digit Key Pressed 7
14:03:33 1850300547mS CMExtnRx: v=202, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
14:03:33 1850300548mS CMARS: FORM: ARSFORM - Received Number: 07xxxxxxx
14:03:33 1850300548mS CMARS: CMARSTargetingPending
14:03:36 1850303908mS CMARS: Case 1 - dial_delay_time: 3000 has expired - Resolve what we might have - Called Number: 07xxxxxxx Addr: f1879b10
14:03:36 1850303908mS CMARS: FORM: ARSFORM - Received Number: 07xxxxxxx
14:03:36 1850303908mS CMARS: FOUND A SHORT CODE - short_code: 0N; - Tel: 0N - Called_Party: 07xxxxxxx - Line Group Id: 30
14:03:36 1850303908mS CMARS: SetCurrentTarget: Short_Code: 0N; - Line_Group_ID: 30
14:03:36 1850303909mS CMARS: FindActiveARSByGroupID GroupID=30 - Not Found
14:03:36 1850303909mS CMLRQ: FindActiveLRQByGroupID GroupID=30 - Not Found
14:03:36 1850303909mS CMTARGET: Group Id: 30 has NOT been found in Line: 13
14:03:36 1850303909mS CMCallEvt: 0000000000000000 0.46243.0 -1 BaseEP: NEW CMEndpoint f18a09d0 TOTAL NOW=5 CALL_LIST=2
14:03:36 1850303911mS CMARS: ModifyCMARSTarget: Short_Code: 0N; - Line_Group_ID: 30 set line status to CMARS_TRYING
14:03:36 1850303912mS CMARS: FOUND LINE - Line Id: 17 - using line group id: 30 (code 0N;, line 30) - Called Number: 07xxxxxxx - Calling Number: 202
14:03:36 1850303912mS CMARS: SEND Setup TO LINE
14:03:36 1850303912mS CMCallEvt: 0a8223fd0000b4a3 17.46243.0 8239 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
14:03:36 1850303914mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=46243 in=0
Called[07xxxxxxx] Type=Default (100) Reason=CMDRdirect SndComp Calling[202] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 17523
user 'Reception2' presentation Allowed
IE CMIEFastStartInfoData (6) 14 item(s)
IE CMIECallingPartyName (110)(Type=CMNameInternalUser) name=Reception2
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Reception 2
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Reception2
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Reception 2
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=202
IE CMIEDeviceDetail (231) 0a8223fd0000b4a0 LOCALE=eng HW=15 VER=11 class=CMDeviceH323Phone type=88 number=4 channel=0 features=0x10000420 rx_gain=32 tx_gain=32
ep_callid=46240 ipaddr=10.130.35.253 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x20 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 7)
}
Locale: eng
14:03:36 1850303917mS SIP Tx: UDP 192.168.1.249:5060 -> x.x.x.x:5060
INVITE sip:07xxxxxxx@sipprovider.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.249:5060;rport;branch=z9hG4bK8e5c1f0e8b93a112ea76d941bf3d6947
From: "2000" <sip:2000@sipprovider.com>;tag=c4b061edf9a5418a
To: <sip:07xxxxxxx@sipprovider.com>
Call-ID: 14164130922706f9fb51df95278e7786
CSeq: 158095855 INVITE
Contact: "2000" <sip:2000@192.168.1.249:5060;transport=udp>
Proxy-Authorization: Digest username="2000",realm="anonymous.invalid",nonce="f6e89196-2614-451b-ab5e-ab42fb05b47e",response="339969b97dbe6f1b3793ab232b399757",uri="sip:07xxxxxxx@sipprovider.com",algorithm=MD5,qop=auth,nc=0000
000e,cnonce="ea2163bf0dabd179decc"
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
User-Agent: IP Office 11.0.4.4.0 build 6
Content-Type: application/sdp
Content-Length: 301

v=0
o=UserA 1205259128 2454588752 IN IP4 192.168.1.249
s=Session SDP
c=IN IP4 192.168.1.249
t=0 0
m=audio 46750 RTP/AVP 8 0 18 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
14:03:36 1850303939mS SIP Rx: UDP x.x.x.x:5060 -> 192.168.1.249:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.249:5060;rport=5060;branch=z9hG4bK8e5c1f0e8b93a112ea76d941bf3d6947;received=213.105.79.42
From: "2000" <sip:2000@sipprovider.com>;tag=c4b061edf9a5418a
To: <sip:07xxxxxxx@sipprovider.com>
Call-ID: 14164130922706f9fb51df95278e7786
CSeq: 158095855 INVITE
User-Agent: BHC
Content-Length: 0

14:03:36 1850303941mS CMLineRx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=46243 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[202] Type=Internal Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=907xxxxxxx
IE CMIEDeviceDetail (231) 0a8223fd0000b4a3 LOCALE=eng HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32
ep_callid=46243 ipaddr=10.130.35.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
14:03:36 1850303941mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
14:03:36 1850303941mS CMCallEvt: 0a8223fd0000b4a3 17.46243.0 8239 SIPTrunk Endpoint: StateChange: END=child CMCSOffering->CMCSAccept
14:03:36 1850303941mS CMCallEvt: 0a8223fd0000b4a0 0.46242.0 8239 ARS for ARSFORM: StateChange: END=B CMCSOverlapRecv->CMCSAccept
14:03:36 1850303942mS CMCallEvt: 0a8223fd0000b4a0 252.46240.0 8239 Reception2.0: StateChange: END=A CMCSDialling->CMCSDialled
14:03:36 1850303942mS CMExtnEvt: v=19 State, new=Proceeding old=Dialling,0,0,Reception2
14:03:36 1850303943mS CMExtnTx: v=202, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 05/01/21 14:03
14:03:36 1850303944mS CMExtnTx: v=202, p1=8001
CMFacility
Line: type=IPLine 250 Call: lid=252 id=19 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
14:03:36 1850303944mS CMExtnTx: v=202, p1=0
CMProceeding
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[202] Type=Internal Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=907xxxxxxx
IE CMIEDeviceDetail (231) 0a8223fd0000b4a3 LOCALE=eng HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32
ep_callid=46243 ipaddr=10.130.35.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
Timed: 05/01/21 14:03
14:03:36 1850303947mS SIP Rx: UDP 178.255.63.75:5060 -> 192.168.1.249:5060
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 192.168.1.249:5060;rport=5060;branch=z9hG4bK8e5c1f0e8b93a112ea76d941bf3d6947;received=213.105.79.42
From: "2000" <sip:2000@sipprovider.com>;tag=c4b061edf9a5418a
To: <sip:07xxxxxxx@sipprovider.com>;tag=54mNry2e7NUaj
Call-ID: 14164130922706f9fb51df95278e7786
CSeq: 158095855 INVITE
User-Agent: BHC
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Length: 0

14:03:36 1850303951mS SIP Tx: UDP 192.168.1.249:5060 -> x.x.x.x:5060
ACK sip:07xxxxxxx@sipprovider.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.249:5060;rport;branch=z9hG4bK8e5c1f0e8b93a112ea76d941bf3d6947
From: "2000" <sip:2000@sipprovider.com>;tag=c4b061edf9a5418a
To: <sip:07xxxxxxx@sipprovider.com>;tag=54mNry2e7NUaj
Call-ID: 14164130922706f9fb51df95278e7786
CSeq: 158095855 ACK
Max-Forwards: 70
Proxy-Authorization: Digest username="2000",realm="anonymous.invalid",nonce="f6e89196-2614-451b-ab5e-ab42fb05b47e",response="de0b489fa3281a6465896becfcebdb8e",uri="sip:07xxxxxxx@sipprovider.com",algorithm=MD5,qop=auth,nc=0000
000f,cnonce="a2ba12dba4ab5deb1e57"
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
User-Agent: IP Office 11.0.4.4.0 build 6
Content-Length: 0

14:03:36 1850303952mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=46243 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[202] Type=Internal Plan=Default
IE CMIEFeedback (188) Ver=1 Size=5 Type=2 Reason=403
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=907xxxxxxx
IE CMIEDeviceDetail (231) 0a8223fd0000b4a3 LOCALE=eng HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x1 rx_gain=32 tx_gain=32
ep_callid=46243 ipaddr=10.130.35.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
Cause=21, Call rejected
14:03:36 1850303952mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSAccept - ARS Call State = CMCSAccept
14:03:36 1850303952mS CMCallEvt: 0a8223fd0000b4a0 0.46242.0 8239 ARS for ARSFORM: StateChange: END=B CMCSAccept->CMCSCompleted
14:03:36 1850303953mS CMLOGGING: CALL:2021/01/0514:03,00:00:00,000,202,O,907xxxxxxx,907xxxxxxx,Reception2,,,1,,"",0,n/a
14:03:36 1850303954mS CMExtnEvt: Reception2: CALL LOST (CMCauseCallRejected)
14:03:36 1850303954mS CMExtnEvt: Reception2: Extn(202) Calling Party Number(202) Type(CMNTypeInternal)
14:03:36 1850303954mS CMCallEvt: 0a8223fd0000b4a0 252.46240.0 -1 Reception2.0: StateChange: END=X CMCSDialled->CMCSCompletedTone
14:03:36 1850303957mS CMExtnTx: v=202, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 05/01/21 14:03
14:03:36 1850303957mS H323Evt: PortmAvayaH323Phone:ortmPhoneDelayFacMsg: 202 - delay disabled
14:03:36 1850303957mS CMExtnTx: v=202, p1=8001
CMFacility
Line: type=IPLine 250 Call: lid=252 id=19 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
14:03:36 1850303958mS CMExtnEvt: v=19 State, new=CMESCompleted old=Proceeding,0,0,Reception2
14:03:36 1850303958mS CMARS: CMARSEndpoint::CallLost(cause=21) - Address: 0a8223fd0000b4a0 0.46242.0 -1 ARS for ARSFORM - Call State: CMCSCompleted
14:03:36 1850303959mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=46243 in=0
Cause=21, Call rejected
14:03:36 1850303959mS CMCallEvt: 0a8223fd0000b4a3 17.46243.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSAccept->CMCSDelete
14:03:36 1850303960mS CMCallEvt: 0a8223fd0000b4a0 0.46242.0 -1 ARS for ARSFORM: StateChange: END=X CMCSCompleted->CMCSDelete
14:03:36 1850303960mS CMCallEvt: END CALL:8239 (f194b4e4)
14:03:36 1850303960mS CMCallEvt: 0a8223fd0000b4a0 0.46242.0 -1 BaseEP: DELETE CMEndpoint f1879b10 TOTAL NOW=4 CALL_LIST=1
14:03:36 1850303962mS CMCallEvt: 0a8223fd0000b4a3 17.46243.0 -1 BaseEP: DELETE CMEndpoint f18a09d0 TOTAL NOW=3 CALL_LIST=1
14:03:36 1850303963mS CMMap: PCG::AddToneGenerator g E1[7] for cp[932]b0r1 append pcp[935]b0r1 (total 1)
14:03:36 1850303963mS CMMap: PlatformConnectionAudioSAP::Bind pcp[935]b0r1 to 0.0
14:03:36 1850303963mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[935]b0r1 Configure 0.0
14:03:36 1850303963mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[935]b0r1 ConnectIndication 0.0
14:03:36 1850303963mS CMMap: a=0.0 b=0.0 pcp[932]b0r1 RTPE1
14:03:36 1850303963mS H323Evt: SESS 238f SetOperational local 10.130.35.253:46752 remote 10.130.35.13:48302 to 1
14:03:37 1850304496mS H323Evt: SESS 238f: RTP(50t): 10.130.35.253/46752 10.130.35.13/48302 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=9234 SENT=50 (avg size=164) RECV=35 (avg size=160)

********** SysMonitor v11.0.4.4.0 build 6 [connected to 192.168.1.249 (CAS)] **********
14:03:37 1850304916mS PRN: Monitor Status IP 500 V2 11.0.4.4.0 build 6
14:03:37 1850304916mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=1(TOT=8239)
14:03:39 1850306205mS H323Evt: Recv: RegistrationRequest 10.130.35.12; Extn(210), time since last RRQ-KA 55004, Endpoints registered: 22; Endpoints in registration: 0
14:03:40 1850307229mS CMExtnTx: v=202, p1=8001
CMFacility
Line: type=IPLine 250 Call: lid=252 id=19 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
14:03:40 1850307229mS CMExtnRx: v=202, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
14:03:40 1850307229mS CMCallEvt: 0a8223fd0000b4a0 252.46240.0 -1 Reception2.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
14:03:40 1850307230mS CMExtnTx: v=202, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=252 id=46240 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 05/01/21 14:03
14:03:40 1850307231mS CMExtnEvt: v=19 State, new=PortRecoverDelay old=CMESCompleted,0,0,Reception2
14:03:40 1850307231mS CMExtnEvt: Reception2: CALL LOST (CMCauseForceClear)
14:03:40 1850307231mS CMExtnEvt: Reception2: Extn(202) Calling Party Number(202) Type(CMNTypeInternal)
14:03:40 1850307231mS CMExtnEvt: Reception2: CMExtnHandler::SetCurrent( id: 46240->0 )
14:03:40 1850307232mS CMCallEvt: 0a8223fd0000b4a0 252.46240.0 -1 Reception2.-1: StateChange: END=X CMCSCompleted->CMCSDelete
14:03:40 1850307233mS CMTARGET: 0a8223fd0000b4a0 252.46240.0 -1 BaseEP: ~CMTargetHandler f193e1d0 ep f5353c54
14:03:40 1850307233mS CMCallEvt: 0a8223fd0000b4a0 252.46240.0 -1 BaseEP: DELETE CMEndpoint f5353c54 TOTAL NOW=2 CALL_LIST=1
14:03:40 1850307235mS CMMap: a=0.0 b=0.0 pcp[932]b0r1 RTPE0
14:03:40 1850307235mS H323Evt: SESS 238f SetOperational local 10.130.35.253:46752 remote 10.130.35.13:48302 to 0
14:03:40 1850307235mS H323Evt: SESS 238f: RTP(END): 10.130.35.253/46752 10.130.35.13/48302 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=off AGE=11974 SENT=186 RECV=172 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
14:03:40 1850307383mS RES: Tue 5/1/2021 14:03:39 FreeMem=59604080 Heap=58893344(5) Cache=710736 MemObjs=10292(Max 12943) CMMsg=7(7) ASN=1 Buff=5200 1359 1000 7418 6 Links=54195(54666) BTree=701(1585) CB=6054 MCT=0 CPU=09.47% CPUStats=08.36%/2/4/1962/2040
9/24404/00.44%/0/02.42% MCR=0
14:03:40 1850307383mS RES2: IP 500 V2 11.0.4.4.0 build 6 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=13+106 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=41(TLS=6 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
14:03:40 1850307383mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
14:03:40 1850307383mS RES5: CLog MemObjs=679 FreePoolMem(Objs)=6776(121) TotalMem=44800 StringsTotalMem=103600
14:03:42 1850309232mS CMExtnEvt: Reception2: Recover Timer reason=CMTRWrapUp
14:03:42 1850309232mS CMExtnEvt: v=19 State, new=Idle old=PortRecoverDelay,0,0,Reception2
14:03:42 1850309233mS CMExtnTx: v=202, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Reception2 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Reception2 Msgs=0]
Timed: 05/01/21 14:03
14:03:42 1850310641mS H323Evt: Recv: RegistrationRequest 10.130.35.16; Extn(205), time since last RRQ-KA 55002, Endpoints registered: 22; Endpoints in registration: 0
14:03:42 1850310935mS H323Evt: Recv: RegistrationRequest 10.130.35.10; Extn(218), time since last RRQ-KA 55004, Endpoints registered: 22; Endpoints in registration: 0
14:03:43 1850311643mS H323Evt: Recv: RegistrationRequest 10.130.35.27; Extn(221), time since last RRQ-KA 55001, Endpoints registered: 22; Endpoints in registration: 0

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

 

[G van Hamburg]

I do not know what SIP provider you use but in The Netherlands we get credentials from the SIP provider to register the trunk. Often the username is the customer's main number but sometimes a random 8 to 10 digit code. When the trunk is registered you can receive incoming calls. To make outside calls we sent an invite which is of course rejected (we receive a nonce) because we need to authenticate and then we resend the invite again with the repsonse and the proper credentials. In your trace I see the 2000 as the digest username and I doubt if that is correct. But also, to me this looks like the second register and if so, I would like to see the first register too.

For what it’s worth. Over here the SIP providers mostly deny messages with an extension in the from or/and without a PAI. That’s why I asked also to get an interop document from the SIP provider.

Freelance Certified Avaya Aura Engineer