Error restoring snapshot problem again!

[DaveP]

I am constantly getting errors on restoring snapshots. The error message is:

"Error restoring snapshot: The file already exists"

This has happened both on Workstation 6.0.0 and now 6.0.1, running on a Windows XP SP2 32 -bit host. Happens with guests of different sorts; e.g. Linux, Windows and others. Also happens irrespective of drive used, and after a re-install of host OS. Anybody got a clue what is going on here? Normally I can see the vmdk file left orphaned, but no that seems to have disappeared!

Dave

[DaveP]

Found the orphaned file, which was a fragment of a snapshot of 2Gb sparse VMDK. Still the question remains, why is this happening?

[Liz]

When you've changed a snapshot, check the log file, Im wondering if its failing to delete something because the AV program is keeping hold of it, failing that, try excluding your vm directory see if that helps, it smells of an AV type issue.

[DaveP]

Hi Liz

I'll double check, but I do have exclusions for vmdk, vmem, vmsn and vmss files setup in AV. I'll see if changing the AV exclusion to the folders helps.

Dave

[admin]

Do you have a custom working directory set? We've had some bugs in the past where we didn't properly clean up disk files in that case. I thought they were all fixed, though.

[DaveP]

Not if you mean the one's specified in the VMX file. The overall directory used to store guests in preferences.ini is set to:

prefvmx.defaultVMPath = "D:\Virtual Machines"[/code]

[Liz]

Id try excluding the whole vm directory, as lock files etc, would then also be excluded.

[DaveP]

Set that up yesterday. Will track it and see how it goes.

[continuum]

> Not if you mean the one's specified in the VMX file. The overall directory used to store guests in preferences.ini is set to:

Dave - Jameslin meant something else. He was refereing to the "redirected workdir bug"

This appears when you redirect the working directory per VM.

In the vmx this may look like

workingDir = "nirvana" or

workingDir = "F:\last exit to\nirvana"

Both variants show strange behaviour in some ? versions.

If you hit revert to snapshot in such a constellation - the latest snaoshot

that you discard is not cleaned up and remains as an orphaned file.

Ulli

[DaveP]

Hi Ulli

Yes I knew he meant the VMX setting. I haven't explicitly set that in any of the guests. It just happened to me again here's the UI log:

Oct 17 13:47:37.843: vmui| Log for VMware Workstation pid=3384 version=6.0.1 build=build-55017 option=Release
Oct 17 13:47:37.843: vmui| Hostname=dparsons
Oct 17 13:47:38.453: vmui| HOSTINFO: Seeing Intel CPU, numCoresPerCPU 2 numThreadsPerCore 1.
Oct 17 13:47:38.453: vmui| HOSTINFO: This machine has 1 physical CPUS, 2 total cores, and 2 logical CPUs.
Oct 17 13:47:41.343: vmui| Foundry Init: setting up global state (0 threads)
Oct 17 13:47:41.343: vmui| Foundry Init: version 3, hostType 1, hostName (null), hostPort 0, options 0x203
Oct 17 13:47:41.625: vmui| ACE extension enabled.
Oct 17 13:47:44.250: vmui| Reloading config state: D:\Virtual Machines\EPM Demo Base Image\epmbase.vmx
Oct 17 13:47:44.500: vmui| Reloading config state: D:\Virtual Machines\ESX3i\esx3i.vmx
Oct 17 13:47:44.609: vmui| Reloading config state: D:\Virtual Machines\JupiterSP3\Jupiter SP3.vmx
Oct 17 13:47:44.671: vmui| CreateFile on pipe \\.\pipe\D:\Virtual Machines\EPM Demo Base Image\epmbase.vmx failed 2.
Oct 17 13:47:44.703: vmui| CreateFile on pipe \\.\pipe\D:\Virtual Machines\ESX3i\esx3i.vmx failed 2.
Oct 17 13:47:44.703: vmui| CreateFile on pipe \\.\pipe\D:\Virtual Machines\JupiterSP3\Jupiter SP3.vmx failed 2.
Oct 17 13:47:44.718: vmui| CVMUIApp::OpenTabInt: opened /vm/#17c21f61bab4e769/
Oct 17 13:47:44.718: vmui| CVMUIApp::OpenTabInt: opened /vm/#4db686a3057c4123/
Oct 17 13:47:44.781: vmui| CVMUIApp::OnCtxCurrentObj: /vm/#4db686a3057c4123/
Oct 17 13:47:45.218: vmui| CVMUIApp::OpenTabInt: opened /vm/#e084a4d4d70c76fc/
Oct 17 13:47:47.109: vmui| CVMUIApp::OnCtxCurrentObj: /vm/#17c21f61bab4e769/
Oct 17 13:47:47.953: vmui| CVMUICmdbar::OnButtonClicked: 16
Oct 17 13:47:47.953: vmui| CVMUIApp::MenuButtonClicked: 40060 /vm/#17c21f61bab4e769/
Oct 17 13:47:48.125: vmui| SnapshotLayout::DumpList
Oct 17 13:47:48.125: vmui| Node[0][2]: 2
Oct 17 13:47:48.125: vmui| Node[0][4]: 0
Oct 17 13:47:48.125: vmui| SnapshotLayout::DumpMatrix
Oct 17 13:47:48.125: vmui|          01234
Oct 17 13:47:48.125: vmui| Row  0: [#-0-*]
Oct 17 13:47:50.859: vmui| CVMUICmdbar::OnButtonClicked: 10
Oct 17 13:47:50.859: vmui| CVMUIApp::MenuButtonClicked: 40051 /vm/#17c21f61bab4e769/
Oct 17 13:47:50.859: vmui| VMDlg::ShowDialog: By restoring this snapshot, the current state will be lost.
Oct 17 13:47:50.859: vmui| Do you want to restore "Baseline"?
Oct 17 13:47:51.812: vmui| SnapshotTree: Restoring snapshot 2
Oct 17 13:47:51.843: vmui| SNAPSHOT: Snapshot_Revert: 'D:\Virtual Machines\EPM Demo Base Image\epmbase.vmx' : 2
Oct 17 13:47:52.046: vmui| DUMPER: restoring checkpoint version 7
Oct 17 13:47:52.390: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.390: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)
Oct 17 13:47:52.453: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.453: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)
Oct 17 13:47:52.515: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.515: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)
Oct 17 13:47:52.531: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.531: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)
Oct 17 13:47:52.562: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.562: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)
Oct 17 13:47:52.562: vmui| SNAPSHOT: SnapshotBranchDisk failed: The file already exists (41).
Oct 17 13:47:52.562: vmui| SNAPSHOT: SnapshotBranch failed: The file already exists (5).
Oct 17 13:47:52.562: vmui| SNAPSHOT: SnapshotExtract failed 5
Oct 17 13:47:52.562: vmui| SNAPSHOT: RevertToSnapshot failed 5
Oct 17 13:47:52.578: vmui| Cmd /vm/#17c21f61bab4e769/cmd/##ab/op/revertToSnapshot/ failed: Error restoring snapshot: The file already exists.
Oct 17 13:47:52.593: vmui| SnapshotTree: Restore failed: Error restoring snapshot: The file already exists.
Oct 17 13:47:52.593: vmui| Error restoring snapshot: The file already exists.

The VMX file is:

 #!/usr/bin/vmware
checkpoint.vmState = ""
config.version = "8"
displayName = "EPM Demo Base Image"
ehci.pciSlotNumber = "33"
ehci.present = "TRUE"
ethernet0.addressType = "generated"
ethernet0.connectionType = "hostonly"
ethernet0.generatedAddress = "00:0c:29:88:ca:ab"
ethernet0.generatedAddressOffset = "0"
ethernet0.pciSlotNumber = "32"
ethernet0.present = "TRUE"
extendedConfigFile = "epmbase.vmxf"
floppy0.present = "FALSE"
guestOS = "winxppro"
ide0:0.fileName = "epmbase-000002.vmdk"
ide0:0.present = "TRUE"
ide0:0.redo = ""
ide0:1.fileName = "data-000002.vmdk"
ide0:1.present = "TRUE"
ide0:1.redo = ""
ide1:0.autodetect = "TRUE"
ide1:0.deviceType = "cdrom-raw"
ide1:0.fileName = "auto detect"
ide1:0.present = "TRUE"
ide1:0.startConnected = "FALSE"
inVMTeam = "FALSE"
isolation.tools.hgfs.disable = "TRUE"
MemAllowAutoScaleDown = "FALSE"
memsize = "768"
nvram = "epmbase.nvram"
pciBridge0.pciSlotNumber = "17"
pciBridge0.present = "TRUE"
scsi0.pciSlotNumber = "16"
scsi0.present = "TRUE"
sharedFolder.maxNum = "2"
sharedFolder.option = "alwaysEnabled"
sharedFolder0.enabled = "TRUE"
sharedFolder0.expiration = "never"
sharedFolder0.guestName = "Windows"
sharedFolder0.hostPath = "D:\"
sharedFolder0.present = "TRUE"
sharedFolder0.readAccess = "TRUE"
sharedFolder0.writeAccess = "TRUE"
sharedFolder1.enabled = "TRUE"
sharedFolder1.expiration = "never"
sharedFolder1.guestName = "Linux"
sharedFolder1.hostPath = "/data"
sharedFolder1.present = "TRUE"
sharedFolder1.readAccess = "TRUE"
sharedFolder1.writeAccess = "TRUE"
snapshot.action = "keep"
sound.autodetect = "TRUE"
sound.fileName = "-1"
sound.present = "FALSE"
sound.virtualDev = "es1371"
svga.autodetect = "TRUE"
tools.syncTime = "TRUE"
tools.upgrade.policy = "manual"
usb.generic.autoconnect = "FALSE"
usb.present = "TRUE"
uuid.action = "keep"
uuid.bios = "56 4d 94 1e cc 72 54 dd-3f be 33 35 28 88 ca ab"
uuid.location = "56 4d 19 04 a8 c3 03 b9-5a f5 22 62 b9 ab 4a c3"
virtualHW.productCompatibility = "hosted"
virtualHW.version = "6"
workingDir = ""

I have excluded folders and files from AV.

Dave

Message was edited by: DaveP because two copies of VMX file in post

[continuum]

13:47:52.390: vmui| DISKLIB-LINK  : File 'D:\Virtual Machines\EPM Demo Base Image\epmbase-000001-s011.vmdk' already exists.
Oct 17 13:47:52.390: vmui| DISKLIB-LIB   : Failed to create link: The file already exists (41)

Dave - thats strange - do you get the message for  one chunk only *-s011.vmdk ?
Or did you cut that log.
Never noticed this for a single chunk - if this the "revert with redirected workdir bug" it should complain for all chunks ???

Oh dear - this "rich text" editor is not my friend 😞 - don't know how to get out of this quote box without having to retype everything

[DaveP]

It's always been one chunk left behind. There isn't a pattern to which chunk. This one is 11 of 17.