rroxxor
Contributor
Contributor

vm with pci-passthrough usb controller does not start

hello,
ESXi-7.0U2a-17867351-standard

two vms, each has a dedicated GPU and a dedicated USB card (Renesas uPD720201 USB3.0) assigned via pci passthrough configuration
e.g.

rroxxor_0-1621685733299.png


when i connect nothing to the renesas card, the vm boots fine
when i connect mouse and keyboard directly to the renesas card, the vm boots fine
when i connect a usb hub (ICY BOX IB-HUB1409-U3) with nothing connected to it to the renesas card, the vm boots fine

as soon as i connect mouse and keyboard to the usb hub, which is connected to the renesas card, the vm does not boot anymore. it gets stuck on a black screen, most likely right before accessing the boot loader (that's all i can guess from vmware.log)

rroxxor_0-1621686658288.png

 

i made fresh logfiles for both cases (working, and not working), they are attached in full version.

here is where it gets stuck:

 

2021-05-22T12:01:47.379Z| vcpu-0| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:01:47.379Z| vcpu-0| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:01:47.403Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xfb800000(0x0)
2021-05-22T12:01:47.403Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:01:47.403Z| vcpu-0| | I005: SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:01:47.403Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:01:47.406Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:01:47.406Z| vcpu-0| | I005: SVGA: Unregistering IOSpace at 0x2040
2021-05-22T12:01:47.406Z| vcpu-0| | I005: AHCI: Tried to enable/disable IO space.
2021-05-22T12:01:47.406Z| vcpu-0| | I005: PCIBridge4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.406Z| vcpu-0| | I005: pciBridge4:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge4:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: PCIBridge5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge5:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge5:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.407Z| vcpu-0| | I005: pciBridge5:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge5:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge5:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge5:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge5:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: PCIBridge6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge6:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: PCIBridge7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.408Z| vcpu-0| | I005: pciBridge7:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.409Z| vcpu-0| | I005: pciBridge7:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:01:47.416Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:01:47.416Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:01:47.416Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:01:47.416Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:01:47.417Z| svga| | I005: SVGA enabling SVGA
2021-05-22T12:01:47.419Z| svga| | I005: SVGA-ScreenMgr: Screen type changed to RegisterMode
2021-05-22T12:01:50.265Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:01:50.266Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:08.294Z| vcpu-0| | I005: Tools: Tools heartbeat timeout.
2021-05-22T12:02:08.295Z| vcpu-0| | I005: Tools: Running status rpc handler: 1 => 0.
2021-05-22T12:02:08.295Z| vcpu-0| | I005: Tools: Changing running status: 1 => 0.
2021-05-22T12:02:08.295Z| vcpu-0| | I005: Tools: [RunningStatus] Last heartbeat value 1 (last received 21s ago)

 

 

and here when it works:

 

2021-05-22T12:02:45.428Z| vcpu-0| | I005: PCIBridge4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge4:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: PCIBridge5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: PCIBridge6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: PCIBridge7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.438Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:45.438Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:45.439Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:45.439Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:45.440Z| svga| | I005: SVGA enabling SVGA
2021-05-22T12:02:45.441Z| svga| | I005: SVGA-ScreenMgr: Screen type changed to RegisterMode
2021-05-22T12:02:45.672Z| vcpu-0| | I005: SECUREBOOT: Image APPROVED.
2021-05-22T12:02:45.702Z| vcpu-0| | I005: Guest: About to do EFI boot: Windows Boot Manager
2021-05-22T12:02:45.711Z| vcpu-0| | I005: HBACommon: First write on scsi0:0.fileName='/vmfs/volumes/5fde2523-5d89094c-2460-107b44948458/AR/rdm1.vmdk'
2021-05-22T12:02:45.711Z| vcpu-0| | I005: DDB: "longContentID" = "4dc461ccd3f7bb6b4013a4037c09d45e" (was "14a6cd39db15eb0e72977f92eebaa9bb")
2021-05-22T12:02:45.715Z| vcpu-0| | I005: DISKLIB-CHAIN : DiskChainUpdateContentID: old=0xeebaa9bb, new=0x7c09d45e (4dc461ccd3f7bb6b4013a4037c09d45e)
2021-05-22T12:02:46.422Z| vcpu-2| | I005: CPU reset: soft (mode Emulation)
2021-05-22T12:02:46.422Z| vcpu-1| | I005: CPU reset: soft (mode Emulation)
2021-05-22T12:02:46.422Z| vcpu-0| | I005: Guest: Firmware has transitioned to runtime.
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Unregistering IOSpace at 0x2040
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:47.529Z| vcpu-0| | I005: Guest: Driver=vsock, Version=9.8.16.0
2021-05-22T12:02:47.530Z| vcpu-0| | I005: Guest: Driver=vmci, Version=9.8.16.0
2021-05-22T12:02:47.545Z| vcpu-2| | I005: LSI: Invalid PageType [21] pageNo 0 Action 0
2021-05-22T12:02:47.599Z| vcpu-2| | I005: AHCI-VMM:HBA reset issued on sata0.
2021-05-22T12:02:48.287Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:48.287Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:48.835Z| vcpu-2| | I005: Guest: vm3d: WDDM Miniport Loading the release build type
2021-05-22T12:02:48.842Z| vcpu-2| | I005: Guest: vm3d: Driver=wddm, Version=8.17.02.0014
2021-05-22T12:02:48.852Z| vcpu-2| | I005: Guest: Driver=vmxnet3, Version=1.8.17.0
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: Unregistering IOSpace at 0x2040
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:48.907Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.908Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.908Z| vcpu-1| | I005: VMXNET3 user: Supported set 0x0000007f, configured set 0x00000003
2021-05-22T12:02:48.908Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.908Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.910Z| vcpu-2| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.910Z| vcpu-2| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:48.912Z| vcpu-0| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:02:48.913Z| vcpu-0| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.913Z| vcpu-0| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.943Z| vcpu-0| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.943Z| vcpu-0| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:48.944Z| vcpu-1| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:02:48.944Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.944Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.976Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.976Z| vcpu-1| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:49.974Z| vcpu-2| | I005: Guest: vm3d: SVGA WDDM Display Only driver, Version: 8.17.02.0014, Build Number: 17592369
2021-05-22T12:02:49.974Z| vcpu-2| | I005: Guest: vm3d: WDDM OS version: 10.0, build number: 19042, service pack version: 0.0, platform Id: 2, product type: 1, suite mask: 0x110
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.978Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA Grow OTable command enabled.
2021-05-22T12:02:49.978Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA IntraSurfaceCopy command enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Guest backed surface is enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Mob Cursor is enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Frame Type is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA DX11.0 (SM5) support is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM 3D is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM DX10 context is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM GL3 is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM DX cap is enabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM HP Queue is enabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: Guest: vm3d: WDDM GDI HW acceleration is disabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: Guest: vm3d: WDDM Guest backed primary in aperture is enabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: SVGA: Guest reported SVGA WDDM driver version: 8.17.02.0014 Build Number: 17592369
2021-05-22T12:02:49.984Z| vcpu-2| | I005: Guest: vm3d: WDDM Preemption Aware is enabled.
2021-05-22T12:02:49.984Z| vcpu-2| | I005: Guest: vm3d: WDDM EDID is disabled.
2021-05-22T12:02:49.987Z| vcpu-2| | I005: Guest: vm3d: Modeset enabled, types available: TR CCD Multi
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM DirectFlip is disabled.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM VSync is disabled.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM primary mem 16384KB.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM VRAM 4096KB.
2021-05-22T12:02:49.989Z| vcpu-2| | I005: Guest: vm3d: WDDM using 12KB memory for OTable.
2021-05-22T12:02:49.989Z| vcpu-2| | I005: Guest: vm3d: WDDM Aperture memory 16384KB.
2021-05-22T12:02:49.990Z| vcpu-2| | I005: Guest: vm3d: 1 monitors active
2021-05-22T12:02:50.032Z| svga| | I005: SVGA-ScreenMgr: Screen type changed to ScreenTarget
2021-05-22T12:02:50.381Z| vcpu-2| | I005: Guest MSR write (0x49: 0x1)
2021-05-22T12:02:53.095Z| vcpu-1| | I005: GuestStore: gstored access is disabled.
2021-05-22T12:02:53.096Z| vcpu-1| | W003: ToolsGetAppInfoEnabledFromConfigStore: No config found, Trying out the default config.
2021-05-22T12:02:53.096Z| vcpu-1| | I005: ToolsGetAppInfoEnabledFromConfigStore: appInfoStatus retrieved as '1'.
2021-05-22T12:02:53.096Z| vcpu-1| | I005: Guest: toolbox: Version: 11.1.1.20749 (build-16303738)
2021-05-22T12:02:53.097Z| vcpu-1| | W003: GuestRpc: application toolbox, changing channel 65535 -> 0
2021-05-22T12:02:53.097Z| vcpu-1| | I005: GuestRpc: Channel 0, guest application toolbox.
2021-05-22T12:02:53.097Z| vcpu-1| | I005: Tools: [AppStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.097Z| vcpu-1| | I005: TOOLS: appName=toolbox, oldStatus=0, status=1, guestInitiated=0.
2021-05-22T12:02:53.109Z| vcpu-1| | I005: TOOLS autoupgrade protocol version 2
2021-05-22T12:02:53.109Z| vcpu-1| | I005: Tools: Changing running status: 1 => 2.
2021-05-22T12:02:53.109Z| vcpu-1| | I005: Tools: [RunningStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.110Z| vcpu-1| | I005: TOOLS Received tools.set.version rpc call, version = 11297, setting type to 1 from guest OS
2021-05-22T12:02:53.110Z| vcpu-1| | I005: Tools_SetVersionAndType did nothing; new tools version (11297) and type (1) match old Tools version and type
2021-05-22T12:02:53.110Z| vcpu-1| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.110Z| vcpu-1| | I005: Starting copy guest manifest.
2021-05-22T12:02:53.116Z| vcpu-0| | I005: Tools: State change '3' progress: last event 0, event 1, success 1.
2021-05-22T12:02:53.117Z| vcpu-0| | I005: HGFileCopyCreateSessionCB: Successfully created the session.
2021-05-22T12:02:53.134Z| vcpu-0| | I005: ToolsLoadManifestFileCB: Queuing a request to update the manifest information.
2021-05-22T12:02:53.134Z| worker-530944| | I005: ToolsISO: Refreshing imageName for 'windows9-64' (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.141Z| worker-530944| | I005: ToolsISO: Updated cached value for imageName to 'windows.iso'.
2021-05-22T12:02:53.141Z| worker-530944| | I005: ToolsISO: Selected Tools ISO 'windows.iso' for 'windows9-64' guest.
2021-05-22T12:02:53.141Z| worker-530944| | I005: GetHostManifests: Extracting /tmp/vmware-root/manifest.txt.530944.iso.shipped manifest file.
2021-05-22T12:02:53.164Z| worker-530944| | I005: GetHostManifests: Done extracting the manifest file.
2021-05-22T12:02:53.165Z| worker-530944| | I005: ToolsVersion: Status is supported old because this is status of monolithic version, and PluginGuestStoreUpgrade and 3 more components are missing from the guest.
2021-05-22T12:02:53.165Z| worker-530944| | I005: Using ToolsMinVersion = 8384
2021-05-22T12:02:53.165Z| worker-530944| | I005: ToolsVersionGetStatusWorkerThread: Tools status 2 derived from environment
2021-05-22T12:02:53.165Z| vmx| | I005: ToolsUpdateManifestInfoWorkerThreadDone: Compared tools manifest from host and from the guest. Status = 2.
2021-05-22T12:02:53.165Z| vmx| | I005: ToolsUpdateManifestInfoWorkerThreadDone: Updating the manifest info.
2021-05-22T12:02:53.167Z| vmx| | A000: ConfigDB: Setting extendedConfigFile = "AR.vmxf"
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.168Z| vmx| | I005: VMXVmdb_SetToolsVersionStatus: status value set to 'oldTools', 'supportedOld', install possible
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS installed legacy version 11297, available legacy version 11333
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS manifest update status is 2
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS can be autoupgraded.
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS VM tools upgrade policy 1
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS need not be autoupgraded according to upgrade policy.
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS Setting autoupgrade-checked TRUE.
2021-05-22T12:02:53.168Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:53.172Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:53.221Z| vcpu-2| | I005: TOOLS state change 3 returned status 1
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: State change '3' progress: last event 1, event 2, success 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: State change '3' progress: last event 1, event 4, success 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Vix: [mainDispatch.c:4122]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=2, err=0).
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: Changing running status: 2 => 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: [RunningStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.636Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:53.636Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:56.117Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:56.118Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:03:01.908Z| vcpu-0| | I005: Guest: vm3d: Memory required for all screens exceeds maximum (required=17510400 max=16777216)
2021-05-22T12:03:07.684Z| vmx| | I005: VigorTransportProcessClientPayload: opID=esxui-1e1c-123e seq=5920: Receiving PowerState.InitiatePowerOff request.
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: sending 'OS_Halt' (state = 1) state change request
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: Changing running status: 1 => 2.
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: [RunningStatus] Last heartbeat value 21 (last received 0s ago)
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Vix: [vmxCommands.c:536]: VMAutomation_InitiatePowerOff. Tried to soft halt. Success = 1
2021-05-22T12:03:07.731Z| vcpu-0| | I005: Tools: State change '1' progress: last event 0, event 1, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: TOOLS state change 1 returned status 1
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: State change '1' progress: last event 1, event 2, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: State change '1' progress: last event 1, event 4, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: VigorTransport_ServerSendResponse opID=esxui-1e1c-123e seq=5920: Completed PowerState request.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: Changing running status: 2 => 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: [RunningStatus] Last heartbeat value 21 (last received 0s ago)
2021-05-22T12:03:09.178Z| vcpu-0| | I005: TOOLS autoupgrade protocol version 0
2021-05-22T12:03:09.179Z| vcpu-0| | I005: ToolsGetAppInfoEnabledFromConfigStore: Returning the cached value: '1'.
2021-05-22T12:03:09.182Z| vcpu-1| | I005: GuestRpc: Reinitializing Channel 0(toolbox)
2021-05-22T12:03:09.182Z| vcpu-1| | I005: Tools: [AppStatus] Last heartbeat value 22 (last received 0s ago)
2021-05-22T12:03:09.182Z| vcpu-1| | I005: TOOLS: appName=toolbox, oldStatus=1, status=0, guestInitiated=1.
2021-05-22T12:03:10.884Z| vcpu-1| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:03:10.884Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:03:10.884Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3

 

 

i'd appreciate any pointers in how to debug this.

are there any additional logfiles?

any settings that can be changed on the host?

 

0 Kudos
2 Replies
hansmaulwurf13
Contributor
Contributor

For me, switching the VM-Firmware from EFI to BIOS fixed this issue.

0 Kudos
scott28tt
VMware Employee
VMware Employee

Add large text dumps with a "spoiler" - the icon of a triangle with an exclamation mark.

Here's what it does:

Spoiler
2021-05-22T12:02:45.428Z| vcpu-0| | I005: PCIBridge4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.429Z| vcpu-0| | I005: pciBridge4:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge4:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: PCIBridge5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.430Z| vcpu-0| | I005: pciBridge5:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: PCIBridge6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.431Z| vcpu-0| | I005: pciBridge6:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: PCIBridge7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:1: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:2: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:3: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:4: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:5: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:6: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.432Z| vcpu-0| | I005: pciBridge7:7: ISA/VGA decoding enabled (ctrl 001C)
2021-05-22T12:02:45.438Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:45.438Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:45.439Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:45.439Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:45.440Z| svga| | I005: SVGA enabling SVGA
2021-05-22T12:02:45.441Z| svga| | I005: SVGA-ScreenMgr: Screen type changed to RegisterMode
2021-05-22T12:02:45.672Z| vcpu-0| | I005: SECUREBOOT: Image APPROVED.
2021-05-22T12:02:45.702Z| vcpu-0| | I005: Guest: About to do EFI boot: Windows Boot Manager
2021-05-22T12:02:45.711Z| vcpu-0| | I005: HBACommon: First write on scsi0:0.fileName='/vmfs/volumes/5fde2523-5d89094c-2460-107b44948458/AR/rdm1.vmdk'
2021-05-22T12:02:45.711Z| vcpu-0| | I005: DDB: "longContentID" = "4dc461ccd3f7bb6b4013a4037c09d45e" (was "14a6cd39db15eb0e72977f92eebaa9bb")
2021-05-22T12:02:45.715Z| vcpu-0| | I005: DISKLIB-CHAIN : DiskChainUpdateContentID: old=0xeebaa9bb, new=0x7c09d45e (4dc461ccd3f7bb6b4013a4037c09d45e)
2021-05-22T12:02:46.422Z| vcpu-2| | I005: CPU reset: soft (mode Emulation)
2021-05-22T12:02:46.422Z| vcpu-1| | I005: CPU reset: soft (mode Emulation)
2021-05-22T12:02:46.422Z| vcpu-0| | I005: Guest: Firmware has transitioned to runtime.
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Unregistering IOSpace at 0x2040
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:47.016Z| vcpu-0| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:47.529Z| vcpu-0| | I005: Guest: Driver=vsock, Version=9.8.16.0
2021-05-22T12:02:47.530Z| vcpu-0| | I005: Guest: Driver=vmci, Version=9.8.16.0
2021-05-22T12:02:47.545Z| vcpu-2| | I005: LSI: Invalid PageType [21] pageNo 0 Action 0
2021-05-22T12:02:47.599Z| vcpu-2| | I005: AHCI-VMM:HBA reset issued on sata0.
2021-05-22T12:02:48.287Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:48.287Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:48.835Z| vcpu-2| | I005: Guest: vm3d: WDDM Miniport Loading the release build type
2021-05-22T12:02:48.842Z| vcpu-2| | I005: Guest: vm3d: Driver=wddm, Version=8.17.02.0014
2021-05-22T12:02:48.852Z| vcpu-2| | I005: Guest: Driver=vmxnet3, Version=1.8.17.0
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: Unregistering IOSpace at 0x2040
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:48.882Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: Registering IOSpace at 0x2040
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfb800000(0xfb800000)
2021-05-22T12:02:48.883Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:48.907Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.908Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.908Z| vcpu-1| | I005: VMXNET3 user: Supported set 0x0000007f, configured set 0x00000003
2021-05-22T12:02:48.908Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.908Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.910Z| vcpu-2| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.910Z| vcpu-2| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:48.912Z| vcpu-0| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:02:48.913Z| vcpu-0| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.913Z| vcpu-0| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.943Z| vcpu-0| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.943Z| vcpu-0| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:48.944Z| vcpu-1| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:02:48.944Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:02:48.944Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3
2021-05-22T12:02:48.976Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 Driver Info: version = 17274505 gosBits = 2 gosType = 2, gosVer = 40960, gosMisc = 212
2021-05-22T12:02:48.976Z| vcpu-1| | I005: VMXNET3 user: Activate request succeeds for device 0.
2021-05-22T12:02:49.974Z| vcpu-2| | I005: Guest: vm3d: SVGA WDDM Display Only driver, Version: 8.17.02.0014, Build Number: 17592369
2021-05-22T12:02:49.974Z| vcpu-2| | I005: Guest: vm3d: WDDM OS version: 10.0, build number: 19042, service pack version: 0.0, platform Id: 2, product type: 1, suite mask: 0x110
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.974Z| vcpu-2| | I005: SVGA: FIFO is already mapped
2021-05-22T12:02:49.978Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA Grow OTable command enabled.
2021-05-22T12:02:49.978Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA IntraSurfaceCopy command enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Guest backed surface is enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Mob Cursor is enabled.
2021-05-22T12:02:49.979Z| vcpu-2| | I005: Guest: vm3d: WDDM Frame Type is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM SVGA DX11.0 (SM5) support is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM 3D is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM DX10 context is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM GL3 is disabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM DX cap is enabled.
2021-05-22T12:02:49.982Z| vcpu-2| | I005: Guest: vm3d: WDDM HP Queue is enabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: Guest: vm3d: WDDM GDI HW acceleration is disabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: Guest: vm3d: WDDM Guest backed primary in aperture is enabled.
2021-05-22T12:02:49.983Z| vcpu-2| | I005: SVGA: Guest reported SVGA WDDM driver version: 8.17.02.0014 Build Number: 17592369
2021-05-22T12:02:49.984Z| vcpu-2| | I005: Guest: vm3d: WDDM Preemption Aware is enabled.
2021-05-22T12:02:49.984Z| vcpu-2| | I005: Guest: vm3d: WDDM EDID is disabled.
2021-05-22T12:02:49.987Z| vcpu-2| | I005: Guest: vm3d: Modeset enabled, types available: TR CCD Multi
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM DirectFlip is disabled.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM VSync is disabled.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM primary mem 16384KB.
2021-05-22T12:02:49.988Z| vcpu-2| | I005: Guest: vm3d: WDDM VRAM 4096KB.
2021-05-22T12:02:49.989Z| vcpu-2| | I005: Guest: vm3d: WDDM using 12KB memory for OTable.
2021-05-22T12:02:49.989Z| vcpu-2| | I005: Guest: vm3d: WDDM Aperture memory 16384KB.
2021-05-22T12:02:49.990Z| vcpu-2| | I005: Guest: vm3d: 1 monitors active
2021-05-22T12:02:50.032Z| svga| | I005: SVGA-ScreenMgr: Screen type changed to ScreenTarget
2021-05-22T12:02:50.381Z| vcpu-2| | I005: Guest MSR write (0x49: 0x1)
2021-05-22T12:02:53.095Z| vcpu-1| | I005: GuestStore: gstored access is disabled.
2021-05-22T12:02:53.096Z| vcpu-1| | W003: ToolsGetAppInfoEnabledFromConfigStore: No config found, Trying out the default config.
2021-05-22T12:02:53.096Z| vcpu-1| | I005: ToolsGetAppInfoEnabledFromConfigStore: appInfoStatus retrieved as '1'.
2021-05-22T12:02:53.096Z| vcpu-1| | I005: Guest: toolbox: Version: 11.1.1.20749 (build-16303738)
2021-05-22T12:02:53.097Z| vcpu-1| | W003: GuestRpc: application toolbox, changing channel 65535 -> 0
2021-05-22T12:02:53.097Z| vcpu-1| | I005: GuestRpc: Channel 0, guest application toolbox.
2021-05-22T12:02:53.097Z| vcpu-1| | I005: Tools: [AppStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.097Z| vcpu-1| | I005: TOOLS: appName=toolbox, oldStatus=0, status=1, guestInitiated=0.
2021-05-22T12:02:53.109Z| vcpu-1| | I005: TOOLS autoupgrade protocol version 2
2021-05-22T12:02:53.109Z| vcpu-1| | I005: Tools: Changing running status: 1 => 2.
2021-05-22T12:02:53.109Z| vcpu-1| | I005: Tools: [RunningStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.110Z| vcpu-1| | I005: TOOLS Received tools.set.version rpc call, version = 11297, setting type to 1 from guest OS
2021-05-22T12:02:53.110Z| vcpu-1| | I005: Tools_SetVersionAndType did nothing; new tools version (11297) and type (1) match old Tools version and type
2021-05-22T12:02:53.110Z| vcpu-1| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.110Z| vcpu-1| | I005: Starting copy guest manifest.
2021-05-22T12:02:53.116Z| vcpu-0| | I005: Tools: State change '3' progress: last event 0, event 1, success 1.
2021-05-22T12:02:53.117Z| vcpu-0| | I005: HGFileCopyCreateSessionCB: Successfully created the session.
2021-05-22T12:02:53.134Z| vcpu-0| | I005: ToolsLoadManifestFileCB: Queuing a request to update the manifest information.
2021-05-22T12:02:53.134Z| worker-530944| | I005: ToolsISO: Refreshing imageName for 'windows9-64' (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.141Z| worker-530944| | I005: ToolsISO: Updated cached value for imageName to 'windows.iso'.
2021-05-22T12:02:53.141Z| worker-530944| | I005: ToolsISO: Selected Tools ISO 'windows.iso' for 'windows9-64' guest.
2021-05-22T12:02:53.141Z| worker-530944| | I005: GetHostManifests: Extracting /tmp/vmware-root/manifest.txt.530944.iso.shipped manifest file.
2021-05-22T12:02:53.164Z| worker-530944| | I005: GetHostManifests: Done extracting the manifest file.
2021-05-22T12:02:53.165Z| worker-530944| | I005: ToolsVersion: Status is supported old because this is status of monolithic version, and PluginGuestStoreUpgrade and 3 more components are missing from the guest.
2021-05-22T12:02:53.165Z| worker-530944| | I005: Using ToolsMinVersion = 8384
2021-05-22T12:02:53.165Z| worker-530944| | I005: ToolsVersionGetStatusWorkerThread: Tools status 2 derived from environment
2021-05-22T12:02:53.165Z| vmx| | I005: ToolsUpdateManifestInfoWorkerThreadDone: Compared tools manifest from host and from the guest. Status = 2.
2021-05-22T12:02:53.165Z| vmx| | I005: ToolsUpdateManifestInfoWorkerThreadDone: Updating the manifest info.
2021-05-22T12:02:53.167Z| vmx| | A000: ConfigDB: Setting extendedConfigFile = "AR.vmxf"
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.168Z| vmx| | I005: VMXVmdb_SetToolsVersionStatus: status value set to 'oldTools', 'supportedOld', install possible
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS installed legacy version 11297, available legacy version 11333
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS manifest update status is 2
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS using cached value 1 for isoImageExists (refreshCount=1, lastCount=1).
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS can be autoupgraded.
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS VM tools upgrade policy 1
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS need not be autoupgraded according to upgrade policy.
2021-05-22T12:02:53.168Z| vmx| | I005: TOOLS Setting autoupgrade-checked TRUE.
2021-05-22T12:02:53.168Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:53.172Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:53.221Z| vcpu-2| | I005: TOOLS state change 3 returned status 1
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: State change '3' progress: last event 1, event 2, success 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: State change '3' progress: last event 1, event 4, success 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Vix: [mainDispatch.c:4122]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=2, err=0).
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: Changing running status: 2 => 1.
2021-05-22T12:02:53.221Z| vcpu-2| | I005: Tools: [RunningStatus] Last heartbeat value 6 (last received 2s ago)
2021-05-22T12:02:53.636Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:53.636Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:02:56.117Z| vmx| | I005: MKSVMX: Vigor requested a screenshot
2021-05-22T12:02:56.118Z| svga| | I005: MKSScreenShotMgr: Taking a screenshot
2021-05-22T12:03:01.908Z| vcpu-0| | I005: Guest: vm3d: Memory required for all screens exceeds maximum (required=17510400 max=16777216)
2021-05-22T12:03:07.684Z| vmx| | I005: VigorTransportProcessClientPayload: opID=esxui-1e1c-123e seq=5920: Receiving PowerState.InitiatePowerOff request.
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: sending 'OS_Halt' (state = 1) state change request
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: Changing running status: 1 => 2.
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Tools: [RunningStatus] Last heartbeat value 21 (last received 0s ago)
2021-05-22T12:03:07.684Z| vmx| opID=esxui-1e1c-123e| I005: Vix: [vmxCommands.c:536]: VMAutomation_InitiatePowerOff. Tried to soft halt. Success = 1
2021-05-22T12:03:07.731Z| vcpu-0| | I005: Tools: State change '1' progress: last event 0, event 1, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: TOOLS state change 1 returned status 1
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: State change '1' progress: last event 1, event 2, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: State change '1' progress: last event 1, event 4, success 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: VigorTransport_ServerSendResponse opID=esxui-1e1c-123e seq=5920: Completed PowerState request.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: Changing running status: 2 => 1.
2021-05-22T12:03:07.970Z| vcpu-2| | I005: Tools: [RunningStatus] Last heartbeat value 21 (last received 0s ago)
2021-05-22T12:03:09.178Z| vcpu-0| | I005: TOOLS autoupgrade protocol version 0
2021-05-22T12:03:09.179Z| vcpu-0| | I005: ToolsGetAppInfoEnabledFromConfigStore: Returning the cached value: '1'.
2021-05-22T12:03:09.182Z| vcpu-1| | I005: GuestRpc: Reinitializing Channel 0(toolbox)
2021-05-22T12:03:09.182Z| vcpu-1| | I005: Tools: [AppStatus] Last heartbeat value 22 (last received 0s ago)
2021-05-22T12:03:09.182Z| vcpu-1| | I005: TOOLS: appName=toolbox, oldStatus=1, status=0, guestInitiated=1.
2021-05-22T12:03:10.884Z| vcpu-1| | I005: VMXNET3 user: Quiesce device 0.
2021-05-22T12:03:10.884Z| vcpu-1| | I005: Ethernet0 MAC Address: 00:0c:29:9f:6e:38
2021-05-22T12:03:10.884Z| vcpu-1| | I005: VMXNET3 user: Ethernet0 RSS fields requested by vmx: 3

-------------------------------------------------------------------------------------------------------------------------------------------------------------

Although I am a VMware employee I contribute to VMware Communities voluntarily (ie. not in any official capacity)
VMware Training & Certification blog
0 Kudos