[2020-05-26 22:23:29,305] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:23:29,308] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:23:29,331] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27017
[2020-05-26 22:23:29,331] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:23:29 Listening for VNC connections on TCP port 5586
[2020-05-26 22:23:29,331] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:23:29 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:23:36,129] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:23:36,130] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:23:36,737] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:23:36,737] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:24:52,934] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52   other clients:
[2020-05-26 22:24:52,935] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Client Protocol Version 3.8
[2020-05-26 22:24:52,935] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Protocol version sent 3.8, using 3.8
[2020-05-26 22:24:52,935] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:24:52,935] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:24:52,951] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Pixel format for client 127.0.0.1:
[2020-05-26 22:24:52,951] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52   32 bpp, depth 24, little endian
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:24:52,952] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:24:52,953] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:24:52 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:19,237] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:25:19,238] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:19,577] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27017
[2020-05-26 22:25:19,582] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:19,605] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:25:19,606] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27017
[2020-05-26 22:25:19,609] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:25:19,609] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:25:19,612] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:25:19,612] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:25:19,645] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27222
[2020-05-26 22:25:19,645] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27222
[2020-05-26 22:25:19,646] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:19 Listening for VNC connections on TCP port 5586
[2020-05-26 22:25:19,646] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:19 Listening for VNC connections on TCP port 5586
[2020-05-26 22:25:19,646] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:19 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:25:19,646] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:19 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:25:22,914] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   other clients:
[2020-05-26 22:25:22,914] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   other clients:
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Client Protocol Version 3.8
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Client Protocol Version 3.8
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Protocol version sent 3.8, using 3.8
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Protocol version sent 3.8, using 3.8
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:25:22,915] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:25:22,916] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:25:22,916] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Pixel format for client 127.0.0.1:
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Pixel format for client 127.0.0.1:
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   32 bpp, depth 24, little endian
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   32 bpp, depth 24, little endian
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:25:22,919] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:25:22,920] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:22,921] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:22 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:25,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:25:25,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:25:25,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:25:25,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:25:25,863] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:25:25,863] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:25:25,863] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:25:25,863] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:25:37,101] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:25:37,101] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:25:37,102] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:37,102] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:37,436] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27222
[2020-05-26 22:25:37,436] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27222
[2020-05-26 22:25:37,439] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:37,439] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:25:37,461] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:25:37,461] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:25:37,461] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27222
[2020-05-26 22:25:37,461] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27222
[2020-05-26 22:25:37,465] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:25:37,465] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:25:37,465] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:25:37,467] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:25:37,467] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:25:37,467] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:25:37,504] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27262
[2020-05-26 22:25:37,504] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27262
[2020-05-26 22:25:37,504] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27262
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:25:37,505] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:25:39,714] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   other clients:
[2020-05-26 22:25:39,714] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   other clients:
[2020-05-26 22:25:39,714] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   other clients:
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Client Protocol Version 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Client Protocol Version 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Client Protocol Version 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Protocol version sent 3.8, using 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Protocol version sent 3.8, using 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Protocol version sent 3.8, using 3.8
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:25:39,715] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:25:39,716] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:25:39,716] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:25:39,716] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Pixel format for client 127.0.0.1:
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Pixel format for client 127.0.0.1:
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Pixel format for client 127.0.0.1:
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   32 bpp, depth 24, little endian
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   32 bpp, depth 24, little endian
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   32 bpp, depth 24, little endian
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:25:39,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,720] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:25:39,721] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:39,722] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:25:39 Using tight encoding for client 127.0.0.1
[2020-05-26 22:25:44,181] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:25:44,181] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:25:44,181] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:25:44,182] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:25:44,182] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:25:44,182] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:25:44,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:28:37,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:28:37,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:28:37,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x822e00000 [sz 16777216]
[2020-05-26 22:28:37,464] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,464] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,464] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,799] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,799] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,799] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,803] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,803] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,803] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:28:37,822] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:28:37,822] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:28:37,822] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:28:37,823] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,823] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,823] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27262
[2020-05-26 22:28:37,826] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:28:37,826] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:28:37,826] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:28:37,826] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:28:37,830] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:28:37,830] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:28:37,830] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:28:37,830] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 512 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:28:37,865] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27349
[2020-05-26 22:28:37,865] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27349
[2020-05-26 22:28:37,865] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27349
[2020-05-26 22:28:37,865] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27349
[2020-05-26 22:28:37,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:28:37,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:28:37,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:28:37,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP port 5586
[2020-05-26 22:28:37,867] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:28:37,867] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:28:37,867] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:28:37,867] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:28:37 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:28:39,803] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:28:39,803] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:28:39,803] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:28:39,803] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:28:40,150] (INFO) VMService.vm_12.run():346 - ===> Error VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,150] (INFO) VMService.vm_12.run():346 - ===> Error VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,150] (INFO) VMService.vm_12.run():346 - ===> Error VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,150] (INFO) VMService.vm_12.run():346 - ===> Error VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,167] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:28:40,167] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:28:40,167] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:28:40,167] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:28:40,220] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:28:40,220] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:28:40,220] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:28:40,220] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:28:40,221] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,221] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,221] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,221] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: -15
[2020-05-26 22:28:40,236] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27349
[2020-05-26 22:28:40,236] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27349
[2020-05-26 22:28:40,236] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27349
[2020-05-26 22:29:00,267] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:29:00,267] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:29:00,267] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:29:00,267] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:29:00,270] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:29:00,270] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:29:00,270] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:29:00,270] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:29:00,294] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27385
[2020-05-26 22:29:00,294] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27385
[2020-05-26 22:29:00,294] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27385
[2020-05-26 22:29:00,294] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27385
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:29:00,295] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:00 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:29:05,854] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   other clients:
[2020-05-26 22:29:05,854] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   other clients:
[2020-05-26 22:29:05,854] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   other clients:
[2020-05-26 22:29:05,854] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   other clients:
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Client Protocol Version 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Client Protocol Version 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Client Protocol Version 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Client Protocol Version 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Protocol version sent 3.8, using 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Protocol version sent 3.8, using 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Protocol version sent 3.8, using 3.8
[2020-05-26 22:29:05,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Protocol version sent 3.8, using 3.8
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:29:05,856] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Pixel format for client 127.0.0.1:
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Pixel format for client 127.0.0.1:
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Pixel format for client 127.0.0.1:
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Pixel format for client 127.0.0.1:
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   32 bpp, depth 24, little endian
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   32 bpp, depth 24, little endian
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   32 bpp, depth 24, little endian
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   32 bpp, depth 24, little endian
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:29:05,859] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:29:05,860] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:29:05,861] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using tight encoding for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using tight encoding for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using tight encoding for client 127.0.0.1
[2020-05-26 22:29:05,862] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:29:05 Using tight encoding for client 127.0.0.1
[2020-05-26 22:29:07,135] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:29:07,135] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:29:07,135] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:29:07,135] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:29:07,136] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:29:07,136] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:29:07,136] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:29:07,136] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:29:07,789] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:29:08,270] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:29:08,270] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:29:08,270] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:29:08,270] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:29:08,802] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:29:08,803] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:29:08,803] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:29:08,803] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:29:08,803] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:29:09,314] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:29:09,314] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:29:09,314] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:29:09,314] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:29:21,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:29:21,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:29:21,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:29:21,866] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:35:41,688] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:35:41,688] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:35:41,688] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:35:41,688] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:35:41,690] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:41,690] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:41,690] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:41,690] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:42,025] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,025] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,025] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,025] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,032] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:42,032] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:42,032] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:42,032] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-26 22:35:42,050] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:35:42,050] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:35:42,050] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:35:42,050] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-26 22:35:42,051] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,051] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,051] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,051] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27385
[2020-05-26 22:35:42,053] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:35:42,053] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:35:42,053] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:35:42,053] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:35:42,053] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:35:42,058] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:35:42,058] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:35:42,058] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:35:42,058] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:35:42,058] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/systank/ubuntu-20.04-live-server-amd64.iso,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:35:42,092] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27695
[2020-05-26 22:35:42,092] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27695
[2020-05-26 22:35:42,092] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27695
[2020-05-26 22:35:42,092] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27695
[2020-05-26 22:35:42,092] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27695
[2020-05-26 22:35:42,093] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP port 5586
[2020-05-26 22:35:42,093] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP port 5586
[2020-05-26 22:35:42,093] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP port 5586
[2020-05-26 22:35:42,093] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP port 5586
[2020-05-26 22:35:42,093] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP port 5586
[2020-05-26 22:35:42,094] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:35:42,094] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:35:42,094] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:35:42,094] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:35:42,094] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:42 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:35:45,102] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   other clients:
[2020-05-26 22:35:45,102] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   other clients:
[2020-05-26 22:35:45,102] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   other clients:
[2020-05-26 22:35:45,102] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   other clients:
[2020-05-26 22:35:45,102] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   other clients:
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Client Protocol Version 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Client Protocol Version 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Client Protocol Version 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Client Protocol Version 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Client Protocol Version 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Protocol version sent 3.8, using 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Protocol version sent 3.8, using 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Protocol version sent 3.8, using 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Protocol version sent 3.8, using 3.8
[2020-05-26 22:35:45,103] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Protocol version sent 3.8, using 3.8
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:35:45,104] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:35:45,107] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Pixel format for client 127.0.0.1:
[2020-05-26 22:35:45,107] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Pixel format for client 127.0.0.1:
[2020-05-26 22:35:45,107] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Pixel format for client 127.0.0.1:
[2020-05-26 22:35:45,107] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Pixel format for client 127.0.0.1:
[2020-05-26 22:35:45,107] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Pixel format for client 127.0.0.1:
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   32 bpp, depth 24, little endian
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   32 bpp, depth 24, little endian
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   32 bpp, depth 24, little endian
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   32 bpp, depth 24, little endian
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   32 bpp, depth 24, little endian
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:35:45,108] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,109] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:35:45,110] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using tight encoding for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using tight encoding for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using tight encoding for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using tight encoding for client 127.0.0.1
[2020-05-26 22:35:45,111] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:35:45 Using tight encoding for client 127.0.0.1
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:35:48,770] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:35:49,277] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:35:49,712] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:35:49,712] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:35:49,712] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:35:49,712] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:35:49,712] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:35:50,226] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:35:50,227] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:35:50,227] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:35:50,227] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:35:50,227] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:35:50,227] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:35:50,267] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:35:50,267] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:35:50,267] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:35:50,267] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:35:50,267] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:35:50,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:35:50,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:35:50,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:35:50,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:35:50,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:02,556] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:02,556] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:02,556] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:02,556] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:02,556] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:05,186] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:36:05,186] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:36:05,186] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:36:05,186] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:36:05,186] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-26 22:36:07,089] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:36:07,089] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:36:07,089] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:36:07,089] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:36:07,089] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-26 22:36:07,090] (DEBUG) VMService.vm_12.stop():474 - ===> Force Stop VM: ubuntu ID: 12 BHYVE_CODE: None
[2020-05-26 22:36:07,090] (DEBUG) VMService.vm_12.stop():474 - ===> Force Stop VM: ubuntu ID: 12 BHYVE_CODE: None
[2020-05-26 22:36:07,090] (DEBUG) VMService.vm_12.stop():474 - ===> Force Stop VM: ubuntu ID: 12 BHYVE_CODE: None
[2020-05-26 22:36:07,090] (DEBUG) VMService.vm_12.stop():474 - ===> Force Stop VM: ubuntu ID: 12 BHYVE_CODE: None
[2020-05-26 22:36:07,090] (DEBUG) VMService.vm_12.stop():474 - ===> Force Stop VM: ubuntu ID: 12 BHYVE_CODE: None
[2020-05-26 22:36:07,105] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,105] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,105] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,105] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,105] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,121] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:36:07,121] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:36:07,121] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:36:07,121] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:36:07,121] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-05-26 22:36:07,170] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:36:07,170] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:36:07,170] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:36:07,170] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:36:07,170] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-05-26 22:36:07,171] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,171] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,171] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,171] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,171] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-05-26 22:36:07,185] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27695
[2020-05-26 22:36:07,185] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27695
[2020-05-26 22:36:07,185] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27695
[2020-05-26 22:36:07,185] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 27695
[2020-05-26 22:36:08,991] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:36:08,991] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:36:08,991] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:36:08,991] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:36:08,991] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-26 22:36:08,994] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:36:08,994] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:36:08,994] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:36:08,994] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:36:08,994] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27741
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27741
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27741
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27741
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 27741
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP port 5586
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP port 5586
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP port 5586
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP port 5586
[2020-05-26 22:36:09,020] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP port 5586
[2020-05-26 22:36:09,021] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:36:09,021] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:36:09,021] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:36:09,021] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:36:09,021] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:09 Listening for VNC connections on TCP6 port 5586
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:36:14,798] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-26 22:36:15,445] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:36:15,445] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:36:15,445] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:36:15,445] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:36:15,445] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-26 22:36:15,446] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:36:15,446] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:36:15,446] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:36:15,446] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:36:15,446] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-26 22:36:15,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:36:15,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:36:15,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:36:15,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:36:15,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-26 22:36:16,140] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   other clients:
[2020-05-26 22:36:16,140] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   other clients:
[2020-05-26 22:36:16,140] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   other clients:
[2020-05-26 22:36:16,140] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   other clients:
[2020-05-26 22:36:16,140] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   other clients:
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Client Protocol Version 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Client Protocol Version 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Client Protocol Version 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Client Protocol Version 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Client Protocol Version 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Protocol version sent 3.8, using 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Protocol version sent 3.8, using 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Protocol version sent 3.8, using 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Protocol version sent 3.8, using 3.8
[2020-05-26 22:36:16,141] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Protocol version sent 3.8, using 3.8
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:36:16,142] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-26 22:36:16,143] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Pixel format for client 127.0.0.1:
[2020-05-26 22:36:16,143] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Pixel format for client 127.0.0.1:
[2020-05-26 22:36:16,143] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Pixel format for client 127.0.0.1:
[2020-05-26 22:36:16,143] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Pixel format for client 127.0.0.1:
[2020-05-26 22:36:16,143] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Pixel format for client 127.0.0.1:
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   32 bpp, depth 24, little endian
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   32 bpp, depth 24, little endian
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   32 bpp, depth 24, little endian
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   32 bpp, depth 24, little endian
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   32 bpp, depth 24, little endian
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:36:16,144] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using image quality level 6 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:36:16,145] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using compression level 2 for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:36:16,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:36:16,148] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:36:16,149] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-26 22:36:16,150] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using tight encoding for client 127.0.0.1
[2020-05-26 22:36:16,150] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using tight encoding for client 127.0.0.1
[2020-05-26 22:36:16,150] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using tight encoding for client 127.0.0.1
[2020-05-26 22:36:16,150] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using tight encoding for client 127.0.0.1
[2020-05-26 22:36:16,150] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:36:16 Using tight encoding for client 127.0.0.1
[2020-05-26 22:36:16,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:36:16,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:36:16,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:36:16,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:36:16,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:36:16,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-26 22:36:16,979] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:16,979] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:16,979] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:16,979] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:16,979] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-26 22:36:19,625] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:19,625] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:19,625] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:19,625] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:36:19,625] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-26 22:37:33,569] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Client 127.0.0.1 gone
[2020-05-26 22:37:33,569] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Client 127.0.0.1 gone
[2020-05-26 22:37:33,569] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Client 127.0.0.1 gone
[2020-05-26 22:37:33,569] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Client 127.0.0.1 gone
[2020-05-26 22:37:33,569] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Client 127.0.0.1 gone
[2020-05-26 22:37:33,570] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-26 22:37:33,570] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-26 22:37:33,570] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-26 22:37:33,570] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-26 22:37:33,570] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-26 22:37:33,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    472 |         0/        0 (  0.0%)
[2020-05-26 22:37:33,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    472 |         0/        0 (  0.0%)
[2020-05-26 22:37:33,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    472 |         0/        0 (  0.0%)
[2020-05-26 22:37:33,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    472 |         0/        0 (  0.0%)
[2020-05-26 22:37:33,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    472 |         0/        0 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  LastRect            :    472 |      5664/     5664 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  LastRect            :    472 |      5664/     5664 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  LastRect            :    472 |      5664/     5664 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  LastRect            :    472 |      5664/     5664 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  LastRect            :    472 |      5664/     5664 (  0.0%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  tight               :  28263 |   8352242/1485122772 ( 99.4%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  tight               :  28263 |   8352242/1485122772 ( 99.4%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  tight               :  28263 |   8352242/1485122772 ( 99.4%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  tight               :  28263 |   8352242/1485122772 ( 99.4%)
[2020-05-26 22:37:33,572] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  tight               :  28263 |   8352242/1485122772 ( 99.4%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :  29208 |   8358161/1485128691 ( 99.4%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :  29208 |   8358161/1485128691 ( 99.4%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :  29208 |   8358161/1485128691 ( 99.4%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :  29208 |   8358161/1485128691 ( 99.4%)
[2020-05-26 22:37:33,573] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :  29208 |   8358161/1485128691 ( 99.4%)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  PointerEvent        :     63 |       378/      378 (  0.0%)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  PointerEvent        :     63 |       378/      378 (  0.0%)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  PointerEvent        :     63 |       378/      378 (  0.0%)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  PointerEvent        :     63 |       378/      378 (  0.0%)
[2020-05-26 22:37:33,574] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  PointerEvent        :     63 |       378/      378 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  KeyEvent            :     45 |       360/      360 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  KeyEvent            :     45 |       360/      360 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  KeyEvent            :     45 |       360/      360 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  KeyEvent            :     45 |       360/      360 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  KeyEvent            :     45 |       360/      360 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    473 |      4730/     4730 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    473 |      4730/     4730 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    473 |      4730/     4730 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    473 |      4730/     4730 (  0.0%)
[2020-05-26 22:37:33,575] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  FramebufferUpdate   :    473 |      4730/     4730 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-26 22:37:33,576] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-26 22:37:33,577] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :    583 |      5556/     5556 (  0.0%)
[2020-05-26 22:37:33,577] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :    583 |      5556/     5556 (  0.0%)
[2020-05-26 22:37:33,577] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :    583 |      5556/     5556 (  0.0%)
[2020-05-26 22:37:33,577] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :    583 |      5556/     5556 (  0.0%)
[2020-05-26 22:37:33,577] (DEBUG) VMService.vm_12.run():315 - ubuntu: 26/05/2020 22:37:33  TOTALS              :    583 |      5556/     5556 (  0.0%)
[2020-05-31 18:37:35,803] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-31 18:37:35,807] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-31 18:37:35,839] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 18333
[2020-05-31 18:37:35,840] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:37:35 Listening for VNC connections on TCP port 5586
[2020-05-31 18:37:35,840] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:37:35 Listening for VNC connections on TCP6 port 5586
[2020-05-31 18:37:42,670] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-31 18:37:42,673] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-31 18:37:43,362] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-31 18:37:43,363] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-31 18:37:43,982] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-31 18:37:44,485] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-31 18:37:44,486] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-31 18:37:44,486] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-31 18:37:44,998] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-31 18:37:48,165] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-31 18:58:51,987] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-05-31 18:58:51,988] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-31 18:58:52,328] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 18333
[2020-05-31 18:58:52,331] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-05-31 18:58:52,349] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-05-31 18:58:52,349] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 18333
[2020-05-31 18:58:52,352] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-31 18:58:52,352] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-05-31 18:58:52,355] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-31 18:58:52,355] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-05-31 18:58:52,396] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 29043
[2020-05-31 18:58:52,396] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 29043
[2020-05-31 18:58:52,396] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:58:52 Listening for VNC connections on TCP port 5586
[2020-05-31 18:58:52,396] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:58:52 Listening for VNC connections on TCP port 5586
[2020-05-31 18:58:52,397] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:58:52 Listening for VNC connections on TCP6 port 5586
[2020-05-31 18:58:52,397] (DEBUG) VMService.vm_12.run():315 - ubuntu: 31/05/2020 18:58:52 Listening for VNC connections on TCP6 port 5586
[2020-05-31 18:58:57,872] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-31 18:58:57,872] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-05-31 18:58:57,872] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-31 18:58:57,872] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-05-31 18:58:58,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-31 18:58:58,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-05-31 18:58:58,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-31 18:58:58,364] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-05-31 18:58:58,864] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-31 18:58:58,864] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-05-31 18:58:58,918] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:58:58,918] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-31 18:58:59,382] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-05-31 18:58:59,893] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-31 18:58:59,893] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-05-31 18:59:02,786] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-31 18:59:02,786] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-05-31 18:59:03,905] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:03,905] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:04,993] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:04,993] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:10,556] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:10,556] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:27,153] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-05-31 18:59:27,153] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-06-01 10:32:15,087] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-06-01 10:32:15,087] (DEBUG) VMService.vm_12.stop():478 - ===> Soft Stop VM: ubuntu ID: 12
[2020-06-01 10:32:16,477] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 keyboard command 0xf6
[2020-06-01 10:32:16,477] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 keyboard command 0xf6
[2020-06-01 10:32:16,750] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-06-01 10:32:16,750] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-06-01 10:32:16,778] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-06-01 10:32:16,778] (INFO) VMService.vm_12.run():338 - ===> Powered off VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-06-01 10:32:16,792] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-06-01 10:32:16,792] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-06-01 10:32:16,799] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 26183807232
[2020-06-01 10:32:16,799] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 26183807232
[2020-06-01 10:32:16,800] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-06-01 10:32:16,800] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: 1
[2020-06-01 10:32:16,814] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 29043
[2020-06-01 18:17:31,289] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-01 18:17:31,289] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-01 18:17:31,292] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-01 18:17:31,292] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-01 18:17:31,322] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 77110
[2020-06-01 18:17:31,322] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 77110
[2020-06-01 18:17:31,323] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:17:31 Listening for VNC connections on TCP port 5586
[2020-06-01 18:17:31,323] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:17:31 Listening for VNC connections on TCP port 5586
[2020-06-01 18:17:31,323] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:17:31 Listening for VNC connections on TCP6 port 5586
[2020-06-01 18:17:31,323] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:17:31 Listening for VNC connections on TCP6 port 5586
[2020-06-01 18:17:37,082] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-01 18:17:37,082] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-01 18:17:37,082] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-01 18:17:37,082] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-01 18:17:37,723] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-01 18:17:37,723] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-01 18:17:37,723] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-01 18:17:37,723] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-01 18:17:38,234] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-01 18:17:38,234] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-01 18:17:38,755] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-01 18:17:39,266] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-01 18:17:39,266] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-01 18:17:41,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-01 18:17:41,943] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-01 18:18:23,457] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   other clients:
[2020-06-01 18:18:23,457] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   other clients:
[2020-06-01 18:18:23,458] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Client Protocol Version 3.8
[2020-06-01 18:18:23,458] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Client Protocol Version 3.8
[2020-06-01 18:18:23,458] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Protocol version sent 3.8, using 3.8
[2020-06-01 18:18:23,458] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Protocol version sent 3.8, using 3.8
[2020-06-01 18:18:23,459] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientSecurityType: executing handler for type 1
[2020-06-01 18:18:23,459] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientSecurityType: executing handler for type 1
[2020-06-01 18:18:23,459] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-06-01 18:18:23,459] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-06-01 18:18:23,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Pixel format for client 127.0.0.1:
[2020-06-01 18:18:23,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Pixel format for client 127.0.0.1:
[2020-06-01 18:18:23,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   32 bpp, depth 24, little endian
[2020-06-01 18:18:23,463] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   32 bpp, depth 24, little endian
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using image quality level 6 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using image quality level 6 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using compression level 2 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using compression level 2 for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-06-01 18:18:23,464] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling LastRect protocol extension for client 127.0.0.1
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling LastRect protocol extension for client 127.0.0.1
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling full-color cursor updates for client 127.0.0.1
[2020-06-01 18:18:23,465] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Enabling full-color cursor updates for client 127.0.0.1
[2020-06-01 18:18:23,466] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using tight encoding for client 127.0.0.1
[2020-06-01 18:18:23,466] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 18:18:23 Using tight encoding for client 127.0.0.1
[2020-06-01 23:18:36,659] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Client 127.0.0.1 gone
[2020-06-01 23:18:36,659] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Client 127.0.0.1 gone
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  FramebufferUpdate   :  85944 |         0/        0 (  0.0%)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  FramebufferUpdate   :  85944 |         0/        0 (  0.0%)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  LastRect            :  85944 |   1031328/  1031328 (  0.0%)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  LastRect            :  85944 |   1031328/  1031328 (  0.0%)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  tight               : 4850261 | 820242492/-168289284 (  0.0%)
[2020-06-01 23:18:36,660] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  tight               : 4850261 | 820242492/-168289284 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  TOTALS              : 5022150 | 821274075/-167257701 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  TOTALS              : 5022150 | 821274075/-167257701 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Statistics             events    Received/ RawEquiv ( saved)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36 Statistics             events    Received/ RawEquiv ( saved)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  KeyEvent            :    204 |      1632/     1632 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  KeyEvent            :    204 |      1632/     1632 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  PointerEvent        :    132 |       792/      792 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  PointerEvent        :    132 |       792/      792 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  FramebufferUpdate   :  85945 |    859450/   859450 (  0.0%)
[2020-06-01 23:18:36,661] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  FramebufferUpdate   :  85945 |    859450/   859450 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  TOTALS              :  86283 |    861962/   861962 (  0.0%)
[2020-06-01 23:18:36,662] (DEBUG) VMService.vm_12.run():315 - ubuntu: 01/06/2020 23:18:36  TOTALS              :  86283 |    861962/   861962 (  0.0%)
[2020-06-04 17:17:52,088] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-04 17:17:52,108] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-04 17:17:52,566] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 2193
[2020-06-04 17:17:52,567] (DEBUG) VMService.vm_12.run():315 - ubuntu: 04/06/2020 17:17:52 Listening for VNC connections on TCP port 5586
[2020-06-04 17:17:52,567] (DEBUG) VMService.vm_12.run():315 - ubuntu: 04/06/2020 17:17:52 Listening for VNC connections on TCP6 port 5586
[2020-06-04 17:17:59,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-04 17:17:59,738] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-04 17:18:00,396] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-04 17:18:00,396] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-04 17:18:00,936] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-04 17:18:01,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-04 17:18:01,467] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-04 17:18:01,468] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-04 17:18:01,985] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-04 17:18:05,571] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-07 15:37:49,260] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-07 15:37:49,271] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-07 15:37:49,422] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 2269
[2020-06-07 15:37:49,422] (DEBUG) VMService.vm_12.run():315 - ubuntu: 07/06/2020 15:37:49 Listening for VNC connections on TCP port 5586
[2020-06-07 15:37:49,422] (DEBUG) VMService.vm_12.run():315 - ubuntu: 07/06/2020 15:37:49 Listening for VNC connections on TCP6 port 5586
[2020-06-07 15:37:55,405] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-07 15:37:55,406] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-07 15:37:56,073] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-07 15:37:56,074] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-07 15:37:56,627] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-07 15:37:57,146] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-07 15:37:57,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-07 15:37:57,147] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-07 15:37:58,080] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-07 15:38:01,072] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-08 15:19:23,477] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:19:23   other clients:
[2020-06-08 15:19:23,512] (INFO) VMService.vm_12.run():346 - ===> Error VM: ubuntu ID: 12 BHYVE_CODE: -11
[2020-06-08 15:19:23,523] (ERROR) VMService.vm_12.running():490 - ===> VMM ubuntu is running without bhyve process.
[2020-06-08 15:19:23,530] (DEBUG) VMService.vm_12.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 28331290880
[2020-06-08 15:19:23,530] (WARNING) VMService.vm_12.destroy_vm():353 - ===> Destroying VM: ubuntu ID: 12 BHYVE_CODE: -11
[2020-06-08 15:20:06,877] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-08 15:20:06,880] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-08 15:20:06,906] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 66898
[2020-06-08 15:20:06,907] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:06 Listening for VNC connections on TCP port 5586
[2020-06-08 15:20:06,907] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:06 Listening for VNC connections on TCP6 port 5586
[2020-06-08 15:20:13,164] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-08 15:20:13,165] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-08 15:20:13,808] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-08 15:20:13,808] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-08 15:20:14,033] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14   other clients:
[2020-06-08 15:20:14,036] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Client Protocol Version 3.8
[2020-06-08 15:20:14,036] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Protocol version sent 3.8, using 3.8
[2020-06-08 15:20:14,037] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientSecurityType: executing handler for type 1
[2020-06-08 15:20:14,037] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-06-08 15:20:14,040] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Pixel format for client 127.0.0.1:
[2020-06-08 15:20:14,040] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14   32 bpp, depth 24, little endian
[2020-06-08 15:20:14,041] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-06-08 15:20:14,041] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Using image quality level 6 for client 127.0.0.1
[2020-06-08 15:20:14,041] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-06-08 15:20:14,041] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Using compression level 2 for client 127.0.0.1
[2020-06-08 15:20:14,042] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-06-08 15:20:14,042] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Enabling LastRect protocol extension for client 127.0.0.1
[2020-06-08 15:20:14,042] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-06-08 15:20:14,042] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-06-08 15:20:14,042] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-06-08 15:20:14,043] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-06-08 15:20:14,043] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Enabling full-color cursor updates for client 127.0.0.1
[2020-06-08 15:20:14,043] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:20:14 Using tight encoding for client 127.0.0.1
[2020-06-08 15:20:14,311] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-08 15:20:14,818] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-08 15:20:14,819] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-08 15:20:14,819] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-08 15:20:15,330] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-08 15:20:18,302] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-08 15:30:38,206] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 keyboard command 0xf6
[2020-06-08 15:30:38,477] (DEBUG) VMService.vm_12.run():315 - ubuntu: fbuf frame buffer base: 0x842e00000 [sz 16777216]
[2020-06-08 15:30:38,536] (INFO) VMService.vm_12.run():333 - ===> Rebooting VM: ubuntu ID: 12 BHYVE_CODE: 0
[2020-06-08 15:30:38,548] (DEBUG) VMService.vm_12.restart():467 - ==> Reset VM: ubuntu ID: 12 BHYVE_CODE: 255
[2020-06-08 15:30:38,894] (DEBUG) VMService.vm_12.kill_bhyve_web():458 - ==> Killing WEBVNC: 66898
[2020-06-08 15:30:38,896] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-08 15:30:38,896] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-08 15:30:38,899] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-08 15:30:38,899] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-08 15:30:38,925] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 67205
[2020-06-08 15:30:38,925] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 67205
[2020-06-08 15:30:38,926] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:38 Listening for VNC connections on TCP port 5586
[2020-06-08 15:30:38,926] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:38 Listening for VNC connections on TCP port 5586
[2020-06-08 15:30:38,926] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:38 Listening for VNC connections on TCP6 port 5586
[2020-06-08 15:30:38,926] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:38 Listening for VNC connections on TCP6 port 5586
[2020-06-08 15:30:44,315] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-08 15:30:44,315] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-08 15:30:44,315] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-08 15:30:44,315] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-08 15:30:44,805] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-08 15:30:44,805] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-08 15:30:44,805] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-08 15:30:44,805] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-08 15:30:45,297] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-08 15:30:45,297] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-08 15:30:45,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-08 15:30:46,344] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-08 15:30:46,344] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-08 15:30:48,957] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-08 15:30:48,957] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-08 15:30:56,045] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   other clients:
[2020-06-08 15:30:56,045] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   other clients:
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Client Protocol Version 3.8
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Client Protocol Version 3.8
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Protocol version sent 3.8, using 3.8
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Protocol version sent 3.8, using 3.8
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientSecurityType: executing handler for type 1
[2020-06-08 15:30:56,047] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientSecurityType: executing handler for type 1
[2020-06-08 15:30:56,048] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-06-08 15:30:56,048] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Pixel format for client 127.0.0.1:
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Pixel format for client 127.0.0.1:
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   32 bpp, depth 24, little endian
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   32 bpp, depth 24, little endian
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using image quality level 6 for client 127.0.0.1
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using image quality level 6 for client 127.0.0.1
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-06-08 15:30:56,051] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using compression level 2 for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using compression level 2 for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling LastRect protocol extension for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling LastRect protocol extension for client 127.0.0.1
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-06-08 15:30:56,052] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling full-color cursor updates for client 127.0.0.1
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Enabling full-color cursor updates for client 127.0.0.1
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using tight encoding for client 127.0.0.1
[2020-06-08 15:30:56,053] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:30:56 Using tight encoding for client 127.0.0.1
[2020-06-08 15:33:15,967] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Client 127.0.0.1 gone
[2020-06-08 15:33:15,967] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Client 127.0.0.1 gone
[2020-06-08 15:33:15,967] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-06-08 15:33:15,967] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  FramebufferUpdate   :    674 |         0/        0 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  FramebufferUpdate   :    674 |         0/        0 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  LastRect            :    674 |      8088/     8088 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  LastRect            :    674 |      8088/     8088 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  tight               :   5943 |    680640/2120291988 (100.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  tight               :   5943 |    680640/2120291988 (100.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  TOTALS              :   7292 |    688983/2120300331 (100.0%)
[2020-06-08 15:33:15,968] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  TOTALS              :   7292 |    688983/2120300331 (100.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Statistics             events    Received/ RawEquiv ( saved)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15 Statistics             events    Received/ RawEquiv ( saved)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  PointerEvent        :    322 |      1932/     1932 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  PointerEvent        :    322 |      1932/     1932 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  FramebufferUpdate   :    675 |      6750/     6750 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  FramebufferUpdate   :    675 |      6750/     6750 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  TOTALS              :   1000 |      8778/     8778 (  0.0%)
[2020-06-08 15:33:15,969] (DEBUG) VMService.vm_12.run():315 - ubuntu: 08/06/2020 15:33:15  TOTALS              :   1000 |      8778/     8778 (  0.0%)
[2020-06-10 09:29:18,665] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-10 09:29:18,675] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-10 09:29:18,834] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 2249
[2020-06-10 09:29:18,834] (DEBUG) VMService.vm_12.run():315 - ubuntu: 10/06/2020 09:29:18 Listening for VNC connections on TCP port 5586
[2020-06-10 09:29:18,834] (DEBUG) VMService.vm_12.run():315 - ubuntu: 10/06/2020 09:29:18 Listening for VNC connections on TCP6 port 5586
[2020-06-10 09:29:25,970] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-10 09:29:25,971] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-10 09:29:26,854] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-10 09:29:26,855] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-10 09:29:27,523] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-10 09:29:28,035] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-10 09:29:28,036] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-10 09:29:28,036] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-10 09:29:28,545] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-10 09:29:31,899] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-06-10 18:36:49,863] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-06-10 18:36:49,872] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-06-10 18:36:49,940] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 2222
[2020-06-10 18:36:49,941] (DEBUG) VMService.vm_12.run():315 - ubuntu: 10/06/2020 18:36:49 Listening for VNC connections on TCP port 5586
[2020-06-10 18:36:49,941] (DEBUG) VMService.vm_12.run():315 - ubuntu: 10/06/2020 18:36:49 Listening for VNC connections on TCP6 port 5586
[2020-06-10 18:36:56,823] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-06-10 18:36:56,823] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-06-10 18:36:57,491] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-06-10 18:36:57,491] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-06-10 18:36:58,067] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-06-10 18:36:58,582] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-06-10 18:36:58,583] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-06-10 18:36:58,583] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-06-10 18:36:59,322] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-06-10 18:37:02,477] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
[2020-07-12 16:31:38,192] (DEBUG) VMService.vm_12.run():179 - ====> NIC_ATTACH: igb0
[2020-07-12 16:31:38,308] (DEBUG) VMService.vm_12.run():293 - Starting bhyve: bhyve -A -H -w -c 1 -m 1024 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm12A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:53:a4:6f -s 29,fbuf,vncserver,tcp=0.0.0.0:5586,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systank/ubuntu-6wrnx9 12_ubuntu
[2020-07-12 16:31:38,385] (DEBUG) VMService.vm_12.run():306 - ==> Start WEBVNC at port 5486 with pid number 2369
[2020-07-12 16:31:38,385] (DEBUG) VMService.vm_12.run():315 - ubuntu: 12/07/2020 16:31:38 Listening for VNC connections on TCP port 5586
[2020-07-12 16:31:38,386] (DEBUG) VMService.vm_12.run():315 - ubuntu: 12/07/2020 16:31:38 Listening for VNC connections on TCP6 port 5586
[2020-07-12 16:31:45,406] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x140 on vcpu 0
[2020-07-12 16:31:45,406] (DEBUG) VMService.vm_12.run():315 - ubuntu: wrmsr to register 0x140(0) on vcpu 0
[2020-07-12 16:31:46,160] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64e on vcpu 0
[2020-07-12 16:31:46,160] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x34 on vcpu 0
[2020-07-12 16:31:46,719] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0xe1
[2020-07-12 16:31:47,229] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x0a
[2020-07-12 16:31:47,230] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x01
[2020-07-12 16:31:47,230] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x41
[2020-07-12 16:31:47,751] (DEBUG) VMService.vm_12.run():315 - ubuntu: Unhandled ps2 mouse command 0x88
[2020-07-12 16:31:51,799] (DEBUG) VMService.vm_12.run():315 - ubuntu: rdmsr to register 0x64d on vcpu 0
