×
INTELLIGENT WORK FORUMS
FOR COMPUTER PROFESSIONALS

Contact US

Log In

Come Join Us!

Are you a
Computer / IT professional?
Join Tek-Tips Forums!
  • Talk With Other Members
  • Be Notified Of Responses
    To Your Posts
  • Keyword Search
  • One-Click Access To Your
    Favorite Forums
  • Automated Signatures
    On Your Posts
  • Best Of All, It's Free!

*Tek-Tips's functionality depends on members receiving e-mail. By joining you are opting in to receive e-mail.

Posting Guidelines

Promoting, selling, recruiting, coursework and thesis posting is forbidden.

Students Click Here

9.0.5 random cold start reboots
4

9.0.5 random cold start reboots

9.0.5 random cold start reboots

(OP)
Hi everyone, I had a system that got hit by lightning last year, changed the cabinet & everything came up. Shortly after they noticed their phones going down during the day. Went back & changed the two combo cards, & the 8 digital port card. I did reused the same PRI card that is attached to the 8 digital card. The system was fine for months after, Early January I switched out my UPS back to their UPS (thinking that was the problem before I switched the cards). A little over a month later the system started to reboot again on its own. Went back & installed my UPS, but still getting random reboots. They don't happen every night, but do so more at night/early morning. Had it three times over the weekend. Thought we would run Monitor & wait for it to reboot. This is the last time it did reboot at 4:12 am today. You can see that in this trace I just did a few before & when the KSU looked like it was back up fully. Hopefully might be able to make sense of it all. The one I’m really questioning & don’t know what it means is:
04:11:13 114240959mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
04:11:13 114240960mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
This is what is shown right before it rebooted as you will see.

Here is the partial list I copied before, during, & after the reboot.
Thanks


04:11:13 114240934mS SIP Rx: UDP 148.72.168.39:5907 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5907;branch=z9hG4bK-3665937468;rport
Content-Length: 0
From: "3021" <sip:3021@192.168.1.9:5060>;tag=727952646
Accept: application/sdp
User-Agent: FPBX
To: "3021" <sip:3021@192.168.1.9:5060>
Contact: sip:123@1.1.1.1
CSeq: 1 REGISTER
Call-ID: 3338793019
Max-Forwards: 70

04:11:13 114240938mS SIP Tx: UDP 192.168.1.9:5060 -> 148.72.168.39:5907
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 127.0.0.1:5907;branch=z9hG4bK-3665937468;rport
From: "3021" <sip:3021@192.168.1.9:5060>;tag=727952646
Call-ID: 3338793019
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: "3021" <sip:3021@192.168.1.9:5060>;tag=6d50332cda7c8e8e
Content-Length: 0

04:11:13 114240954mS SIP Rx: UDP 148.72.168.39:5907 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5907;branch=z9hG4bK-554063819;rport
Content-Length: 0
From: "3021" <sip:3021@192.168.1.9:5060>;tag=1948478350
Accept: application/sdp
User-Agent: FPBX
To: "3021" <sip:3021@192.168.1.9:5060>
Contact: sip:123@1.1.1.1
CSeq: 1 REGISTER
Call-ID: 1073353056
Max-Forwards: 70

04:11:13 114240958mS SIP Tx: UDP 192.168.1.9:5060 -> 148.72.168.39:5907
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 127.0.0.1:5907;branch=z9hG4bK-554063819;rport
From: "3021" <sip:3021@192.168.1.9:5060>;tag=1948478350
Call-ID: 1073353056
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: "3021" <sip:3021@192.168.1.9:5060>;tag=2d0852578a8fff4b
Content-Length: 0

04:11:13 114240959mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
04:11:13 114240960mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)

********** contact lost with 207.109.8.178 at 04:12:03 19/2/2018 - reselect = 9 **********
******************************************************************

********** SysMonitor v9.1.12.0 build 212 **********

********** contact made with 207.109.8.178 at 04:12:40 19/2/2018 **********

********** System (207.109.8.178) has been up and running for 44secs(44419mS) **********

********** Warning: TEXT File Logging selected **********


********** Warning: TEXT Logging to C:\Program Files (x86)\Avaya\IP Office\Monitor\\5852.TMP File: STARTED on 19/2/2018 04:12:40 **********
44420mS PRN: Monitor Started IP=65.101.168.132 IP 500 V2 9.0.5.0 build 972 CH Wilson
(Supports Unicode, System Locale is default)
44420mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=0(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=1)
04:12:39 44910mS PRN: Run Conference Scheduler TIME: Mon 19/02/2018 04:12:39
04:12:41 46186mS LIC: INFO: 0 power user licenses 0 0
04:12:41 46189mS PRN: Created Tone MOH Source 0
04:12:41 46189mS PRN: Playing tone MOH Source 0
04:12:41 46189mS PRN: Created Tone MOH Source 1
04:12:41 46189mS PRN: Playing tone MOH Source 1
04:12:41 46189mS PRN: Created Tone MOH Source 2
04:12:41 46190mS PRN: Playing tone MOH Source 2
04:12:41 46190mS PRN: Created Tone MOH Source 3
04:12:41 46190mS PRN: Playing tone MOH Source 3
04:12:41 46190mS PRN: Created Tone MOH Source 4
04:12:41 46191mS PRN: Playing tone MOH Source 4
04:12:41 46275mS PRN: System Started from System Card, Primary Directory
04:12:41 46276mS PRN: WARNING:
04:12:41 46276mS PRN: System was not Shutdown correctly
04:12:41 46276mS PRN:
04:12:41 46276mS PRN: Last Shutdown/Reboot: Fri 16/02/2018 10:25:31
04:12:41 46276mS PRN: IPOFFICE: Mon 2018/2/19 04:12:38 FreeMem=54293008 FreeNoC=2875316
04:12:41 46285mS PRN: Removing Temporary Files
04:12:42 46784mS RES: Mon 19/2/2018 04:12:42 FreeMem=54296688 54157552(1) CachedMem=139136 CMMsg=3(5) Buff=5200 1353 1000 7404 5 Links=4704 BTree=13863 CPU=100.00% CPUStats=0/0/4294967295/0/0/0/0 MCR=0 MCW=0
04:12:42 46784mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=71 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:12:42 46784mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:12:42 46877mS SIP Rx: UDP 89.163.255.176:49870 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 0.0.0.0:49870;branch=z9hG4bK1840638478
Max-Forwards: 70
From: <sip:11500@192.168.1.9:5060>;tag=348601259
To: <sip:11500@192.168.1.9:5060>
Call-ID: 1066014421-1810769402-670967041
CSeq: 1 REGISTER
Contact: <sip:11500@0.0.0.0:49870>
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: pplsip
Content-Type: application/sdp
Content-Length: 0

04:12:42 46881mS SIP Tx: UDP 192.168.1.9:5060 -> 89.163.255.176:49870
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 0.0.0.0:49870;branch=z9hG4bK1840638478
From: <sip:11500@192.168.1.9:5060>;tag=348601259
Call-ID: 1066014421-1810769402-670967041
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: <sip:11500@192.168.1.9:5060>;tag=2a0a7fead83acffa
Content-Length: 0

04:12:43 47286mS RES3: Tasks=(239)CM (122)Daemon (40)BufferReporter
04:12:43 47286mS RES: Mon 19/2/2018 04:12:42 FreeMem=54295712 54138832(2) CachedMem=156880 CMMsg=3(5) Buff=5200 1353 1000 7404 5 Links=4698 BTree=13863 CPU=00.00% CPUStats=348/741/608513/608513/608513/0/0 MCR=8316812 MCW=762
04:12:43 47286mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=72 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:12:43 47287mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:12:43 47653mS SIP Rx: UDP 163.172.116.38:55406 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 163.172.116.38:55406;branch=z9hG4bK93f5248f-c88b-43d5-bd6b-2545b88661c6;rport
To: "3560"<sip:3560@192.168.1.9:5060>
From: "3560"<sip:3560@192.168.1.9:5060>;tag=usoweldo
CSeq: 1 REGISTER
Call-ID: mgvpgbqeonphtvbqtdcgmbcgqvqroduslqqwfknlflwnumlpkh
Max-Forwards: 70
Contact: <sip:3560@163.172.116.38:55406;rinstance=55cd253ecf27fb4b>
User-Agent: Cisco v11.0.0
Content-Length: 0
Expires: 3600
Supported: 100rel
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE

04:12:43 47657mS SIP Tx: UDP 192.168.1.9:5060 -> 163.172.116.38:55406
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 163.172.116.38:55406;branch=z9hG4bK93f5248f-c88b-43d5-bd6b-2545b88661c6;rport
From: "3560" <sip:3560@192.168.1.9:5060>;tag=usoweldo
Call-ID: mgvpgbqeonphtvbqtdcgmbcgqvqroduslqqwfknlflwnumlpkh
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: "3560" <sip:3560@192.168.1.9:5060>;tag=9443ca5aef235478
Content-Length: 0

04:12:44 48420mS PRN: +++ START OF UDP MONITOR CLIENT DUMP +++
04:12:44 48420mS PRN: CLIENT: IPAddress=65.101.168.132 UDPPort=19447 LastReceived=44523(3897) LastSent=48420(0) LastOldUpdate=44419(4001) SentPackets=40 MissedPackets=0 OldSentPackets=0 OldMissedPackets=0
04:12:44 48420mS PRN: ++++ END OF UDP MONITOR CLIENT DUMP ++++
04:12:44 48743mS ISDNL3Evt: v=9 p1=9,p2=1001,p3=4,p4=127,s1=
04:12:44 48744mS ISDNL3Evt: v=9 p1=9,p2=1001,p3=5,p4=0,s1=
04:12:44 48744mS CMLineRx: v=9
CMLineDown
Line: type=Q931Line 9 Call: lid=0 id=-1 in=0
04:12:44 48744mS CMLineTx: v=9
CMRestart
Line: type=Q931Line 9 Call: lid=0 id=-1 in=0
04:12:44 48748mS CMTARGET: CMQ931Trunk::PreprocessInboundMsg - failure_timer set
04:12:44 48851mS CMExtnEvt: 22 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48857mS CMExtnEvt: 13 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 28 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 29 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 30 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 33 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 34 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48871mS CMExtnEvt: 35 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48879mS CMExtnEvt: 22 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48879mS PRN: No Font Download needed
04:12:44 48879mS CMExtnEvt: 13 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48879mS PRN: No Font Download needed
04:12:44 48880mS CMExtnEvt: 27 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48880mS CMExtnEvt: 12 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48880mS CMExtnEvt: 24 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48880mS CMExtnEvt: 31 UpgradeServer: Fw Param Resp: TYPE=0x67 HW-vin=01, FW-vint=09, AppType=0x00 UpgradeState=0x00
04:12:44 48893mS CMExtnEvt: 29 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48893mS PRN: No Font Download needed
04:12:44 48893mS CMExtnEvt: 30 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48893mS PRN: No Font Download needed
04:12:44 48894mS CMExtnEvt: 33 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48894mS PRN: No Font Download needed
04:12:44 48894mS CMExtnEvt: 34 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48894mS PRN: No Font Download needed
04:12:44 48894mS CMExtnEvt: 35 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48894mS PRN: No Font Download needed
04:12:44 48899mS CMExtnEvt: 28 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48899mS PRN: No Font Download needed
04:12:44 48903mS CMExtnEvt: 12 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48903mS PRN: No Font Download needed
04:12:44 48904mS CMExtnEvt: 24 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48904mS PRN: No Font Download needed
04:12:44 48904mS CMExtnEvt: 27 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48904mS PRN: No Font Download needed
04:12:44 48904mS CMExtnEvt: 31 UpgradeServer: RxFontCapabilities Font1=0x00 Font2=0x00 UpgradeState=0x0f
04:12:44 48904mS PRN: No Font Download needed
04:12:44 48920mS PRN: DNS - refreshing: have valid (new) result
04:12:45 49621mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
04:12:45 49621mS PRN: + loader: 1.40
04:12:45 49622mS PRN: + cpu: id 12 board 8 pld 27 type c10 options a802
04:12:45 49622mS PRN: + cpu: sn 17WZ0840C10Y
04:12:45 49622mS PRN: + fpga: id 1 issue 0 build 827
04:12:45 49622mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
04:12:45 49622mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
04:12:45 49622mS PRN: +------------------------------------------------------------
04:12:45 49622mS PRN: + Slot 1: Base COMBO6210 Board=0x04 PLD=0x09
04:12:45 49622mS PRN: + Mezzanine ATM4 V2 Board=0x01 PLD=0x04
04:12:45 49622mS PRN: +------------------------------------------------------------
04:12:45 49622mS PRN: + Slot 2: Base COMBO6210 Board=0x04 PLD=0x09
04:12:45 49622mS PRN: + Mezzanine ATM4 V2 Board=0x01 PLD=0x04
04:12:45 49622mS PRN: +------------------------------------------------------------
04:12:45 49622mS PRN: + Slot 3: Base DIGSTA8 Board=0x13 PLD=0x06
04:12:45 49622mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
04:12:45 49623mS PRN: +------------------------------------------------------------
04:12:45 49623mS PRN: + Slot 4: Base NONE
04:12:45 49623mS PRN: + Mezzanine NONE
04:12:45 49623mS PRN: +------------------------------------------------------------
04:12:45 49623mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
04:12:45 49623mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
04:12:45 49623mS PRN: +------------------------------------------------------------
04:12:45 49623mS PRN: + System SD Card: MNTD
04:12:45 49678mS PRN: + CID Register: 0x284245202020202010942a35a400e6cf
04:12:45 49678mS PRN: + CSD Register: 0x400e00325b5900003bad7f800a4000c5
04:12:45 49678mS PRN: + SCR Register: 0x0235800000000000
04:12:45 49679mS PRN: + OCR Register: 0xc0ff8000
04:12:45 49681mS PRN: + Status Register: 0x0000000003000000030390000805
04:12:45 49681mS PRN: +------------------------------------------------------------
04:12:45 49681mS PRN: + Optional SD Card: UMNT
04:12:45 49681mS PRN: +------------------------------------------------------------
04:12:45 49681mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
04:12:45 49681mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
04:12:45 49681mS PRN: +------------------------------------------------------------
04:12:45 49681mS PRN: + 0x00000003, 0x00000000, 0x00000000, 0x00000022, 0x00000000
04:12:45 49681mS PRN: + 0x00000000, 0x00000000, 0x00000022, 0x00000000, 0x00000000
04:12:45 49681mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000022, 0x00000000
04:12:45 49681mS PRN: + 0x00000007, 0x00000000, 0x00000000, 0x00000000, 0x00000000
04:12:45 49682mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
04:12:45 49682mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
04:12:45 49682mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
04:12:45 49682mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
04:12:45 49682mS PRN: + 0x00000000, 0x594a11fe, 0x488780e6, 0x52133895, 0x8fc717bb
04:12:45 49682mS PRN: +------------------------------------------------------------
04:12:45 49682mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
04:12:46 50328mS CMExtnTx: v=60, p1=8000
CMProceeding
Line: type=IPLine 250 Call: lid=251 id=1 in=1
04:12:46 50328mS CMExtnTx: v=60, p1=8000
CMConnect
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
04:12:46 50366mS CMExtnTx: v=61, p1=8001
CMProceeding
Line: type=IPLine 250 Call: lid=252 id=2 in=1
04:12:46 50366mS CMExtnTx: v=61, p1=8001
CMConnect
Line: type=IPLine 250 Call: lid=252 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
04:12:46 50888mS CMExtnEvt: Heather:22 ExtnFault now 0
04:12:46 50889mS CMExtnEvt: 22 ReportAttachment type=0
04:12:46 50891mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Heather Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Heather Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50892mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGAccts Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGAccts Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50892mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50895mS CMExtnEvt: Dustin:13 ExtnFault now 0
04:12:46 50896mS CMExtnEvt: 13 ReportAttachment type=0
04:12:46 50898mS CMExtnTx: v=13, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dustin Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50903mS PRN: Config Write Wake Up
04:12:46 50907mS CMExtnEvt: Kelly:29 ExtnFault now 0
04:12:46 50909mS CMExtnEvt: 29 ReportAttachment type=0
04:12:46 50910mS CMExtnTx: v=29, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kelly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kelly Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50913mS CMExtnEvt: Holly:30 ExtnFault now 0
04:12:46 50915mS PRN: ObjectMemoryPool: SSIComponentId, filled 7 times
04:12:46 50915mS CMExtnEvt: 30 ReportAttachment type=0
04:12:46 50917mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Holly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Holly Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50917mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50920mS CMExtnEvt: Kim:33 ExtnFault now 0
04:12:46 50922mS CMExtnEvt: 33 ReportAttachment type=0
04:12:46 50923mS CMExtnTx: v=33, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kim Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kim Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50926mS CMExtnEvt: Cindy:34 ExtnFault now 0
04:12:46 50927mS CMExtnEvt: 34 ReportAttachment type=0
04:12:46 50929mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Cindy Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cindy Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50929mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGRec1 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGRec1 Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50932mS CMExtnEvt: Brandon:35 ExtnFault now 0
04:12:46 50934mS CMExtnEvt: 35 ReportAttachment type=0
04:12:46 50936mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Brandon Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Brandon Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50936mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50940mS PRN: Updates IO list size 0 updated list size 7
04:12:46 50950mS PRN: Config Write Completed
04:12:46 50955mS CMExtnEvt: Cris:28 ExtnFault now 0
04:12:46 50956mS CMExtnEvt: 28 ReportAttachment type=0
04:12:46 50958mS CMExtnTx: v=28, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Cris Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cris Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50960mS CMExtnEvt: Dennis:12 ExtnFault now 0
04:12:46 50962mS CMExtnEvt: 12 ReportAttachment type=0
04:12:46 50964mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dennis Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dennis Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50964mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGSales1 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGSales1 Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50967mS CMExtnEvt: Amber:24 ExtnFault now 0
04:12:46 50968mS CMExtnEvt: 24 ReportAttachment type=0
04:12:46 50970mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Amber Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Amber Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50970mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50973mS CMExtnEvt: Jenn:27 ExtnFault now 0
04:12:46 50974mS CMExtnEvt: 27 ReportAttachment type=0
04:12:46 50976mS CMExtnTx: v=27, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Jenn Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Jenn Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50984mS CMExtnEvt: Dustin2:31 ExtnFault now 0
04:12:46 50985mS CMExtnEvt: 31 ReportAttachment type=0
04:12:46 50987mS CMExtnTx: v=31, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Dustin2 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin2 Msgs=0]
Timed: 19/02/18 04:12
04:12:46 50991mS PRN: Config Write Wake Up
04:12:47 51159mS PRN: TI VCM: R13 API interfaces initialised
04:12:47 51160mS PRN: TI VCM: DSP 0 download complete
04:12:47 51160mS PRN: TI VCM: download DSP 2, serial
04:12:47 51492mS PRN: Updates IO list size 0 updated list size 5
04:12:47 51498mS PRN: Config Write Completed
04:12:47 51514mS PRN: +++ Slot (b) Line ( 0), line voltage -53v, current 0mA
04:12:47 51515mS PRN: +++ Slot (a) Line ( 0), line voltage -51v, current 0mA
04:12:46 51803mS RES: Mon 19/2/2018 04:12:46 FreeMem=53909600 53740000(1) CachedMem=169600 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3601 BTree=13833 CPU=00.00% CPUStats=31/61/3932/7300/608513/0/0 MCR=6656 MCW=0
04:12:46 51804mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=67 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=21(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:12:46 51804mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:12:48 53352mS ISDNL3Evt: v=9 p1=9,p2=1001,p3=5,p4=0,s1=
04:12:48 53390mS CMExtnTx: v=60, p1=8000
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE (13) unknown
04:12:48 53393mS CMExtnEvt: CH Dub.:60 ExtnFault now 0
04:12:48 53395mS CMExtnEvt: 60 ReportAttachment type=0
04:12:48 53397mS CMExtnTx: v=60, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[CH Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [CH Dub. Msgs=0]
Timed: 19/02/18 04:12
04:12:48 53400mS PRN: Config Write Wake Up
04:12:48 53433mS CMExtnTx: v=61, p1=8001
CMInformation
Line: type=IPLine 250 Call: lid=252 id=2 in=1
IE (13) unknown
04:12:48 53435mS CMExtnEvt: Sham Dub.:61 ExtnFault now 0
04:12:48 53437mS PRN: ObjectMemoryPool: SSIComponentId, filled 8 times
04:12:48 53437mS CMExtnEvt: 61 ReportAttachment type=0
04:12:48 53440mS CMExtnTx: v=61, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Sham Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Sham Dub. Msgs=0]
Timed: 19/02/18 04:12
04:12:48 53444mS PRN: Updates IO list size 0 updated list size 2
04:12:48 53448mS PRN: Config Write Completed
04:12:48 53697mS ISDNL1Evt: v=9 peb=9,F1 F2
04:12:48 53746mS ISDNL1Evt: v=9 peb=9,Locked
04:12:48 53746mS PRN: Falc: LockToFalc: 9
04:12:48 53746mS PRN: MEZZ_PRI_CLOCK_CNTL: f1
04:12:48 53746mS PRN: FPGA_REC_CLK_SELECT: 0c
04:12:48 53746mS PRN: SetSlaveMode: 09
04:12:48 53748mS ISDNL1Evt: v=9 peb=9,PHAI
04:12:49 54886mS SIP Rx: UDP 163.172.116.38:61305 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 163.172.116.38:61305;branch=z9hG4bK3e412b4c-bd70-4385-bf4a-34cd933c1136;rport
To: "3561"<sip:3561@192.168.1.9:5060>
From: "3561"<sip:3561@192.168.1.9:5060>;tag=rblqbolp
CSeq: 1 REGISTER
Call-ID: qqqeraejoqykkauxvnbxbcebeydfbbykhjatkphgkgaodjwpyi
Max-Forwards: 70
Contact: <sip:3561@163.172.116.38:61305;rinstance=5fd435fbc227cf4b>
User-Agent: Cisco v11.0.0
Content-Length: 0
Expires: 3600
Supported: 100rel
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE

04:12:49 54890mS SIP Tx: UDP 192.168.1.9:5060 -> 163.172.116.38:61305
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 163.172.116.38:61305;branch=z9hG4bK3e412b4c-bd70-4385-bf4a-34cd933c1136;rport
From: "3561" <sip:3561@192.168.1.9:5060>;tag=rblqbolp
Call-ID: qqqeraejoqykkauxvnbxbcebeydfbbykhjatkphgkgaodjwpyi
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: "3561" <sip:3561@192.168.1.9:5060>;tag=1b08651a33389aec
Content-Length: 0

04:12:49 54972mS ISDNL3Evt: v=9 p1=9,p2=1000,p3=0,p4=0,s1=
04:12:49 54974mS CMLineRx: v=9
CMLineUp
Line: type=Q931Line 9 Call: lid=0 id=-1 in=0
04:12:49 54974mS CMTARGET: ISDN BChannel 1: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 1: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 2: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 2: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 3: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 3: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 4: in-service check = 0
04:12:49 54975mS CMTARGET: ISDN BChannel 4: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 5: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 5: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 6: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 6: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 7: in-service check = 0
04:12:49 54976mS CMTARGET: ISDN BChannel 7: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 8: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 8: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 9: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 9: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 10: in-service check = 0
04:12:49 54977mS CMTARGET: ISDN BChannel 10: in-service check = 0
04:12:49 54978mS CMTARGET: ISDN BChannel 11: in-service check = 0
04:12:49 54978mS CMTARGET: ISDN BChannel 11: in-service check = 0
04:12:49 54978mS CMTARGET: ISDN BChannel 12: in-service check = 0
04:12:49 54978mS CMTARGET: ISDN BChannel 12: in-service check = 0
04:12:49 54984mS CMTARGET: ISDN BChannel 1: in-service check = 1
04:12:49 54984mS CMTARGET: ISDN BChannel 1: in-service check = 1
04:12:49 54984mS CMTARGET: ISDN BChannel 2: in-service check = 1
04:12:49 54984mS CMTARGET: ISDN BChannel 2: in-service check = 1
04:12:49 54984mS CMTARGET: ISDN BChannel 3: in-service check = 1
04:12:49 54985mS CMTARGET: ISDN BChannel 3: in-service check = 1
04:12:49 54985mS CMTARGET: ISDN BChannel 4: in-service check = 1
04:12:49 54985mS CMTARGET: ISDN BChannel 4: in-service check = 1
04:12:49 54985mS CMTARGET: ISDN BChannel 5: in-service check = 1
04:12:49 54985mS CMTARGET: ISDN BChannel 5: in-service check = 1
04:12:49 54986mS CMTARGET: ISDN BChannel 6: in-service check = 1
04:12:49 54986mS CMTARGET: ISDN BChannel 6: in-service check = 1
04:12:49 54986mS CMTARGET: ISDN BChannel 7: in-service check = 1
04:12:49 54986mS CMTARGET: ISDN BChannel 7: in-service check = 1
04:12:49 54986mS CMTARGET: ISDN BChannel 8: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 8: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 9: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 9: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 10: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 10: in-service check = 1
04:12:49 54987mS CMTARGET: ISDN BChannel 11: in-service check = 1
04:12:49 54988mS CMTARGET: ISDN BChannel 11: in-service check = 1
04:12:49 54988mS CMTARGET: ISDN BChannel 12: in-service check = 1
04:12:49 54988mS CMTARGET: ISDN BChannel 12: in-service check = 1
04:12:50 55923mS PRN: +------ Line Tester Slot(b) Line( 0) Impedance Match Results -------------
04:12:50 55923mS PRN: + AtmIO::ImpedanceMatch - curr Imp=370 ohms + (820 ohms || 110nF) ->Erl=7, best Imp=370 ohms + (820 ohms || 110nF) ->Erl=7
04:12:50 55923mS PRN: +-----------------------------------------------------------------------------
04:12:50 55928mS PRN: +------ Line Tester Slot(a) Line( 0) Impedance Match Results -------------
04:12:50 55928mS PRN: + AtmIO::ImpedanceMatch - curr Imp=370 ohms + (820 ohms || 110nF) ->Erl=7, best Imp=370 ohms + (820 ohms || 110nF) ->Erl=7
04:12:50 55928mS PRN: +-----------------------------------------------------------------------------
04:12:51 56805mS RES: Mon 19/2/2018 04:12:51 FreeMem=53852152 53669720(2) CachedMem=182432 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3306 BTree=13825 CPU=64.52% CPUStats=3/35/3789/3789/608513/1/0 MCR=2560 MCW=0
04:12:51 56805mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=61 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=21(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:12:51 56805mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:12:52 57362mS PRN: +------ Echo Reduction - Slot(b) Line( 0) Echo Reduction Results -------------
04:12:52 57362mS PRN: + Echo Reduction - Current Erl=7, New Erl=13
04:12:52 57362mS PRN: +-----------------------------------------------------------------------------
04:12:52 57367mS PRN: +------ Echo Reduction - Slot(a) Line( 0) Echo Reduction Results -------------
04:12:52 57367mS PRN: + Echo Reduction - Current Erl=7, New Erl=12
04:12:52 57367mS PRN: +-----------------------------------------------------------------------------
04:12:52 57568mS PRN: +------ Echo Cancellation - Slot(b) Line( 0) Echo Cancellation Results -------------
04:12:52 57568mS PRN: + Echo Cancellation - Current Erl=11, New Erl=43
04:12:52 57568mS PRN: +-----------------------------------------------------------------------------
04:12:52 57568mS PRN: TxLlMessageToParent llMsgType= CmLlMsgImpedance code =107
04:12:52 57572mS PRN: Config Write Wake Up
04:12:52 57575mS PRN: +------ Echo Cancellation - Slot(a) Line( 0) Echo Cancellation Results -------------
04:12:52 57575mS PRN: + Echo Cancellation - Current Erl=11, New Erl=53
04:12:52 57575mS PRN: +-----------------------------------------------------------------------------
04:12:52 57575mS PRN: TxLlMessageToParent llMsgType= CmLlMsgImpedance code =107
04:12:52 57579mS PRN: Updates IO list size 0 updated list size 2
04:12:52 57580mS PRN: Config Write Completed
04:12:52 57844mS PRN: TI VCM: DSP 2 download complete
04:12:56 61840mS RES: Mon 19/2/2018 04:12:56 FreeMem=53840432 53656688(2) CachedMem=183744 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3240 BTree=13825 CPU=03.66% CPUStats=3/11/3789/8756/608513/0/0 MCR=7168 MCW=0
04:12:56 61840mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=21(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:12:56 61840mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:02 67284mS RES: Mon 19/2/2018 04:13:02 FreeMem=53828816 53644480(2) CachedMem=184336 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3180 BTree=13825 CPU=12.33% CPUStats=3/13/3789/7340/608513/0/0 MCR=5120 MCW=0
04:13:02 67285mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=60 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:02 67285mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:03 68896mS PRN: Optimizing BTree Lists Completed...Started:68225
04:13:07 72332mS RES: Mon 19/2/2018 04:13:07 FreeMem=53820464 53635560(1) CachedMem=184904 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3137 BTree=14203 CPU=15.33% CPUStats=3/12/3789/7845/608513/0/0 MCR=4608 MCW=0
04:13:07 72333mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:07 72333mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)

********** SysMonitor v9.1.12.0 build 212 [connected to 207.109.8.178 (CH Wilson)] **********
04:13:09 74140mS PRN: Monitor Status IP 500 V2 9.0.5.0 build 972
04:13:09 74141mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=9 VMAIL=0(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=1)
04:13:11 76198mS PRN: WAVFileReader - completed
04:13:12 77789mS RES: Mon 19/2/2018 04:13:12 FreeMem=53810120 53626944(1) CachedMem=183176 CMMsg=3(5) Buff=5200 1341 1000 7404 5 Links=3087 BTree=14203 CPU=21.33% CPUStats=3/13/3789/6665/608513/0/0 MCR=3072 MCW=0
04:13:12 77789mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:12 77789mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:13 78821mS SIP Rx: UDP 89.163.255.176:55102 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 0.0.0.0:55102;branch=z9hG4bK1501390606
Max-Forwards: 70
From: <sip:11501@192.168.1.9:5060>;tag=823719572
To: <sip:11501@192.168.1.9:5060>
Call-ID: 208887483-14146065-835386336
CSeq: 1 REGISTER
Contact: <sip:11501@0.0.0.0:55102>
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: pplsip
Content-Type: application/sdp
Content-Length: 0

04:13:13 78825mS SIP Tx: UDP 192.168.1.9:5060 -> 89.163.255.176:55102
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 0.0.0.0:55102;branch=z9hG4bK1501390606
From: <sip:11501@192.168.1.9:5060>;tag=823719572
Call-ID: 208887483-14146065-835386336
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: <sip:11501@192.168.1.9:5060>;tag=107610cc5288b635
Content-Length: 0

04:13:19 83344mS RES: Mon 19/2/2018 04:13:18 FreeMem=53803768 53619200(2) CachedMem=184568 CMMsg=3(5) Buff=5200 1342 1000 7404 5 Links=3061 BTree=14203 CPU=25.33% CPUStats=3/14/3789/7163/608513/0/0 MCR=3072 MCW=0
04:13:19 83344mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:19 83344mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:24 88378mS RES: Mon 19/2/2018 04:13:23 FreeMem=53796344 53611728(2) CachedMem=184616 CMMsg=3(5) Buff=5200 1342 1000 7404 5 Links=3006 BTree=14203 CPU=20.50% CPUStats=3/14/3789/7451/608513/0/0 MCR=4096 MCW=0
04:13:24 88378mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:24 88378mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:28 93821mS RES: Mon 19/2/2018 04:13:29 FreeMem=53784408 53598224(2) CachedMem=186184 CMMsg=3(5) Buff=5200 1342 1000 7404 5 Links=2963 BTree=14203 CPU=01.83% CPUStats=3/11/3789/8904/608513/0/0 MCR=6656 MCW=0
04:13:28 93821mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:28 93821mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:29 94510mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Amber Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Amber Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94511mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Brandon Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Brandon Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94511mS CMExtnTx: v=60, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[CH Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [CH Dub. Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94511mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Cindy Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cindy Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94512mS CMExtnTx: v=28, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Cris Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cris Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94512mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dennis Msgs=0 Old=0 Sav=1] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dennis Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94513mS CMExtnTx: v=13, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dustin Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94576mS CMExtnTx: v=31, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Dustin2 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin2 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94577mS CMExtnTx: v=36, p1=0
CMVoiceMailStatus
Line: type=AnalogueExtn 19 Call: lid=0 id=1 in=0
Called[Extn36 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
BChan: slot=2 chan=15
UUI type=Local [......2Pd..] [0x01 0xaa 0x00 0x00 0x01 0x02 0x32 0x50 0x64 0x00 0x00 ]
Display [Extn36 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94577mS CMExtnTx: v=37, p1=0
CMVoiceMailStatus
Line: type=AnalogueExtn 19 Call: lid=0 id=2 in=0
Called[Extn37 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
BChan: slot=2 chan=16
UUI type=Local [......2Pd..] [0x01 0xaa 0x01 0x00 0x01 0x02 0x32 0x50 0x64 0x00 0x00 ]
Display [Extn37 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94580mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Heather Msgs=2 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000002] Type=Default Plan=Default
Display [Heather Msgs=2]
Timed: 19/02/18 04:13
04:13:29 94580mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Holly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Holly Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94581mS CMExtnTx: v=27, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Jenn Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Jenn Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94581mS CMExtnTx: v=29, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kelly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kelly Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94581mS CMExtnTx: v=33, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kim Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kim Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94582mS CMExtnTx: v=61, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Sham Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Sham Dub. Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94582mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGAccts Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGAccts Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94583mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94583mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94584mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94584mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94584mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGRec1 Msgs=0 Old=0 Sav=3] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGRec1 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94585mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGSales1 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGSales1 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94587mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dennis Msgs=0 Old=0 Sav=1] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dennis Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94587mS CMExtnTx: v=12, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGSales1 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGSales1 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94587mS CMExtnTx: v=13, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Dustin Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94588mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Heather Msgs=2 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000002] Type=Default Plan=Default
Display [Heather Msgs=2]
Timed: 19/02/18 04:13
04:13:29 94588mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGAccts Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGAccts Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94588mS CMExtnTx: v=22, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94589mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Amber Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Amber Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94589mS CMExtnTx: v=24, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[HGOper Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGOper Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94589mS CMExtnTx: v=27, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Jenn Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Jenn Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94590mS CMExtnTx: v=28, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 2 Call: lid=0 id=-1 in=0
Called[Cris Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cris Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94590mS CMExtnTx: v=10, p1=0
CMVoiceMailStatus
Line: type=AnalogueExtn 18 Call: lid=0 id=1 in=0
Called[Shamrock1 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
BChan: slot=0 chan=7
UUI type=Local [......2Pd..] [0x01 0xf5 0x00 0x00 0x01 0x02 0x32 0x50 0x64 0x00 0x00 ]
Display [Shamrock1 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94590mS CMExtnTx: v=11, p1=0
CMVoiceMailStatus
Line: type=AnalogueExtn 18 Call: lid=0 id=2 in=0
Called[Shamrock2 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
BChan: slot=0 chan=8
UUI type=Local [......2Pd..] [0x01 0xf5 0x00 0x00 0x01 0x02 0x32 0x50 0x64 0x00 0x00 ]
Display [Shamrock2 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94591mS CMExtnTx: v=29, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kelly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kelly Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94591mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Holly Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Holly Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94591mS CMExtnTx: v=30, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94592mS CMExtnTx: v=31, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Dustin2 Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dustin2 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94592mS CMExtnTx: v=33, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Kim Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Kim Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94592mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Cindy Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Cindy Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94592mS CMExtnTx: v=34, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGRec1 Msgs=0 Old=0 Sav=3] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGRec1 Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94593mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[Brandon Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Brandon Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94593mS CMExtnTx: v=35, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
Called[HGDispatch Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [HGDispatch Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94594mS CMExtnTx: v=60, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[CH Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [CH Dub. Msgs=0]
Timed: 19/02/18 04:13
04:13:29 94594mS CMExtnTx: v=61, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Sham Dub. Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Sham Dub. Msgs=0]
Timed: 19/02/18 04:13
04:13:33 98838mS RES: Mon 19/2/2018 04:13:33 FreeMem=53784352 53590624(2) CachedMem=193728 CMMsg=3(5) Buff=5200 1343 1000 7404 5 Links=2971 BTree=14203 CPU=23.50% CPUStats=3/11/3789/6989/608513/0/0 MCR=0 MCW=0
04:13:33 98838mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=56 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:33 98838mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:40 104296mS RES: Mon 19/2/2018 04:13:39 FreeMem=53825752 53590624(2) CachedMem=235128 CMMsg=3(5) Buff=5200 1342 998 7404 5 Links=3046 BTree=14203 CPU=00.00% CPUStats=3/8/3521/10060/608513/0/0 MCR=0 MCW=0
04:13:40 104297mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=54 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=57 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=20(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
04:13:40 104297mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
04:13:47 111169mS SIP Rx: UDP 89.163.255.176:60334 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 0.0.0.0:60334;branch=z9hG4bK1486115913
Max-Forwards: 70
From: <sip:11502@192.168.1.9:5060>;tag=481561727
To: <sip:11502@192.168.1.9:5060>
Call-ID: 1397992236-200388090-780881737
CSeq: 1 REGISTER
Contact: <sip:11502@0.0.0.0:60334>
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: pplsip
Content-Type: application/sdp
Content-Length: 0

04:13:47 111172mS SIP Tx: UDP 192.168.1.9:5060 -> 89.163.255.176:60334
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 0.0.0.0:60334;branch=z9hG4bK1486115913
From: <sip:11502@192.168.1.9:5060>;tag=481561727
Call-ID: 1397992236-200388090-780881737
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,INFO,SUBSCRIBE,REGISTER,PUBLISH
Supported: timer
Server: IP Office 9.0.5.0 build 972
Reason: Q.850;cause=21;text="Call rejected"
To: <sip:11502@192.168.1.9:5060>;tag=2ed342d1a1a6c5a0
Content-Length: 0

04:14:05 129319mS PRN: Optimizing BTree Lists Completed...Started:128898
04:14:09 133535mS SIP Rx: UDP 51.15.149.61:5073 -> 192.168.1.9:5060
REGISTER sip:192.168.1.9:5060 SIP/2.0
Via: SIP/2.0/UDP 51.15.149.61:5073;branch=z9hG4bK17437489084b231b115a3d4b38;rport
From: "6563" <sip:6563@192.168.1.9:5060>;tag=17437489086634
To: "6563" <sip:6563@192.168.1.9:5060>
Call-ID: 89084b23-2406f1b11-5a3d4b38@207.109.8.178
CSeq: 1 REGISTER
Contact: "6563" <sip:6563@51.15.149.61:5073>
User-Agent: VaxSIPUserAgent/3.1
Expires: 1800
Max-Forwards: 70
Content-Length: 0

04:14:09 133538mS SIP Tx: UDP 192.168.1.9:5060 -> 51.15.149.61:5073
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 51.15.149.61:5073;branch=z9hG4bK17437489084b231b115a3d4b38;rport
From: "6563" <sip:6563@192.168.1.9:5060>;tag=17437489086634
Call-ID: 89084b23-2406f1b11-5a3d4b38@207.109.8.178
CSeq: 1 REGISTER
User-Agent: IP Office 9.0.5.0 build 972

RE: 9.0.5 random cold start reboots

Don't post long traces, use the attachment feature.

Also since you're on 9.0.5 start by upgrading to latest service pack.

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

RE: 9.0.5 random cold start reboots

(OP)
Sorry, didn't know how much info was needed. We were thinking going to 9.0.12.

RE: 9.0.5 random cold start reboots

You can attach a trace, if a post is several pages I usually don't bother to read it.

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

RE: 9.0.5 random cold start reboots

If you go on ssa and click on the Alarms it should give you an error code as to why it rebooted or on the first dump when opening a fresh trace on the system it will give you a abnormal termination code and a brief note i.e brownout. However for abnormal terminations i would always recommend upgrading to latest and greatest.

Thank you

ACSS (Manieee on a new account)

RE: 9.0.5 random cold start reboots

(OP)
Thanks Manie, in ssa clicked alarms & get

Last System Restart

Date: 2/19/2018 4:11:54 AM
Reason: Normal Powerup

RE: 9.0.5 random cold start reboots

(OP)
I see how to do that now janni, again sorry. Do you or anyone else knows what this means

04:11:13 114240959mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
04:11:13 114240960mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)

This was the last thing before the shut down, everything else looked consistent before & after the system shut down. This is the only time I see anything like this.
Thanks again everyone

RE: 9.0.5 random cold start reboots

If you are getting normal power up it doesn't look like abnormal termination that avaya system love every now and then. Check the control unit audit on ssa it will probably indicate power failure cold start. I would change kettle lead, change to using a different power socket or connect to a ups and if after all that it continues to happen swap the system out it could be the internal power supply in the system being faulty.

ACSS (Manieee on a new account)

RE: 9.0.5 random cold start reboots

(OP)
Thanks Manie, Yes I get "cold start" but not "power failure" I get "system reboot" under the IP Office Account. Whenever I physically unplug the KSU then I get "power failure" under the IP Office Account. Don't know if you read my first post completely, but the system got hit by lightning about a year ago, replaced the KSU cabinet, everything came up, soon after they said it shuts down on its own. Went & replaced the UPS, system still did it, replaced 3 of the 4 cards, & thought the problem was fixed for numerous months. Finally went back early last month (after having their UPS in my office this whole time with equipment on it to test & was good) to put the original UPS back in. A month later it's resetting again on it's own. I went back & put the UPS it was using for the previous months, & still acting up.
Just been Monitoring things now, hoping someone can read the log & say for sure what to do.

RE: 9.0.5 random cold start reboots

By the way... I see a lot of REGISTER attempts from public IPs. Lock down the system to not be reachable under port 5060 from outside! It’s just a matter of time until you get the bill for expensive long distance calls...

RE: 9.0.5 random cold start reboots

(OP)
Thanks derfloh, don't you need that for port for remote access & IP phones to work? This system has 2 offsite Ip Phone working off it

RE: 9.0.5 random cold start reboots

(OP)
If anyone can tell me what this is:

04:11:13 114240959mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
04:11:13 114240960mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)

I only see it, on the last logs right before it shuts down & on the only the times it does shut down, could it mean a PRI issue? PRI card/ provider issue?
Thanks again everyone

RE: 9.0.5 random cold start reboots

I cant say for certain that ti is related to the lightning strike but it is a possibility.

this is why we always replace ALL equipment in these circumstances - it is impossible to tell what equipment has been weakened by the damage, it is even possible that a weakened card causes damage to the new equipment when installed


Do things on the cheap & it will cost you dear

RE: 9.0.5 random cold start reboots

2
04:12:42 46784mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=71 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034 BURNED MY ASS WHEN STROKE BY LIGHTNING NEED REPLACEMENT

RE: 9.0.5 random cold start reboots

(OP)
Thanks intrigrant, since we replaced everything other then the PRI card so far, would you just start with that? Or, since we never replaced the PRI card & that is/was faulty could that of wrecked the new cards/control unit? Meaning to replace everything?
Thanks again everyone

RE: 9.0.5 random cold start reboots

A PRI card is very sensitive and a system hit by lightning should be considered as total loss, always replace the complete system although some components seem to work.

RE: 9.0.5 random cold start reboots

(OP)
Thanks again intrigrant, we are going to try just switching the 8 digital with the PRI daughter card attached & see what happens. The system just rebooted as I was typing. I see in the new trace the same error you were referring to. Again I see that error I am always asking about right before it reboots also, if you know what that means?

09:31:42 105596476mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
09:31:42 105596477mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)

Also this kind of cold start, system reboot gives me an email from the system events as it happens, but does not include the time or date of when it happens on my email. Like other systems if a line is bad or something similar, we always got the time & date of when it occurs. Is this because of the system is in the process of rebooting & don't have a time stamp to include in the email, or do I have something wrong programmed in the system events tab?

RE: 9.0.5 random cold start reboots

Remote extensions should always use encrypted connections or better VPN. Most here use 9600 phones as remote so that SIP port doesn't need to be open.

RE: 9.0.5 random cold start reboots

The line cards take the biggest hit typically, the fact they are fed externally means they have the greatest potential voltage difference and therefore pass the most current, first thing to change as oppose to the last.

But as mentioned, just swap all, it's what insurance is for and best practice anyway smile

RE: 9.0.5 random cold start reboots

@Derlof : what did you drink with your meal?

RE: 9.0.5 random cold start reboots

(OP)
Well, I replaced the PRI card Friday. On Saturday the system rebooted again on it's own. So, now everything since the lightning hit has been replaced, except the SD card. Still wondering what this means, it only does it right before the system reboots:

09:31:42 105596476mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)
09:31:42 105596477mS PRN: Biggest pools: -1(-1) 0(0) 0(0) 0(0) 0(0)

I see the info intrigrant posted that "burned his ass before" every hour even when the system does not shut down:

04:12:42 46784mS RES2: IP 500 V2 9.0.5.0 build 972 Tasks=53 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=71 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=16(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034 BURNED MY ASS WHEN STROKE BY LIGHTNING NEED REPLACEMENT

Thanks again everyone, would love to get to the bottom of this.

RE: 9.0.5 random cold start reboots

(OP)
After looking at other post & I always wondered if the provider is sending something over the PRI causing the reboot. The other post said if receive a yellow alarm it is from the provider. So I'm getting from SSA (this is from the last time it rebooted by itself) on alarms, trunk 9 (the PRI trunk) I get a yellow alarm at 4:14:03 & a trunk out of service at 4:14:08. Under the Control unit audit I get my Cold start/ System reboot at 4:14:06.
So, to me it looks like I get a yellow alarm from the provider, causing the system to reboot, the system rebooting is what causes the trunk out of service.
Agree or disagree?

RE: 9.0.5 random cold start reboots

Personally i haven't heard of the the yellow alarm rebooting a phone system. If anything it would just drop the trunk.

So to clarify

You have swapped the phone system including all cards in the phone system.
Swapped power cable
Swapped associating patch cable

I would suggest recreating the SD card as it is the only thing left on the system that hasn't been replaced. Alternatively arrange for a licence swap and put in a brand new card.



ACSS (Manieee on a new account)

RE: 9.0.5 random cold start reboots

(OP)
Thanks Manie, you asked, You have swapped the phone system including all cards in the phone system. Yes, but did not swap any cables. Since this is all from a lightning hit, & the provider has three different pieces of equipment that requires power, before the PRI goes to my KSU & I'm getting a yellow alarm. I know they haven't replaced any of their equipment. You still don't think anything could be related to them. I could recreate the SD card, but this is a 24 hour dispatch site (on the phones all the time) & the time we already spent on this is getting lengthy. I would have to do it in the middle of the night & they aren't even in the same city as me.

RE: 9.0.5 random cold start reboots

You do not need to shutdown the phone system to recreate the SD card. Just shutdown SD card via SSA and the unplug it. the system will run for 2 hours without it. Then put the SD card in your laptop recreate it and then pop it back in the phone system.

ACSS (Manieee on a new account)

RE: 9.0.5 random cold start reboots

Just be careful you don't have DECT R4 running as it will stop working when the SD card is removed - seems it isn't covered by the two hour grace period.

| ACSS SME |

RE: 9.0.5 random cold start reboots

Did you upgrade the site to 9.0.12 ?

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

RE: 9.0.5 random cold start reboots

(OP)
Janni, no I have not upgraded yet, after you posted everyone started leaning towards replacing the PRI card so I went that route first. I'm leaning towards a provider problem now since I get those yellow alarms before it shuts down, but since no one is confirming that could be the case I might try upgrading before I get the provider involved.
Thanks

RE: 9.0.5 random cold start reboots

Yellow alarms aren't good but it shouldn't cause the IPO to reboot.

Considering the amount of reboot bugs fixed in each SP upgrading is usually a good idea if you have those issues since that's the first thing Avaya will ask you to do if you create a ticket.

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

RE: 9.0.5 random cold start reboots

(OP)
Ok thanks Janni, is upgrading to the 9.0.12 basically doing a recreate of the SD card that Manie is recommending. Planning on doing that this weekend, the upgrade.
Thanks again everyone

RE: 9.0.5 random cold start reboots

if you have a window of downtime You can upgrade the phone system to 9.0.12 via recreating the SD card. You will just need to make sure you have installed the correct version of manager (or alter your directory) and then format and recreate the SD card. When you plug the SD card back in and boot up the system it will upgrade the phone system.

ACSS (Manieee on a new account)

RE: 9.0.5 random cold start reboots

(OP)
Thanks Manie, I am planning on upgrading the system remotely on Saturday, so I won't be on site since it is not in my same city. I am going to watch it the whole time to make sure everything goes smoothly & be ready to go there if something doesn't load right. Would this be the same as reformatting & recreating?

RE: 9.0.5 random cold start reboots

(OP)
Well it's been close to two weeks now, & no reboots. I ended up remotely upgrading to 9.0.12, so far, so good. Thought I'd let everyone know, if anyone else runs across same problem.
Thanks to everyone who helped.

Red Flag This Post

Please let us know here why this post is inappropriate. Reasons such as off-topic, duplicates, flames, illegal, vulgar, or students posting their homework.

Red Flag Submitted

Thank you for helping keep Tek-Tips Forums free from inappropriate posts.
The Tek-Tips staff will check this out and take appropriate action.

Reply To This Thread

Posting in the Tek-Tips forums is a member-only feature.

Click Here to join Tek-Tips and talk with other members! Already a Member? Login

Close Box

Join Tek-Tips® Today!

Join your peers on the Internet's largest technical computer professional community.
It's easy to join and it's free.

Here's Why Members Love Tek-Tips Forums:

Register now while it's still free!

Already a member? Close this window and log in.

Join Us             Close