IP500 V2 R11.04. I have enabled outcalling in the Voicemail Tab under System. We are running Intuity Mode. I have gone into 3 different mailboxes, pressed 6 and entered the phone number with a 9 in the front and verified that I turned it on in the mailbox. We are using SIP trunks so I verified that the SIP Settings under the Voicemail tab are correct and Anonymous is unchecked. Upon leaving a message it never even attempts to access an outside line to make a call. I have trace turned on for the SIP trunks in System Status to verify this. Here is a copy of the monitor output.
19:16:40 19648158mS RES: Wed 11/9/2019 19:16:39 FreeMem=56602624 Heap=56278604(6) Cache=324020 MemObjs=8945(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52011(52168) BTree=402(1105) CB=6061 MCT=0 CPU=10.02% CPUStats=10.02%/2/5/3775/15949
/17990/00.16%/0/02.63% MCR=0 MCW=0 DEV=0
19:16:40 19648158mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+50 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:40 19648158mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:40 19648159mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:42 19650547mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1012 in=0
19:16:42 19650547mS CMCallEvt: c0a801e900000ad9 0.2777.0 104 RAS.0: StateChange: END=T CMCSRinging->CMCSConnReq
19:16:42 19650548mS CMCallEvt: 0000000000000000 0.2776.0 104 TargetingEP: RequestEnd c0a801e900000ad9 0.2777.0 104 RAS.0
19:16:42 19650548mS CMCallEvt: 0000000000000000 0.2776.0 -1 BaseEP: DELETE CMEndpoint f17941bc TOTAL NOW=2 CALL_LIST=1
19:16:42 19650548mS CMExtnEvt: v=1012 State, new=Connected old=Ringing,0,0,RAS
19:16:42 19650549mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
19:16:42 19650550mS CMMap: IP::SetCodec pcp[132]b0r0 0 -> f5737b60
19:16:42 19650553mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
BChan: slot=21 chan=11
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14711
user '#Test' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#Test
IE CMIEDeviceDetail (231) c0a801e900000ad9 LOCALE=enu HW=15 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32
ep_callid=2777 ipaddr=192.168.1.233 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=133
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 5)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 5)
}
19:16:42 19650555mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+2a56cf08f2426103510fdf968f383e961+sip+5+ac03d105
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
CSeq: 827106143 INVITE
Contact: <sip:41025517004102551700@192.168.1.233:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
Content-Type: application/sdp
Content-Length: 206
v=0
o=UserA 3877271032 2293778853 IN IP4 192.168.1.233
s=Session SDP
c=IN IP4 192.168.1.233
t=0 0
m=audio 46750 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
19:16:42 19650558mS H323Evt: SESS 25 SetOperational local 192.168.1.233:0 remote 0.0.0.0:0 to 0
19:16:42 19650558mS H323Evt: SESS 25 Configure: Ulaw64K packet size 160
19:16:42 19650558mS H323Evt: SESS 25 SetRemUDP 0 -> 37240, remote IP 0.0.0.0 -> 192.58.90.20
19:16:42 19650559mS H323Evt: SESS 25 SetRfc2833: (1) rx payload 101 tx payload 101
19:16:42 19650559mS H323Evt: SESS 25 SetHnt (rtp keepalives): enabled 3, send_initial_packet 1, send_periodic_packets 30
19:16:42 19650559mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
19:16:42 19650559mS CMMap: PCGS CPReserveCodec for the other end (pcp[132]b0r1) true
19:16:42 19650559mS CMMap: PCG::MapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0 type CGTypeSimple
19:16:42 19650559mS CMMap: a=0.18 b=21.11 M12
19:16:42 19650559mS CMMap: PlatformConnectionAudioSAP::Bind pcp[132]b0r1 to 0.18
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[132]b0r1 echo_cancellation set to 0
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 Configure 0.18
19:16:42 19650560mS PRN: rtpRelayFilter ioctl(open) rc: 0 rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelayId: 1
19:16:42 19650560mS PRN: rtpRelayFilter configure rc: 0 rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.1.233:46750, Remote IP addr 192.58.90.20:37240
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.4:32768, telogyHost IP addr 1.1.1.1:10
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success rtpRelayId: 1
19:16:42 19650560mS CMMap: pcp[132]b0r1 rtpRelayId: 1 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 ConnectIndication 0.18
19:16:42 19650561mS PRN: RTCP collector is NOT initialized
19:16:42 19650561mS H323Evt: SESS 25 SetOperational local 192.168.1.233:46750 remote 192.58.90.20:37240 to 1
19:16:42 19650561mS PRN: RTCP collector is NOT initialized
19:16:42 19650571mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
ACK sip:41025517004102551700@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;branch=z9hG4bK+af5ccbfe0f9e4644eb5d0064214efcc61+sip+5+ac03d255
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
CSeq: 827106143 ACK
Contact: <sip:3a8129b8f04b05499eae859df1037188@reg.nhcgrp.com:5070>
Content-Length: 0
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Organization: Metaswitch Networks
19:16:42 19650574mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:16:42 19650574mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
19:16:42 19650574mS CMTARGET: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: ~CMTargetHandler f174efe0 ep f4fbaf6c
19:16:42 19650576mS CMCallEvt: c0a801e900000ad9 0.2777.0 104 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
19:16:42 19650578mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
BChan: slot=21 chan=11
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:16:42 19650584mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:42 19650584mS CMMap: a=0.18 b=21.11 CheckSpeechPathMonitor (pcp[23]b1r0) register
19:16:42 19650584mS PRN: Confer DSP: channel 0: allocated DTMF detector 0, 23 free
19:16:42 19650584mS PRN: Confer DSP: channel 0 enabled DTMF detector 0(0)
19:16:42 19650584mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:42 19650584mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b f5683ca0 other_cp_b f567ac9c type CGTypeSimple
19:16:42 19650584mS CMMap: a=21.11 b=0.18 M12
19:16:42 19650585mS PRN: RTCP collector is NOT initialized
19:16:42 19650585mS PRN: RTCP collector is NOT initialized
19:16:42 19650585mS CMMap: PCGS CPReserveCodec for the other end (pcp[132]b0r1) true
19:16:42 19650585mS CMMap: PCG::MapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0 type CGTypeSimple
19:16:42 19650585mS CMMap: a=0.18 b=21.11 M12
19:16:42 19650586mS PRN: RTCP collector is NOT initialized
19:16:42 19650586mS PRN: RTCP collector is NOT initialized
19:16:42 19650597mS PRN: Task CM waiting on Call Model Semaphore held by CMRtEngine
19:16:45 19653090mS CMExtnTx: v=RAS, p1=0
CMInformation
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
BChan: slot=21 chan=11
19:16:45 19653093mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:45 19653094mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:45 19653094mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b f5683ca0 other_cp_b f567ac9c type CGTypeSimple
19:16:45 19653094mS CMMap: a=21.11 b=0.18 M12
19:16:45 19653094mS PRN: RTCP collector is NOT initialized
19:16:45 19653094mS PRN: RTCP collector is NOT initialized
19:16:45 19653179mS RES: Wed 11/9/2019 19:16:45 FreeMem=56607992 Heap=56278604(5) Cache=329388 MemObjs=9051(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1332 1000 7440 5 Links=52061(52168) BTree=402(1105) CB=6035 MCT=0 CPU=10.59% CPUStats=12.19%/1/24/3775/1566
6/17990/00.54%/0/03.07% MCR=10119 MCW=0 DEV=0
19:16:45 19653179mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:45 19653179mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:45 19653179mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:16:47 19655216mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:16:47 19655216mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=1(TOT=104)
19:16:50 19658019mS CMCallEvt: 0000000000000000 0.2778.0 -1 BaseEP: NEW CMEndpoint f1770db0 TOTAL NOW=3 CALL_LIST=1
19:16:50 19658019mS NAT: Info: Line 17: Not using STUN for media in this case.
19:16:50 19658021mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK10b0d6a98e899458ab8541dfe53853ab
From: <sip:4103606400@wlr.nhcgrp.com>;tag=8cab58aea0e7694c
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662873 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="d5ab86c0c98da1b682134c223c35195f",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c6,cnonce="9111d72e79289bf4c8e3"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:16:50 19658035mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK10b0d6a98e899458ab8541dfe53853ab
From: <sip:4103606400@wlr.nhcgrp.com>;tag=8cab58aea0e7694c
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662873 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="d5ab86c0c98da1b682134c223c35195f",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c6,cnonce="9111d72e79289bf4c8e3"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:16:50 19658038mS CMCallEvt: c0a801e900000ada 0.2778.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:16:50 19658038mS CMCallEvt: c0a801e900000ada 0.2778.0 -1 BaseEP: DELETE CMEndpoint f1770db0 TOTAL NOW=2 CALL_LIST=1
19:16:50 19658191mS RES: Wed 11/9/2019 19:16:50 FreeMem=56604520 Heap=56278604(5) Cache=325916 MemObjs=9089(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52039(52168) BTree=403(1105) CB=6060 MCT=0 CPU=12.03% CPUStats=12.25%/2/24/3775/1562
2/17990/00.56%/0/03.08% MCR=0 MCW=10256 DEV=0
19:16:50 19658191mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:50 19658191mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:50 19658192mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:55 19663198mS RES: Wed 11/9/2019 19:16:55 FreeMem=56646284 Heap=56311392(5) Cache=334892 MemObjs=8937(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52031(52168) BTree=403(1105) CB=6060 MCT=0 CPU=12.43% CPUStats=13.19%/3/23/3775/1543
8/17990/00.52%/0/03.05% MCR=0 MCW=10256 DEV=0
19:16:55 19663198mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:55 19663199mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:55 19663199mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:57 19665353mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
BYE sip:41025517004102551700@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+6cf75cd38fd7d88d792a5ad3fe0e0bf91+sip+5+ac03d568
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
CSeq: 827106144 BYE
Content-Length: 0
Supported: resource-priority, siprec, 100rel
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Organization: Metaswitch Networks
19:16:57 19665357mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+6cf75cd38fd7d88d792a5ad3fe0e0bf91+sip+5+ac03d568
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
CSeq: 827106144 BYE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
Content-Length: 0
19:16:57 19665358mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 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
19:16:57 19665358mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
19:16:57 19665361mS CMLOGGING: CALL:2019/09/1119:16,00:00:14,005,91443XXXXXXX@wlr.nhcgrp.com,I,133,41025517004102551700,INCOMINGCALL,,,0,,"",0,n/a
19:16:57 19665361mS CMCallEvt: c0a801e900000ad7 17.2775.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
19:16:57 19665361mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
19:16:57 19665362mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
19:16:57 19665362mS CMExtnEvt: v=1012 State, new=PortRecoverDelay old=Connected,0,0,RAS
19:16:57 19665362mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[#Test] Type=Voicemail (102) Reason=107 Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
BChan: slot=21 chan=11
Cause=16, Normal call clearing
19:16:57 19665362mS CMMap: a=21.11 b=0.0 T0
19:16:57 19665363mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 2777->0 )
19:16:57 19665364mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
19:16:57 19665364mS CMExtnEvt: v=1012 State, new=Idle old=PortRecoverDelay,0,0,RAS
19:16:57 19665364mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
Cause=16, Normal call clearing
19:16:57 19665364mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 BaseEP: DELETE CMEndpoint f175f238 TOTAL NOW=1 CALL_LIST=0
19:16:57 19665364mS CMCallEvt: END CALL:104 (f17846dc)
19:16:57 19665366mS CMCallEvt: c0a801e900000ad7 17.2775.1 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
19:16:57 19665368mS CMMap: a=0.18 b=21.11 CheckSpeechPathMonitor (pcp[23]b1r0) unregister
19:16:57 19665368mS PRN: Confer DSP: channel 0 disabled DTMF detector 0(0)
19:16:57 19665368mS PRN: Confer DSP: channel 0: freed DTMF detector 0, 24 free
19:16:57 19665368mS CMMap: PCG::UnmapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0
19:16:57 19665368mS CMMap: a=0.18 b=21.11 M02
19:16:57 19665369mS PRN: rtpRelayFilter close rc: 0 rtpRelayId: 1
19:16:57 19665369mS CMMap: PlatformConnectionAudioSAP:
isconnectRtpFilter rtpRelayId: 1 Close success
19:16:57 19665369mS H323Evt: SESS 25 SetOperational local 192.168.1.233:46750 remote 192.58.90.20:37240 to 0
19:16:57 19665369mS CMMap: PCG::UnmapBChan pcp[132]b0r1 cp_b 0 other_cp_b 0
19:16:57 19665369mS H323Evt: SESS 25: RTP(END): 192.168.1.233/46750 192.58.90.20/37240 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=14811 SENT=728 RECV=757 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
19:16:57 19665370mS CMMap: a=0.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f5737b60) resource busy 1, total 10
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:16:59 19667235mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:16:59 19667235mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=104)
19:17:03 19670612mS RES: Wed 11/9/2019 19:17:03 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51954(52168) BTree=402(1105) CB=6064 MCT=0 CPU=12.15% CPUStats=08.85%/1/4/3775/16485
/17990/00.10%/0/02.60% MCR=0 MCW=0 DEV=0
19:17:03 19670612mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:03 19670612mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:03 19670612mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:17:06 19673038mS CMCallEvt: 0000000000000000 0.2779.0 -1 BaseEP: NEW CMEndpoint f4fbaf6c TOTAL NOW=1 CALL_LIST=0
19:17:06 19673038mS NAT: Info: Line 17: Not using STUN for media in this case.
19:17:06 19673040mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bKcf5a6fa6b9b1fbc5ce54c7502763eb49
From: <sip:4103606400@wlr.nhcgrp.com>;tag=f1997a46cd4a4d09
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662874 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="9987e4719bb75406c83c8e34e4a6a355",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c7,cnonce="5f07703ce2cd2cffc2aa"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:06 19673054mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bKcf5a6fa6b9b1fbc5ce54c7502763eb49
From: <sip:4103606400@wlr.nhcgrp.com>;tag=f1997a46cd4a4d09
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662874 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="9987e4719bb75406c83c8e34e4a6a355",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c7,cnonce="5f07703ce2cd2cffc2aa"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:06 19673057mS CMCallEvt: c0a801e900000adb 0.2779.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:17:06 19673057mS CMCallEvt: c0a801e900000adb 0.2779.0 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
19:17:11 19678112mS RES: Wed 11/9/2019 19:17:09 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51946(52168) BTree=397(1105) CB=6064 MCT=0 CPU=11.32% CPUStats=10.43%/1/4/3775/16001
/17990/00.10%/0/02.62% MCR=0 MCW=0 DEV=0
19:17:11 19678112mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:11 19678112mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:11 19678112mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:17:12 19679221mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:17:12 19679221mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=104)
19:17:12 19680573mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
OPTIONS sip:4103606400@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+a07169ebbc3f39b1fcf516b7a303c51d1+sip+6+abf4d16f
From: <sip:4103606400@10.20.20.140>;tag=reg.nhcgrp.com+6+dcaf5077+b4ed2048
Content-Length: 0
Supported: resource-priority, siprec, 100rel
To: <sip:4103606400@wlr.nhcgrp.com>
Contact: <sip:73779d5cc331ecbc2700c9686b64993f@reg.nhcgrp.com:5070>
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Call-ID: 0gQAAC8WAAACBAAALxYAAHN2pc7hlYdwaEgI5BU/Qr+xDj/QVlXooPRZfGljQZsO@reg.nhcgrp.com
CSeq: 419635477 OPTIONS
Organization: Metaswitch Networks
Accept: application/sdp, application/dtmf-relay
19:17:12 19680577mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+a07169ebbc3f39b1fcf516b7a303c51d1+sip+6+abf4d16f
From: <sip:4103606400@10.20.20.140>;tag=reg.nhcgrp.com+6+dcaf5077+b4ed2048
Call-ID: 0gQAAC8WAAACBAAALxYAAHN2pc7hlYdwaEgI5BU/Qr+xDj/QVlXooPRZfGljQZsO@reg.nhcgrp.com
CSeq: 419635477 OPTIONS
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:4103606400@wlr.nhcgrp.com>;tag=4e44485292787219
Content-Type: application/sdp
Content-Length: 195
v=0
o=UserA 1190979677 319948969 IN IP4 192.168.1.233
s=Session SDP
c=IN IP4 192.168.1.233
t=0 0
m=audio 8000 RTP/AVP 0 18
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
19:17:18 19685614mS RES: Wed 11/9/2019 19:17:18 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51954(52168) BTree=397(1105) CB=6064 MCT=0 CPU=10.45% CPUStats=09.03%/1/4/3775/16610
/17990/00.11%/1/02.59% MCR=0 MCW=0 DEV=0
19:17:18 19685614mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:18 19685614mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:18 19685614mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:17:21 19688056mS CMCallEvt: 0000000000000000 0.2780.0 -1 BaseEP: NEW CMEndpoint f4fbaf6c TOTAL NOW=1 CALL_LIST=0
19:17:21 19688057mS NAT: Info: Line 17: Not using STUN for media in this case.
19:17:21 19688058mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK02769b23f9133828bc68c4924cff2d4e
From: <sip:4103606400@wlr.nhcgrp.com>;tag=c352d98377af4351
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662875 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="15b658b278c9dcec6b5a156b0c420a10",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c8,cnonce="4ce3da844e2623742d42"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:21 19688070mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK02769b23f9133828bc68c4924cff2d4e
From: <sip:4103606400@wlr.nhcgrp.com>;tag=c352d98377af4351
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662875 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="15b658b278c9dcec6b5a156b0c420a10",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c8,cnonce="4ce3da844e2623742d42"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:21 19688073mS CMCallEvt: c0a801e900000adc 0.2780.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:17:21 19688073mS CMCallEvt: c0a801e900000adc 0.2780.0 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
********** Warning: Logging to Screen Stopped **********
19:16:40 19648158mS RES: Wed 11/9/2019 19:16:39 FreeMem=56602624 Heap=56278604(6) Cache=324020 MemObjs=8945(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52011(52168) BTree=402(1105) CB=6061 MCT=0 CPU=10.02% CPUStats=10.02%/2/5/3775/15949
/17990/00.16%/0/02.63% MCR=0 MCW=0 DEV=0
19:16:40 19648158mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+50 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:40 19648158mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:40 19648159mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:42 19650547mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=RAS 1 Call: lid=0 id=1012 in=0
19:16:42 19650547mS CMCallEvt: c0a801e900000ad9 0.2777.0 104 RAS.0: StateChange: END=T CMCSRinging->CMCSConnReq
19:16:42 19650548mS CMCallEvt: 0000000000000000 0.2776.0 104 TargetingEP: RequestEnd c0a801e900000ad9 0.2777.0 104 RAS.0
19:16:42 19650548mS CMCallEvt: 0000000000000000 0.2776.0 -1 BaseEP: DELETE CMEndpoint f17941bc TOTAL NOW=2 CALL_LIST=1
19:16:42 19650548mS CMExtnEvt: v=1012 State, new=Connected old=Ringing,0,0,RAS
19:16:42 19650549mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
19:16:42 19650550mS CMMap: IP::SetCodec pcp[132]b0r0 0 -> f5737b60
19:16:42 19650553mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
BChan: slot=21 chan=11
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14711
user '#Test' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=#Test
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#Test
IE CMIEDeviceDetail (231) c0a801e900000ad9 LOCALE=enu HW=15 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32
ep_callid=2777 ipaddr=192.168.1.233 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=Test
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Test
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=133
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 5)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 5)
}
19:16:42 19650555mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+2a56cf08f2426103510fdf968f383e961+sip+5+ac03d105
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
CSeq: 827106143 INVITE
Contact: <sip:41025517004102551700@192.168.1.233:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
Content-Type: application/sdp
Content-Length: 206
v=0
o=UserA 3877271032 2293778853 IN IP4 192.168.1.233
s=Session SDP
c=IN IP4 192.168.1.233
t=0 0
m=audio 46750 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
19:16:42 19650558mS H323Evt: SESS 25 SetOperational local 192.168.1.233:0 remote 0.0.0.0:0 to 0
19:16:42 19650558mS H323Evt: SESS 25 Configure: Ulaw64K packet size 160
19:16:42 19650558mS H323Evt: SESS 25 SetRemUDP 0 -> 37240, remote IP 0.0.0.0 -> 192.58.90.20
19:16:42 19650559mS H323Evt: SESS 25 SetRfc2833: (1) rx payload 101 tx payload 101
19:16:42 19650559mS H323Evt: SESS 25 SetHnt (rtp keepalives): enabled 3, send_initial_packet 1, send_periodic_packets 30
19:16:42 19650559mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
19:16:42 19650559mS CMMap: PCGS CPReserveCodec for the other end (pcp[132]b0r1) true
19:16:42 19650559mS CMMap: PCG::MapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0 type CGTypeSimple
19:16:42 19650559mS CMMap: a=0.18 b=21.11 M12
19:16:42 19650559mS CMMap: PlatformConnectionAudioSAP::Bind pcp[132]b0r1 to 0.18
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[132]b0r1 echo_cancellation set to 0
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 Configure 0.18
19:16:42 19650560mS PRN: rtpRelayFilter ioctl(open) rc: 0 rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelayId: 1
19:16:42 19650560mS PRN: rtpRelayFilter configure rc: 0 rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success rtpRelayId: 1
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.1.233:46750, Remote IP addr 192.58.90.20:37240
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.4:32768, telogyHost IP addr 1.1.1.1:10
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success rtpRelayId: 1
19:16:42 19650560mS CMMap: pcp[132]b0r1 rtpRelayId: 1 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
19:16:42 19650560mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 ConnectIndication 0.18
19:16:42 19650561mS PRN: RTCP collector is NOT initialized
19:16:42 19650561mS H323Evt: SESS 25 SetOperational local 192.168.1.233:46750 remote 192.58.90.20:37240 to 1
19:16:42 19650561mS PRN: RTCP collector is NOT initialized
19:16:42 19650571mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
ACK sip:41025517004102551700@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;branch=z9hG4bK+af5ccbfe0f9e4644eb5d0064214efcc61+sip+5+ac03d255
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
CSeq: 827106143 ACK
Contact: <sip:3a8129b8f04b05499eae859df1037188@reg.nhcgrp.com:5070>
Content-Length: 0
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Organization: Metaswitch Networks
19:16:42 19650574mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:16:42 19650574mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
19:16:42 19650574mS CMTARGET: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: ~CMTargetHandler f174efe0 ep f4fbaf6c
19:16:42 19650576mS CMCallEvt: c0a801e900000ad9 0.2777.0 104 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
19:16:42 19650578mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
BChan: slot=21 chan=11
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:16:42 19650584mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:42 19650584mS CMMap: a=0.18 b=21.11 CheckSpeechPathMonitor (pcp[23]b1r0) register
19:16:42 19650584mS PRN: Confer DSP: channel 0: allocated DTMF detector 0, 23 free
19:16:42 19650584mS PRN: Confer DSP: channel 0 enabled DTMF detector 0(0)
19:16:42 19650584mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:42 19650584mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b f5683ca0 other_cp_b f567ac9c type CGTypeSimple
19:16:42 19650584mS CMMap: a=21.11 b=0.18 M12
19:16:42 19650585mS PRN: RTCP collector is NOT initialized
19:16:42 19650585mS PRN: RTCP collector is NOT initialized
19:16:42 19650585mS CMMap: PCGS CPReserveCodec for the other end (pcp[132]b0r1) true
19:16:42 19650585mS CMMap: PCG::MapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0 type CGTypeSimple
19:16:42 19650585mS CMMap: a=0.18 b=21.11 M12
19:16:42 19650586mS PRN: RTCP collector is NOT initialized
19:16:42 19650586mS PRN: RTCP collector is NOT initialized
19:16:42 19650597mS PRN: Task CM waiting on Call Model Semaphore held by CMRtEngine
19:16:45 19653090mS CMExtnTx: v=RAS, p1=0
CMInformation
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
BChan: slot=21 chan=11
19:16:45 19653093mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:45 19653094mS CMMap: a=21.11 b=0.0 PCGS CPReserveCodec (pcp[132]b0r1) true
19:16:45 19653094mS CMMap: PCG::MapBChan pcp[132]b0r1 cp_b f5683ca0 other_cp_b f567ac9c type CGTypeSimple
19:16:45 19653094mS CMMap: a=21.11 b=0.18 M12
19:16:45 19653094mS PRN: RTCP collector is NOT initialized
19:16:45 19653094mS PRN: RTCP collector is NOT initialized
19:16:45 19653179mS RES: Wed 11/9/2019 19:16:45 FreeMem=56607992 Heap=56278604(5) Cache=329388 MemObjs=9051(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1332 1000 7440 5 Links=52061(52168) BTree=402(1105) CB=6035 MCT=0 CPU=10.59% CPUStats=12.19%/1/24/3775/1566
6/17990/00.54%/0/03.07% MCR=10119 MCW=0 DEV=0
19:16:45 19653179mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:45 19653179mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:45 19653179mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:16:47 19655216mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:16:47 19655216mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=1(TOT=104)
19:16:50 19658019mS CMCallEvt: 0000000000000000 0.2778.0 -1 BaseEP: NEW CMEndpoint f1770db0 TOTAL NOW=3 CALL_LIST=1
19:16:50 19658019mS NAT: Info: Line 17: Not using STUN for media in this case.
19:16:50 19658021mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK10b0d6a98e899458ab8541dfe53853ab
From: <sip:4103606400@wlr.nhcgrp.com>;tag=8cab58aea0e7694c
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662873 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="d5ab86c0c98da1b682134c223c35195f",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c6,cnonce="9111d72e79289bf4c8e3"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:16:50 19658035mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK10b0d6a98e899458ab8541dfe53853ab
From: <sip:4103606400@wlr.nhcgrp.com>;tag=8cab58aea0e7694c
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662873 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="d5ab86c0c98da1b682134c223c35195f",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c6,cnonce="9111d72e79289bf4c8e3"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:16:50 19658038mS CMCallEvt: c0a801e900000ada 0.2778.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:16:50 19658038mS CMCallEvt: c0a801e900000ada 0.2778.0 -1 BaseEP: DELETE CMEndpoint f1770db0 TOTAL NOW=2 CALL_LIST=1
19:16:50 19658191mS RES: Wed 11/9/2019 19:16:50 FreeMem=56604520 Heap=56278604(5) Cache=325916 MemObjs=9089(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52039(52168) BTree=403(1105) CB=6060 MCT=0 CPU=12.03% CPUStats=12.25%/2/24/3775/1562
2/17990/00.56%/0/03.08% MCR=0 MCW=10256 DEV=0
19:16:50 19658191mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+54 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:50 19658191mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:50 19658192mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:55 19663198mS RES: Wed 11/9/2019 19:16:55 FreeMem=56646284 Heap=56311392(5) Cache=334892 MemObjs=8937(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7441 5 Links=52031(52168) BTree=403(1105) CB=6060 MCT=0 CPU=12.43% CPUStats=13.19%/3/23/3775/1543
8/17990/00.52%/0/03.05% MCR=0 MCW=10256 DEV=0
19:16:55 19663198mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+53 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:16:55 19663199mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:16:55 19663199mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:16:57 19665353mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
BYE sip:41025517004102551700@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+6cf75cd38fd7d88d792a5ad3fe0e0bf91+sip+5+ac03d568
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
CSeq: 827106144 BYE
Content-Length: 0
Supported: resource-priority, siprec, 100rel
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Organization: Metaswitch Networks
19:16:57 19665357mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+6cf75cd38fd7d88d792a5ad3fe0e0bf91+sip+5+ac03d568
From: "INCOMING CALL" <sip:443XXXXXXX@wlr.nhcgrp.com>;tag=reg.nhcgrp.com+5+3c7eae0e+c61f4865
Call-ID: 0gQAAC8WAAACBAAALxYAAGz23aca1z2dJpss4+TXMwPscBd80cdW59BVAJG7DE7r@reg.nhcgrp.com
CSeq: 827106144 BYE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:41025517004102551700@wlr.nhcgrp.com>;tag=812abea156add4ff
Content-Length: 0
19:16:57 19665358mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=2775 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=INCOMING CALL
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=91443XXXXXXX@wlr.nhcgrp.com
IE CMIEDeviceDetail (231) c0a801e900000ad7 LOCALE=enu HW=15 VER=11 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=2775 ipaddr=192.168.1.233 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
19:16:57 19665358mS CMCallEvt: c0a801e900000ad7 17.2775.1 104 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
19:16:57 19665361mS CMLOGGING: CALL:2019/09/1119:16,00:00:14,005,91443XXXXXXX@wlr.nhcgrp.com,I,133,41025517004102551700,INCOMINGCALL,,,0,,"",0,n/a
19:16:57 19665361mS CMCallEvt: c0a801e900000ad7 17.2775.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
19:16:57 19665361mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
19:16:57 19665362mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
19:16:57 19665362mS CMExtnEvt: v=1012 State, new=PortRecoverDelay old=Connected,0,0,RAS
19:16:57 19665362mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[#Test] Type=Voicemail (102) Reason=107 Calling[91443XXXXXXX@wlr.nhcgrp.com] Type=Unknown Plan=Default
BChan: slot=21 chan=11
Cause=16, Normal call clearing
19:16:57 19665362mS CMMap: a=21.11 b=0.0 T0
19:16:57 19665363mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 2777->0 )
19:16:57 19665364mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
19:16:57 19665364mS CMExtnEvt: v=1012 State, new=Idle old=PortRecoverDelay,0,0,RAS
19:16:57 19665364mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
Cause=16, Normal call clearing
19:16:57 19665364mS CMCallEvt: c0a801e900000ad9 0.2777.0 -1 BaseEP: DELETE CMEndpoint f175f238 TOTAL NOW=1 CALL_LIST=0
19:16:57 19665364mS CMCallEvt: END CALL:104 (f17846dc)
19:16:57 19665366mS CMCallEvt: c0a801e900000ad7 17.2775.1 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
19:16:57 19665368mS CMMap: a=0.18 b=21.11 CheckSpeechPathMonitor (pcp[23]b1r0) unregister
19:16:57 19665368mS PRN: Confer DSP: channel 0 disabled DTMF detector 0(0)
19:16:57 19665368mS PRN: Confer DSP: channel 0: freed DTMF detector 0, 24 free
19:16:57 19665368mS CMMap: PCG::UnmapBChan pcp[23]b1r0 cp_b f567ac9c other_cp_b f5683ca0
19:16:57 19665368mS CMMap: a=0.18 b=21.11 M02
19:16:57 19665369mS PRN: rtpRelayFilter close rc: 0 rtpRelayId: 1
19:16:57 19665369mS CMMap: PlatformConnectionAudioSAP:
isconnectRtpFilter rtpRelayId: 1 Close success19:16:57 19665369mS H323Evt: SESS 25 SetOperational local 192.168.1.233:46750 remote 192.58.90.20:37240 to 0
19:16:57 19665369mS CMMap: PCG::UnmapBChan pcp[132]b0r1 cp_b 0 other_cp_b 0
19:16:57 19665369mS H323Evt: SESS 25: RTP(END): 192.168.1.233/46750 192.58.90.20/37240 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=14811 SENT=728 RECV=757 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
19:16:57 19665370mS CMMap: a=0.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f5737b60) resource busy 1, total 10
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:16:59 19667235mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:16:59 19667235mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=104)
19:17:03 19670612mS RES: Wed 11/9/2019 19:17:03 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51954(52168) BTree=402(1105) CB=6064 MCT=0 CPU=12.15% CPUStats=08.85%/1/4/3775/16485
/17990/00.10%/0/02.60% MCR=0 MCW=0 DEV=0
19:17:03 19670612mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:03 19670612mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:03 19670612mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:17:06 19673038mS CMCallEvt: 0000000000000000 0.2779.0 -1 BaseEP: NEW CMEndpoint f4fbaf6c TOTAL NOW=1 CALL_LIST=0
19:17:06 19673038mS NAT: Info: Line 17: Not using STUN for media in this case.
19:17:06 19673040mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bKcf5a6fa6b9b1fbc5ce54c7502763eb49
From: <sip:4103606400@wlr.nhcgrp.com>;tag=f1997a46cd4a4d09
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662874 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="9987e4719bb75406c83c8e34e4a6a355",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c7,cnonce="5f07703ce2cd2cffc2aa"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:06 19673054mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bKcf5a6fa6b9b1fbc5ce54c7502763eb49
From: <sip:4103606400@wlr.nhcgrp.com>;tag=f1997a46cd4a4d09
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662874 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="9987e4719bb75406c83c8e34e4a6a355",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c7,cnonce="5f07703ce2cd2cffc2aa"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:06 19673057mS CMCallEvt: c0a801e900000adb 0.2779.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:17:06 19673057mS CMCallEvt: c0a801e900000adb 0.2779.0 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
19:17:11 19678112mS RES: Wed 11/9/2019 19:17:09 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51946(52168) BTree=397(1105) CB=6064 MCT=0 CPU=11.32% CPUStats=10.43%/1/4/3775/16001
/17990/00.10%/0/02.62% MCR=0 MCW=0 DEV=0
19:17:11 19678112mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:11 19678112mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:11 19678112mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
********** SysMonitor v11.0.4.0.0 build 74 [connected to 192.168.1.233 (Murphys Fuel)] **********
19:17:12 19679221mS PRN: Monitor Status IP 500 V2 11.0.0.1.0 build 8
19:17:12 19679221mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=104)
19:17:12 19680573mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
OPTIONS sip:4103606400@192.168.1.233:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+a07169ebbc3f39b1fcf516b7a303c51d1+sip+6+abf4d16f
From: <sip:4103606400@10.20.20.140>;tag=reg.nhcgrp.com+6+dcaf5077+b4ed2048
Content-Length: 0
Supported: resource-priority, siprec, 100rel
To: <sip:4103606400@wlr.nhcgrp.com>
Contact: <sip:73779d5cc331ecbc2700c9686b64993f@reg.nhcgrp.com:5070>
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event, calling-name, ua-profile
Max-Forwards: 69
Call-ID: 0gQAAC8WAAACBAAALxYAAHN2pc7hlYdwaEgI5BU/Qr+xDj/QVlXooPRZfGljQZsO@reg.nhcgrp.com
CSeq: 419635477 OPTIONS
Organization: Metaswitch Networks
Accept: application/sdp, application/dtmf-relay
19:17:12 19680577mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.58.90.20:5070;rport;branch=z9hG4bK+a07169ebbc3f39b1fcf516b7a303c51d1+sip+6+abf4d16f
From: <sip:4103606400@10.20.20.140>;tag=reg.nhcgrp.com+6+dcaf5077+b4ed2048
Call-ID: 0gQAAC8WAAACBAAALxYAAHN2pc7hlYdwaEgI5BU/Qr+xDj/QVlXooPRZfGljQZsO@reg.nhcgrp.com
CSeq: 419635477 OPTIONS
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.0.1.0 build 8
To: <sip:4103606400@wlr.nhcgrp.com>;tag=4e44485292787219
Content-Type: application/sdp
Content-Length: 195
v=0
o=UserA 1190979677 319948969 IN IP4 192.168.1.233
s=Session SDP
c=IN IP4 192.168.1.233
t=0 0
m=audio 8000 RTP/AVP 0 18
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
19:17:18 19685614mS RES: Wed 11/9/2019 19:17:18 FreeMem=56729688 Heap=56333288(5) Cache=396400 MemObjs=8552(Max 9842) CMMsg=4(5) ASN=0 Buff=5200 1357 1000 7443 5 Links=51954(52168) BTree=397(1105) CB=6064 MCT=0 CPU=10.45% CPUStats=09.03%/1/4/3775/16610
/17990/00.11%/1/02.59% MCR=0 MCW=0 DEV=0
19:17:18 19685614mS RES2: IP 500 V2 11.0.0.1.0 build 8 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=20(TLS=7 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:17:18 19685614mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:17:18 19685614mS RES5: CLog MemObjs=125 FreePoolMem(Objs)=3900(75) TotalMem=10400 StringsTotalMem=80150
19:17:21 19688056mS CMCallEvt: 0000000000000000 0.2780.0 -1 BaseEP: NEW CMEndpoint f4fbaf6c TOTAL NOW=1 CALL_LIST=0
19:17:21 19688057mS NAT: Info: Line 17: Not using STUN for media in this case.
19:17:21 19688058mS SIP Tx: UDP 192.168.1.233:5060 -> 192.58.90.20:5070
REGISTER sip:wlr.nhcgrp.com SIP/2.0
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK02769b23f9133828bc68c4924cff2d4e
From: <sip:4103606400@wlr.nhcgrp.com>;tag=c352d98377af4351
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662875 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>
Expires: 3600
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="15b658b278c9dcec6b5a156b0c420a10",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c8,cnonce="4ce3da844e2623742d42"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:21 19688070mS SIP Rx: UDP 192.58.90.20:5070 -> 192.168.1.233:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.233:5060;rport;branch=z9hG4bK02769b23f9133828bc68c4924cff2d4e
From: <sip:4103606400@wlr.nhcgrp.com>;tag=c352d98377af4351
To: <sip:4103606400@wlr.nhcgrp.com>
Call-ID: 1213e819156e1132c90f5bf85146839a
CSeq: 1625662875 REGISTER
Contact: <sip:4103606400@192.168.1.233:5060;transport=udp>;expires=30
Expires: 30
Authorization: Digest username="4103606400",realm="wlr.nhcgrp.com",nonce="93936a9ddd96",response="15b658b278c9dcec6b5a156b0c420a10",uri="sip:wlr.nhcgrp.com",algorithm=MD5,qop=auth,nc=000001c8,cnonce="4ce3da844e2623742d42"
Max-Forwards: 70
User-Agent: IP Office 11.0.0.1.0 build 8
Supported: timer
Content-Length: 0
19:17:21 19688073mS CMCallEvt: c0a801e900000adc 0.2780.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
19:17:21 19688073mS CMCallEvt: c0a801e900000adc 0.2780.0 -1 BaseEP: DELETE CMEndpoint f4fbaf6c TOTAL NOW=0 CALL_LIST=0
********** Warning: Logging to Screen Stopped **********