Begin VxMS Session...

Logmask set to 0xfc007780

Current Environment Variables

VFM_ROOT = /usr/openv

VFM_PRIVATE_ROOT = /usr/openv

VFM_MAP_API_LIB = NULL

VFM_MAP_DIR = NULL

VFM_UTIL_LIB = NULL

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /proc

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /sys

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /sys/kernel/debug

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /dev

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /dev/shm

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /dev/pts

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /boot

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /inst

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /log

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /repository

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /repository/checkpoints/appliance_checkpoint_5wqDtF

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /dev/vx

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /config

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /advanceddisk/dp1/advol

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /disk

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /var/lib/ntp/proc

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /proc/fs/nfsd

GFSP gfsp_mntcache_addentry : osdep_gfsp.c.418 <INFO> : Add cache entry for /var/lib/nfs/rpc_pipefs

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_mntcache_findmnt : mntcache.c.114 <INFO> : Entering gfsp_mntcache_findmnt

GFSP gfsp_map_claim_id : mapintf.c.176 <INFO> : Entering gfsp_map_claim_id

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:17 : vix_init:libvix.cpp:162 <TRACE> : in

07/21/2015 16:13:17 : vix_init:libvix.cpp:162 <TRACE> : out

07/21/2015 16:13:17 : vix_map_init:libvix.cpp:1008 <TRACE> : in

07/21/2015 16:13:17 : VixManager:VixManager.cpp:42 <TRACE> : in

07/21/2015 16:13:17 : InitializeCriticalSection:RvpVxms.cpp:8 <TRACE> : in

07/21/2015 16:13:17 : InitializeCriticalSection:RvpVxms.cpp:8 <TRACE> : out

07/21/2015 16:13:17 : VixManager:VixManager.cpp:42 <TRACE> : out

07/21/2015 16:13:17 : VixCoordinator:VixCoordinator.cpp:90 <TRACE> : in

07/21/2015 16:13:17 : VixCoordinator:VixCoordinator.cpp:90 <TRACE> : out

07/21/2015 16:13:17 : vix_map_init:libvix.cpp:1031 <TRACE> : Allocated VixCoordinator

07/21/2015 16:13:17 : vixMapInit:VixCoordinator.cpp:869 <TRACE> : in

07/21/2015 16:13:17 : vixMapInit:VixCoordinator.cpp:869 <TRACE> : out

07/21/2015 16:13:17 : vix_map_init:libvix.cpp:1008 <TRACE> : out

07/21/2015 16:13:17 : vix_map_claim_id:libvix.cpp:222 <TRACE> : in

07/21/2015 16:13:17 : vix_map_claim_id:libvix.cpp:226 <INFO> : Objid: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:17 : vixMapClaimId:VixCoordinator.cpp:177 <TRACE> : in

07/21/2015 16:13:17 : vixMapClaimId:VixCoordinator.cpp:184 <TRACE> : Objid: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:17 : claimId:VixManager.cpp:192 <TRACE> : in

07/21/2015 16:13:17 : claimId:VixManager.cpp:199 <INFO> : Objid: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:17 : RvpMemory:RvpMemory.cpp:35 <TRACE> : in

07/21/2015 16:13:17 : RvpMemory:RvpMemory.cpp:35 <TRACE> : out

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:17 : getSize:RvpFile.cpp:320 <TRACE> : in

07/21/2015 16:13:17 : getSize:RvpFile.cpp:320 <TRACE> : out

07/21/2015 16:13:17 : alloc:RvpMemory.cpp:104 <TRACE> : in

07/21/2015 16:13:17 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:17 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:17 : alloc:RvpMemory.cpp:104 <TRACE> : out

07/21/2015 16:13:17 : readData:RvpFile.cpp:228 <TRACE> : in

07/21/2015 16:13:17 : readData:RvpFile.cpp:228 <TRACE> : out

07/21/2015 16:13:17 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : in

07/21/2015 16:13:17 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:17 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:17 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : out

07/21/2015 16:13:17 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:17 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:17 : claimId:VixManager.cpp:277 <INFO> : Returned: VF_CLAIM_SECONDARY

07/21/2015 16:13:17 : claimId:VixManager.cpp:192 <TRACE> : out

07/21/2015 16:13:17 : vixMapClaimId:VixCoordinator.cpp:177 <TRACE> : out

07/21/2015 16:13:17 : getInstance:VixInterface.cpp:1253 <TRACE> : in

07/21/2015 16:13:17 : VixInterface:VixInterface.h:477 <TRACE> : in

07/21/2015 16:13:17 : setEnvVar:VixInterface.h:261 <TRACE> : in

07/21/2015 16:13:17 : setEnvVar:VixInterface.h:291 <INFO> : Existing key:PATH value: /opt/NBUAppliance/bin:/usr/bin:/bin:/usr/sbin:/sbin:/usr/openv/netbackup/bin/goodies:/usr/openv/netbackup/bin/admincmd:/usr/openv/volmgr/bin:/usr/openv/netbackup/bin:/usr/openv/db/bin:/usr/openv/netbackup/bin/support:/usr/openv/netbackup/bin/goodies/support:/opt/VRTSpbx/bin/:/opt/VRTS/bin/:/usr/bin

07/21/2015 16:13:17 : setEnvVar:VixInterface.h:297 <INFO> : Adding '/usr/openv/lib/shared/vddk/lib64/' to 'PATH'

07/21/2015 16:13:17 : setEnvVar:VixInterface.h:261 <TRACE> : out

07/21/2015 16:13:17 : DynLoadDiskLib:VixInterface.h:376 <DEBUG> : Loading: /usr/openv/lib/shared/vddk/lib64/libvixDiskLib.so

07/21/2015 16:13:17 : VixInterface:VixInterface.h:509 <INFO> : m_libDir: /usr/openv/lib/shared/vddk/

07/21/2015 16:13:17 : VixInterface:VixInterface.h:477 <TRACE> : out

07/21/2015 16:13:17 : getInstance:VixInterface.cpp:1259 <INFO> : Allocated VIX instance: 0x937070

07/21/2015 16:13:17 : getInstance:VixInterface.cpp:1260 <INFO> : Reference VIX instance: 1

07/21/2015 16:13:17 : getInstance:VixInterface.cpp:1253 <TRACE> : out

07/21/2015 16:13:17 : getInstance:VMWareVIClient.cpp:528 <TRACE> : in

07/21/2015 16:13:17 : getInstance:VMWareVIClient.cpp:528 <TRACE> : out

07/21/2015 16:13:17 : vdInit:VixInterface.cpp:251 <TRACE> : in

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:17 : vdInit:VixInterface.cpp:366 <INFO> : vixDiskLib directory: /usr/openv/lib/shared/vddk/

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:17 : openFile:RvpFile.cpp:190 <DEBUG> : Unable to open: /usr/openv/lib/shared/vddk/lib64/vixDiskLib.ini [2]

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:17 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:17 : isValid:RvpFile.cpp:54 <TRACE> : out

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:17 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:17 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:17 : writeData:RvpFile.cpp:291 <TRACE> : in

07/21/2015 16:13:17 : writeData:RvpFile.cpp:291 <TRACE> : out

07/21/2015 16:13:17 : vdInit:VixInterface.cpp:413 <INFO> : Using created vixDiskLib config file: /tmp/vixDiskLib-1901172073_143274_1

07/21/2015 16:13:17 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:17 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:17 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: linuxVerifySSLCertificates is 0

07/21/2015 16:13:17 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: config options: libdir '/usr/openv/lib/shared/vddk/', tmpDir '/tmp/vmware-root'.

07/21/2015 16:13:17 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Initialize Disklib.

07/21/2015 16:13:17 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] OBJLIB-LIB: Objlib initialized.

07/21/2015 16:13:17 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Attempting to locate advanced transport module in "/usr/openv/lib/shared/vddk/".

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Advanced transport plugin was successfully loaded into vixDiskLib.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Enabling advanced transport modes.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] OBJLIB-LIB: Objlib initialized.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Available transport modes from DiskLib: file:nbdssl:nbd:san.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Initialize transport modes.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Available transport modes: file:san:hotadd:nbdssl:nbd.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VMware VixDiskLib (5.5) Release build-1601882

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Initializing SSL.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLib: Initializing NFC.

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] 2015-07-21T16:13:19.138+01:00 [7FB11FEE1700 info 'Libs'] GVMOMI request limit: -1

07/21/2015 16:13:19 : g_vixInterfaceLogger:libvix.cpp:1825 <DEBUG> : [VFM_ESINFO] VixDiskLibVim: VixDiskLibVim_Init: Initialization is completed.

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : vdInit:VixInterface.cpp:251 <TRACE> : out

07/21/2015 16:13:19 : init:VMWareVIClient.cpp:59 <TRACE> : in

07/21/2015 16:13:19 : init:VMWareVIClient.cpp:59 <TRACE> : out

07/21/2015 16:13:19 : vix_map_claim_id:libvix.cpp:222 <TRACE> : out

EMCPOWER emcpower_map_claim_id : mapintf.c.175 <INFO> : Entering emcpower_map_claim_id

EMCPOWER emcpower_parse_devname : osdep_emcpower.c.29 <INFO> : Entering emcpower_parse_devname

EMCPOWER emcpower_parse_devname : osdep_emcpower.c.36 <INFO> : emcpower_parse_devname:Pathname or Device path /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

EMCPOWER emcpower_parse_devname : osdep_emcpower.c.43 <DEBUG> : emcpower_parse_devname:Token: usr:Index: 0

EMCPOWER emcpower_parse_devname : osdep_emcpower.c.70 <INFO> : emcpower_parse_devname: is returning with ERROR

07/21/2015 16:13:19 : vix_map_open_id:libvix.cpp:1270 <TRACE> : in

07/21/2015 16:13:19 : vix_map_open_id:libvix.cpp:1274 <TRACE> : Flags: 0x80003

07/21/2015 16:13:19 : vixMapOpenId:VixCoordinator.cpp:999 <TRACE> : in

07/21/2015 16:13:19 : vixMapOpenId:VixCoordinator.cpp:1003 <TRACE> : Flags: 0x80003

07/21/2015 16:13:19 : openId:VixManager.cpp:315 <TRACE> : in

07/21/2015 16:13:19 : claimId:VixManager.cpp:192 <TRACE> : in

07/21/2015 16:13:19 : claimId:VixManager.cpp:199 <INFO> : Objid: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : in

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : out

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : in

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : out

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : in

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : out

07/21/2015 16:13:19 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:19 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : out

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : claimId:VixManager.cpp:277 <INFO> : Returned: VF_CLAIM_SECONDARY

07/21/2015 16:13:19 : claimId:VixManager.cpp:192 <TRACE> : out

07/21/2015 16:13:19 : openId:VixManager.cpp:350 <INFO> : Opening guest

07/21/2015 16:13:19 : openGuest:VixManager.cpp:436 <TRACE> : in

07/21/2015 16:13:19 : openGuest:VixManager.cpp:438 <DEBUG> : Flags: 0x80003

07/21/2015 16:13:19 : EnterCriticalSection:RvpVxms.cpp:20 <TRACE> : in

07/21/2015 16:13:19 : EnterCriticalSection:RvpVxms.cpp:20 <TRACE> : out

07/21/2015 16:13:19 : VixGuest:VixGuest.cpp:118 <TRACE> : in

07/21/2015 16:13:19 : InitializeCriticalSection:RvpVxms.cpp:8 <TRACE> : in

07/21/2015 16:13:19 : InitializeCriticalSection:RvpVxms.cpp:8 <TRACE> : out

07/21/2015 16:13:19 : VixGuest:VixGuest.cpp:118 <TRACE> : out

07/21/2015 16:13:19 : openId:VixGuest.cpp:333 <TRACE> : in

07/21/2015 16:13:19 : open:VixMetaSave.cpp:39 <TRACE> : in

07/21/2015 16:13:19 : open:VixMetaSave.cpp:66 <INFO> : Opening Dump Log File: vix_Tic129da0_Pid143274_vmware

07/21/2015 16:13:19 : open:VixMetaSave.cpp:39 <TRACE> : out

07/21/2015 16:13:19 : openId:VixGuest.cpp:333 <TRACE> : out

07/21/2015 16:13:19 : LeaveCriticalSection:RvpVxms.cpp:28 <TRACE> : in

07/21/2015 16:13:19 : LeaveCriticalSection:RvpVxms.cpp:28 <TRACE> : out

07/21/2015 16:13:19 : openGuest:VixManager.cpp:436 <TRACE> : out

07/21/2015 16:13:19 : openId:VixManager.cpp:315 <TRACE> : out

07/21/2015 16:13:19 : vixMapOpenId:VixCoordinator.cpp:1022 <TRACE> : rflags returned: 0x1b42fa88

07/21/2015 16:13:19 : vixMapOpenId:VixCoordinator.cpp:999 <TRACE> : out

07/21/2015 16:13:19 : vix_map_open_id:libvix.cpp:1270 <TRACE> : out

07/21/2015 16:13:19 : vix_map_get_obj_prop:libvix.cpp:817 <TRACE> : in

07/21/2015 16:13:19 : vix_map_get_obj_prop:libvix.cpp:823 <TRACE> : Obj: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:19 : vixMapGetObjProp:VixCoordinator.cpp:659 <TRACE> : in

07/21/2015 16:13:19 : vixMapGetObjProp:VixCoordinator.cpp:665 <TRACE> : Obj: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:19 : vixMapGetObjProp:VixCoordinator.cpp:659 <TRACE> : out

07/21/2015 16:13:19 : vix_map_get_obj_prop:libvix.cpp:817 <TRACE> : out

07/21/2015 16:13:19 : vix_map_objctl:libvix.cpp:1209 <TRACE> : in

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:919 <TRACE> : in

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:923 <TRACE> : cmd: -109

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:926 <TRACE> : Obj: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:951 <TRACE> : case RVP_OBJECT_TYPE_GUEST

07/21/2015 16:13:19 : objCtl:VixGuest.cpp:2739 <TRACE> : in

07/21/2015 16:13:19 : objCtl:VixGuest.cpp:2739 <TRACE> : out

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:919 <TRACE> : out

07/21/2015 16:13:19 : vix_map_objctl:libvix.cpp:1209 <TRACE> : out

07/21/2015 16:13:19 : vix_map_objctl:libvix.cpp:1209 <TRACE> : in

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:919 <TRACE> : in

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:923 <TRACE> : cmd: -103

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:926 <TRACE> : Obj: /usr/openv/netbackup/online_util/fi_cntl/bpfis.fim.VM_NAME_1437491556.1.0.NBU_DATA.xml

07/21/2015 16:13:19 : vixMapObjCtl:VixCoordinator.cpp:951 <TRACE> : case RVP_OBJECT_TYPE_GUEST

07/21/2015 16:13:19 : objCtl:VixGuest.cpp:2739 <TRACE> : in

07/21/2015 16:13:19 : openVM:VixGuest.cpp:781 <TRACE> : in

07/21/2015 16:13:19 : loadVixConfiguration:VixGuest.cpp:1081 <TRACE> : in

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : in

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : out

07/21/2015 16:13:19 : readVixData:VixGuest.cpp:1322 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : out

07/21/2015 16:13:19 : seperatePathFileName:VixGuest.cpp:316 <TRACE> : in

07/21/2015 16:13:19 : seperatePathFileName:VixGuest.cpp:316 <TRACE> : out

07/21/2015 16:13:19 : logBinaryFile:VixMetaSave.cpp:132 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : in

07/21/2015 16:13:19 : openFile:RvpFile.cpp:155 <TRACE> : out

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : out

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : in

07/21/2015 16:13:19 : RvpMemory:RvpMemory.cpp:35 <TRACE> : out

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : in

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : out

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : in

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : out

07/21/2015 16:13:19 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:19 : ~RvpMemory:RvpMemory.cpp:41 <TRACE> : out

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : logBinaryFile:VixMetaSave.cpp:132 <TRACE> : out

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : in

07/21/2015 16:13:19 : getSize:RvpFile.cpp:320 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : in

07/21/2015 16:13:19 : freeMemory:RvpMemory.cpp:157 <TRACE> : out

07/21/2015 16:13:19 : alloc:RvpMemory.cpp:104 <TRACE> : out

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : in

07/21/2015 16:13:19 : readData:RvpFile.cpp:228 <TRACE> : out

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : readVixData:VixGuest.cpp:1322 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : loadVixConfiguration:VixGuest.cpp:1310 <INFO> :

Successfully parsed vix xml file 07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1439 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1459 <INFO> : SnapshotName = NBU_SNAPSHOT nbu5230 1437491566

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1466 <INFO> : SnapshotMoref = snapshot-183798

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1500 <INFO> : Disk Key = 2000

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1519 <INFO> : DiskController = 2000

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1540 <INFO> : ChangeId = *

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : in

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : in

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1500 <INFO> : Disk Key = 2001

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1519 <INFO> : DiskController = 2001

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1540 <INFO> : ChangeId = *

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : in

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : in

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1500 <INFO> : Disk Key = 2002

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1519 <INFO> : DiskController = 2002

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1540 <INFO> : ChangeId = *

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : in

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : in

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1500 <INFO> : Disk Key = 2003

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1519 <INFO> : DiskController = 2003

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1540 <INFO> : ChangeId = *

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : in

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : in

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1500 <INFO> : Disk Key = 2004

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1519 <INFO> : DiskController = 2004

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1540 <INFO> : ChangeId = *

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : in

07/21/2015 16:13:19 : parseAndRemoveEscapeSeq:VixGuest.cpp:881 <TRACE> : out

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : in

07/21/2015 16:13:19 : findValueForKey:VixGuest.cpp:832 <TRACE> : out

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : in

07/21/2015 16:13:19 : addToDiskList:VixGuest.cpp:1379 <TRACE> : out

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1637 <INFO> : Number of VirtualDisks (excluding Independent disks) = 5

07/21/2015 16:13:19 : parseAndLoadDrives:VixGuest.cpp:1638 <INFO> : Number of Independent disks = 0

07/21/2015 16:13:19 : setupVixDiskSnapshotChains:VixGuest.cpp:1691 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:19 : VixSnapshot:VixSnapshot.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : VixSnapshot:VixSnapshot.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : ~VixSnapshot:VixSnapshot.cpp:48 <TRACE> : in

07/21/2015 16:13:19 : close:VixSnapshot.cpp:141 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : out

07/21/2015 16:13:19 : close:VixSnapshot.cpp:141 <TRACE> : out

07/21/2015 16:13:19 : ~VixSnapshot:VixSnapshot.cpp:48 <TRACE> : out

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : ~VixSnapshot:VixSnapshot.cpp:48 <TRACE> : in

07/21/2015 16:13:19 : close:VixSnapshot.cpp:141 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : out

07/21/2015 16:13:19 : close:VixSnapshot.cpp:141 <TRACE> : out

07/21/2015 16:13:19 : ~VixSnapshot:VixSnapshot.cpp:48 <TRACE> : out

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : ~RvpFile:RvpFile.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : in

07/21/2015 16:13:19 : RvpFile:RvpFile.cpp:36 <TRACE> : out

07/21/2015 16:13:19 : VixSnapshot:VixSnapshot.cpp:42 <TRACE> : in

07/21/2015 16:13:19 : VixSnapshot:VixSnapshot.cpp:42 <TRACE> : out

07/21/2015 16:13:19 : ~VixSnapshot:VixSnapshot.cpp:48 <TRACE> : in

07/21/2015 16:13:19 : close:VixSnapshot.cpp:141 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : in

07/21/2015 16:13:19 : isValid:RvpFile.cpp:54 <TRACE> : out