IP Office only partially upgrades to service pack 3 9.1.3.0

[djkoby]

I was trying to upgrade an IP Office 500v2 from 9.1.1 to 9.1.3 and only some of the files upgraded. It's the oddest thing. Hopefully somebody can shed light on it. Details are in the attached picture. I normally see this when a bad password is entered upon upgrading but if that were the case wouldn't ALL the files fail to upgrade?

 

[holdmusic34]

I see it too. Will post what I find.

'She doesn't appear to be at her desk.'

 

[holdmusic34]

Just run the upgrade again selecting those sections that did not upgrade.
I suspect the memory buffer was too full to upgrade during the main event.
Here is a monitor trace of a 9.0.3 system upgrading the extra sections to 9.0.3:

Enjoy.

TFTP Download: UPGRADE: Preallocate store for filereader
09:32:28 63101490mS TFTP Download: Upgrader Offline=0 Requires 622884, Need to allocate 622884 (so far=0, max block=56604632)
09:32:28 63101490mS TFTP Download: Upgrader Allocation result: Requires 622884, Need to allocate 0 (so far=622884, max block=56604632)
09:32:28 63101490mS TFTP Download: Upgrader Allocated Successfully
09:32:28 63101490mS TFTP Download: UPGRADE: Preallocated buffer = nadcpV2.bin size = 622884
09:32:28 63101497mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/allocate/273128/naatm16.bin (Total 0)
09:32:28 63101497mS TFTP Download: UPGRADE: Preallocate store for filereader
09:32:28 63101497mS TFTP Download: Upgrader Offline=0 Requires 273640, Need to allocate 273640 (so far=0, max block=56604632)
09:32:28 63101497mS TFTP Download: Upgrader Allocation result: Requires 273640, Need to allocate 0 (so far=273640, max block=56604632)
09:32:28 63101497mS TFTP Download: Upgrader Allocated Successfully
09:32:28 63101498mS TFTP Download: UPGRADE: Preallocated buffer = naatm16.bin size = 273640
09:32:28 63101518mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/allocate/1449776/nadcpaV1.bin (Total 0)
09:32:28 63101518mS TFTP Download: UPGRADE: Preallocate store for filereader
09:32:28 63101518mS TFTP Download: Upgrader Offline=0 Requires 1450288, Need to allocate 1450288 (so far=0, max block=56604632)
09:32:28 63101518mS TFTP Download: Upgrader Allocation result: Requires 1450288, Need to allocate 0 (so far=1450288, max block=55154324)
09:32:28 63101518mS TFTP Download: Upgrader Allocated Successfully
09:32:28 63101518mS TFTP Download: UPGRADE: Preallocated buffer = nadcpaV1.bin size = 1450288
09:32:28 63101526mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/214 (Total 0)
09:32:28 63101526mS TFTP Download: State request received
09:32:28 63101527mS TFTP Warning: Status: unit #214 unknown
09:32:28 63101544mS TFTP: Server::WRQ(from 192.168.8.95, 62535) nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin (Total 0)
09:32:28 63101544mS TFTP: (0) 192.168.8.95 TFTPReader: nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin
09:32:28 63101544mS TFTP: (0) 192.168.8.95(SrcPort 4228) TFTPReader: nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin
09:32:28 63101544mS TFTP Download: Starting Image Download
09:32:28 63101546mS TFTP: (0) 192.168.8.95(SrcPort 4228) TFTPReader: ConnectIndication: nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin 2ms
09:32:28 63101885mS RES: Fri 3/7/2015 09:32:28 FreeMem=58351916 (Heap=58026176(3) + Cache=325740) MemObjs=8387(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52156(58000) BTree=14209 CPU=09.88% CPUStats=10.20%/1/3/1873/23755/26624/00.04%/0/02.0
7% MCR=0 MCW=0
09:32:28 63101885mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:28 63101885mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:28 63101886mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:32:30 63103984mS TFTP Download: Complete: Image Download Complete
09:32:30 63103984mS TFTP: (0) 192.168.8.95(SrcPort 4228) TFTPReader: Complete: nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin
09:32:30 63103984mS TFTP: (0) 192.168.8.95(SrcPort 4228) ~TFTPReader: nasystem/remote/$t%$'~ q/newimage/622372/nadcpV2.bin
09:32:30 63103989mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade/nadcpV2.bin/622372/214/40171164 (Total 0)
09:32:30 63103989mS TFTP Download: Upgrade message received
09:32:30 63103989mS TFTP Download: Binfile f5a0eef8: filename="nadcpV2.bin", size=622372, checksum=0264f69c, server=192.168.8.95 store=00000000 state=Created
09:32:30 63103990mS TFTP Download: Performing download on nadcpV2.bin
09:32:30 63103990mS TFTP Download: UPGRADE: Using predownloaded buffer 0
09:32:30 63103990mS TFTP Download: Performing checksum on nadcpV2.bin
09:32:31 63104013mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/214 (Total 0)
09:32:31 63104013mS TFTP Download: State request received
09:32:31 63104013mS TFTP Download: Binfile f5a0eef8: filename="nadcpV2.bin", size=622372, checksum=0264f69c, server=192.168.8.95 store=f19270a4 state=Downloaded
09:32:31 63104013mS TFTP Download: Status response is "Ok/214/nadcpV2.bin/622372/0"
09:32:31 63104037mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/216 (Total 0)
09:32:31 63104038mS TFTP Download: State request received
09:32:31 63104038mS TFTP Warning: Status: unit #216 unknown
09:32:31 63104062mS TFTP: Server::WRQ(from 192.168.8.95, 62535) nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin (Total 0)
09:32:31 63104062mS TFTP: (1) 192.168.8.95 TFTPReader: nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin
09:32:31 63104062mS TFTP: (1) 192.168.8.95(SrcPort 4232) TFTPReader: nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin
09:32:31 63104062mS TFTP Download: Starting Image Download
09:32:31 63104066mS TFTP: (1) 192.168.8.95(SrcPort 4232) TFTPReader: ConnectIndication: nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin 3ms
09:32:31 63104189mS TFTP Download: ChecksumValid: checksum=#40171164, expected=#40171164
09:32:31 63104189mS TFTP Download: Checksum for "nadcpV2.bin" is correct
09:32:32 63105184mS TFTP Download: Complete: Image Download Complete
09:32:32 63105184mS TFTP: (1) 192.168.8.95(SrcPort 4232) TFTPReader: Complete: nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin
09:32:32 63105184mS TFTP: (1) 192.168.8.95(SrcPort 4232) ~TFTPReader: nasystem/remote/$t%$'~ q/newimage/273128/naatm16.bin
09:32:32 63105188mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade/naatm16.bin/273128/216/16464289 (Total 0)
09:32:32 63105188mS TFTP Download: Upgrade message received
09:32:32 63105188mS TFTP Download: Binfile f192644c: filename="naatm16.bin", size=273128, checksum=00fb39a1, server=192.168.8.95 store=00000000 state=Created
09:32:32 63105188mS TFTP Download: Performing download on naatm16.bin
09:32:32 63105188mS TFTP Download: UPGRADE: Using predownloaded buffer 1
09:32:32 63105188mS TFTP Download: Performing checksum on naatm16.bin
09:32:32 63105212mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/216 (Total 0)
09:32:32 63105212mS TFTP Download: State request received
09:32:32 63105212mS TFTP Download: Binfile f192644c: filename="naatm16.bin", size=273128, checksum=00fb39a1, server=192.168.8.95 store=f1926c38 state=Downloaded
09:32:32 63105212mS TFTP Download: Status response is "Ok/216/naatm16.bin/273128/0"
09:32:32 63105236mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/217 (Total 0)
09:32:32 63105236mS TFTP Download: State request received
09:32:32 63105236mS TFTP Warning: Status: unit #217 unknown
09:32:32 63105260mS TFTP: Server::WRQ(from 192.168.8.95, 62535) nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin (Total 0)
09:32:32 63105261mS TFTP: (2) 192.168.8.95 TFTPReader: nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin
09:32:32 63105261mS TFTP: (2) 192.168.8.95(SrcPort 4236) TFTPReader: nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin
09:32:32 63105261mS TFTP Download: Starting Image Download
09:32:32 63105264mS TFTP: (2) 192.168.8.95(SrcPort 4236) TFTPReader: ConnectIndication: nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin 4ms
09:32:32 63105272mS TFTP Download: ChecksumValid: checksum=#16464289, expected=#16464289
09:32:32 63105272mS TFTP Download: Checksum for "naatm16.bin" is correct
09:32:34 63107911mS TFTP Download: Upgrader: Upgrade is not in Progress
09:32:37 63110949mS TFTP Download: Complete: Image Download Complete
09:32:37 63110949mS TFTP: (2) 192.168.8.95(SrcPort 4236) TFTPReader: Complete: nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin
09:32:37 63110949mS TFTP: (2) 192.168.8.95(SrcPort 4236) ~TFTPReader: nasystem/remote/$t%$'~ q/newimage/1449776/nadcpaV1.bin
09:32:37 63110959mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade/nadcpaV1.bin/1449776/217/87709070 (Total 0)
09:32:37 63110959mS TFTP Download: Upgrade message received
09:32:37 63110959mS TFTP Download: Binfile f19263c0: filename="nadcpaV1.bin", size=1449776, checksum=053a558e, server=192.168.8.95 store=00000000 state=Created
09:32:37 63110959mS TFTP Download: Performing download on nadcpaV1.bin
09:32:37 63110959mS TFTP Download: UPGRADE: Using predownloaded buffer 2
09:32:37 63110959mS TFTP Download: Performing checksum on nadcpaV1.bin
09:32:37 63110983mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/status/217 (Total 0)
09:32:37 63110983mS TFTP Download: State request received
09:32:37 63110983mS TFTP Download: Binfile f19263c0: filename="nadcpaV1.bin", size=1449776, checksum=053a558e, server=192.168.8.95 store=f19267cc state=Downloaded
09:32:37 63110983mS TFTP Download: Status response is "Ok/217/nadcpaV1.bin/1449776/0"
09:32:38 63111344mS RES: Fri 3/7/2015 09:32:38 FreeMem=58352084 (Heap=58025896(3) + Cache=326188) MemObjs=8392(Max 10002) CMMsg=2(2) Buff=5200 1372 1000 7420 5 Links=52155(58000) BTree=14209 CPU=11.67% CPUStats=43.38%/3/47/1873/11081/26624/01.39%/1/02.
63% MCR=0 MCW=0
09:32:38 63111344mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:38 63111344mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:38 63111344mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:32:38 63111382mS TFTP Download: ChecksumValid: checksum=#87709070, expected=#87709070
09:32:38 63111382mS TFTP Download: Checksum for "nadcpaV1.bin" is correct
09:32:44 63116596mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/backup (Total 0)
09:32:44 63116596mS MEMCARDCMD: Backing up entered 1
09:32:44 63116596mS MEMCARDCMD: Backing up system\primary to system\backup folder
09:32:44 63116596mS MEMCARDCMD: Starting CopyDirTask to A:\system\primary\ from A:\system\backup\ 0
09:32:44 63116612mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/backup (Total 0)
09:32:44 63116632mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/backup (Total 0)
09:32:44 63116848mS RES: Fri 3/7/2015 09:32:44 FreeMem=58324368 (Heap=57948028(1) + Cache=376340) MemObjs=8411(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52165(58000) BTree=14209 CPU=11.39% CPUStats=09.22%/1/4/1873/23929/26624/00.04%/1/02.1
0% MCR=0 MCW=0
09:32:44 63116849mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:44 63116849mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:44 63116849mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:32:45 63117661mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:46 63118359mS TFTP Download: Upgrader: Upgrade is in Progress. 0/1/0
09:32:46 63118691mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:47 63119718mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:48 63120749mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:49 63121777mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:49 63121897mS RES: Fri 3/7/2015 09:32:49 FreeMem=58316760 (Heap=57917392(1) + Cache=399368) MemObjs=8444(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52188(58000) BTree=14209 CPU=29.01% CPUStats=71.51%/2/14/1873/5788/26624/00.08%/1/02.3
0% MCR=0 MCW=0
09:32:49 63121897mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:49 63121897mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:49 63121897mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:32:50 63122532mS R2DSP: PRIU DSP 1: is alive
09:32:50 63122812mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:51 63123838mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:52 63124868mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:53 63125895mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:54 63126925mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:55 63127346mS RES: Fri 3/7/2015 09:32:54 FreeMem=58290844 (Heap=57915376(1) + Cache=375468) MemObjs=8534(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52281(58000) BTree=14209 CPU=46.93% CPUStats=72.75%/2/14/1873/5464/26624/00.05%/1/02.3
3% MCR=0 MCW=0
09:32:55 63127346mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:55 63127346mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:55 63127346mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:32:55 63127955mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:55 63128859mS TFTP Download: Upgrader: Upgrade is in Progress. 0/1/0
09:32:55 63128985mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:57 63130016mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:58 63131047mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:59 63132079mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:32:59 63132397mS RES: Fri 3/7/2015 09:32:59 FreeMem=58315312 (Heap=57920272(1) + Cache=395040) MemObjs=8446(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52190(58000) BTree=14209 CPU=58.36% CPUStats=74.97%/5/15/1873/5010/26624/00.08%/1/02.2
9% MCR=0 MCW=0
09:32:59 63132397mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:32:59 63132398mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:32:59 63132398mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:00 63133106mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:01 63134133mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:02 63135165mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:03 63136195mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:04 63137223mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:04 63137851mS RES: Fri 3/7/2015 09:33:05 FreeMem=58325128 (Heap=57918556(1) + Cache=406572) MemObjs=8403(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52160(58000) BTree=14209 CPU=66.50% CPUStats=70.27%/5/16/1873/6154/26624/00.06%/1/02.2
7% MCR=0 MCW=0
09:33:04 63137851mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:04 63137851mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:04 63137851mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:05 63138254mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:06 63138864mS TFTP Download: Upgrader: Upgrade is in Progress. 0/1/0
09:33:07 63139283mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:08 63140313mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:09 63141342mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:09 63141546mS MEMCARDCMD: Backing up Finished OK
09:33:10 63142370mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:10 63142885mS RES: Fri 3/7/2015 09:33:10 FreeMem=58352084 (Heap=57943012(2) + Cache=409072) MemObjs=8392(Max 10002) CMMsg=2(2) Buff=5200 1372 1000 7420 5 Links=52156(58000) BTree=14209 CPU=67.46% CPUStats=18.45%/2/13/1873/20974/26624/00.04%/1/02.
09% MCR=0 MCW=0
09:33:10 63142885mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:10 63142886mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:10 63142886mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:11 63143399mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:11 63143415mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/proceed/300/0/0 (Total 0)
09:33:11 63143415mS TFTP Download: Module #217. Received Proceed message
09:33:11 63143415mS TFTP Download: Module #216. Received Proceed message
09:33:11 63143415mS TFTP Download: Module #214. Received Proceed message
09:33:11 63143432mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/proceed/300/0/0 (Total 0)
09:33:11 63143446mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/proceed/300/0/0 (Total 0)
09:33:12 63144476mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:13 63145506mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)

********** SysMonitor v9.1.1.0 build 10 [connected to 192.168.8.53 (00E0070C42EE (Basic Edition))] **********
09:33:14 63146350mS PRN: Monitor Status IP 500 V2 9.1.3.0 build 120
09:33:14 63146350mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=0, MDM=0, WAN=0, MODU=4 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=0)
09:33:14 63146535mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:15 63147565mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:16 63148415mS TFTP Download: Upgrader: All expansion units loaded
09:33:16 63148415mS TFTP Download: Upgrade Complete
09:33:16 63148415mS TFTP Download: Upgrader: ClearAll Posted
09:33:16 63148595mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:16 63148888mS TFTP Download: Upgrader: Upgrade is in Progress. 1/0/0
09:33:17 63149624mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:18 63150654mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:19 63151684mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:20 63152713mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:21 63153415mS TFTP Download: Attempting to write new binary to memory card: A:\system\primary\nadcpaV1.bin
09:33:21 63153415mS TFTP Download: Card is available to write new binary: A:\system\primary\nadcpaV1.bin
09:33:21 63153622mS TFTP Download: Opened file to write new binary to memory card: A:\system\primary\nadcpaV1.bin
09:33:21 63153752mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:20 63153906mS RES: Fri 3/7/2015 09:33:20 FreeMem=58318524 (Heap=57956656(1) + Cache=361868) MemObjs=8394(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7419 5 Links=52160(58000) BTree=14209 CPU=32.92% CPUStats=09.31%/1/4/1873/23822/26624/00.03%/1/02.0
7% MCR=0 MCW=0
09:33:20 63153906mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=43 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:20 63153906mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:20 63153906mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:21 63154777mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:22 63155825mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:23 63156855mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:24 63157883mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:25 63158939mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:26 63159320mS TFTP Download: Upgrader: Upgrade is in Progress. 1/0/0
09:33:26 63159598mS TFTP Download: Closed file to write new binary to memory card: A:\system\primary\nadcpaV1.bin
09:33:26 63159854mS RES: Fri 3/7/2015 09:33:26 FreeMem=58351956 (Heap=57989464(2) + Cache=362492) MemObjs=8390(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52160(58000) BTree=14209 CPU=50.69% CPUStats=97.24%/5/101/836/836/26624/00.04%/0/01.57
% MCR=573440 MCW=0
09:33:26 63159854mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:26 63159854mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:26 63159854mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:26 63159871mS TFTP Download: Successfully wrote new binary to memory card: A:\system\primary\nadcpaV1.bin
09:33:26 63159871mS PRN: LoadControl: Cleared Marked For NAND Boot Already
09:33:26 63159975mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:28 63161018mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:29 63162072mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:30 63163114mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:31 63164144mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:32 63165205mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:32 63165386mS RES: Fri 3/7/2015 09:33:32 FreeMem=58332272 (Heap=57971680(2) + Cache=360592) MemObjs=8409(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52166(58000) BTree=14209 CPU=67.10% CPUStats=97.16%/2/89/836/1019/26624/00.03%/0/01.65
% MCR=728944 MCW=0
09:33:32 63165386mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=41 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:32 63165386mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:32 63165386mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:33 63166245mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:34 63167276mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:35 63168331mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:36 63169351mS TFTP Download: Upgrader: Upgrade is in Progress. 1/0/0
09:33:36 63169381mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:38 63170408mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:38 63170959mS TFTP Download: Attempting to write new binary to memory card: A:\system\primary\naatm16.bin
09:33:38 63170959mS TFTP Download: Card is available to write new binary: A:\system\primary\naatm16.bin
09:33:39 63171135mS TFTP Download: Opened file to write new binary to memory card: A:\system\primary\naatm16.bin
09:33:39 63171379mS RES: Fri 3/7/2015 09:33:38 FreeMem=59770104 (Heap=59405032(2) + Cache=365072) MemObjs=8391(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52156(58000) BTree=14209 CPU=81.73% CPUStats=95.47%/6/86/836/1466/26624/00.05%/0/01.76
% MCR=429584 MCW=147456
09:33:39 63171380mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:39 63171380mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:39 63171380mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:39 63171459mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:40 63172027mS TFTP Download: Closed file to write new binary to memory card: A:\system\primary\naatm16.bin
09:33:40 63172280mS TFTP Download: Successfully wrote new binary to memory card: A:\system\primary\naatm16.bin
09:33:40 63172280mS PRN: LoadControl: Cleared Marked For NAND Boot Already
09:33:40 63172513mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:41 63173553mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:42 63174605mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:42 63175650mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:43 63176695mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:44 63177345mS RES: Fri 3/7/2015 09:33:44 FreeMem=59783852 (Heap=59421436(2) + Cache=362416) MemObjs=8406(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52164(58000) BTree=14209 CPU=92.54% CPUStats=96.89%/2/90/836/1026/26624/00.05%/0/01.65
% MCR=728944 MCW=0
09:33:44 63177345mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:44 63177345mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:44 63177345mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:44 63177724mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:45 63178786mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:46 63179426mS TFTP Download: Upgrader: Upgrade is in Progress. 1/0/0
09:33:46 63179819mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:47 63180858mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:48 63181916mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:49 63182385mS RES: Fri 3/7/2015 09:33:49 FreeMem=59783520 (Heap=59421436(2) + Cache=362084) MemObjs=8408(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52165(58000) BTree=14209 CPU=96.50% CPUStats=96.89%/3/90/836/1020/26624/00.04%/0/01.66
% MCR=737280 MCW=0
09:33:49 63182385mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:49 63182386mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:49 63182386mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:49 63182532mS R2DSP: PRIU DSP 1: is alive
09:33:49 63182967mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:50 63183187mS TFTP Download: Attempting to write new binary to memory card: A:\system\primary\nadcpV2.bin
09:33:50 63183187mS TFTP Download: Card is available to write new binary: A:\system\primary\nadcpV2.bin
09:33:50 63183386mS TFTP Download: Opened file to write new binary to memory card: A:\system\primary\nadcpV2.bin
09:33:51 63184025mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:52 63185070mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:52 63185366mS TFTP Download: Closed file to write new binary to memory card: A:\system\primary\nadcpV2.bin
09:33:52 63185617mS TFTP Download: Successfully wrote new binary to memory card: A:\system\primary\nadcpV2.bin
09:33:52 63185617mS PRN: LoadControl: Cleared Marked For NAND Boot Already
09:33:53 63186108mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:54 63187141mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:54 63187831mS RES: Fri 3/7/2015 09:33:54 FreeMem=60059780 (Heap=59695960(2) + Cache=363820) MemObjs=8397(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52161(58000) BTree=14209 CPU=96.66% CPUStats=96.98%/3/90/836/1012/26624/00.06%/0/01.66
% MCR=729992 MCW=0
09:33:54 63187831mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:33:54 63187831mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:33:54 63187831mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:33:55 63188169mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:56 63189213mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:56 63189870mS TFTP Download: Upgrader: Upgrade is in Progress. 1/0/0
09:33:57 63190277mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:33:59 63191339mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:00 63192392mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:00 63192858mS RES: Fri 3/7/2015 09:34:00 FreeMem=60058784 (Heap=59697112(2) + Cache=361672) MemObjs=8403(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52164(58000) BTree=14209 CPU=96.74% CPUStats=96.89%/2/89/836/1067/26624/00.04%/0/01.64
% MCR=770048 MCW=0
09:34:00 63192859mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=44 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:34:00 63192859mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:34:00 63192859mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:34:01 63193425mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:02 63194451mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:03 63195483mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:04 63196509mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:04 63196539mS TFTP Warning: UPGRADE: Cannot unlock memorycard commands
09:34:04 63196539mS TFTP Download: Upgrader: Actioned clear all
09:34:05 63197535mS TFTP: Server::RRQ(from 192.168.8.95, 62535) (loc 192.168.8.53) nasystem/remote/$t%$'~ q/upgrade_status (Total 0)
09:34:05 63197909mS RES: Fri 3/7/2015 09:34:04 FreeMem=60700848 (Heap=60347932(3) + Cache=352916) MemObjs=8409(Max 10002) CMMsg=2(2) Buff=5200 1371 1000 7420 5 Links=52183(58000) BTree=14209 CPU=92.77% CPUStats=34.07%/5/85/836/17215/26624/00.06%/0/02.1
1% MCR=101904 MCW=0
09:34:05 63197909mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:34:05 63197909mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:34:05 63197909mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:34:08 63200318mS TFTP Download: Upgrader: Upgrade is not in Progress
09:34:10 63203319mS RES: Fri 3/7/2015 09:34:10 FreeMem=60702940 (Heap=60375268(2) + Cache=327672) MemObjs=8380(Max 10002) CMMsg=2(2) Buff=5200 1372 1000 7420 5 Links=52154(58000) BTree=14209 CPU=68.65% CPUStats=08.34%/1/3/836/23901/26624/00.06%/0/02.07
% MCR=0 MCW=0
09:34:10 63203319mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:34:10 63203319mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:34:10 63203319mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:34:13 63206996mS TFTP: Server::RRQ(from 192.168.8.95, 62536) (loc 192.168.8.53) nasystemw/who_is3 (Total 0)
09:34:14 63207002mS TFTP: Server::WRQ(from 192.168.8.15, 6845) nasystemw (Total 1)
09:34:14 63207003mS TFTP: Server::WRQ(from 192.168.8.17, 4244) nasystemw (Total 1)
09:34:14 63207003mS TFTP: Server::RRQ(from 192.168.8.95, 62537) (loc 192.168.8.53) nasystemw/who_is (Total 1)

********** SysMonitor v9.1.1.0 build 10 [connected to 192.168.8.53 (00E0070C42EE (Basic Edition))] **********
09:34:14 63207196mS PRN: Monitor Status IP 500 V2 9.1.3.0 build 120
09:34:14 63207196mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=0, MDM=0, WAN=0, MODU=4 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=0)
09:34:16 63209320mS RES: Fri 3/7/2015 09:34:16 FreeMem=60702940 (Heap=60375268(2) + Cache=327672) MemObjs=8380(Max 10002) CMMsg=2(2) Buff=5200 1372 1000 7420 5 Links=52154(58000) BTree=14209 CPU=43.55% CPUStats=07.18%/1/3/836/23879/26624/00.07%/0/02.09
% MCR=0 MCW=0
09:34:16 63209320mS RES2: (BE) IP 500 V2 9.1.3.0 build 120 Tasks=41 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=16(TLS=5) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:34:16 63209321mS RES4: XML MemObjs=63 PoolMem=4876404(2) FreeMem=4861100(0)
09:34:16 63209321mS RES5: CLog MemObjs=0 FreePoolMem(Objs)=5200(100) TotalMem=5200
09:34:17 63210321mS TFTP Download: Upgrader: Upgrade is not in Progress
09:34:27 63220321mS TFTP Download: Upgrader: Upgrade is not in Progress


'She doesn't appear to be at her desk.'