Errors from VM manager after upgrade to 11.2 U1 (from 11.1)

[tslw]

I am running FreeNAS 11.2-U1 on a supermicro motherboard with D-1521 and 64 GB memory.
Since the upgrade from 11.1 to 11.2 my system runs slower then before and my jails/iocages/VM seem do not seem to run fine.
One example of a VM issue is below.

When I stop a VM in the new GUI a see a lot of error messages appearing related to the VM:

Code:

[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM mosquitto is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM splunk is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM splunk is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM splunk is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM docker is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM docker is running without bhyve process.
[2019/01/27 09:50:20] (ERROR) VMService.running():438 - ===> VMM docker is running without bhyve process.
[2019/01/27 09:50:21] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/01/27 09:50:21] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/01/27 09:50:21] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/01/27 09:50:23] (ERROR) VMService.running():438 - ===> VMM lms3 is running without bhyve process.

When I start a VM in the GUI I also get an error (however the error about running withou bhyve for that VM is then gone):

Code:

[2019/01/27 09:52:28] (INFO) VMService.__set_guest_vmemory():901 - ===> Setting ARC FROM: 35778400256 TO: 35241529344
[2019/01/27 09:52:28] (DEBUG) VMService.run():179 - ====> NIC_ATTACH: ix0
[2019/01/27 09:52:28] (DEBUG) VMService.run():264 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm13A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 3,e1000,tap2,mac=00:A0:98:13:88:1F -s 29,fbuf,vncserver,tcp=192.168.178.163:5913,w=1920,h=1080,, -s 30,xhci,tablet -s 4:0,ahci,hd:/dev/zvol/Tank/VM/sysman 13_sysman
[2019/01/27 09:52:28] (DEBUG) VMService.run():277 - ==> Start WEBVNC at port 5813 with pid number 84352
[2019/01/27 09:52:28] (DEBUG) VMService.run():286 - sysman: vm_reinit: Device busy
[2019/01/27 09:52:28] (INFO) VMService.run():311 - ===> Error VM: sysman ID: 13 BHYVE_CODE: 4
[2019/01/27 09:52:28] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/01/27 09:52:28] (ERROR) VMService.running():438 - ===> VMM docker is running without bhyve process.
[2019/01/27 09:52:28] (DEBUG) VMService.__teardown_guest_vmemory():338 - ===> Give back guest memory to ARC: -34167787520
[2019/01/27 09:52:28] (WARNING) application.call_method():189 - Exception while calling vm.start(*[13, {'overcommit': True}])
Traceback (most recent call last):
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 161, in call_method
    result = await self.middleware.call_method(self, message)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 1109, in call_method
    return await self._call(message['method'], serviceobj, methodobj, params, app=app, io_thread=False)
  File "/usr/local/lib/python3.6/site-packages/middlewared/main.py", line 1049, in _call
    return await methodobj(*args)
  File "/usr/local/lib/python3.6/site-packages/middlewared/schema.py", line 664, in nf
    return await f(*args, **kwargs)
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/vm.py", line 1132, in start
    await self._manager.start(vm)
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/vm.py", line 61, in start
    list(done)[0].result()
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/vm.py", line 312, in run
    await self.__teardown_guest_vmemory(self.vm['id'])
  File "/usr/local/lib/python3.6/site-packages/middlewared/plugins/vm.py", line 339, in __teardown_guest_vmemory
    sysctl.filter('vfs.zfs.arc_max')[0].value = new_arc_max
TypeError: Invalid argument
[2019/01/27 09:52:28] (DEBUG) middlewared.logger.CrashReporting.report():103 - Sending a crash report...

Besides this if I stop all my jails/iocages/VMs I see that my disk activities is twice as hifh as before (from 30 disk_ops to around 60) and my disk activity is on all the time (was blinking every 5-10 seconds before). I am not sure if these two are related.

Do you have any suggestions?

 

[dlavigne]

Create a report at bugs.freenas.org and post the issue number here.

 

[tslw]

Issue #73011 created.