1
23.1 Legacy Series / os-vmware problems with ESXi 8
« on: April 04, 2023, 10:21:39 am »
Hello
I use opnsense on a protectli clone as a VM under ESXi, which so far seems to work fine. However, when I install the plugin os-vmware, I get frequent hard resets of the machine, typically 1-2 per day. The vmware.log looks like this:
or this:
The main interesting line seems to be this:
With os-vmware deinstalled the box is running without any issues for several days now. Anyone else with similar experience?
I use opnsense on a protectli clone as a VM under ESXi, which so far seems to work fine. However, when I install the plugin os-vmware, I get frequent hard resets of the machine, typically 1-2 per day. The vmware.log looks like this:
Code: [Select]
2023-03-31T18:02:54.996Z In(05) vmx - MKSVMX: Vigor requested a screenshot
2023-03-31T18:02:54.997Z In(05) svga - MKSScreenShotMgr: Taking a screenshot
2023-03-31T20:53:32.316Z In(05) vcpu-0 - TOOLS Received tools.set.version rpc call, version = TOOLS_VERSION_NONE (uninstalled), type is unset
2023-03-31T20:53:32.316Z In(05) vcpu-0 - TOOLS Setting toolsVersionStatus = TOOLS_STATUS_NO_TOOLS
2023-03-31T20:53:32.316Z In(05) vcpu-0 - TOOLS setting legacy tools version to '0' type -1, manifest status is 7
2023-03-31T20:53:32.316Z In(05) vcpu-0 - Tools_SetVersionAndType: Updating the Tools Version in the disk
2023-03-31T20:53:32.331Z In(05) vcpu-0 - Tools_SetVersionAndType: Done updating the Tools Version in the disk
2023-03-31T20:53:32.331Z In(05) vcpu-0 - TOOLS using cached value 0 for isoImageExists (refreshCount=1, lastCount=1).
2023-03-31T20:53:32.331Z In(05) vcpu-0 - VMXVmdb_SetToolsVersionStatus: status value set to 'notAvailable', 'noTools', install impossible
2023-03-31T20:53:32.331Z No(00) vcpu-0 - ConfigDB: Setting toolsInstallManager.updateCounter = "3"
2023-03-31T20:53:32.333Z No(00) vcpu-0 - ConfigDB: Setting extendedConfigFile = "opnsense.vmxf"
2023-03-31T21:55:11.804Z In(05) vcpu-0 - CPU reset: soft (mode Emulation)
2023-03-31T21:55:11.804Z In(05) vcpu-1 - CPU reset: soft (mode HV)
2023-03-31T21:55:11.804Z In(05) vcpu-2 - CPU reset: soft (mode HV)
2023-03-31T21:55:11.804Z In(05) vcpu-3 - CPU reset: soft (mode HV)
2023-03-31T21:55:11.805Z In(05) vcpu-0 - SVGA: Unregistering IOSpace at 0x1070
2023-03-31T21:55:11.805Z In(05) vcpu-0 - SVGA: Unregistering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2023-03-31T21:55:11.805Z In(05) vcpu-0 - SVGA: FIFO is already mapped
2023-03-31T21:55:11.818Z In(05) vcpu-0 - PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.818Z In(05) vcpu-0 - pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.818Z In(05) vcpu-0 - pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.818Z In(05) vcpu-0 - pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.819Z In(05) vcpu-0 - pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.820Z In(05) vcpu-0 - pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.821Z In(05) vcpu-0 - pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.821Z In(05) vcpu-0 - pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.821Z In(05) vcpu-0 - pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.821Z In(05) vcpu-0 - pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.821Z In(05) vcpu-0 - pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T21:55:11.832Z In(05) vcpu-0 - Chipset: The guest has requested that the virtual machine be hard reset.
2023-03-31T21:55:11.836Z In(05) vcpu-0 - Destroying virtual dev for scsi0:0 vscsi=1136439756595205
2023-03-31T21:55:11.836Z In(05) vcpu-0 - VMMon_VSCSIStopVports: No such target on adapter
2023-03-31T21:55:11.838Z In(05) vcpu-0 - DEVICE: Resetting device 'ALL'.
2023-03-31T21:55:11.838Z In(05) vcpu-0 - USB: Per-Device Resetting device 0x1000000105572008
2023-03-31T21:55:12.297Z Wa(03) vcpu-0 - USBGL: DevID(1000000105572008): Failed to reset device. Error(0x16): Invalid argument.
2023-03-31T21:55:12.297Z Wa(03) vcpu-0 - USBGL: DevID(1000000105572008): Failed to reset port.
2023-03-31T21:55:12.297Z In(05) vcpu-0 - USB: Per-Device Resetting device 0x200000050e0f0003
or this:
Code: [Select]
2023-03-30T07:37:39.993Z In(05) svga - MKSScreenShotMgr: Taking a screenshot
2023-03-30T07:38:40.044Z In(05) vmx - MKSVMX: Vigor requested a screenshot
2023-03-30T07:38:40.045Z In(05) svga - MKSScreenShotMgr: Taking a screenshot
2023-03-31T12:57:57.019Z In(05) vmx - GuestRpcSendTimedOut: message to toolbox timed out.
2023-03-31T12:57:57.019Z In(05) vmx - Tools: [AppStatus] Last heartbeat value 459578 (last received 8s ago)
2023-03-31T12:57:57.019Z In(05) vmx - TOOLS: appName=toolbox, oldStatus=1, status=2, guestInitiated=0.
2023-03-31T12:58:01.162Z In(05) vcpu-0 - CPU reset: soft (mode Emulation)
2023-03-31T12:58:01.162Z In(05) vcpu-2 - CPU reset: soft (mode HV)
2023-03-31T12:58:01.162Z In(05) vcpu-3 - CPU reset: soft (mode HV)
2023-03-31T12:58:01.162Z In(05) vcpu-1 - CPU reset: soft (mode HV)
2023-03-31T12:58:01.163Z In(05) vcpu-0 - SVGA: Unregistering IOSpace at 0x1070
2023-03-31T12:58:01.163Z In(05) vcpu-0 - SVGA: Unregistering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2023-03-31T12:58:01.163Z In(05) vcpu-0 - SVGA: FIFO is already mapped
2023-03-31T12:58:01.174Z In(05) vcpu-0 - PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.174Z In(05) vcpu-0 - pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.174Z In(05) vcpu-0 - pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.175Z In(05) vcpu-0 - pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.176Z In(05) vcpu-0 - pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.177Z In(05) vcpu-0 - pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
2023-03-31T12:58:01.189Z In(05) vcpu-0 - Chipset: The guest has requested that the virtual machine be hard reset.
2023-03-31T12:58:01.190Z In(05) vcpu-0 - Destroying virtual dev for scsi0:0 vscsi=1136439756595204
2023-03-31T12:58:01.190Z In(05) vcpu-0 - VMMon_VSCSIStopVports: No such target on adapter
2023-03-31T12:58:01.194Z In(05) vcpu-0 - DEVICE: Resetting device 'ALL'.
2023-03-31T12:58:01.194Z In(05) vcpu-0 - USB: Per-Device Resetting device 0x1000000105572008
2023-03-31T12:58:01.647Z Wa(03) vcpu-0 - USBGL: DevID(1000000105572008): Failed to reset device. Error(0x16): Invalid argument.
2023-03-31T12:58:01.647Z Wa(03) vcpu-0 - USBGL: DevID(1000000105572008): Failed to reset port.
2023-03-31T12:58:01.647Z In(05) vcpu-0 - USB: Per-Device Resetting device 0x200000050e0f0003
2023-03-31T12:58:01.647Z In(05) vcpu-0 - Tools: ToolsRunningStatus_Reset, delayedRequest is 0x0
2023-03-31T12:58:01.647Z In(05) vcpu-0 - Tools: Changing running status: 1 => 0.
2023-03-31T12:58:01.647Z In(05) vcpu-0 - Tools: [RunningStatus] Last heartbeat value 459578 (last received 13s ago)
The main interesting line seems to be this:
Code: [Select]
vcpu-0 - Chipset: The guest has requested that the virtual machine be hard reset.
With os-vmware deinstalled the box is running without any issues for several days now. Anyone else with similar experience?