gistfile1.txt

$ tail -f /Users/pichuang/Library/Logs/VMware\ Fusion/vmware-vmfusion.log
2022-02-10T08:12:59.643Z In(05) VMware Fusion VMMgr::OpenVM (cfgPath=/Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx)
2022-02-10T08:12:59.664Z In(05) host-24220517 /Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx: Reloading config state.
2022-02-10T08:12:59.671Z In(05) host-24220517 VMHS: Transitioned vmx/execState/val to poweredOff
2022-02-10T08:12:59.680Z In(05) RemoteDeviceMgr VTHREAD 123145527058432 "RemoteDeviceMgr" tid 24220520
2022-02-10T08:12:59.680Z In(05) vigorCnx VTHREAD 123145527595008 "vigorCnx" tid 24220521
2022-02-10T08:12:59.682Z In(05) VMware Fusion SnapshotTree: Emitting refresh (/Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx)
2022-02-10T08:12:59.684Z In(05) vigorCnx VigorOfflineGetToolsVersion: updated tools version to 0
2022-02-10T08:12:59.685Z In(05) VMware Fusion ReadProxyFile: Failed to read file: /etc/vmware/hostd/proxy.xml
2022-02-10T08:12:59.692Z In(05) VMware Fusion VMStatus: Unknown guest os type:
2022-02-10T08:12:59.702Z In(05) VMware Fusion MKSControlClientBase: Initialized mksControl client 7F82B9B62DE0.
2022-02-10T08:12:59.702Z In(05) VMware Fusion CUIMKS: cui::MKS (7F82BB8C9800)
2022-02-10T08:12:59.702Z In(05) VMware Fusion OnVMStateChanged: alive = 0
2022-02-10T08:12:59.702Z In(05) VMware Fusion CUIMKS: cui::MKS::SetAttached (7F82BB8C9800): detach
2022-02-10T08:12:59.702Z In(05) VMware Fusion CUIMKS: cui::MKS::OnSetAttachedCompleted (7F82BB8C9800)
2022-02-10T08:12:59.702Z In(05) VMware Fusion VMMKS: to observe MKS device.
2022-02-10T08:12:59.703Z In(05) VMware Fusion VMMKS::OnMKSDeviceInfoChanged: reset pipe name now in offline.
2022-02-10T08:12:59.703Z In(05) VMware Fusion VMMKS::OnInitVMDeviceObserverDone: done.
2022-02-10T08:12:59.703Z In(05) VMware Fusion VMMgr: Finished opening VM at /vm/#2bbcbd459ec480a9/ from /Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx
2022-02-10T08:12:59.712Z In(05) VMware Fusion SnapshotTree: Populating (/Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx)
2022-02-10T08:13:00.199Z In(05) VMware Fusion CnxFSVigor: Could not get the dataFileKey
2022-02-10T08:13:05.283Z In(05) VMware Fusion VMMgr::CloseVM: closing VM at /vm/#51d075675f0dfdde/
2022-02-10T08:13:05.283Z In(05) VMware Fusion VMMgr::CloseVM: closing VM at /vm/#2bbcbd459ec480a9/
2022-02-10T08:13:05.295Z In(05) VMware Fusion VMMgr::OnVMDestroyed: cleaning up after destroyed VM at /vm/#2bbcbd459ec480a9/
2022-02-10T08:13:05.295Z In(05) VMware Fusion CUIMKS: Destroy cui::MKS (7F82BB8C9800)
2022-02-10T08:13:05.295Z In(05) VMware Fusion CUIMKS: cui::MKS::SetAttached (7F82BB8C9800): detach
2022-02-10T08:13:05.295Z In(05) VMware Fusion CUIMKS: cui::MKS::OnSetAttachedCompleted (7F82BB8C9800)
2022-02-10T08:13:05.295Z In(05) VMware Fusion CUIMKS: Destroying cui::MKS (7F82BB8C9800) is done.
2022-02-10T08:13:05.295Z In(05) VMware Fusion MKSWindowTrans: The current transaction 6000021023A0 has not been submitted.
2022-02-10T08:13:05.296Z In(05) VMware Fusion MKSControlClientBase: Destroy mksControl client 7F82B9B62DE0.
2022-02-10T08:13:05.296Z In(05) VMware Fusion MKSControlClientBase: Disconnect is called (7F82B9B62DE0) (current state 0).
2022-02-10T08:13:05.296Z In(05) VMware Fusion MKSControlClientBase: Disconnect is done (7F82B9B62DE0) (current state 0).
2022-02-10T08:13:05.296Z In(05) VMware Fusion MKSControlClientBase: Destroying mksControl client 7F82B9B62DE0 is done.
2022-02-10T08:13:05.299Z In(05) VMware Fusion VMMgr::OnVMDestroyed: cleaning up after destroyed VM at /vm/#51d075675f0dfdde/
2022-02-10T08:13:05.300Z In(05) VMware Fusion CUIMKS: Destroy cui::MKS (7F82BB95AC00)
2022-02-10T08:13:05.300Z In(05) VMware Fusion CUIMKS: cui::MKS::SetAttached (7F82BB95AC00): detach
2022-02-10T08:13:05.300Z In(05) VMware Fusion CUIMKS: cui::MKS::OnSetAttachedCompleted (7F82BB95AC00)
2022-02-10T08:13:05.300Z In(05) VMware Fusion CUIMKS: Destroying cui::MKS (7F82BB95AC00) is done.
2022-02-10T08:13:05.300Z In(05) VMware Fusion MKSWindowTrans: The current transaction 60000213BC00 has not been submitted.
2022-02-10T08:13:05.300Z In(05) VMware Fusion MKSControlClientBase: Destroy mksControl client 7F82B9B6D580.
2022-02-10T08:13:05.300Z In(05) VMware Fusion MKSControlClientBase: Disconnect is called (7F82B9B6D580) (current state 0).
2022-02-10T08:13:05.300Z In(05) VMware Fusion MKSControlClientBase: Disconnect is done (7F82B9B6D580) (current state 0).
2022-02-10T08:13:05.300Z In(05) VMware Fusion MKSControlClientBase: Destroying mksControl client 7F82B9B6D580 is done.
2022-02-10T08:13:12.338Z In(05) VMware Fusion -[DUIController onVixUnmountVMCommand:], Error: unable to find VM at path: /Users/pichuang/vm_pool/test-os.vmwarevm/test-os.vmx

vmrest-cannot-poweron

$ ./vmrest -c ./fusionapi-cert.pem -k fusionapi-key.pem -d
VMware Fusion REST API
Copyright (C) 2015-2020 VMware Inc.
All Rights Reserved

vmrest 1.2.1 build-18811640
[vmrest] UI Proxy:starts to run...
-
Using the VMware Fusion UI while API calls are in progress is not recommended and may yield unexpected results.
-
Serving HTTPS on 127.0.0.1:8697
-
Press Ctrl+C to stop.
http: TLS handshake error from 127.0.0.1:65244: remote error: tls: unknown certificate
[vmrest]  set VM power state request, vmID: PAEGRUP0M8IK015H2GRCV00ERA9CUDPR, request ID: 71b5d27568959460a5265cd047b90e45d7537a1a
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to create/get a new vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to join the vigor call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a joined the call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a can't find a cached vigor connection, creating one
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a initialize vigor connection
[goVigor]started, syncOfflineConnect
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc000bbe2a0, callback returned, syncOfflineConnect execution took 565.486µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, syncOfflineConnect
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a syncOfflineConnect execution took 667.837µs
[goVigor]started, syncConnectOnlineLocal
WQPoolAllocPoll : pollIx = 2, signalHandle = 34
VigorOnline_ConnectOnlineLocal: Connecting to local VM /Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx
/Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx: Connected to vmx-vmdb-fd, remote end sent pid: 96578
VmdbAddConnection: cnxPath=/db/connection/#1/, cnxIx=1
Vmdb_AddConnectionAsync_Secure: registered VERSION completion callback, cnx = /db/connection/#1/
/Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx: Mounting virtual machine paths on connection: /db/connection/#1/.
VmdbCnxControlCb: registered SUBSCRIBE completion callback, cnx = /db/connection/#1/
VigorOnlineStartMountCb Succeeded
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc000b6c1c0, callback returned, syncConnectOnlineLocal execution took 51.644723ms
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, syncConnectOnlineLocal
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a syncConnectOnlineLocal execution took 51.736625ms
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address: 0xc000b6c1c0, vigor op call error:resource temporarily unavailable
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a trying to increase reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a increased reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a trying to increase reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a increased reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a created a vigor connection successfully
[goVigor]started, PowerStateOnlineDevice.SyncQuery
[goVigor]started, PowerStateOnlineDevice.Query
[goVigor]op address:0xc0009cdf10, callback returned, PowerStateOnlineDevice.Query execution took 87.712µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc0009cdea0, callback returned, PowerStateOnlineDevice.SyncQuery execution took 138.827µs
[goVigor]done, PowerStateOnlineDevice.Query
[goVigor]PowerStateOnlineDevice.Query execution took 136.109µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, PowerStateOnlineDevice.SyncQuery
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a PowerStateOnlineDevice.SyncQuery execution took 194.921µs
[goVigor]started, VMAutomationOnlineDevice.SyncQuery
[goVigor]started, VMAutomationOnlineDevice.Query
[goVigor]op address:0xc000c30000, callback returned, VMAutomationOnlineDevice.Query execution took 71.166µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc0009cdf80, callback returned, VMAutomationOnlineDevice.SyncQuery execution took 122.146µs
[goVigor]done, VMAutomationOnlineDevice.Query
[goVigor]VMAutomationOnlineDevice.Query execution took 120.429µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, VMAutomationOnlineDevice.SyncQuery
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a VMAutomationOnlineDevice.SyncQuery execution took 193.685µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to decrease the reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a decreased reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a exited the vigor call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a released vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to create/get a new vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to join the vigor call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a joined the call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a found a cached vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a trying to increase reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a increased reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a created a vigor connection successfully
[goVigor]started, PowerStateOnlineDevice.SyncQuery
[goVigor]started, PowerStateOnlineDevice.Query
[goVigor]op address:0xc000c300e0, callback returned, PowerStateOnlineDevice.Query execution took 45.264µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc000c30070, callback returned, PowerStateOnlineDevice.SyncQuery execution took 75.267µs
[goVigor]done, PowerStateOnlineDevice.Query
[goVigor]PowerStateOnlineDevice.Query execution took 98.786µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, PowerStateOnlineDevice.SyncQuery
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a PowerStateOnlineDevice.SyncQuery execution took 139.432µs
[goVigor]started, VMAutomationOnlineDevice.SyncQuery
[goVigor]started, VMAutomationOnlineDevice.Query
[goVigor]op address:0xc000c301c0, callback returned, VMAutomationOnlineDevice.Query execution took 54.174µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a op address:0xc000c30150, callback returned, VMAutomationOnlineDevice.SyncQuery execution took 86.613µs
[goVigor]done, VMAutomationOnlineDevice.Query
[goVigor]VMAutomationOnlineDevice.Query execution took 94.359µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a done, VMAutomationOnlineDevice.SyncQuery
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a VMAutomationOnlineDevice.SyncQuery execution took 139.297µs
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a attempting to decrease the reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a decreased reference count of vigor connection
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a exited the vigor call queue
[goVigor] reqID=71b5d27568959460a5265cd047b90e45d7537a1a released vigor connection
[vmrest] UI Proxy:send message
VigorOfflineFreeCryptoInfo: Disk crypto info was not initialized: /Users/pichuang/vm_pool/CentOS 7 64-bit.vmwarevm/CentOS 7 64-bit.vmx.
VigorOnlineDisconnect: disconnect /vm/#VMX/ via /db/connection/#1/
VMDB unmount initiated for /vm/#VMX/.
VigorOnlineUnmountCb: Mount state values have changed for /vm/#VMX/.
VmdbDbRemoveCnx: Removing Cnx from Db for '/db/connection/#1/'
VmdbCnxDisconnect: Disconnect: closed pipe for sub cnx '/db/connection/#1/' (0)
VigorOnlineUnmountCb: Unmount complete for /vm/#VMX/.
VigorOnlineUnmountCb unset Vigor path on vm unmount.
WQPoolFreePoll : pollIx = 2, signalHandle = 32

macOS Monterey 12.1