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
24 Replies
continuum
Immortal
Immortal
Jump to solution

Oh dear - oh dear

if I look at the screenshot of datastorebrowser and your filelisting from above it makes no sense at all.

The snapshot 000007 you send with last zip-file does not even exist in your file-listing.

This means that the filelisting from above must be out-dated by now.

If you are still interested in fixing this you must send consistent data.

That means the VM must be powered down.

Then this data is required for an educated guess:

filelisting created with WinSCP - including size and date.

All vmware*.logs - especially the old ones.

The current vmx

All small vmdks.

If possible : a screenshot of snapshotmanager

Ulli

After you send that data you must not power on the VM or all detective work is useless.

___________________________________

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

thanks for the heads up on english as a second language, i've quite the experience in my life with non native english speakers and can appreciate the directness. i'm not at all offended by continum here, but thanks again

as for the vmware image, it's been off now since saturday sometime, please see the attached "newest" zip, thistime containing all log files as well as previous files. I can only venture a guess that the 7th snapshot had come from the scheduled task (on a thurs) that exists. i thought i turned off this task for this machine but either i didn't, or it was turned back on.

thanks again for all your time into this, i've skimmed over your posts above but will read them in great detail tomorrow (monday)

Reply
0 Kudos
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
Reply
0 Kudos
continuum
Immortal
Immortal
Jump to solution

I doubt that this case helps here.

In this case the problem is that the user has paniced in the first place.

This VM has been started too many times with the wrong state.

Looks like everything other than the current chain

basedisk + snapshot 000007

is lost.

It is also very tricky to help with inconsistent data - again the filelisting is missing.

Sorry - I can't do anything here

___________________________________

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

as an update to this thread i was able to recover my data off the vmware image, loosely following the idea suggested by both of you in following the parentCID and CID values

whether wrong or not (the image is marked to be destroyed and recreated now that i have the important data off of it), what i did was the following in attempting to follow the link in the post two above this

1) not having to take a snapshot since i had a single most recent one (snapshot -0007) (otherwise i would have at this point)

2) i wrote down the file name of the vmdk that was being referenced as the hard drive in the settings of the VM Image (in this case BU_NDVSndnpos-000007.vmdk)

3) in my putty ssh session i renamed that file as well as the -delta affiliated file to keep as backups

4) i then copied the .vmdk and affiliated -delta file that i wanted to use to boot the system. in this case i had to try two different times with different files, i ended up using BU_NDVSndnpos-000002-delta.vmdk and its corresponding BU_NDVSndnpos-000002.vmdk, and renamed it to BU_NDVSndnpos-00007-delta.vmdk and BU_NDVSndnpos-00007.vmdk respectively

So now at this point i've replaced the vmdks that will be used

5) I used VI to edit the newly made BU_NDVSndnpos-000007.vmdk to be sure the parentCID corresponded to the CID listed in BU_NDVSndnpos-000001.vmdk. then i did the same to make sure BU_NDVSndnpos-000001.vmdk had a parentCID listed that corresponded with what was listed as the CID in the BU_NDVSndnpos.vmdk

6) at this point i did try to "delete all snapshots" from the snapshot manager to force the disks to commit to the flatfile, and the operation completed succesfully, ridding me of the single snapshot listed in the snapshot manager but it DID NOT take as long as i know it should have based on previous experience and what was referenced in the "manually committing snapshots to flat file" link. ALSO the files were still present in the datastore. they were not deleted. *shrug

7) I powered on the VM.

At this point during powering on, the machine booted but for one reason or another windows failed to boot, mentioning that a file was missing or corrupt. I took this as a positive sign

😎 I performed a repair install and all was well. Alternatively i could have installed a 2nd copy of Windows to this VM Image, either way if anyone else is needing to try this, don't forget to NOT overwrite any Windows directories if doing a new install, and also don't format your drive obviously

9) all is well with the data i recovered as it netted me some files that i will have to manually splice into other, more current up to date files

Although continuum did mention the CID values and put in the most help i want to reward malaysiavm for his help too so i'll be giving him the 10 points, but will mark 2 of continuum's post as helpful so he gets the overall 12 pts

Reply
0 Kudos