Spoke to the SIP vendor and they confirmed the Vmpro address is not filtered.
I run a trace and found this:
17:08:57 34919345mS RES: Sat 17/12/2022 17:08:57 FreeMem=55960228 Heap=55601016(1) Cache=359212 MemObjs=12441(Max 13384) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7427 5 Links=55317(55522) BTree=831(1777) CB=6059 MCT=0 CPU=06.77% CPUStats=05.83%/1/4/4942/13
852/15031/00.36%/1/02.97% MCR=0 MCW=0 DEV=0
17:08:57 34919345mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+61 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=27(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:08:57 34919346mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:08:57 34919346mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:07 34929949mS H323Evt: v=0 stacknum=18 State, new=NullState, old=NullState id=-1
17:09:07 34929950mS H323Evt: v=0 stacknum=18 State, new=Present, old=NullState id=23
17:09:07 34929953mS CMLineRx: v=18
CMSetup
Line: type=IPLine 18 Call: lid=18 id=23 in=1
Called[045628401] Type=Default (100) Reason=CMDRdirect SndComp Calling[1031] 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.CodePageBmpString
invokeId 38096
user 'Robel ACW' presentation Allowed
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIECallingPartyName (110)(Type=CMNameInternalUser) name=Robel ACW
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=0 (0000) id=126561 (1ee61) in=0 (0000)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 101, rxp 101, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Robel ACW
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Robel ACW
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Robel ACW
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=1031
IE CMIEDeviceDetail (231) 0a0a0c050001ee5e LOCALE=aru HW=11 VER=11 class=CMDeviceSIPPhone type=151 number=1 channel=0 features=0x20110020 rx_gain=32 tx_gain=32
ep_callid=126558 ipaddr=10.10.12.5 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=045628401
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 3)
}
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 3)
}
17:09:07 34929954mS CMCallEvt: 0000000000000000 0.1313.0 -1 BaseEP: NEW CMEndpoint f16c0844 TOTAL NOW=3 CALL_LIST=1
17:09:07 34929954mS CMCallEvt: CREATE CALL:23 (f16c2ff4)
17:09:07 34929955mS CMCallEvt: 0000000000000000 0.1314.0 -1 BaseEP: NEW CMEndpoint f16dba60 TOTAL NOW=4 CALL_LIST=1
17:09:07 34929957mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSIdle->CMCSDialInitiated
17:09:07 34929958mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: LOOKUP CALL ROUTE: GID=0 type=100 called_party=045628401 sub= calling=1031 calling_sub= dir=out complete=1 ses=0
17:09:07 34929959mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: ADD TARGET (N): number=045628401 type=100 depth=1 nobar=1 setorig=1 ses=0
17:09:07 34929959mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: DEF SC: 045628401 0 sc=type=Dial code=?, num=.
17:09:07 34929960mS CMARS: FindActiveARSByGroupID GroupID=53 - Found
17:09:07 34929960mS CMARS: MakeCallTarget - Called Number: 045628401
17:09:07 34929960mS CMCallEvt: 0000000000000000 0.1315.0 -1 BaseEP: NEW CMEndpoint f16b4660 TOTAL NOW=5 CALL_LIST=2
17:09:07 34929961mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: INITIAL TARGETING SUCCEEDED
17:09:07 34929962mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: GetNoAnswerTimer:15
17:09:07 34929962mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSDialInitiated->CMCSDialled
17:09:07 34929964mS CMLineTx: v=18
CMProceeding
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 101, rxp 101, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
17:09:07 34929964mS CMCallEvt: 0000000000000000 0.1314.0 23 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
17:09:07 34929965mS CMCallEvt: 0000000000000000 0.1315.0 23 ARS for BRI B 2025: StateChange: END=T CMCSIdle->CMCSOffering
17:09:07 34929965mS CMCallEvt: 0000000000000000 0.1314.0 23 TargetingEP: RequestEnd 0000000000000000 0.1315.0 23 ARS for BRI B 2025
17:09:07 34929966mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: CancelTimer CMTCNoAnswerTimeout
17:09:07 34929967mS CMCallEvt: 0000000000000000 0.1315.0 23 ARS for BRI B 2025: StateChange: END=B CMCSOffering->CMCSOverlapRecv
17:09:07 34929967mS CMARS: FORM: BRI B 2025 - Received Number: 045628401
17:09:07 34929967mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: Ns042432025 - Called_Party: 045628401 - Line Group Id: 12
17:09:07 34929967mS CMARS: SetCurrentTarget: Short_Code: N; - Line_Group_ID: 12
17:09:07 34929967mS CMARS: FindActiveARSByGroupID GroupID=12 - Not Found
17:09:07 34929968mS CMLRQ: FindActiveLRQByGroupID GroupID=12 - Not Found
17:09:07 34929968mS CMTARGET: Group Id: 12 has NOT been found in Line: 5
17:09:07 34929968mS CMMap: a=2.17 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f53951f0) resource busy 2, total 42
17:09:07 34929969mS CMCallEvt: 0000000000000000 0.1316.0 -1 BaseEP: NEW CMEndpoint f16a932c TOTAL NOW=6 CALL_LIST=2
17:09:07 34929969mS CMARS: ModifyCMARSTarget: Short_Code: N; - Line_Group_ID: 12 set line status to CMARS_TRYING
17:09:07 34929969mS CMARS: FOUND LINE - Line Id: 6 - using line group id: 12 (code N;, line 12) - Called Number: 045628401 - Calling Number: 042432025
17:09:07 34929970mS CMARS: SEND Setup TO LINE
17:09:07 34929970mS CMCallEvt: 0a0a0c0600000524 0.1316.0 23 Q931 Trunk:6 CHAN=-1: StateChange: END=child CMCSIdle->CMCSOffering
17:09:07 34929970mS CMLineTx: v=6
CMSetup
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
Called[045628401] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[042432025] Type=Unknown Plan=Unknown
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
Locale: aru
17:09:07 34929971mS CMCallEvt: 0000000000000000 0.1314.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
17:09:07 34929971mS CMCallEvt: 0000000000000000 0.1314.0 -1 BaseEP: DELETE CMEndpoint f16dba60 TOTAL NOW=5 CALL_LIST=2
17:09:07 34929974mS ISDNL3Evt: v=6 stacknum=6 State, new=Initiated, old=NullState id=1316
17:09:07 34929974mS H323Evt: v=0 stacknum=18 State, new=ICProceeding, old=Present id=23
17:09:08 34930127mS ISDNL3Evt: v=6 stacknum=6 State, new=Proceeding, old=Initiated id=1316
17:09:08 34930127mS CMLineRx: v=6
CMProceeding
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
BChan: slot=0 chan=2
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
17:09:08 34930128mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
17:09:08 34930129mS CMARS: CallProceeding with PI=8 received
17:09:08 34930129mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
17:09:08 34930129mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0a0a0c050001ee5e 0.1315.0 23 ARS for BRI B 2025 - Call State: CMCSOverlapRecv
17:09:08 34930129mS CMCallEvt: 0a0a0c050001ee5e 0.1315.0 -1 ARS for BRI B 2025: StateChange: END=X CMCSOverlapRecv->CMCSDelete
17:09:08 34930130mS CMCallEvt: 0a0a0c0600000524 0.1316.0 23 Q931 Trunk:6 CHAN=2: StateChange: END=B CMCSOffering->CMCSAccept
17:09:08 34930132mS CMMap: IP::SetCodec pcp[138]b0r0 0 -> f53951f0
17:09:08 34930134mS CMLineTx: v=18
CMProgress
Line: type=IPLine 18 Call: lid=18 id=23 in=1
BChan: slot=3 chan=6
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 101, rxp 101, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=045628401
IE CMIEDeviceDetail (231) 0a0a0c0600000524 LOCALE=aru HW=15 VER=11 class=CMDeviceISDNTrunk type=0 number=6 channel=2 features=0x1 rx_gain=32 tx_gain=32
ep_callid=1316 ipaddr=10.10.12.6 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
17:09:08 34930135mS CMCallEvt: 0a0a0c050001ee5e 0.1315.0 -1 BaseEP: DELETE CMEndpoint f16b4660 TOTAL NOW=4 CALL_LIST=2
17:09:08 34930138mS H323Evt: SESS 1d SetOperational local 10.10.12.6:0 remote 0.0.0.0:0 to 0
17:09:08 34930138mS H323Evt: SESS 1d Configure: Alaw64K packet size 160
17:09:08 34930139mS H323Evt: SESS 1d SetRemUDP 0 -> 48400, remote IP 0.0.0.0 -> 10.10.12.5
17:09:08 34930139mS H323Evt: SESS 1d SetRfc2833: (1) rx payload 101 tx payload 101
17:09:08 34930139mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
17:09:08 34930139mS CMMap: PCGS CPReserveCodec for the other end (pcp[138]b0r1) true
17:09:08 34930140mS CMMap: PCG::MapBChan pcp[107]b1r0 cp_b f53513f0 other_cp_b f5395054 type CGTypeSimple
17:09:08 34930140mS CMMap: a=2.17 b=3.6 M12
17:09:08 34930140mS CMMap: PlatformConnectionAudioSAP::Bind pcp[138]b0r1 to 2.17
17:09:08 34930140mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[138]b0r1 Configure 2.17
17:09:08 34930140mS PRN: rtpRelayFilter ioctl(open) rc: 0 rtpRelayId: 2
17:09:08 34930141mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelayId: 2
17:09:08 34930141mS PRN: rtpRelayFilter configure rc: 0 rtpRelayId: 2
17:09:08 34930141mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success rtpRelayId: 2
17:09:08 34930141mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 10.10.12.6:46752, Remote IP addr 10.10.12.5:48400
17:09:08 34930141mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.7:32768, telogyHost IP addr 1.1.1.1:62
17:09:08 34930141mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success rtpRelayId: 2
17:09:08 34930142mS CMMap: pcp[138]b0r1 rtpRelayId: 2 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
17:09:08 34930142mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[138]b0r1 ConnectIndication 2.17
17:09:08 34930142mS PRN: RTCP collector is NOT initialized
17:09:08 34930142mS H323Evt: SESS 1d SetOperational local 10.10.12.6:46752 remote 10.10.12.5:48400 to 1
17:09:08 34930142mS PRN: RTCP collector is NOT initialized
17:09:08 34930377mS RES: Sat 17/12/2022 17:09:07 FreeMem=55920572 Heap=55592620(2) Cache=327952 MemObjs=12592(Max 13384) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7415 5 Links=55389(55522) BTree=845(1777) CB=6054 MCT=0 CPU=07.23% CPUStats=15.57%/20/31/4942/
12729/15031/00.62%/1/03.11% MCR=0 MCW=0 DEV=0
17:09:08 34930378mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+62 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=27(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:08 34930378mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:08 34930378mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:08 34930384mS CMLineRx: v=6
CMProgress
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDDestNotISDN (2)
17:09:08 34930385mS CMCallEvt: 0a0a0c0600000524 0.1316.0 23 Q931 Trunk:6 CHAN=2: StateChange: END=B CMCSAccept->CMCSRinging
17:09:08 34930386mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSDialled->CMCSRingBack
17:09:08 34930389mS CMLineTx: v=18
CMProgress
Line: type=IPLine 18 Call: lid=18 id=23 in=1
BChan: slot=3 chan=6
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDDestNotISDN (2)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=045628401
IE CMIEDeviceDetail (231) 0a0a0c0600000524 LOCALE=aru HW=15 VER=11 class=CMDeviceISDNTrunk type=0 number=6 channel=2 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1316 ipaddr=10.10.12.6 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
17:09:08 34930391mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:08 34930391mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:08 34930391mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:08 34930391mS CMMap: a=3.6 b=2.17 M12
17:09:08 34930392mS PRN: RTCP collector is NOT initialized
17:09:08 34930392mS PRN: RTCP collector is NOT initialized
17:09:08 34930509mS SIP Rx: UDP 10.59.108.25:5060 -> 10.255.1.194:5060
INVITE sip:045628401@10.255.1.194:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK89c79eb336a4f689eee85330fab54e036233c45f-0-476a6ea6-639dbf741c75f51f;_aluscr_
Route: <sip:045628401@du.ae;user=phone>
Content-Length: 290
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
To: <sip:045628401@du.ae;user=phone>
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 1 INVITE
Max-Forwards: 68
Request-Disposition: no-fork
P-Asserted-Identity: <sip:042432025@du.com.ae;user=phone>
P-Asserted-Identity: <tel:042432025;phone-context=+971>
Contact: <sip:lucentNGFS-083444@10.59.108.25:5060;x-afi=128;encoded-parm=QbkRBthOEgsTXgkTBA0HHiUrKz1CQElBR0BBNhEMGAklOiF0dGguJTpkJiIufn9jfz8wN2FkY3ZqPUFCXVFBQVhKUh5BXAZYVlRTVg__>
Supported: norefersub,histinfo,100rel
Allow: INVITE,BYE,REGISTER,ACK,OPTIONS,CANCEL,SUBSCRIBE,NOTIFY,PRACK,INFO,REFER,UPDATE
Content-Type: application/sdp
History-Info: <sip:045628401@du.ae;user=phone>;index=1
P-Called-Party-ID: <sip:045628401@du.ae;user=phone>
P-NOKIA.Traffica: S1;i="8AC82F87835900F5D3447597@4d70ffffffff"
P-NOKIA.Traffica: I1;t=1671282548.385
v=0
o=LucentPCSF 1684390706 1684390706 IN IP4 i4.sbc.du.com
s=-
c=IN IP4 10.59.109.72
t=0 0
m=audio 43098 RTP/AVP 96 8 0 18 9 97 13
a=rtpmap:96 AMR/8000
a=fmtp:96 mode-change-capability=2; max-red=0
a=fmtp:18 annexb=yes
a=rtpmap:97 telephone-event/8000
a=ptime:20
a=maxptime:20
17:09:08 34930514mS CMCallEvt: 0000000000000000 0.1317.0 -1 BaseEP: NEW CMEndpoint f4fc2d64 TOTAL NOW=5 CALL_LIST=2
17:09:08 34930515mS NAT: Info: Line 25: Not using STUN for media in this case.
17:09:08 34930517mS SIP Tx: UDP 10.255.1.194:5060 -> 10.59.108.25:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK89c79eb336a4f689eee85330fab54e036233c45f-0-476a6ea6-639dbf741c75f51f;_aluscr_
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer,100rel
Server: IP Office 11.0.0.0.0 build 849
To: <sip:045628401@du.ae;user=phone>;tag=55143420adf80876
Content-Length: 0
17:09:08 34930520mS CMCallEvt: CREATE CALL:24 (f16b72d8)
17:09:08 34930521mS CMCallEvt: 0000000000000000 0.1318.0 -1 BaseEP: NEW CMEndpoint f16dba60 TOTAL NOW=6 CALL_LIST=2
17:09:08 34930523mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
Called[045628401] Type=Default (100) Reason=CMDRdirect SndComp Calling[042432025@du.com.ae] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=042432025@du.com.ae
IE CMIEDeviceDetail (231) 0a0a0c0600000525 LOCALE=aru HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=25 channel=1 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1317 ipaddr=10.10.12.6 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
17:09:08 34930524mS CMCallEvt: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
17:09:08 34930524mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: LOOKUP CALL ROUTE: GID=100 type=100 called_party=045628401 sub= calling=042432025@du.com.ae calling_sub= dir=in complete=1 ses=0
17:09:08 34930524mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=1208
17:09:08 34930524mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: SET BESTMATCH: length 9 vs 0 match=045628401 dest=VM:ExtendedHours
17:09:08 34930525mS CMCallEvt: Priority hike: call 24 priority 0->1
17:09:08 34930525mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: LOOKUP ICR: DDI=045628401 CGPN=042432025@du.com.ae (Destination VM:ExtendedHours ) => CDPN=VM:ExtendedHours
17:09:08 34930525mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: ADD TARGET (N): number=VM:ExtendedHours type=100 depth=1 nobar=1 setorig=1 ses=0
17:09:08 34930525mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: ADD VM TARGET
17:09:08 34930526mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=2
17:09:08 34930526mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: MakeTargetLine number 18
17:09:08 34930526mS CMCallEvt: 0000000000000000 0.1319.0 -1 BaseEP: NEW CMEndpoint f16b4d78 TOTAL NOW=7 CALL_LIST=3
17:09:08 34930528mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
17:09:08 34930529mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
17:09:08 34930529mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: GetNoAnswerTimer:15
17:09:08 34930529mS CMCallEvt: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
17:09:08 34930530mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
17:09:08 34930531mS CMCallEvt: 0000000000000000 0.1318.0 24 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
17:09:08 34930532mS CMCallEvt: 0a0a0c0600000527 0.1319.0 24 H323TrunkEP: StateChange: END=T CMCSIdle->CMCSOffering
17:09:08 34930533mS CMLineTx: v=18
CMSetup
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
Called[] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[042432025@du.com.ae] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIECallSignalChannelData (8) unknown
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 97, rxp 97, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=042432025@du.com.ae
IE CMIEDeviceDetail (231) 0a0a0c0600000525 LOCALE=aru HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=25 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1317 ipaddr=10.10.12.6 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=045628401
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
Display [++ffExtendedHours]
Timed: 17/12/22 17:09
Locale: aru
17:09:08 34930536mS H323Evt: v=0 stacknum=18 State, new=NullState, old=NullState id=-1
17:09:08 34930538mS H323Evt: v=0 stacknum=18 State, new=Initiated, old=NullState id=1319
17:09:08 34930558mS H323Evt: v=0 stacknum=18 State, new=Proceeding, old=Initiated id=1319
17:09:08 34930558mS CMLineRx: v=18
CMProceeding
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=7 (0007) id=539 (021b) in=1 (0001)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 97, rxp 97, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
17:09:08 34930559mS CMCallEvt: 0000000000000000 0.1318.0 24 TargetingEP: RequestEnd 0a0a0c0600000527 0.1319.0 24 H323TrunkEP
17:09:08 34930559mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
17:09:08 34930559mS CMCallEvt: 0000000000000000 0.1318.0 -1 BaseEP: DELETE CMEndpoint f16dba60 TOTAL NOW=6 CALL_LIST=3
17:09:08 34930560mS CMCallEvt: 0a0a0c0600000527 0.1319.0 24 H323TrunkEP: StateChange: END=B CMCSOffering->CMCSAccept
17:09:08 34930561mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=7 (0007) id=539 (021b) in=1 (0001)
17:09:08 34930565mS H323Evt: v=0 stacknum=18 State, new=Active, old=Proceeding id=1319
17:09:08 34930568mS CMLineRx: v=18
CMConnect
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageBmpString
invokeId 38097
user 'ExtendedHours' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEPrimitiveData (7)
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=7 (0007) id=539 (021b) in=1 (0001)
IE CMIEDtmf (209) CMIEDtmf oob 1, txp 97, rxp 97, fmtp = <null>, misc 12 (misc oob 1 misc rfc2833 0 prio oob 0 prio rfc2833 1)
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=ExtendedHours
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=ExtendedHours
IE CMIEDeviceDetail (231) 0a0a0c050001ee64 LOCALE=aru HW=11 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=0 features=0x0 rx_gain=32 tx_gain=32
ep_callid=126564 ipaddr=10.10.12.5 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
17:09:08 34930569mS CMCallEvt: 0a0a0c050001ee64 0.1319.0 24 H323TrunkEP: StateChange: END=B CMCSAccept->CMCSConnReq
17:09:08 34930569mS CMCallEvt: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSOGConnReq
17:09:08 34930577mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14631
user 'ExtendedHours' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEFarEndCMCallIdData (182) Far End CM Call: lid=7 (0007) id=539 (021b) in=1 (0001)
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=ExtendedHours
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=ExtendedHours
IE CMIEDeviceDetail (231) 0a0a0c050001ee64 LOCALE=aru HW=11 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=0 features=0x0 rx_gain=32 tx_gain=32
ep_callid=126564 ipaddr=10.10.12.5 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
17:09:08 34930579mS SIP Tx: UDP 10.255.1.194:5060 -> 10.59.108.25:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK89c79eb336a4f689eee85330fab54e036233c45f-0-476a6ea6-639dbf741c75f51f;_aluscr_
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 1 INVITE
Contact: <sip:045628401@10.255.1.194:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer,100rel
Server: IP Office 11.0.0.0.0 build 849
To: <sip:045628401@du.ae;user=phone>;tag=55143420adf80876
Content-Type: application/sdp
Content-Length: 201
v=0
o=UserA 3724505615 3300135745 IN IP4 10.255.1.194
s=Session SDP
c=IN IP4 10.255.1.194
t=0 0
m=audio 40750 RTP/AVP 8 97
a=rtpmap:8 PCMA/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
17:09:08 34930582mS CMLineTx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
17:09:08 34930584mS CMLineTx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
IE CMIEPrimitiveData (7)
17:09:08 34930587mS H323Evt: SESS 1e SetOperational local 10.10.12.6:0 remote 0.0.0.0:0 to 0
17:09:08 34930587mS H323Evt: SESS 1e Configure: Alaw64K packet size 160
17:09:08 34930587mS H323Evt: SESS 1e SetRemUDP 0 -> 48386, remote IP 0.0.0.0 -> 10.10.12.5
17:09:08 34930587mS H323Evt: SESS 1e SetRfc2833: (1) rx payload 97 tx payload 97
17:09:08 34930587mS CMMap: PCG::MapBChan pcp[140]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
17:09:08 34930588mS H323Evt: SESS 1f SetOperational local 10.255.1.194:0 remote 0.0.0.0:0 to 0
17:09:08 34930588mS H323Evt: SESS 1f Configure: Alaw64K packet size 160
17:09:08 34930588mS H323Evt: SESS 1f SetRemUDP 0 -> 43098, remote IP 0.0.0.0 -> 10.59.109.72
17:09:08 34930588mS H323Evt: SESS 1f SetRfc2833: (1) rx payload 97 tx payload 97
17:09:08 34930589mS H323Evt: SESS 1f SetHnt (rtp keepalives): enabled 3, send_initial_packet 0, send_periodic_packets 0
17:09:08 34930589mS CMMap: PCG::MapBChan pcp[139]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
17:09:08 34930589mS CMMap: PCG::MapBChan cp RTP local 10.255.1.194:40750 remote 10.59.109.72:43098 , cp_other RTP local: 10.10.12.6:46754 remote 10.10.12.5:48386
17:09:08 34930590mS CMMap: PCG::MapBChan cp oob 0 rfc_2833 1 , cp_other oob 0, rfc_2833 1, cp behind_nat 0 cp_other behind_nat 0
17:09:08 34930590mS PRN: rtpRelayFilter ioctl(open) rc: 0 rtpRelayId: 3
17:09:08 34930590mS CMMap: PCG::MapBChan rtpRelay Open got rtpRelayId: 3
17:09:08 34930590mS PRN: rtpRelayFilter configure rc: 0 rtpRelayId: 3
17:09:08 34930590mS CMMap: PCG::MapBChan rtpRelay Configure success rtpRelayId: 3
17:09:08 34930590mS CMMap: PCG::MapBChan rtpRelay Start success rtpRelayId: 3
17:09:08 34930590mS CMMap: pcp[139]b0r1 rtpRelayId: 3 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
17:09:08 34930591mS CMMap: pcp[140]b0r1 rtpRelayId: 3 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
17:09:08 34930591mS H323Evt: SESS 1f SetOperational local 10.255.1.194:40750 remote 10.59.109.72:43098 to 1
17:09:08 34930591mS H323Evt: SESS 1e SetOperational local 10.10.12.6:46754 remote 10.10.12.5:48386 to 1
17:09:08 34930622mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
IE CMIEPrimitiveData (7)
17:09:08 34930627mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
IE CMIEPrimitiveData (7)
17:09:08 34930909mS SIP Rx: UDP 10.59.108.25:5060 -> 10.255.1.194:5060
ACK sip:045628401@10.255.1.194:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK0140401484881591;_aluscr_
Content-Length: 0
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
To: <sip:045628401@du.ae;user=phone>;tag=55143420adf80876
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 1 ACK
Max-Forwards: 68
Request-Disposition: no-fork
Contact: <sip:lucentNGFS-083444@10.59.108.25:5060;x-afi=128;encoded-parm=QbkRBthOEgsTXgkTBA0HHiUrKz1CQElBR0BBNhEMGAklOiF0dGguJTpkJiIufn9jfz8wN2FkY3ZqPUFCXVFBQVhKUh5BXAZYVlRTVg__>
P-NOKIA.Traffica: S1;i="8AC82F87835900F5D3447597@4d70ffffffff"
17:09:08 34930913mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[042432025@du.com.ae] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=042432025@du.com.ae
IE CMIEDeviceDetail (231) 0a0a0c0600000525 LOCALE=aru HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=25 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1317 ipaddr=10.10.12.6 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
17:09:08 34930913mS CMCallEvt: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
17:09:08 34930913mS CMTARGET: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: ~CMTargetHandler f16c7044 ep f4fc2d64
17:09:08 34930914mS CMCallEvt: 0a0a0c050001ee64 0.1319.0 24 H323TrunkEP: StateChange: END=B CMCSConnReq->CMCSConnected
17:09:08 34930916mS CMLineTx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[042432025@du.com.ae] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=042432025@du.com.ae
IE CMIEDeviceDetail (231) 0a0a0c0600000525 LOCALE=aru HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=25 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1317 ipaddr=10.10.12.6 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
17:09:09 34931101mS ISDNL3Evt: v=6 stacknum=6 State, new=Active, old=Proceeding id=1316
17:09:09 34931101mS CMLineRx: v=6
CMConnect
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDDestNotISDN (2)
17:09:09 34931102mS CMCallEvt: 0a0a0c0600000524 0.1316.0 23 Q931 Trunk:6 CHAN=2: StateChange: END=B CMCSRinging->CMCSConnReq
17:09:09 34931103mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSRingBack->CMCSOGConnReq
17:09:09 34931104mS CMLineTx: v=18
CMConnect
Line: type=IPLine 18 Call: lid=18 id=23 in=1
BChan: slot=3 chan=6
IE CMIEPrimitiveData (7)
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDDestNotISDN (2)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=045628401
IE CMIEDeviceDetail (231) 0a0a0c0600000524 LOCALE=aru HW=15 VER=11 class=CMDeviceISDNTrunk type=0 number=6 channel=2 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1316 ipaddr=10.10.12.6 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
17:09:09 34931107mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931107mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931107mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:09 34931107mS CMMap: a=3.6 b=2.17 M12
17:09:09 34931107mS PRN: RTCP collector is NOT initialized
17:09:09 34931108mS PRN: RTCP collector is NOT initialized
17:09:09 34931109mS H323Evt: v=0 stacknum=18 State, new=Active, old=ICProceeding id=23
17:09:09 34931112mS CMLineRx: v=18
CMConnectAck
Line: type=IPLine 18 Call: lid=18 id=23 in=1
17:09:09 34931112mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSOGConnReq->CMCSConnected
17:09:09 34931112mS CMTARGET: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: ~CMTargetHandler f16d54e8 ep f16c0844
17:09:09 34931114mS CMCallEvt: 0a0a0c0600000524 0.1316.0 23 Q931 Trunk:6 CHAN=2: StateChange: END=B CMCSConnReq->CMCSConnected
17:09:09 34931117mS CMLineTx: v=6
CMConnectAck
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
BChan: slot=0 chan=2
17:09:09 34931118mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931118mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931118mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:09 34931119mS CMMap: a=3.6 b=2.17 M12
17:09:09 34931119mS PRN: RTCP collector is NOT initialized
17:09:09 34931120mS PRN: RTCP collector is NOT initialized
17:09:09 34931121mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEPrimitiveData (7)
17:09:09 34931125mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931125mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931125mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:09 34931126mS CMMap: a=3.6 b=2.17 M12
17:09:09 34931126mS PRN: RTCP collector is NOT initialized
17:09:09 34931126mS PRN: RTCP collector is NOT initialized
17:09:09 34931130mS CMLineRx: v=18
CMUserInformation
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIERecordingEvent (251) recording_start=1
17:09:09 34931133mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931134mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931134mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:09 34931134mS CMMap: a=3.6 b=2.17 M12
17:09:09 34931135mS PRN: RTCP collector is NOT initialized
17:09:09 34931135mS PRN: RTCP collector is NOT initialized
17:09:09 34931140mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEPrimitiveData (7)
17:09:09 34931142mS CMLineTx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEPrimitiveData (7)
17:09:09 34931144mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEPrimitiveData (7)
17:09:09 34931146mS CMLineTx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIEPrimitiveData (7)
17:09:09 34931147mS CMLineRx: v=18
CMFacility
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Robel ACW
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Robel ACW
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=1031
IE CMIEDeviceDetail (231) 0a0a0c050001ee5e LOCALE=aru HW=11 VER=11 class=CMDeviceSIPPhone type=151 number=1 channel=0 features=0x20110020 rx_gain=32 tx_gain=32
ep_callid=126558 ipaddr=10.10.12.5 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
17:09:09 34931152mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931152mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:09 34931152mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:09 34931152mS CMMap: a=3.6 b=2.17 M12
17:09:09 34931152mS PRN: RTCP collector is NOT initialized
17:09:09 34931153mS PRN: RTCP collector is NOT initialized
17:09:13 34935117mS PRN: Monitor Status S-Edition Expansion (V2) 11.0.0.0.0 build 849
17:09:13 34935118mS PRN: LAW=A PRI=0, BRI=2, ALOG=4, VCOMP=42, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=5 VMAIL=1(VER=3 TYP=2) 1-X=0 CALLS=3(TOT=24)
17:09:14 34936298mS RES: Sat 17/12/2022 17:09:14 FreeMem=55861236 Heap=55569552(2) Cache=291684 MemObjs=13282(Max 13505) CMMsg=4(5) ASN=0 Buff=5200 1363 1000 7426 5 Links=55557(55588) BTree=858(1777) CB=6046 MCT=0 CPU=08.15% CPUStats=07.35%/1/4/4942/13
532/15031/00.96%/1/03.69% MCR=0 MCW=0 DEV=0
17:09:14 34936298mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+64 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=27(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:14 34936299mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:14 34936299mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:26 34948696mS CMLineRx: v=18
CMUserInformation
Line: type=IPLine 18 Call: lid=18 id=23 in=1
IE CMIERecordingEvent (251) recording_start=0
17:09:26 34948700mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:26 34948700mS CMMap: a=3.6 b=0.0 PCGS CPReserveCodec (pcp[138]b0r1) true
17:09:26 34948700mS CMMap: PCG::MapBChan pcp[138]b0r1 cp_b f5395054 other_cp_b f53513f0 type CGTypeSimple
17:09:26 34948701mS CMMap: a=3.6 b=2.17 M12
17:09:26 34948701mS PRN: RTCP collector is NOT initialized
17:09:26 34948701mS PRN: RTCP collector is NOT initialized
17:09:26 34948704mS H323Evt: v=0 stacknum=18 State, new=NullState, old=Active id=23
17:09:26 34948705mS CMLineRx: v=18
CMReleaseComp
Line: type=IPLine 18 Call: lid=18 id=23 in=1
Cause=16, Normal call clearing
17:09:26 34948705mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 23 H323TrunkEP: StateChange: END=A CMCSConnected->CMCSCompleted
17:09:26 34948708mS CMLOGGING: CALL:2022/12/1717:09,00:00:17,000,1031,I,045628401,045628401,RobelACW,,,0,,"",0,n/a
17:09:26 34948709mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 -1 H323TrunkEP: StateChange: END=X CMCSCompleted->CMCSDelete
17:09:26 34948710mS CMLineTx: v=6
CMDisconnect
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
Cause=16, Normal call clearing
17:09:26 34948711mS CMCallEvt: 0a0a0c0600000524 0.1316.0 -1 Q931 Trunk:6 CHAN=2: StateChange: END=X CMCSConnected->CMCSCompleted
17:09:26 34948711mS CMCallEvt: END CALL:23 (f16c2ff4)
17:09:26 34948712mS CMCallEvt: 0a0a0c050001ee5e 18.23.1 -1 BaseEP: DELETE CMEndpoint f16c0844 TOTAL NOW=5 CALL_LIST=2
17:09:26 34948713mS CMMap: PCG::UnmapBChan pcp[107]b1r0 cp_b f53513f0 other_cp_b f5395054
17:09:26 34948714mS CMMap: a=2.17 b=3.6 M02
17:09:26 34948714mS PRN: rtpRelayFilter close rc: 0 rtpRelayId: 2
17:09:26 34948714mS CMMap: PlatformConnectionAudioSAP:
isconnectRtpFilter rtpRelayId: 2 Close success
17:09:26 34948715mS H323Evt: SESS 1d SetOperational local 10.10.12.6:46752 remote 10.10.12.5:48400 to 0
17:09:26 34948715mS CMMap: PCG::UnmapBChan pcp[138]b0r1 cp_b 0 other_cp_b 0
17:09:26 34948716mS H323Evt: SESS 1d: RTP(END): 10.10.12.6/46752 10.10.12.5/48400 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=18578 SENT=920 RECV=920 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
17:09:26 34948717mS CMMap: a=2.17 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f53951f0) resource busy 2, total 42
17:09:26 34948718mS ISDNL3Evt: v=6 stacknum=6 State, new=DiscReq, old=Active id=1316
17:09:26 34948753mS ISDNL3Evt: v=6 stacknum=6 State, new=NullState, old=DiscReq id=1316
17:09:26 34948754mS CMLineRx: v=6
CMReleaseComp
Line: type=Q931Line 6 Call: lid=0 id=1316 in=0
BChan: slot=0 chan=2
Cause=16, Normal call clearing
17:09:26 34948754mS CMCallEvt: 0a0a0c0600000524 0.1316.0 -1 Q931 Trunk:6 CHAN=2: StateChange: END=X CMCSCompleted->CMCSDelete
17:09:26 34948755mS CMCallEvt: 0a0a0c0600000524 0.1316.0 -1 BaseEP: DELETE CMEndpoint f16a932c TOTAL NOW=4 CALL_LIST=2
17:09:26 34948814mS RES: Sat 17/12/2022 17:09:26 FreeMem=55894628 Heap=55568996(2) Cache=325632 MemObjs=13119(Max 13505) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7421 5 Links=55480(55588) BTree=856(1777) CB=6051 MCT=0 CPU=07.65% CPUStats=06.09%/1/5/4942/13
764/15031/00.93%/1/03.65% MCR=0 MCW=0 DEV=0
17:09:26 34948814mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+63 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=27(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:26 34948814mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:26 34948815mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:26 34948892mS SIP Rx: UDP 10.59.108.25:5060 -> 10.255.1.194:5060
BYE sip:045628401@10.255.1.194:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK1a537df7494c11261fd120aa0f339b2b6233c45f-0-476a7252-639dbf863366e7da;_aluscr_
Content-Length: 0
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
To: <sip:045628401@du.ae;user=phone>;tag=55143420adf80876
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 2 BYE
Max-Forwards: 68
Request-Disposition: no-fork
Reason: X.int;reasoncode=0x00000000;add-info=01CA.FFFE.0000
Reason: Q.850;cause=16
P-NOKIA.Traffica: S1;i="8AC82F87835900F5D3447597@4d70ffffffff"
17:09:26 34948896mS SIP Tx: UDP 10.255.1.194:5060 -> 10.59.108.25:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.59.108.25:5060;branch=z9hG4bK1a537df7494c11261fd120aa0f339b2b6233c45f-0-476a7252-639dbf863366e7da;_aluscr_
From: <sip:042432025@du.com.ae;user=phone>;tag=6233c45f-639dbf741c7443b4-gm-pt-lucentPCSF-054983
Call-ID: LU-1671282548477378-65269734@i4.sbc.du.com
CSeq: 2 BYE
Supported: timer,100rel
Server: IP Office 11.0.0.0.0 build 849
To: <sip:045628401@du.ae;user=phone>;tag=55143420adf80876
Content-Length: 0
17:09:26 34948897mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[042432025@du.com.ae] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=042432025@du.com.ae
IE CMIEDeviceDetail (231) 0a0a0c0600000525 LOCALE=aru HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=25 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1317 ipaddr=10.10.12.6 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
Cause=16, Normal call clearing
17:09:26 34948898mS CMCallEvt: 0a0a0c0600000525 25.1317.1 24 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
17:09:26 34948900mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 25 Call: lid=25 id=1317 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
17:09:26 34948901mS CMLOGGING: CALL:2022/12/1717:09,00:00:17,000,042432025@du.com.ae,I,ExtendedHours,045628401,,,,0,,"",0,n/a
17:09:26 34948902mS CMCallEvt: 0a0a0c0600000525 25.1317.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
17:09:26 34948903mS CMLineTx: v=18
CMReleaseComp
Line: type=IPLine 18 Call: lid=0 id=1319 in=0
Cause=16, Normal call clearing
17:09:26 34948903mS CMCallEvt: 0a0a0c050001ee64 0.1319.0 -1 H323TrunkEP: StateChange: END=X CMCSConnected->CMCSDelete
17:09:26 34948904mS CMCallEvt: 0a0a0c050001ee64 0.1319.0 -1 BaseEP: DELETE CMEndpoint f16b4d78 TOTAL NOW=3 CALL_LIST=1
17:09:26 34948904mS CMCallEvt: END CALL:24 (f16b72d8)
17:09:26 34948906mS CMCallEvt: 0a0a0c0600000525 25.1317.1 -1 BaseEP: DELETE CMEndpoint f4fc2d64 TOTAL NOW=2 CALL_LIST=1
17:09:26 34948908mS CMMap: PCG::UnmapBChan pcp[140]b0r1 cp_b 0 other_cp_b 0
17:09:26 34948908mS H323Evt: SESS 1e SetOperational local 10.10.12.6:46754 remote 10.10.12.5:48386 to 0
17:09:26 34948908mS H323Evt: SESS 1f SetOperational local 10.255.1.194:40750 remote 10.59.109.72:43098 to 0
17:09:26 34948908mS PRN: rtpRelayFilter close rc: 0 rtpRelayId: 3
17:09:26 34948908mS CMMap: PCG::UMMapBChan rtpRelayId: 3 Close success
17:09:26 34948908mS CMMap: PCG::UnmapBChan pcp[139]b0r1 cp_b 0 other_cp_b 0
17:09:26 34948909mS H323Evt: SESS 1e: RTP(END): 10.10.12.6/46754 10.10.12.5/48386 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=18322 SENT=904 RECV=917 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
17:09:26 34948910mS H323Evt: SESS 1f: RTP(END): 10.255.1.194/40750 10.59.109.72/43098 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=18321 SENT=914 RECV=907 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
17:09:26 34948911mS H323Evt: v=0 stacknum=18 State, new=ReleaseReq, old=Active id=1319
17:09:26 34948911mS H323Evt: v=0 stacknum=18 State, new=NullState, old=ReleaseReq id=1319
17:09:32 34954319mS RES: Sat 17/12/2022 17:09:31 FreeMem=55959052 Heap=55582108(1) Cache=376944 MemObjs=12445(Max 13505) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7427 5 Links=55318(55588) BTree=854(1777) CB=6059 MCT=0 CPU=07.81% CPUStats=06.89%/4/8/4942/13
669/15031/00.41%/0/02.98% MCR=0 MCW=0 DEV=0
17:09:32 34954319mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=28(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:32 34954320mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:32 34954320mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:34 34956255mS PRN: Config Write Wake Up
17:09:34 34956755mS PRN: Config Write Completed
********** SysMonitor v11.1.0.2.0 build 14 [connected to 10.10.12.6 (Unified Dubai (Server Edition(E)))] **********
17:09:43 34965960mS PRN: Monitor Status S-Edition Expansion (V2) 11.0.0.0.0 build 849
17:09:43 34965960mS PRN: LAW=A PRI=0, BRI=2, ALOG=4, VCOMP=42, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=5 VMAIL=1(VER=3 TYP=2) 1-X=0 CALLS=1(TOT=24)
17:09:46 34968664mS PRN: Service Access Connection from 10.10.12.5(49456) to Port 8443
17:09:46 34968848mS RES: Sat 17/12/2022 17:09:46 FreeMem=55942692 Heap=55587016(0) Cache=355676 MemObjs=12515(Max 13505) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7423 5 Links=55325(55588) BTree=854(1777) CB=6058 MCT=0 CPU=06.65% CPUStats=05.89%/1/5/4942/14
103/15031/00.38%/0/02.94% MCR=0 MCW=0 DEV=0
17:09:46 34968848mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=28(TLS=7 OFF=1) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:46 34968849mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:46 34968849mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
17:09:56 34978855mS RES: Sat 17/12/2022 17:09:56 FreeMem=55960228 Heap=55587016(0) Cache=373212 MemObjs=12441(Max 13505) CMMsg=4(5) ASN=0 Buff=5200 1364 1000 7427 5 Links=55317(55588) BTree=854(1777) CB=6059 MCT=0 CPU=07.34% CPUStats=06.49%/1/4/4942/14
006/15031/00.37%/0/02.93% MCR=0 MCW=0 DEV=0
17:09:56 34978856mS RES2: (SE-E) IP 500 V2 11.0.0.0.0 build 849 Tasks=59 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=27(TLS=6 OFF=0) TAPI=1 Partner=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=
2034
17:09:56 34978856mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
17:09:56 34978856mS RES5: CLog MemObjs=70 FreePoolMem(Objs)=1560(30) TotalMem=5200 StringsTotalMem=80150
********** Warning: Logging to Screen Stopped **********
