2021-10-06, 20:45:44
Hi,
No worries, use what works for you.
re. the problem that you highlighted with VMware Player.
It was not very easy to reproduce, but in the end I managed to get it to misbehave.
First I installed VMware Workstation Player 16.1.2 on a Windows 2016 Server host.
Followed that up by installing the latest Vimalin.
Then grabbed a Windows 3.1 VM from a backup repository, ran it in Player and made about 5 or 6 backups with Vimalin with the VM running.
No problem so far.
So figured to step up and grabbed a Windows 7 VM from a backup, restored that one, opened Player on it and started playing Solitaire in it.
Then started the first backup.
No problem.
So started the second backup, had dinner..
Came back.
Backup done, but VM has suspended now.
Resumed it..
Started another on demand backup.
Used another VM for a bit.
Backup done.
Continued playing Solitaire.
Started backup number 4.
Completed OK
Suspend VM
Resume VM
Start a new game of Solitaire.
Start Backup number 5.
Play game.
Backup number 5 is done & OK.
Starting backup number 6
Continuing to play Solitaire, I'm getting better in it (hadn't played this for many years)
backup number 6 completes OK
Save game
Shut down VM
Start VM
start game again and continue saved game.
Start backup number 7
Backup failed "unable to take the backup snapshot"
Tried starting backup number 8
Backup failed "unable to take the backup snapshot"
Observations:
- vix log(s), nothing out of the ordinary in there. It's a noisy log file with lots of warnings, but that's normal.
- VMware log ... still investigating
- task manager ----> There's a whopping 23 vmware player processes running. Say what? ...
So I kill all the vmplayer instances.
The vmware-vmx process is still there
Started a new vmware player instance and it connects to the existing vm running when trying to start it.
So far so good.
Tried starting backup number 9
failed.
So checking the vmware.log.
The full set of events from the first failed backup is this:
2021-10-06T22:06:27.859+02:00| vmx| A000: ConfigDB: Setting guestOS.detailed.data = ""
2021-10-06T22:06:36.549+02:00| vmx| I005: SOCKET 12 (1488) recv error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:36.549+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (1) on connection 1.
2021-10-06T22:06:38.857+02:00| vmx| W003: FILE: FileLockMemberValues file size failure on 'C:\VMs\Windows 7\Windows 7.vmsd.lck\M64918.lck': The data is invalid
2021-10-06T22:06:38.858+02:00| vmx| I005: SNAPSHOT: SnapshotConfigInfoLockVmsd: Failed to lock 'C:\VMs\Windows 7\Windows 7.vmsd': 'Insufficient permission to access the file'.
2021-10-06T22:06:38.858+02:00| vmx| I005: SNAPSHOT: SnapshotConfigInfoReadEx failed for file 'Windows 7.vmx': Insufficient permission to access the file (3)
2021-10-06T22:06:38.858+02:00| vmx| I005: SnapshotVMX_GetDictionaryBuffers: GetDictionaryBuffers failed. Insufficient permission to access the file (3).
2021-10-06T22:06:38.858+02:00| vmx| W003: VMAutomation: Vix unable to get snapshot tree.
2021-10-06T22:06:38.860+02:00| vmx| I005: SOCKET 13 (1596) send error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:38.860+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (4) on connection 2.
2021-10-06T22:06:38.860+02:00| vmx| I005: SOCKET 13 (1596) send error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:38.878+02:00| vmx| I005: Vix: [vmxCommands.c:2790]: VMAutomation_CreateSnapshot: clientFlags = 0, displayName = Vimalin temporary snapshot.
2021-10-06T22:06:38.878+02:00| vmx| I005: SnapshotVMX_TakeSnapshot start: 'Vimalin temporary snapshot', deviceState=1, lazy=1, quiesced=0, forceNative=0, tryNative=0, saveAllocMaps=0
2021-10-06T22:06:38.881+02:00| vmx| I005: DISKLIB-LIB_CREATE : Failed to get info: no handle.
2021-10-06T22:06:38.881+02:00| vmx| I005: SNAPSHOT: SnapshotPrepareTakeDoneCB: Prepare phase complete (The operation completed successfully).
2021-10-06T22:06:38.883+02:00| vmx| I005: Vix: [mainDispatch.c:4656]: VMAutomationProcessMessage: Postpone the command. VM not running (opcode 62)
2021-10-06T22:06:38.884+02:00| svga| I005: MKSScreenShotMgr: Taking a screenshot
2021-10-06T22:06:38.951+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotWork: Transition to mode 0.
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: FILE: FileLockWaitForPossession timeout on 'C:\VMs\Windows 7\Windows 7.vmsd.lck\M04078.lck' due to a local process '1104-132780243221493399(vmware-vmx.exe)'
2021-10-06T22:06:42.504+02:00| vcpu-0| W003: SnapshotMapErrno: Unexpected error code: 11
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: SNAPSHOT: SnapshotConfigInfoLockVmsd: Failed to lock 'C:\VMs\Windows 7\Windows 7.vmsd': 'Insufficient permissions'.
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: SNAPSHOT: SnapshotConfigInfoReadEx failed for file 'Windows 7.vmx': Insufficient permissions (10)
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotWork: Transition to mode 1.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotComplete: Done with snapshot 'Vimalin temporary snapshot': 0
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: DISKLIB-LIB_CREATE : Failed to get info: no handle.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotComplete: Snapshot 0 failed: Insufficient permissions (10).
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: Msg_Reset:
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: [msg.checkpoint.save.fail2.std3] An error occurred while saving the snapshot: Insufficient permissions.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: ----------------------------------------
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: Vix: [vmxCommands.c:2904]: VMAutomationCreateSnapshotCallback: Got CreateSnapshot callback, snapshotErr = Insufficient permissions (10:0), UID = 0
2021-10-06T22:06:42.533+02:00| vmx| I005: SOCKET 14 (1460) recv error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:42.533+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (1) on connection 3.
Trying to suspend the running VM from within the attached player gives a unable to suspend error.
Shut down works.
No vmware player or vmx process is left running.
Restarting vmware player
Starting Solitaire
Starting Vimalin Backup
... works OK again it seems.
So yes, something is up there.
All those extra vmware player instances are weird.
and it seems that one of these overwrites the lock file(s).
Will investigate some more and see if there is something I can do and if not see if I can get this reported to vmware.
--
Wil
No worries, use what works for you.
re. the problem that you highlighted with VMware Player.
It was not very easy to reproduce, but in the end I managed to get it to misbehave.
First I installed VMware Workstation Player 16.1.2 on a Windows 2016 Server host.
Followed that up by installing the latest Vimalin.
Then grabbed a Windows 3.1 VM from a backup repository, ran it in Player and made about 5 or 6 backups with Vimalin with the VM running.
No problem so far.
So figured to step up and grabbed a Windows 7 VM from a backup, restored that one, opened Player on it and started playing Solitaire in it.
Then started the first backup.
No problem.
So started the second backup, had dinner..
Came back.
Backup done, but VM has suspended now.
Resumed it..
Started another on demand backup.
Used another VM for a bit.
Backup done.
Continued playing Solitaire.
Started backup number 4.
Completed OK
Suspend VM
Resume VM
Start a new game of Solitaire.
Start Backup number 5.
Play game.
Backup number 5 is done & OK.
Starting backup number 6
Continuing to play Solitaire, I'm getting better in it (hadn't played this for many years)
backup number 6 completes OK
Save game
Shut down VM
Start VM
start game again and continue saved game.
Start backup number 7
Backup failed "unable to take the backup snapshot"
Tried starting backup number 8
Backup failed "unable to take the backup snapshot"
Observations:
- vix log(s), nothing out of the ordinary in there. It's a noisy log file with lots of warnings, but that's normal.
- VMware log ... still investigating
- task manager ----> There's a whopping 23 vmware player processes running. Say what? ...
So I kill all the vmplayer instances.
The vmware-vmx process is still there
Started a new vmware player instance and it connects to the existing vm running when trying to start it.
So far so good.
Tried starting backup number 9
failed.
So checking the vmware.log.
The full set of events from the first failed backup is this:
2021-10-06T22:06:27.859+02:00| vmx| A000: ConfigDB: Setting guestOS.detailed.data = ""
2021-10-06T22:06:36.549+02:00| vmx| I005: SOCKET 12 (1488) recv error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:36.549+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (1) on connection 1.
2021-10-06T22:06:38.857+02:00| vmx| W003: FILE: FileLockMemberValues file size failure on 'C:\VMs\Windows 7\Windows 7.vmsd.lck\M64918.lck': The data is invalid
2021-10-06T22:06:38.858+02:00| vmx| I005: SNAPSHOT: SnapshotConfigInfoLockVmsd: Failed to lock 'C:\VMs\Windows 7\Windows 7.vmsd': 'Insufficient permission to access the file'.
2021-10-06T22:06:38.858+02:00| vmx| I005: SNAPSHOT: SnapshotConfigInfoReadEx failed for file 'Windows 7.vmx': Insufficient permission to access the file (3)
2021-10-06T22:06:38.858+02:00| vmx| I005: SnapshotVMX_GetDictionaryBuffers: GetDictionaryBuffers failed. Insufficient permission to access the file (3).
2021-10-06T22:06:38.858+02:00| vmx| W003: VMAutomation: Vix unable to get snapshot tree.
2021-10-06T22:06:38.860+02:00| vmx| I005: SOCKET 13 (1596) send error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:38.860+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (4) on connection 2.
2021-10-06T22:06:38.860+02:00| vmx| I005: SOCKET 13 (1596) send error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:38.878+02:00| vmx| I005: Vix: [vmxCommands.c:2790]: VMAutomation_CreateSnapshot: clientFlags = 0, displayName = Vimalin temporary snapshot.
2021-10-06T22:06:38.878+02:00| vmx| I005: SnapshotVMX_TakeSnapshot start: 'Vimalin temporary snapshot', deviceState=1, lazy=1, quiesced=0, forceNative=0, tryNative=0, saveAllocMaps=0
2021-10-06T22:06:38.881+02:00| vmx| I005: DISKLIB-LIB_CREATE : Failed to get info: no handle.
2021-10-06T22:06:38.881+02:00| vmx| I005: SNAPSHOT: SnapshotPrepareTakeDoneCB: Prepare phase complete (The operation completed successfully).
2021-10-06T22:06:38.883+02:00| vmx| I005: Vix: [mainDispatch.c:4656]: VMAutomationProcessMessage: Postpone the command. VM not running (opcode 62)
2021-10-06T22:06:38.884+02:00| svga| I005: MKSScreenShotMgr: Taking a screenshot
2021-10-06T22:06:38.951+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotWork: Transition to mode 0.
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: FILE: FileLockWaitForPossession timeout on 'C:\VMs\Windows 7\Windows 7.vmsd.lck\M04078.lck' due to a local process '1104-132780243221493399(vmware-vmx.exe)'
2021-10-06T22:06:42.504+02:00| vcpu-0| W003: SnapshotMapErrno: Unexpected error code: 11
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: SNAPSHOT: SnapshotConfigInfoLockVmsd: Failed to lock 'C:\VMs\Windows 7\Windows 7.vmsd': 'Insufficient permissions'.
2021-10-06T22:06:42.504+02:00| vcpu-0| I005: SNAPSHOT: SnapshotConfigInfoReadEx failed for file 'Windows 7.vmx': Insufficient permissions (10)
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotWork: Transition to mode 1.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotComplete: Done with snapshot 'Vimalin temporary snapshot': 0
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: DISKLIB-LIB_CREATE : Failed to get info: no handle.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: SnapshotVMXTakeSnapshotComplete: Snapshot 0 failed: Insufficient permissions (10).
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: Msg_Reset:
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: [msg.checkpoint.save.fail2.std3] An error occurred while saving the snapshot: Insufficient permissions.
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: ----------------------------------------
2021-10-06T22:06:42.505+02:00| vcpu-0| I005: Vix: [vmxCommands.c:2904]: VMAutomationCreateSnapshotCallback: Got CreateSnapshot callback, snapshotErr = Insufficient permissions (10:0), UID = 0
2021-10-06T22:06:42.533+02:00| vmx| I005: SOCKET 14 (1460) recv error 10054: An existing connection was forcibly closed by the remote host
2021-10-06T22:06:42.533+02:00| vmx| I005: Vix: [mainDispatch.c:2806]: VMAutomation: Connection Error (1) on connection 3.
Trying to suspend the running VM from within the attached player gives a unable to suspend error.
Shut down works.
No vmware player or vmx process is left running.
Restarting vmware player
Starting Solitaire
Starting Vimalin Backup
... works OK again it seems.
So yes, something is up there.
All those extra vmware player instances are weird.
and it seems that one of these overwrites the lock file(s).
Will investigate some more and see if there is something I can do and if not see if I can get this reported to vmware.
--
Wil