I've got a vmware image that, after i moved it from 1 host to another, when i went to power it on, i received the following message "cannot open the disk '/vmfs/volumes/hexnumberofsomekind/BU_NDVSndnpos/BU_NDVSndnpos-0000004.vmdk' or one of the snapshot disks it depends on. Reason: the parent virtual disk has been modified since the child was created"
In my haste to get this server up and running again, i was able to do so after performing the following steps
1) Deleting all snapshots from the snapshot manager as i thought this caused the problem
2) With the machine powered off, Removing the current hard drive from the edit settings area of the VM (this was done because the size was detected as "unavailable")
3) Adding a "current existing hard drive" BU_NDVSndnpos.vmdk
4) the machine now booted but i've come to find out that the hard disk contents are a few weeks old.
it appears the snapshot files still exist on disk, as the contents of my datastore for this vm are listed in the attached screenie
Contents of VMWare.log
May 12 13:44:45.850: vmx| Log for VMware ESX Server pid=101345 version=3.5.0 build=build-82663 option=Release
May 12 13:44:45.851: vmx| Hostname=ndvmware03.ad.ewsad.net
May 12 13:44:45.851: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-ssched.group=host/user/pool0" "-#" "name=VMware ESX Server;version=3.5.0;licensename=VMware ESX Server;licenseversion=2.0 build-82663;" "-@" "pipe=/tmp/vmhsdaemon-0/vmx9cd07f3791d15d6c;" "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmx"
May 12 13:44:45.899: vmx| UI Connecting to pipe '/tmp/vmhsdaemon-0/vmx9cd07f3791d15d6c' with user '(null)'
May 12 13:44:45.983: vmx| Sig_Init already initialized
May 12 13:44:45.988: vmx| CPUID[0] vendor: GenuntelineI
May 12 13:44:45.988: vmx| CPUID[0] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.988: vmx| CPUID[0] level 00000001, 0: 0x00010676 0x00040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.989: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.989: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.989: vmx| CPUID[0] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.989: vmx| CPUID[1] vendor: GenuntelineI
May 12 13:44:45.989: vmx| CPUID[1] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.989: vmx| CPUID[1] level 00000001, 0: 0x00010676 0x01040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.989: vmx| CPUID[1] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.989: vmx| CPUID[1] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.989: vmx| CPUID[1] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.989: vmx| CPUID[2] vendor: GenuntelineI
May 12 13:44:45.990: vmx| CPUID[2] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.990: vmx| CPUID[2] level 00000001, 0: 0x00010676 0x02040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.990: vmx| CPUID[2] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.990: vmx| CPUID[2] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.990: vmx| CPUID[2] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.990: vmx| CPUID[3] vendor: GenuntelineI
May 12 13:44:45.990: vmx| CPUID[3] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.990: vmx| CPUID[3] level 00000001, 0: 0x00010676 0x03040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.990: vmx| CPUID[3] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.990: vmx| CPUID[3] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.991: vmx| CPUID[3] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.991: vmx| CPUID[4] vendor: GenuntelineI
May 12 13:44:45.991: vmx| CPUID[4] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.991: vmx| CPUID[4] level 00000001, 0: 0x00010676 0x04040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.991: vmx| CPUID[4] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.991: vmx| CPUID[4] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.991: vmx| CPUID[4] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.991: vmx| CPUID[5] vendor: GenuntelineI
May 12 13:44:45.991: vmx| CPUID[5] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.991: vmx| CPUID[5] level 00000001, 0: 0x00010676 0x05040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.992: vmx| CPUID[5] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.992: vmx| CPUID[5] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.992: vmx| CPUID[5] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.992: vmx| CPUID[6] vendor: GenuntelineI
May 12 13:44:45.992: vmx| CPUID[6] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.992: vmx| CPUID[6] level 00000001, 0: 0x00010676 0x06040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.992: vmx| CPUID[6] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.992: vmx| CPUID[6] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.992: vmx| CPUID[6] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.992: vmx| CPUID[7] vendor: GenuntelineI
May 12 13:44:45.993: vmx| CPUID[7] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.993: vmx| CPUID[7] level 00000001, 0: 0x00010676 0x07040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.993: vmx| CPUID[7] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.993: vmx| CPUID[7] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.993: vmx| CPUID[7] level 80000008, 0: 0x00003026 0x00000000 0x00000000 0x00000000
May 12 13:44:45.993: vmx| hostCPUID vendor: GenuntelineI
May 12 13:44:45.993: vmx| hostCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:45.993: vmx| hostCPUID level 00000001, 0: 0x00010676 0x00040800 0x000ce3bd 0xbfebfbff
May 12 13:44:45.993: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:45.993: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:45.994: vmx| hostCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
May 12 13:44:45.994: vmx| CPUID Maximum Physical Address Bits supported across all CPUs: 38
May 12 13:44:46.005: vmx| Setup symlink /var/run/vmware/091d39d9943fd4db6b0bcf9990000ee2 -> /var/run/vmware/root_0/1242150285852860_101345
May 12 13:44:46.007: vmx| ACL_InitCapabilities: current IPC thread
May 12 13:44:46.008: vmx| ACL_InitCapabilities: done
May 12 13:44:46.021: vmx| changing directory to /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/.
May 12 13:44:46.021: vmx| Config file: /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmx
May 12 13:44:46.062: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (23) size = 9673977856, hd = 0. Type 8
May 12 13:44:46.067: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (23) size = 9673977856, hd = 0. Type 3
May 12 13:44:46.068: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 13:44:46.068: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 13:44:46.100: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:44:46.145: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset
May 12 13:44:46.145: vmx| TOOLS delaying state change request to state 3
May 12 13:44:46.145: vmx| PowerOn
May 12 13:44:46.149: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:44:46.153: vmx| UNAME Linux NDVMWare03.ad.ewsad.net 2.4.21-47.0.1.ELvmnix #1 Tue Mar 18 18:07:00 PDT 2008 i686 (uwglibc version 6)
May 12 13:44:46.153: vmx| DICT --- USER PREFERENCES
May 12 13:44:46.153: vmx| DICT --- USER DEFAULTS
May 12 13:44:46.153: vmx| DICT --- HOST DEFAULTS
May 12 13:44:46.153: vmx| DICT libdir = /usr/lib/vmware
May 12 13:44:46.153: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
May 12 13:44:46.153: vmx| DICT authd.client.port = 902
May 12 13:44:46.153: vmx| DICT vmware.fullpath = /usr/bin/vmware
May 12 13:44:46.153: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
May 12 13:44:46.153: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
May 12 13:44:46.153: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
May 12 13:44:46.153: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
May 12 13:44:46.153: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
May 12 13:44:46.153: vmx| DICT authd.proxy.nfcssl = vmware-hostd:ha-nfcssl
May 12 13:44:46.153: vmx| DICT authd.soapServer = TRUE
May 12 13:44:46.153: vmx| DICT authd.proxy.vpxa-nfc = vmware-vpxa:vpxa-nfc
May 12 13:44:46.153: vmx| DICT authd.proxy.vpxa-nfcssl = vmware-vpxa:vpxa-nfcssl
May 12 13:44:46.153: vmx| DICT --- SITE DEFAULTS
May 12 13:44:46.153: vmx| DICT tag.help = introduction.htm
May 12 13:44:46.153: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
May 12 13:44:46.153: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
May 12 13:44:46.153: vmx| DICT tag.floppyConfig = devices_floppy.htm
May 12 13:44:46.153: vmx| DICT tag.mouseConfig = devices_mouse.htm
May 12 13:44:46.153: vmx| DICT tag.netConfig = devices_netadapter.htm
May 12 13:44:46.153: vmx| DICT tag.parallelConfig = devices_parallel.htm
May 12 13:44:46.153: vmx| DICT tag.serialConfig = devices_serial.htm
May 12 13:44:46.153: vmx| DICT tag.soundConfig = devices_sound.htm
May 12 13:44:46.153: vmx| DICT tag.memConfig = configvm_memory.htm
May 12 13:44:46.153: vmx| DICT tag.miscConfig = configvm.htm
May 12 13:44:46.153: vmx| DICT tag.usbConfig = devices_usb.htm
May 12 13:44:46.153: vmx| DICT tag.displayConfig = configvm_display-problems.htm
May 12 13:44:46.153: vmx| DICT tag.tools = vmtools.htm
May 12 13:44:46.153: vmx| DICT --- COMMAND LINE
May 12 13:44:46.153: vmx| DICT sched.group = host/user/pool0
May 12 13:44:46.153: vmx| DICT --- CONFIGURATION
May 12 13:44:46.153: vmx| DICT config.version = 8
May 12 13:44:46.153: vmx| DICT virtualHW.version = 4
May 12 13:44:46.153: vmx| DICT floppy0.present = TRUE
May 12 13:44:46.153: vmx| DICT nvram = BU_NDVSndnpos.nvram
May 12 13:44:46.153: vmx| DICT deploymentPlatform = windows
May 12 13:44:46.153: vmx| DICT virtualHW.productCompatibility = hosted
May 12 13:44:46.153: vmx| DICT tools.upgrade.policy = manual
May 12 13:44:46.153: vmx| DICT powerType.powerOff = default
May 12 13:44:46.153: vmx| DICT powerType.powerOn = default
May 12 13:44:46.153: vmx| DICT powerType.suspend = default
May 12 13:44:46.153: vmx| DICT powerType.reset = default
May 12 13:44:46.153: vmx| DICT displayName = BU_NDVSndnpos
May 12 13:44:46.153: vmx| DICT extendedConfigFile = BU_NDVSndnpos.vmxf
May 12 13:44:46.153: vmx| DICT scsi0.present = TRUE
May 12 13:44:46.153: vmx| DICT scsi0.sharedBus = none
May 12 13:44:46.153: vmx| DICT memsize = 1024
May 12 13:44:46.153: vmx| DICT scsi0:0.present = FALSE
May 12 13:44:46.153: vmx| DICT scsi0:0.fileName = BU_NDVSndnpos-000002.vmdk
May 12 13:44:46.153: vmx| DICT scsi0:0.deviceType = scsi-hardDisk
May 12 13:44:46.153: vmx| DICT sched.scsi0:0.shares = normal
May 12 13:44:46.153: vmx| DICT ide0:0.present = TRUE
May 12 13:44:46.153: vmx| DICT ide0:0.fileName = /dev/cdrom
May 12 13:44:46.153: vmx| DICT ide0:0.deviceType = atapi-cdrom
May 12 13:44:46.153: vmx| DICT ide0:1.present = TRUE
May 12 13:44:46.153: vmx| DICT ide0:1.fileName = /dev/cdrom0
May 12 13:44:46.153: vmx| DICT ide0:1.deviceType = atapi-cdrom
May 12 13:44:46.153: vmx| DICT floppy0.fileName = /dev/fd0
May 12 13:44:46.153: vmx| DICT ethernet0.present = TRUE
May 12 13:44:46.153: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE
May 12 13:44:46.153: vmx| DICT ethernet0.networkName = VM Network
May 12 13:44:46.153: vmx| DICT ethernet0.addressType = vpx
May 12 13:44:46.153: vmx| DICT ethernet0.generatedAddress = 00:50:56:9f:69:a7
May 12 13:44:46.153: vmx| DICT serial0.present = TRUE
May 12 13:44:46.153: vmx| DICT serial0.fileName = /dev/ttyS0
May 12 13:44:46.153: vmx| DICT serial1.present = TRUE
May 12 13:44:46.153: vmx| DICT serial1.fileName = /dev/ttyS1
May 12 13:44:46.153: vmx| DICT parallel0.present = TRUE
May 12 13:44:46.153: vmx| DICT parallel0.fileName = /dev/parport0
May 12 13:44:46.153: vmx| DICT parallel0.bidirectional = FALSE
May 12 13:44:46.153: vmx| DICT guestOS = win2000serv
May 12 13:44:46.153: vmx| DICT annotation = good backup as of 3-30-2009 @615pm, clone this back over the original before reverting!
May 12 13:44:46.153: vmx| DICT uuid.bios = 50 1f c9 78 b1 ef 60 40-6a 99 ff 9c ce 09 be 5c
May 12 13:44:46.153: vmx| DICT log.fileName = vmware.log
May 12 13:44:46.153: vmx| DICT snapshot.action = keep
May 12 13:44:46.153: vmx| DICT sched.cpu.min = 0
May 12 13:44:46.153: vmx| DICT sched.cpu.units = mhz
May 12 13:44:46.153: vmx| DICT sched.cpu.shares = normal
May 12 13:44:46.153: vmx| DICT sched.mem.minsize = 0
May 12 13:44:46.153: vmx| DICT sched.mem.shares = normal
May 12 13:44:46.153: vmx| DICT scsi0:0.redo =
May 12 13:44:46.153: vmx| DICT vmware.tools.requiredversion = 7299
May 12 13:44:46.153: vmx| DICT uuid.location = 56 4d 71 2a f6 85 30 4e-cc 29 84 2d 5c 3f ee 15
May 12 13:44:46.153: vmx| DICT sched.swap.derivedName = /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-b94fdefe.vswp
May 12 13:44:46.153: vmx| DICT scsi0:1.present = TRUE
May 12 13:44:46.153: vmx| DICT scsi0:1.fileName = BU_NDVSndnpos-000007.vmdk
May 12 13:44:46.153: vmx| DICT scsi0:1.deviceType = scsi-hardDisk
May 12 13:44:46.153: vmx| DICT scsi0:1.redo =
May 12 13:44:46.153: vmx| DICT tools.syncTime = FALSE
May 12 13:44:46.153: vmx| DICT sched.mem.max = 1024
May 12 13:44:46.153: vmx| DICT --- USER DEFAULTS
May 12 13:44:46.153: vmx| DICT --- HOST DEFAULTS
May 12 13:44:46.153: vmx| DICT libdir = /usr/lib/vmware
May 12 13:44:46.153: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
May 12 13:44:46.153: vmx| DICT authd.client.port = 902
May 12 13:44:46.153: vmx| DICT vmware.fullpath = /usr/bin/vmware
May 12 13:44:46.153: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
May 12 13:44:46.153: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
May 12 13:44:46.153: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
May 12 13:44:46.153: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
May 12 13:44:46.153: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
May 12 13:44:46.153: vmx| DICT authd.proxy.nfcssl = vmware-hostd:ha-nfcssl
May 12 13:44:46.153: vmx| DICT authd.soapServer = TRUE
May 12 13:44:46.153: vmx| DICT authd.proxy.vpxa-nfc = vmware-vpxa:vpxa-nfc
May 12 13:44:46.153: vmx| DICT authd.proxy.vpxa-nfcssl = vmware-vpxa:vpxa-nfcssl
May 12 13:44:46.153: vmx| DICT --- SITE DEFAULTS
May 12 13:44:46.153: vmx| DICT tag.help = introduction.htm
May 12 13:44:46.153: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
May 12 13:44:46.153: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
May 12 13:44:46.153: vmx| DICT tag.floppyConfig = devices_floppy.htm
May 12 13:44:46.153: vmx| DICT tag.mouseConfig = devices_mouse.htm
May 12 13:44:46.153: vmx| DICT tag.netConfig = devices_netadapter.htm
May 12 13:44:46.153: vmx| DICT tag.parallelConfig = devices_parallel.htm
May 12 13:44:46.153: vmx| DICT tag.serialConfig = devices_serial.htm
May 12 13:44:46.153: vmx| DICT tag.soundConfig = devices_sound.htm
May 12 13:44:46.153: vmx| DICT tag.memConfig = configvm_memory.htm
May 12 13:44:46.153: vmx| DICT tag.miscConfig = configvm.htm
May 12 13:44:46.153: vmx| DICT tag.usbConfig = devices_usb.htm
May 12 13:44:46.153: vmx| DICT tag.displayConfig = configvm_display-problems.htm
May 12 13:44:46.153: vmx| DICT tag.tools = vmtools.htm
May 12 13:44:46.153: vmx| DICT --- GLOBAL SETTINGS
May 12 13:44:46.154: vmx| hostCpuFeatures = 0x446000fc
May 12 13:44:46.154: vmx| hostNumPerfCounters = 2
May 12 13:44:46.154: vmx| guestCPUID vendor: GenuntelineI
May 12 13:44:46.154: vmx| guestCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
May 12 13:44:46.154: vmx| guestCPUID level 00000001, 0: 0x00010678 0x00010800 0x00082211 0x0febfbff
May 12 13:44:46.154: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
May 12 13:44:46.154: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
May 12 13:44:46.154: vmx| guestCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
May 12 13:44:46.217: vmx| VMMon_CreateVM: vmmon.numVCPUs=1
May 12 13:44:46.218: vmx| Swap file path: '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-b94fdefe.vswp'
May 12 13:44:46.230: vmx| Using swap file '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-b94fdefe.vswp'
May 12 13:44:46.230: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:44:47.865: vmx| guestCpuFeatures = 0x406000fc
May 12 13:44:47.912: vmx| vmm32-modules:
May 12 13:44:47.915: vmx| KHZEstimate 2992531
May 12 13:44:47.915: vmx| MHZEstimate 2993
May 12 13:44:47.915: vmx| NumVCPUs 1
May 12 13:44:47.916: vmx| UUID: location-UUID is 56 4d 71 2a f6 85 30 4e-cc 29 84 2d 5c 3f ee 15
May 12 13:44:47.916: vmx| WORKER: Creating new group with numThreads=3 (3)
May 12 13:44:47.927: vmx| Mapped mainmem as pageable
May 12 13:44:47.928: vmx| MStat: Creating Stat vm.uptime
May 12 13:44:47.928: vmx| MStat: Creating Stat vm.suspendTime
May 12 13:44:47.928: vmx| MStat: Creating Stat vm.powerOnTimeStamp
May 12 13:44:47.934: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:44:47.938: vmx| DISK: OPEN scsi0:1 '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk' persistent R[(null)]
May 12 13:44:47.939: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (10) size = 33575424, hd = 487625. Type 8
May 12 13:44:47.939: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-000007-delta.vmdk" (0xa)
May 12 13:44:47.939: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk' (0xa): vmfsSparse, 18894488 sectors / 9226 Mb.
May 12 13:44:47.942: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (14) size = 9673977856, hd = 413898. Type 3
May 12 13:44:47.942: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-flat.vmdk" (0xe)
May 12 13:44:47.942: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' (0xe): vmfs, 18894488 sectors / 9226 Mb.
May 12 13:44:47.961: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk" (flags 0xa). 87E6954
May 12 13:44:48.012: vmx| DISK: OPEN '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk' Geo (1176/255/63) BIOS Geo (0/0/0) freeSpace=371079Mb, buslogic
May 12 13:44:48.015: vmx| TimeTracker host to guest rate conversion 25391646521128116 @ 2992531000Hz -> 25391646521128116 @ 2992531000Hz
May 12 13:44:48.015: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
May 12 13:44:48.016: vmx| MKS not attempting to initialize X.
May 12 13:44:48.022: vmx| MKS REMOTE Loading VNC Configuration from VM config file
May 12 13:44:48.024: vmx| DVGA: Full screen VGA will not be available.
May 12 13:44:48.024: vmx| SVGA: Truncated max res to VRAM size: 4194304 bytes VRAM, 1180x885 Max Resolution
May 12 13:44:48.025: vmx| Creating virtual dev for 0:1
May 12 13:44:48.025: vmx| DumpDiskInfo: scsi0:1 createType=11, capacity = 18894488, numLinks = 2, allocationType = 0
May 12 13:44:48.025: vmx| SCSIDiskESXPopulateVDevDesc: Using FS backend
May 12 13:44:48.025: vmx| VMMon_SetStorageSchedPolicy: Not supported
May 12 13:44:48.025: vmx| DISKUTIL: scsi0:1 : geometry=1176/255/63
May 12 13:44:48.025: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
May 12 13:44:48.026: vmx| Ethernet0 MAC Address: 00:50:56:9f:69:a7
May 12 13:44:48.027: vmx| MStat: Creating Stat vm.heartbeat
May 12 13:44:48.027: vmx| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:44:48.027: vmx| TOOLS INSTALL initializing state to IDLE on power on.
May 12 13:44:48.226: vmx| PTSC to VMI Wallclock (nsec) 25391647153558206 @ 2992531000Hz -> 1242150288000000000 @ 1000000000Hz
May 12 13:44:48.226: vmx| PTSC to VMI Wallclock (nsec) ((x * 2870458014) >> 33) + 1233665280776723644
May 12 13:44:48.226: vmx| PTSC to ParaTime RealCycles 0 @ 2992531000Hz -> 0 @ 2992531000Hz
May 12 13:44:48.226: vmx| PTSC to ParaTime RealCycles ((x * 1) >> 0) + 0
May 12 13:44:48.226: vmx| ParaTime RealCycles to PTSC 0 @ 2992531000Hz -> 0 @ 2992531000Hz
May 12 13:44:48.226: vmx| ParaTime RealCycles to PTSC ((x * 1) >> 0) + 0
May 12 13:44:48.256: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
May 12 13:44:48.256: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
May 12 13:44:48.257: mks| Async MKS thread is alive
May 12 13:44:48.258: vcpu-0| VMMon_Start: vcpu-0: worldID=1346
May 12 13:44:48.271: vcpu-0| APIC: version = 0x14, max LVT = 5
May 12 13:44:48.271: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff
May 12 13:44:48.275: vcpu-0| guestCpuFeatures = 0x406000fc
May 12 13:44:48.280: vcpu-0| CPU reset: hard
May 12 13:44:48.302: vcpu-0| Msg_Post: Warning
May 12 13:44:48.302: vcpu-0| http://msg.parallel.cannotclaim23 Parallel port "/dev/parport0" is used by another program (such as another instance of VMware ESX Server) or driver (such as lp).
May 12 13:44:48.302: vcpu-0| http://msg.device.startdisconnected Virtual device parallel0 will start disconnected.
May 12 13:44:48.302: vcpu-0| -
May 12 13:44:48.365: vcpu-0| Msg_Post: Warning
May 12 13:44:48.365: vcpu-0| http://msg.serial.device.locked serial0: Unable to obtain lock for file "/dev/ttyS0".
May 12 13:44:48.365: vcpu-0| http://msg.device.startdisconnected Virtual device serial0 will start disconnected.
May 12 13:44:48.365: vcpu-0| -
May 12 13:44:48.392: vcpu-0| FLOPPYLIB-LINUX: Cannot validate floppy device /dev/fd0: No such device or address.
May 12 13:44:48.392: vcpu-0| Msg_Post: Warning
May 12 13:44:48.392: vcpu-0| http://msg.floppy.badConnectLong Could not connect to floppy "/dev/fd0". Please correct your configuration and then re-attempt to connect the virtual floppy drive.
May 12 13:44:48.392: vcpu-0| http://msg.device.startdisconnected Virtual device floppy0 will start disconnected.
May 12 13:44:48.392: vcpu-0| -
May 12 13:44:48.406: vcpu-0| CDROM-LIN: Verify Open '/dev/cdrom0' failed: 'No such file or directory'
May 12 13:44:48.406: vcpu-0| Msg_Post: Warning
May 12 13:44:48.406: vcpu-0| http://msg.cdromLin.badFileName Unable to open host CD-ROM drive "/dev/cdrom0": No such file or directory.
May 12 13:44:48.406: vcpu-0| http://msg.device.startdisconnected Virtual device ide0:1 will start disconnected.
May 12 13:44:48.407: vcpu-0| -
May 12 13:44:48.430: vcpu-0| CDROM-LIN: Implementing mediaChange workaround.
May 12 13:44:48.473: vcpu-0| sz=2977760
May 12 13:44:48.492: vcpu-0| vmm32 initialized: Releasebuild-82663. cflags: 0x06000000.00000000.4000c203.00000001
May 12 13:44:48.708: vmx| TOOLS received request in VMX to set option 'synctime' -> '0'
May 12 13:44:48.709: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:44:48.859: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0x0) and 0xf4000000(0x0)
May 12 13:44:48.867: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:48.879: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:48.889: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:48.890: vcpu-0| SVGA: Registering IOSpace at 0x1400
May 12 13:44:48.891: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:49.074: vcpu-0| DISKUTIL: scsi0:1 : geometry=1176/255/63
May 12 13:44:49.074: vcpu-0| BUSLOGIC: Soft reset 0x6cfd6
May 12 13:44:49.074: vcpu-0| BUSLOGIC: Bus reset 0x6cfd6 (0 cif)
May 12 13:44:49.074: vcpu-0| BUSLOGIC: Sync reset target 1, handle 8263
May 12 13:44:49.075: vcpu-0| BUSLOGIC: Adapter reset complete 0x6cfd6
May 12 13:44:49.110: vcpu-0| BIOS-UUID is 50 1f c9 78 b1 ef 60 40-6a 99 ff 9c ce 09 be 5c
May 12 13:44:49.351: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:44:49.351: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:44:49.586: vcpu-0| Unknown int 10h func 0x2000
May 12 13:44:53.587: mks| VNCENCODE 2 encoding mode change: (640x480x16depth,16bpp)
May 12 13:44:54.364: vcpu-0| SVGA: Unregistering IOSpace at 0x1400
May 12 13:44:54.364: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:54.365: vcpu-0| SVGA: Registering IOSpace at 0x1400
May 12 13:44:54.365: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:44:55.039: vmx| CDROM: Mode Sense for Unsupported Page 0x1B
May 12 13:44:55.045: vcpu-0| CDROM: Mode Sense for Unsupported Page 0x1B
May 12 13:44:55.059: vcpu-0| BUSLOGIC: Hard reset 0x6cfd6
May 12 13:44:55.059: vcpu-0| BUSLOGIC: Bus reset 0x6cfd6 (0 cif)
May 12 13:44:55.059: vcpu-0| BUSLOGIC: Sync reset target 1, handle 8263
May 12 13:44:55.060: vcpu-0| BUSLOGIC: Adapter reset complete 0x6cfd6
May 12 13:44:55.060: vcpu-0| BUSLOGIC: Disable HAC
May 12 13:44:55.078: vcpu-0| BUSLOGIC: Init ext-mbox, count 211 addr 0x5df32d0
May 12 13:44:55.079: vcpu-0| BUSLOGIC: Enable aggressive round robin mode (1)
May 12 13:44:55.079: vcpu-0| BUSLOGIC: Set 64 LUN CCB format (1)
May 12 13:44:55.086: vcpu-0| BUSLOGIC: Enable HAC
May 12 13:44:55.202: vcpu-0| HBACommon: First write on scsi0:1.fileName='/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk'
May 12 13:44:55.203: vcpu-0| DISKLIB-CHAIN : UpdateContentID: old = 0x9a0b39b4, new = 0x691f5076
May 12 13:44:58.651: vcpu-0| Guest OS = 0x5007
May 12 13:44:58.746: vcpu-0| Guest: VMXNET: Initialization completed successfully. Version 2.0
May 12 13:44:58.747: vcpu-0| Guest: VMXNET: Features: zerocopy csum
May 12 13:45:08.226: mks| VNCENCODE 2 encoding mode change: (1024x768x24depth,32bpp)
May 12 13:45:10.284: mks| MKS remote display status changed, enabling remote optimizations
May 12 13:45:10.298: mks| HostOps hideCursor before defineCursor!
May 12 13:45:15.342: vcpu-0| Guest: vmdesched:driver:vmdesched Descheduled Time Accounting Service version=01.00.00 loaded
May 12 13:45:23.757: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running
May 12 13:45:23.757: vcpu-0| GuestRpc: Channel 0, registration number 1, guest application toolbox.
May 12 13:45:23.757: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
May 12 13:45:23.757: vcpu-0| TOOLS sending 'OS_PowerOn' (3) state change request
May 12 13:45:23.757: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:45:23.857: vcpu-0| TOOLS autoupgrade protocol version 1
May 12 13:45:23.913: vcpu-0| Guest: toolbox: Version: build-52542
May 12 13:45:23.913: vcpu-0| TOOLS setting the tools version to '7202'
May 12 13:45:23.915: vcpu-0| VMXVmdbGuest_GetGuestUptime failed. ret = Type mismatch
May 12 13:45:23.915: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:45:23.915: vcpu-0| TOOLS installed version 7202, available version 7300
May 12 13:45:23.915: vcpu-0| TOOLS VM tools upgrade policy "manual"
May 12 13:45:23.915: vcpu-0| TOOLS don't need to be upgraded.
May 12 13:45:23.917: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
May 12 13:46:22.209: vcpu-0| VMMouse: CMD Read ID
May 12 13:46:22.209: vcpu-0| MKS switching absolute mouse on
May 12 13:46:24.108: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
May 12 13:46:24.109: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
May 12 13:46:24.109: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:47:40.625: vmx| TOOLS received request in VMX to set option 'synctime' -> '0'
May 12 13:47:40.626: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:48:20.595: vmx| TOOLS received request in VMX to set option 'synctime' -> '0'
May 12 13:48:20.603: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:48:20.936: vcpu-0| Guest: toolbox: Got a logoff event.
May 12 13:48:21.074: vcpu-0| GuestRpc: Channel 1 reinitialized.
May 12 13:48:29.255: vcpu-0| Guest: toolbox: Got a logoff event.
May 12 13:48:32.322: vcpu-0| Guest: toolbox: VMware Tools Service Shutdown.
May 12 13:48:32.322: vcpu-0| Guest: toolbox: VMware Tools Service Stopping.
May 12 13:48:32.336: vcpu-0| GuestRpc: Channel 0 reinitialized.
May 12 13:49:02.529: vcpu-0| VMMouse: CMD Disable
May 12 13:49:02.529: vcpu-0| VMMouse: Disabling VMMouse mode
May 12 13:49:02.529: vcpu-0| MKS switching absolute mouse on
May 12 13:49:02.634: mks| SVGA: Sync FIFO with SVGA disabled
May 12 13:49:02.634: vcpu-0| CPU reset: soft
May 12 13:49:02.733: mks| VNCENCODE 2 encoding mode change: (640x480x16depth,16bpp)
May 12 13:49:02.769: vcpu-0| SVGA: Unregistering IOSpace at 0x1400
May 12 13:49:02.769: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.850: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.860: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.871: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.883: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.884: vcpu-0| SVGA: Registering IOSpace at 0x1400
May 12 13:49:02.885: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:02.916: mks| VNCENCODE 2 encoding mode change: (720x400x16depth,16bpp)
May 12 13:49:02.943: mks| VNCENCODE 2 encoding mode change: (640x480x16depth,16bpp)
May 12 13:49:03.079: vcpu-0| DISKUTIL: scsi0:1 : geometry=1176/255/63
May 12 13:49:03.080: vcpu-0| BUSLOGIC: Soft reset 0x6cfd6
May 12 13:49:03.080: vcpu-0| BUSLOGIC: Bus reset 0x6cfd6 (0 cif)
May 12 13:49:03.080: vcpu-0| BUSLOGIC: Sync reset target 1, handle 8263
May 12 13:49:03.081: vcpu-0| BUSLOGIC: Adapter reset complete 0x6cfd6
May 12 13:49:03.124: vcpu-0| BIOS-UUID is 50 1f c9 78 b1 ef 60 40-6a 99 ff 9c ce 09 be 5c
May 12 13:49:03.367: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:49:03.367: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:49:03.390: mks| VNCENCODE 2 encoding mode change: (720x400x16depth,16bpp)
May 12 13:49:03.590: vcpu-0| Unknown int 10h func 0x2000
May 12 13:49:07.709: mks| VNCENCODE 2 encoding mode change: (640x480x16depth,16bpp)
May 12 13:49:08.471: vcpu-0| SVGA: Unregistering IOSpace at 0x1400
May 12 13:49:08.471: vcpu-0| SVGA: Unregistering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:08.473: vcpu-0| SVGA: Registering IOSpace at 0x1400
May 12 13:49:08.473: vcpu-0| SVGA: Registering MemSpace at 0xf8000000(0xf8000000) and 0xf4000000(0xf4000000)
May 12 13:49:09.128: vmx| CDROM: Mode Sense for Unsupported Page 0x1B
May 12 13:49:09.135: vcpu-0| CDROM: Mode Sense for Unsupported Page 0x1B
May 12 13:49:09.149: vcpu-0| BUSLOGIC: Hard reset 0x6cfd6
May 12 13:49:09.149: vcpu-0| BUSLOGIC: Bus reset 0x6cfd6 (0 cif)
May 12 13:49:09.149: vcpu-0| BUSLOGIC: Sync reset target 1, handle 8263
May 12 13:49:09.150: vcpu-0| BUSLOGIC: Adapter reset complete 0x6cfd6
May 12 13:49:09.150: vcpu-0| BUSLOGIC: Disable HAC
May 12 13:49:09.167: vcpu-0| BUSLOGIC: Init ext-mbox, count 211 addr 0x5df32d0
May 12 13:49:09.167: vcpu-0| BUSLOGIC: Enable aggressive round robin mode (1)
May 12 13:49:09.167: vcpu-0| BUSLOGIC: Set 64 LUN CCB format (1)
May 12 13:49:09.175: vcpu-0| BUSLOGIC: Enable HAC
May 12 13:49:12.802: vcpu-0| Guest: VMXNET: Initialization completed successfully. Version 2.0
May 12 13:49:12.804: vcpu-0| Guest: VMXNET: Features: zerocopy csum
May 12 13:49:20.230: mks| HostOps hideCursor before defineCursor!
May 12 13:49:20.244: mks| VNCENCODE 2 encoding mode change: (1024x768x24depth,32bpp)
May 12 13:49:22.776: mks| HostOps hideCursor before defineCursor!
May 12 13:49:22.920: mks| MKS remote display status changed, enabling remote optimizations
May 12 13:49:27.741: vcpu-0| Guest: vmdesched:driver:vmdesched Descheduled Time Accounting Service version=01.00.00 loaded
May 12 13:49:27.772: vcpu-0| Balloon: Start: vmmemctl reset balloon
May 12 13:49:27.773: vcpu-0| Balloon: Reset (n=1 pages=0)
May 12 13:49:27.773: vcpu-0| Balloon: Reset: nUnlocked=0 (size=0)
May 12 13:49:28.509: vcpu-0| VMMouse: CMD Read ID
May 12 13:49:28.509: vcpu-0| MKS switching absolute mouse on
May 12 13:49:40.293: vcpu-0| GuestRpc: Channel 0, registration number 1, guest application toolbox.
May 12 13:49:40.293: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
May 12 13:49:40.293: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:49:40.299: vcpu-0| TOOLS autoupgrade protocol version 1
May 12 13:49:40.438: vcpu-0| Guest: toolbox: Version: build-52542
May 12 13:49:40.440: vcpu-0| TOOLS setting the tools version to '7202'
May 12 13:49:40.448: vcpu-0| VMXVmdbGuest_GetGuestUptime failed. ret = Type mismatch
May 12 13:49:40.448: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:49:40.448: vcpu-0| TOOLS installed version 7202, available version 7300
May 12 13:49:40.449: vcpu-0| TOOLS VM tools upgrade policy "manual"
May 12 13:49:40.449: vcpu-0| TOOLS don't need to be upgraded.
May 12 13:49:40.450: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
May 12 13:50:25.936: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
May 12 13:50:25.937: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
May 12 13:50:25.937: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 7202
May 12 13:53:50.616: vmx| TOOLS received request in VMX to set option 'synctime' -> '0'
May 12 13:53:50.620: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 13:54:16.070: mks| SOCKET 2 recv error 5: Input/output error
May 12 13:54:16.070: mks| SOCKET 2 destroying VNC backend on socket error: 5
May 12 13:54:20.668: vmx| TOOLS received request in VMX to set option 'synctime' -> '0'
May 12 13:54:20.669: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 14:21:19.024: vmx| SnapshotVMX_Consolidate: starting
May 12 14:21:19.041: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.041: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.048: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.048: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.049: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:19.049: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:19.059: vmx| ConsolidateOnlineCB: nextState = 0
May 12 14:21:19.061: vcpu-0| Sync monModules(1).
May 12 14:21:19.061: vcpu-0| Done Sync monModules(1).
May 12 14:21:19.080: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.080: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:19.080: vmx| ConsolidateOnlineCB: nextState = 1
May 12 14:21:19.082: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.082: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.083: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.083: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.084: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:19.084: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:19.084: vmx| ConsolidateOnlineCB: taking intermediate snapshot
May 12 14:21:19.086: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.086: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.091: vmx| DUMPER: Creating checkpoint file /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-Snapshot4.vmsn
May 12 14:21:19.092: vmx| FILEIO: pread got 0 out of 4 bytes.
May 12 14:21:19.097: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.122: vmx| DISKLIB-VMFS_SPARSE :ExtentCreate "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : success
May 12 14:21:19.124: vmx| DISKLIB-LINK : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk" : creation successful.
May 12 14:21:19.124: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (17) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.125: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:19.125: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.132: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 14:21:19.135: vmx| ConsolidateOnlineCB: nextState = 1
May 12 14:21:19.137: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.137: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:19.138: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.138: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.138: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.139: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:19.139: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:19.139: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:19.140: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:19.140: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:19.141: vmx| Checkpoint_Unstun: vm stopped for 81981 us
May 12 14:21:19.141: vcpu-0| Sync monModules(5).
May 12 14:21:19.142: vcpu-0| Done Sync monModules(5).
May 12 14:21:19.165: vmx| DISK: OPEN scsi0:1 '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk' persistent R[(null)]
May 12 14:21:19.166: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (8) size = 20992, hd = 508117. Type 8
May 12 14:21:19.166: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-000008-delta.vmdk" (0x8)
May 12 14:21:19.166: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk' (0x8): vmfsSparse, 18894488 sectors / 9226 Mb.
May 12 14:21:19.167: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk" (flags 0x8). 8922ABC
May 12 14:21:19.168: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (8) size = 536891904, hd = 733399. Type 8
May 12 14:21:19.168: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-000007-delta.vmdk" (0x8)
May 12 14:21:19.168: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk' (0x8): vmfsSparse, 18894488 sectors / 9226 Mb.
May 12 14:21:19.169: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007.vmdk" (flags 0x8). 8A65B94
May 12 14:21:19.170: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (10) size = 9673977856, hd = 741593. Type 3
May 12 14:21:19.170: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-flat.vmdk" (0xa)
May 12 14:21:19.170: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' (0xa): vmfs, 18894488 sectors / 9226 Mb.
May 12 14:21:19.170: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk" (flags 0xa). 87C04D4
May 12 14:21:19.229: vmx| DISK: OPEN '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk' Geo (1176/255/63) BIOS Geo (0/0/0) freeSpace=388641Mb, buslogic
May 12 14:21:19.229: vmx| Creating virtual dev for 0:1
May 12 14:21:19.229: vmx| DumpDiskInfo: scsi0:1 createType=11, capacity = 18894488, numLinks = 3, allocationType = 0
May 12 14:21:19.229: vmx| SCSIDiskESXPopulateVDevDesc: Using FS backend
May 12 14:21:19.230: vmx| VMMon_SetStorageSchedPolicy: Not supported
May 12 14:21:19.230: vmx| ConsolidateOnlineCB: nextState = 2
May 12 14:21:19.231: vmx| ConsolidateThread: Created thread 6 for scsi0:1
May 12 14:21:19.231: SnapshotVMXCombiner| ConsolidateOnlineCB: nextState = 3
May 12 14:21:19.231: SnapshotVMXCombiner| ConsolidateOnlineCB: Starting combine of scsi0:1 /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk. 2 links, starting from 1
May 12 14:21:19.259: SnapshotVMXCombiner| DISKLIB-LIB : Combine 2 links at offset 1 in chain 8922ABC.
May 12 14:21:42.922: vcpu-0| HBACommon: First write on scsi0:1.fileName='/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk'
May 12 14:21:43.004: vcpu-0| DISKLIB-CHAIN : UpdateContentID: old = 0x691f5076, new = 0xae73db69
May 12 14:21:47.385: SnapshotVMXCombiner| SnapshotVMXConConsolidateOnlineCB: Done with combine of /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk. (2 links, starting from 1
May 12 14:21:47.385: vcpu-0| Sync monModules(1).
May 12 14:21:47.385: vcpu-0| Done Sync monModules(1).
May 12 14:21:47.391: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.391: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:47.391: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.391: vmx| ConsolidateOnlineCB: nextState = 4
May 12 14:21:47.477: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.477: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.478: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.478: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:47.478: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.478: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.479: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.479: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:47.480: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:47.480: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.480: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (17) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.481: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.483: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : open successful (17) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.483: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000007-delta.vmdk" : closed.
May 12 14:21:47.540: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 14:21:47.543: vmx| ConsolidateOnlineCB: nextState = 1
May 12 14:21:47.545: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.545: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.546: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:47.546: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.547: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.547: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.548: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:47.548: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.550: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.550: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.551: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:47.551: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.579: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.580: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.580: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.580: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.581: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (21) size = 9673977856, hd = 0. Type 3
May 12 14:21:47.581: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.581: vmx| SNAPSHOT: Consolidating from '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk' to '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk'.
May 12 14:21:47.583: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (24) size = 9673977856, hd = 151774. Type 3
May 12 14:21:47.583: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-flat.vmdk" (0x18)
May 12 14:21:47.583: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' (0x18): vmfs, 18894488 sectors / 9226 Mb.
May 12 14:21:47.583: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk" (flags 0x18). 8A71484
May 12 14:21:47.589: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (24) size = 16798208, hd = 90335. Type 8
May 12 14:21:47.589: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-000008-delta.vmdk" (0x18)
May 12 14:21:47.589: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk' (0x18): vmfsSparse, 18894488 sectors / 9226 Mb.
May 12 14:21:47.599: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008.vmdk" (flags 0x18). 8A72AC4
May 12 14:21:47.654: vmx| DISKLIB-LIB : Combine 2 links at offset 0 in chain 8A72AC4.
May 12 14:21:47.659: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.659: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : closed.
May 12 14:21:47.671: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : open successful (17) size = 9673977856, hd = 0. Type 8
May 12 14:21:47.671: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-000008-delta.vmdk" : closed.
May 12 14:21:47.679: vmx| VMXVmdb_LoadRawConfig: Loading raw config
May 12 14:21:47.682: vmx| Checkpoint_Unstun: vm stopped for 296920 us
May 12 14:21:47.682: vcpu-0| Sync monModules(5).
May 12 14:21:47.683: vcpu-0| Done Sync monModules(5).
May 12 14:21:47.708: vmx| DISK: OPEN scsi0:1 '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' persistent R[(null)]
May 12 14:21:47.709: vmx| DISKLIB-VMFS : "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos-flat.vmdk" : open successful (10) size = 9673977856, hd = 631005. Type 3
May 12 14:21:47.709: vmx| DISKLIB-DSCPTR: Opened : "BU_NDVSndnpos-flat.vmdk" (0xa)
May 12 14:21:47.709: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' (0xa): vmfs, 18894488 sectors / 9226 Mb.
May 12 14:21:47.709: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk" (flags 0xa). 8A62AEC
May 12 14:21:47.759: vmx| DISK: OPEN '/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk' Geo (1176/255/63) BIOS Geo (0/0/0) freeSpace=389154Mb, buslogic
May 12 14:21:47.759: vmx| Creating virtual dev for 0:1
May 12 14:21:47.759: vmx| DumpDiskInfo: scsi0:1 createType=11, capacity = 18894488, numLinks = 1, allocationType = 0
May 12 14:21:47.759: vmx| SCSIDiskESXPopulateVDevDesc: Using FS backend
May 12 14:21:47.767: vmx| ConsolidateOnlineCB: nextState = 5
May 12 14:21:47.767: vmx| ConsolidateThread: Destroying thread 6
May 12 14:21:47.767: vmx| ConsolidateOnlineCB: Done with consolidate
May 12 14:22:13.079: vcpu-0| HBACommon: First write on scsi0:1.fileName='/vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos/BU_NDVSndnpos.vmdk'
May 12 14:22:13.081: vcpu-0| DISKLIB-CHAIN : UpdateContentID: old = 0xae73db69, new = 0xa82ba4d3
May 13 17:39:28.409: mks| SOCKET 3 recv error 5: Input/output error
May 13 17:39:28.409: mks| SOCKET 3 destroying VNC backend on socket error: 5
is there a way to recover the "old" disk at this point or did i epic fail when deleting the snapshots from the manager?
Message was edited by: ITJS
you may want to refer to the article here, should able to help a little bit for you to understand about this.
Craig
vExpert 2009
Malaysia VMware Communities - http://www.malaysiavm.com
Looks like you made all possible mistakes in handling such a case.
if you ever see something like that again - first rule is DO NOT PANIC
Anyway - lets see what is left.
Please post all small vmdks - smaller than a few kbs.
To see thise files you must NOT use the datastore-browser - that one does not list the files correctly.
Instead have a look with winscp or Fastscp or whatever your prefered SSH-client is.
___________________________________
description of vmx-parameters:
- Looks like you made all possible mistakes in handling such a case
Do i get extra special points for that? (haha seriously i appreciate a little honesty once in a while and lacing comments with sarcasm so whichever way you went, you got me chuckling good)
From: /vmfs/volumes/4889c3cb-0ff2f5df-21c4-001ec9e803b3/BU_NDVSndnpos and existing the exact same as from: /vmfs/volumes/NDVMWare03:storage1/BU_NDVSndnpos
drwxr-xr-x 1 root root 3920 May 12 14:21 .
drwxr-xr-t 1 root root 2100 May 11 18:28 ..
-rw------- 1 root root 956322304 May 11 18:18 BU_NDVSndnpos-000001-delta.vmdk
-rw------- 1 root root 261 May 11 18:17 BU_NDVSndnpos-000001.vmdk
-rw------- 1 root root 838881792 May 11 18:17 BU_NDVSndnpos-000002-delta.vmdk
-rw------- 1 root root 268 May 11 18:12 BU_NDVSndnpos-000002.vmdk
-rw------- 1 root root 20992 May 12 08:01 BU_NDVSndnpos-000003-delta.vmdk
-rw------- 1 root root 235 May 12 08:01 BU_NDVSndnpos-000003.vmdk
-rw------- 1 root root 20992 May 12 08:02 BU_NDVSndnpos-000004-delta.vmdk
-rw------- 1 root root 242 May 12 08:02 BU_NDVSndnpos-000004.vmdk
-rw------- 1 root root 20992 May 12 08:05 BU_NDVSndnpos-000005-delta.vmdk
-rw------- 1 root root 242 May 12 08:05 BU_NDVSndnpos-000005.vmdk
-rw------- 1 root root 20992 May 12 08:06 BU_NDVSndnpos-000006-delta.vmdk
-rw------- 1 root root 235 May 12 08:06 BU_NDVSndnpos-000006.vmdk
-rw------- 1 root root 1073741824 May 12 13:44 BU_NDVSndnpos-b94fdefe.vswp
-rw------- 1 root root 9673977856 May 14 11:52 BU_NDVSndnpos-flat.vmdk
-rw------- 1 root root 8684 May 12 13:45 BU_NDVSndnpos.nvram
-rw------- 1 root root 406 May 12 14:22 BU_NDVSndnpos.vmdk
-rw------- 1 root root 1209 May 12 14:21 BU_NDVSndnpos.vmsd
-rwxr-xr-x 1 root root 2000 May 12 14:21 BU_NDVSndnpos.vmx
-rw------- 1 root root 268 May 12 13:54 BU_NDVSndnpos.vmxf
-rw-rr 1 root root 37708 May 12 08:00 vmware-15.log
-rw-rr 1 root root 24342 May 12 08:02 vmware-16.log
-rw-rr 1 root root 24415 May 12 08:02 vmware-17.log
-rw-rr 1 root root 24342 May 12 08:05 vmware-18.log
-rw-rr 1 root root 26022 May 12 08:06 vmware-19.log
-rw-rr 1 root root 38549 May 12 08:16 vmware-20.log
-rw-rr 1 root root 56418 May 13 17:39 vmware.log
This listing was pulled using PuTTy, yet it shows all the same files as the datastore explorer does (with the addition of *-delta.vmdk files which are ginormous). If that sures up for you that this list is all encompassing, let me know and i'll rip out #s 3, 4, 5, and 6 which are all the same size 64kb.
Hi - no special points this time - just don't react like this again.
If you ever get the message about parent has been changed you must do an analysis first. Otherwise you risk loosing data inside the snapshots.
Now zip all the vmdks without delta or flat extension along with the vmx file and attach them.
___________________________________
description of vmx-parameters:
Hi ITJS
I'll be away now for a few hours - I'll explain later what I look for ....
See you later
Ulli
by the way - are you in germany ? - then I can explain via telephone
___________________________________
description of vmx-parameters:
nah i'm actually in the united states (on the east coast)
thanks for the offer though, i'll wait patiently and google during my spare time at work today
i'm here at work until 2pm eastern (3.5 more hours from the time of this post) else if i don't see your resolution by then i'll check later today from home or perhaps on monday
thanks
Hi
the file BU_NDVSndnpos.vmdk is missing - please post it as well
___________________________________
description of vmx-parameters:
I apologize i thought you only wanted the smaller vmdks, that file is what i would call the base disk, it is 9 gigabytes in size, and currently being used by the image itself (in other words it works)
would i be able to help by shutting down the image and opening the vmdk in whatever program you recommend to paste any / all parameters here?
BU_NDVSndnpos-flat.vmdk is the large 9 Gb file - the one I asked for is 1 Kb only.
You say you have this VM in use right now ????
___________________________________
description of vmx-parameters:
DAMNIT, you caught me with my pants down,
i realized after your probing post there that i gave you the specs based off the datastore browser, which doesn't list the -flat.vmdk, as it must link to it though with the filename BU_NDVSndnpos.vmdk.
anyway after checking correctly with putty as i did before, you'll see your requested file attached...sorry about that
The data you send is inconsistent - it is only useful to look at the vmdks if this VM is shutdown.
___________________________________
description of vmx-parameters:
for that to happen it'll have to wait another 4 hours until the end of the business day today (5:30pm eastern time). at that point would you be needing just bu_ndvsndnpos.vmdk or all of the vmdks again?
VMware uses CID and parentCID values in the descriptor of each vmdk and snapshot.
This values will be overwritten each time you start the VM.
The error you once got - that the parent has been modified means that this CID chain is out of sync.
So each start after that error makes the situation worse.
And yes - to analyse the snapshot chain you must look at all the vmdks at the same time. And it MUST BE DONE when the VM is powered off.
So when ever you notice that your VM does NOT seem to use latest state you MUST power it off at once and fix the issue.
Every more minute you let this VM run makes recovering more imporbable.
It also looks like you have manually edited the vmx and selected a different vmdk to use - this is totally prohibited in your case .
NEVER do that again
___________________________________
description of vmx-parameters:
in regards to manually editing the vmx, i've not done that but as the first post suggets, i did tell it to use a different vmdk file in order to get the server functioning for the daily business need (unaware at the time that it would cause a loss of data)
please see the attached zip for the same vmdks, only from this time a powered off vm image
IT JS.
Take note of what Continuum is telling you. You are really getting a master class from one of the greats. What he does not know about VMDKs, VMXs and snapshot recovery could be written on a postage stamp in large letters. Ulli is German by the way so if some time his language may seem a little brusque remember that English is not his first language. :smileygrin:
If you found this or any other answer useful please consider the use of the Helpful or correct buttons to award points
Tom Howarth VCP / vExpert
VMware Communities User Moderator
Blog: www.planetvm.net
Contributing author for the upcoming book "[VMware vSphere and Virtual Infrastructure Security: Securing ESX and the Virtual Environment|http://my.safaribooksonline.com/9780136083214]”. Currently available on roughcuts
Tom - do I really sound unfriendly so often that you mention it ?
> in regards to manually editing the vmx, i've not done that but as the first post suggets, i did tell it to use a different vmdk file
Hi - that makes no difference.
A snapshot chain always looks like this for example BASEDISK > snapshot1 > snapshot2 > snapshot3 > snapshot4 ... and so on.
If your VM use lets say snapshot4 and you manually change it to snapshot2 for example - then snapshot3 and snapshot4 (which contain the data you last created) loose sync with the rest of the chain.
This is harmless in itself as long as you do not write to the edited VM.
So if you power on that VM and then notice that the data is not up to latest state you used to have you should immeditaly power off the VM again.
The more you write into the VM with the old state the more the snapshot3 and 4 become out of sync with the partly chain.
If you just add one or two files this is quite harmless.
But if you for example run a disk defragmentation while using the partly chain only you start to mess things up.
Remember - when you use the VM with the full chain BASEDISK > snapshot1 > snapshot2 > snapshot3 > snapshot4
all files but snapshot4 are used readonly.
So lets say you edited the VM to use snapshot2 instead of snapshot4 you run the high risk that the data inside snapshot3 and snapshot4 can no longer be recovered.
You can force a re-attaching of snapshot3 and snapshot4 to the "messed up snapshot2" but you can not count on getting your data back.
Another problem ...
Vmware for some obscure reasons doesn;t use the snapshots in a straight number sequence.
This means basedisk-000001.vmdk can be the latest snapshot as well as the earliest one.
So you need both the CID-values and the parent-filename hints to guess the most probable oroiginal state.
The parentfilename hints do not get overwritten in such a case as yours.
But every time you start a VM the CID calues get overwrittten.
So guessing the original state becomes more and more tricky the more times you start the VM with an incorrect vmdk referenced in the vmx-file - which is what you have done in this case.
Hope this helps when you next time run into something similar.
I'll now download the files and see what I can do.
Ah before I forget - next time you run into this at least make a backup of all small vmdks , all vmware.logs and the current vmx-file.
This will help a lot in restoring the latest state.
Ulli
___________________________________
description of vmx-parameters:
Not at all my good friend, it is just none native English speakers are more direct in their use of language
If you found this or any other answer useful please consider the use of the Helpful or correct buttons to award points
Tom Howarth VCP / vExpert
VMware Communities User Moderator
Blog: www.planetvm.net
Contributing author for the upcoming book "[VMware vSphere and Virtual Infrastructure Security: Securing ESX and the Virtual Environment|http://my.safaribooksonline.com/9780136083214]”. Currently available on roughcuts
Oh dear - this one is really tricky ....
se yourself - the only partial chain that looks good is this part
(leaving out the irrelevant data)
############################################## basedisk CID=a82ba4d3 parentCID=ffffffff createType="vmfs" # Extent description RW 18894488 VMFS "BU_NDVSndnpos-flat.vmdk" ######################################### snapshot 000007 CID=f33f78d4 parentCID=a82ba4d3 createType="vmfsSparse" parentFileNameHint="BU_NDVSndnpos.vmdk" # Extent description RW 18894488 VMFSSPARSE "BU_NDVSndnpos-000007-delta.vmdk"
In snapshot 000007 both the parentfilename hint and the parentCID value reference the basedisk.
But in your vmx file you have
scsi0:0.fileName = "BU_NDVSndnpos-000002.vmdk"
and if you look at snapshot 000002
######################################### snapshot 000002 CID=8d0d1273 parentCID=9d1ae588 createType="vmfsSparse" parentFileNameHint="BU_NDVSndnpos-000001.vmdk" # Extent description RW 18894488 VMFSSPARSE "BU_NDVSndnpos-000002-delta.vmdk"
you see that it points to snapshot 000001
######################################### snapshot 000001 CID=9d1ae588 parentCID=d5209fe0 createType="vmfsSparse" parentFileNameHint="BU_NDVSndnpos.vmdk" # Extent description RW 18894488 VMFSSPARSE "BU_NDVSndnpos-000001-delta.vmdk"
but here the chain to the basedisk is broken as the parentCID pointer to the basedisk is out of sync.
Oh dear - very tricky
please zip all vmware.logs you may have and attach them - I need more input to restore the full chain.
___________________________________
description of vmx-parameters: