VMware Cloud Community
ITJS
Contributor
Contributor
Jump to solution

Recovering a VMware image disk

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

Reply
0 Kudos
1 Solution

Accepted Solutions
malaysiavm
Expert
Expert
Jump to solution

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

Craig vExpert 2009 & 2010 Netapp NCIE, NCDA 8.0.1 Malaysia VMware Communities - http://www.malaysiavm.com

View solution in original post

Reply
0 Kudos
24 Replies
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

- 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.

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

i'm a hands on learner, and sometimes because of that i break shit...helps me learn, so i live with it Smiley Happy

see attached, also if you wouldn't mind telling me what you'll be analyzing in the files so i can learn for next time, it'd be appreciated

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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 Smiley Wink

___________________________________

description of vmx-parameters:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

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

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

Hi

the file BU_NDVSndnpos.vmdk is missing - please post it as well

___________________________________

description of vmx-parameters:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

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?

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

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 Smiley Happy

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

Is this VM in use right now ?

___________________________________

description of vmx-parameters:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

The data you send is inconsistent - it is only useful to look at the vmdks if this VM is shutdown.

___________________________________

description of vmx-parameters:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

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?

Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

Reply
0 Kudos
ITJS
Contributor
Contributor
Jump to solution

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

Reply
0 Kudos
TomHowarth
Leadership
Leadership
Jump to solution

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 Howarth VCP / VCAP / vExpert
VMware Communities User Moderator
Blog: http://www.planetvm.net
Contributing author on VMware vSphere and Virtual Infrastructure Security: Securing ESX and the Virtual Environment
Contributing author on VCP VMware Certified Professional on VSphere 4 Study Guide: Exam VCP-410
Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...

TomHowarth
Leadership
Leadership
Jump to solution

Not at all my good friend, it is just none native English speakers are more direct in their use of language Smiley Wink

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 Howarth VCP / VCAP / vExpert
VMware Communities User Moderator
Blog: http://www.planetvm.net
Contributing author on VMware vSphere and Virtual Infrastructure Security: Securing ESX and the Virtual Environment
Contributing author on VCP VMware Certified Professional on VSphere 4 Study Guide: Exam VCP-410
Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

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:

VMware-liveCD:


________________________________________________
Do you need support with a VMFS recovery problem ? - send a message via skype "sanbarrow"
I do not support Workstation 16 at this time ...