Project

General

Profile

Bug #73729

VM does not start

Added by Bug Clerk over 1 year ago. Updated over 1 year ago.

Status:
Done
Priority:
No priority
Assignee:
Vladimir Vinogradenko
Category:
Middleware
Severity:
New
Reason for Closing:
Reason for Blocked:
Needs QA:
No
Needs Doc:
No
Needs Merging:
No
Needs Automation:
No
Support Suite Ticket:
n/a
Hardware Configuration:
ChangeLog Required:
No

Description

When in the legacy interface a VM is started I get the following error mesage (the VM was created in a previous vesion of Freenas):

[2019/02/03 11:23:57] (INFO) VMService.__set_guest_vmemory():901 - ===> Setting ARC FROM: 17667395584 TO: 17130524672
[2019/02/03 11:23:57] (DEBUG) VMService.run():179 - ====> NIC_ATTACH: ix0
[2019/02/03 11:23:57] (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,tap19,mac=00:A0:98:13:88:1F -s 4:0,virtio-blk,/dev/zvol/Tank/VM/sysman 13_sysman
[2019/02/03 11:23:57] (DEBUG) VMService.run():286 - sysman: vm_reinit: Device busy
[2019/02/03 11:23:57] (INFO) VMService.run():311 - ===> Error VM: sysman ID: 13 BHYVE_CODE: 4
[2019/02/03 11:23:57] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:23:57] (DEBUG) VMService.__teardown_guest_vmemory():338 - ===> Give back guest memory to ARC: -16056782848
[2019/02/03 11:23:57] (WARNING) application.call_method():189 - Exception while calling vm.start(*['13'])
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/02/03 11:23:57] (DEBUG) middlewared.logger.CrashReporting.report():103 - Sending a crash report...
[2019/02/03 11:23:57] (DEBUG) raven.base.Client.send_remote():716 - Sending message of length 3986 to https://sentry.ixsystems.com/api/2/store/
[2019/02/03 11:23:57] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.

The behaviour from the new GUI is similar, I only see more messages in the log about running without bhyve process:

[2019/02/03 11:27:12] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:13] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:17] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:22] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:27] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:29] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:32] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:33] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:34] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:34] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:34] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:34] (INFO) VMService.__set_guest_vmemory():901 - ===> Setting ARC FROM: 17130524672 TO: 16593653760
[2019/02/03 11:27:34] (DEBUG) VMService.run():179 - ====> NIC_ATTACH: ix0
[2019/02/03 11:27:34] (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,tap20,mac=00:A0:98:13:88:1F -s 4:0,virtio-blk,/dev/zvol/Tank/VM/sysman 13_sysman
[2019/02/03 11:27:34] (DEBUG) VMService.run():286 - sysman: vm_reinit: Device busy
[2019/02/03 11:27:34] (INFO) VMService.run():311 - ===> Error VM: sysman ID: 13 BHYVE_CODE: 4
[2019/02/03 11:27:34] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:34] (DEBUG) VMService.__teardown_guest_vmemory():338 - ===> Give back guest memory to ARC: -15519911936
[2019/02/03 11:27:34] (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/02/03 11:27:34] (DEBUG) middlewared.logger.CrashReporting.report():103 - Sending a crash report...
[2019/02/03 11:27:34] (DEBUG) raven.base.Client.send_remote():716 - Sending message of length 4116 to https://sentry.ixsystems.com/api/2/store/
[2019/02/03 11:27:37] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.
[2019/02/03 11:27:42] (ERROR) VMService.running():438 - ===> VMM sysman is running without bhyve process.


Related issues

Copied from FreeNAS - Bug #73441: Prevent setting vfs.zfs.arc_max to lower than vfs.zfs.arc_minDone

History

#1 Updated by Bug Clerk over 1 year ago

  • Copied from Bug #73441: Prevent setting vfs.zfs.arc_max to lower than vfs.zfs.arc_min added

#2 Updated by Bug Clerk over 1 year ago

  • Target version changed from Master - FreeNAS Nightlies to 11.3

#3 Updated by Bug Clerk over 1 year ago

  • Status changed from Unscreened to In Progress

#4 Updated by Bug Clerk over 1 year ago

  • Status changed from In Progress to Ready for Testing

#5 Updated by Dru Lavigne over 1 year ago

  • Status changed from Ready for Testing to Done
  • Target version changed from 11.3 to Master - FreeNAS Nightlies
  • Needs QA changed from Yes to No
  • Needs Doc changed from Yes to No
  • Needs Merging changed from Yes to No

Also available in: Atom PDF