[2020-05-06 17:44:37,740] (DEBUG) VMService.vm_7.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:44:37,743] (DEBUG) VMService.vm_7.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm7A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:65:8b:78 -s 29,fbuf,vncserver,tcp=0.0.0.0:5697,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 7_RancherOS
[2020-05-06 17:44:37,767] (DEBUG) VMService.vm_7.run():306 - ==> Start WEBVNC at port 5597 with pid number 36418
[2020-05-06 17:44:37,768] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:37 Listening for VNC connections on TCP port 5697
[2020-05-06 17:44:37,768] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:37 Listening for VNC connections on TCP6 port 5697
[2020-05-06 17:44:44,671] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44   other clients:
[2020-05-06 17:44:44,673] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Client Protocol Version 3.8
[2020-05-06 17:44:44,674] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Protocol version sent 3.8, using 3.8
[2020-05-06 17:44:44,674] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:44:44,675] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:44:44,681] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Pixel format for client 127.0.0.1:
[2020-05-06 17:44:44,681] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44   32 bpp, depth 24, little endian
[2020-05-06 17:44:44,681] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:44:44,681] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:44:44,681] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:44:44,682] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:44:44,683] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:44:44,683] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:44:44 Using tight encoding for client 127.0.0.1
[2020-05-06 17:45:25,068] (DEBUG) VMService.vm_7.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:45:25,107] (INFO) VMService.vm_7.run():333 - ===> Rebooting VM: RancherOS ID: 7 BHYVE_CODE: 0
[2020-05-06 17:45:25,118] (DEBUG) VMService.vm_7.restart():467 - ==> Reset VM: RancherOS ID: 7 BHYVE_CODE: 255
[2020-05-06 17:45:25,456] (DEBUG) VMService.vm_7.kill_bhyve_web():458 - ==> Killing WEBVNC: 36418
[2020-05-06 17:45:25,458] (DEBUG) VMService.vm_7.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:45:25,458] (DEBUG) VMService.vm_7.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:45:25,462] (DEBUG) VMService.vm_7.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm7A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:65:8b:78 -s 29,fbuf,vncserver,tcp=0.0.0.0:5697,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 7_RancherOS
[2020-05-06 17:45:25,462] (DEBUG) VMService.vm_7.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm7A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:65:8b:78 -s 29,fbuf,vncserver,tcp=0.0.0.0:5697,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 7_RancherOS
[2020-05-06 17:45:25,496] (DEBUG) VMService.vm_7.run():306 - ==> Start WEBVNC at port 5597 with pid number 36510
[2020-05-06 17:45:25,496] (DEBUG) VMService.vm_7.run():306 - ==> Start WEBVNC at port 5597 with pid number 36510
[2020-05-06 17:45:25,496] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:25 Listening for VNC connections on TCP port 5697
[2020-05-06 17:45:25,496] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:25 Listening for VNC connections on TCP port 5697
[2020-05-06 17:45:25,497] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:25 Listening for VNC connections on TCP6 port 5697
[2020-05-06 17:45:25,497] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:25 Listening for VNC connections on TCP6 port 5697
[2020-05-06 17:45:26,491] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   other clients:
[2020-05-06 17:45:26,491] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   other clients:
[2020-05-06 17:45:26,492] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Client Protocol Version 3.8
[2020-05-06 17:45:26,492] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Client Protocol Version 3.8
[2020-05-06 17:45:26,492] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Protocol version sent 3.8, using 3.8
[2020-05-06 17:45:26,492] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Protocol version sent 3.8, using 3.8
[2020-05-06 17:45:26,493] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:45:26,493] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:45:26,493] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:45:26,493] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:45:26,498] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Pixel format for client 127.0.0.1:
[2020-05-06 17:45:26,498] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Pixel format for client 127.0.0.1:
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   32 bpp, depth 24, little endian
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   32 bpp, depth 24, little endian
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:45:26,499] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:45:26,500] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:45:26,501] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:45:26,501] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:45:26,501] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using tight encoding for client 127.0.0.1
[2020-05-06 17:45:26,501] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:26 Using tight encoding for client 127.0.0.1
[2020-05-06 17:45:37,027] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 17:45:37,027] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 17:45:37,401] (INFO) VMService.vm_7.run():346 - ===> Error VM: RancherOS ID: 7 BHYVE_CODE: -15
[2020-05-06 17:45:37,401] (INFO) VMService.vm_7.run():346 - ===> Error VM: RancherOS ID: 7 BHYVE_CODE: -15
[2020-05-06 17:45:37,424] (ERROR) VMService.vm_7.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 17:45:37,424] (ERROR) VMService.vm_7.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 17:45:37,441] (DEBUG) VMService.vm_7.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 27794419968
[2020-05-06 17:45:37,441] (DEBUG) VMService.vm_7.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 27794419968
[2020-05-06 17:45:37,441] (WARNING) VMService.vm_7.destroy_vm():353 - ===> Destroying VM: RancherOS ID: 7 BHYVE_CODE: -15
[2020-05-06 17:45:37,441] (WARNING) VMService.vm_7.destroy_vm():353 - ===> Destroying VM: RancherOS ID: 7 BHYVE_CODE: -15
[2020-05-06 17:45:37,454] (DEBUG) VMService.vm_7.kill_bhyve_web():458 - ==> Killing WEBVNC: 36510
[2020-05-06 17:45:39,271] (DEBUG) VMService.vm_7.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:45:39,271] (DEBUG) VMService.vm_7.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:45:39,275] (DEBUG) VMService.vm_7.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm7A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:65:8b:78 -s 29,fbuf,vncserver,tcp=0.0.0.0:5697,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/ubuntu-18.04.4-live-server-amd64.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 7_RancherOS
[2020-05-06 17:45:39,275] (DEBUG) VMService.vm_7.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm7A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:65:8b:78 -s 29,fbuf,vncserver,tcp=0.0.0.0:5697,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/ubuntu-18.04.4-live-server-amd64.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 7_RancherOS
[2020-05-06 17:45:39,299] (DEBUG) VMService.vm_7.run():306 - ==> Start WEBVNC at port 5597 with pid number 36545
[2020-05-06 17:45:39,299] (DEBUG) VMService.vm_7.run():306 - ==> Start WEBVNC at port 5597 with pid number 36545
[2020-05-06 17:45:39,299] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:39 Listening for VNC connections on TCP port 5697
[2020-05-06 17:45:39,299] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:39 Listening for VNC connections on TCP port 5697
[2020-05-06 17:45:39,300] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:39 Listening for VNC connections on TCP6 port 5697
[2020-05-06 17:45:39,300] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:45:39 Listening for VNC connections on TCP6 port 5697
[2020-05-06 17:45:46,106] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x140 on vcpu 0
[2020-05-06 17:45:46,106] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x140 on vcpu 0
[2020-05-06 17:45:46,107] (DEBUG) VMService.vm_7.run():315 - RancherOS: wrmsr to register 0x140(0) on vcpu 0
[2020-05-06 17:45:46,107] (DEBUG) VMService.vm_7.run():315 - RancherOS: wrmsr to register 0x140(0) on vcpu 0
[2020-05-06 17:45:46,218] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x140 on vcpu 1
[2020-05-06 17:45:46,218] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x140 on vcpu 1
[2020-05-06 17:45:46,219] (DEBUG) VMService.vm_7.run():315 - RancherOS: wrmsr to register 0x140(0) on vcpu 1
[2020-05-06 17:45:46,219] (DEBUG) VMService.vm_7.run():315 - RancherOS: wrmsr to register 0x140(0) on vcpu 1
[2020-05-06 17:45:47,307] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x64e on vcpu 0
[2020-05-06 17:45:47,307] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x64e on vcpu 0
[2020-05-06 17:45:47,307] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x34 on vcpu 0
[2020-05-06 17:45:47,307] (DEBUG) VMService.vm_7.run():315 - RancherOS: rdmsr to register 0x34 on vcpu 0
[2020-05-06 17:45:47,723] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0xe1
[2020-05-06 17:45:47,723] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0xe1
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x0a
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x0a
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x01
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x01
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x41
[2020-05-06 17:45:48,251] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x41
[2020-05-06 17:45:48,763] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x88
[2020-05-06 17:45:48,763] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 mouse command 0x88
[2020-05-06 17:47:43,309] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   other clients:
[2020-05-06 17:47:43,309] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   other clients:
[2020-05-06 17:47:43,314] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Client Protocol Version 3.8
[2020-05-06 17:47:43,314] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Client Protocol Version 3.8
[2020-05-06 17:47:43,314] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Protocol version sent 3.8, using 3.8
[2020-05-06 17:47:43,314] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Protocol version sent 3.8, using 3.8
[2020-05-06 17:47:43,315] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:47:43,315] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:47:43,315] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:47:43,315] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Pixel format for client 127.0.0.1:
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Pixel format for client 127.0.0.1:
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   32 bpp, depth 24, little endian
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   32 bpp, depth 24, little endian
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:47:43,318] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:47:43,319] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using tight encoding for client 127.0.0.1
[2020-05-06 17:47:43,320] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 17:47:43 Using tight encoding for client 127.0.0.1
[2020-05-06 18:26:35,144] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Client 127.0.0.1 gone
[2020-05-06 18:26:35,144] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Client 127.0.0.1 gone
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  FramebufferUpdate   :  11109 |         0/        0 (  0.0%)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  FramebufferUpdate   :  11109 |         0/        0 (  0.0%)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  LastRect            :  11109 |    133308/   133308 (  0.0%)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  LastRect            :  11109 |    133308/   133308 (  0.0%)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  tight               : 333270 |   8689012/590153224 ( 98.5%)
[2020-05-06 18:26:35,145] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  tight               : 333270 |   8689012/590153224 ( 98.5%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  TOTALS              : 355489 |   8822575/590286787 ( 98.5%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  TOTALS              : 355489 |   8822575/590286787 ( 98.5%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  PointerEvent        :    139 |       834/      834 (  0.0%)
[2020-05-06 18:26:35,146] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  PointerEvent        :    139 |       834/      834 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  FramebufferUpdate   :  11110 |    111100/   111100 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  FramebufferUpdate   :  11110 |    111100/   111100 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  TOTALS              :  11252 |    112030/   112030 (  0.0%)
[2020-05-06 18:26:35,147] (DEBUG) VMService.vm_7.run():315 - RancherOS: 06/05/2020 18:26:35  TOTALS              :  11252 |    112030/   112030 (  0.0%)
[2020-05-06 18:26:42,735] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 18:26:42,735] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 18:27:09,834] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 18:27:09,834] (DEBUG) VMService.vm_7.stop():478 - ===> Soft Stop VM: RancherOS ID: 7
[2020-05-06 18:56:43,836] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 keyboard command 0xf6
[2020-05-06 18:56:43,836] (DEBUG) VMService.vm_7.run():315 - RancherOS: Unhandled ps2 keyboard command 0xf6
[2020-05-06 18:56:44,111] (DEBUG) VMService.vm_7.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 18:56:44,111] (DEBUG) VMService.vm_7.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 18:56:44,115] (INFO) VMService.vm_7.run():338 - ===> Powered off VM: RancherOS ID: 7 BHYVE_CODE: 1
[2020-05-06 18:56:44,115] (INFO) VMService.vm_7.run():338 - ===> Powered off VM: RancherOS ID: 7 BHYVE_CODE: 1
[2020-05-06 18:56:44,125] (ERROR) VMService.vm_7.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 18:56:44,125] (ERROR) VMService.vm_7.running():490 - ===> VMM RancherOS is running without bhyve process.
