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
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:
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)
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
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:
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