[2020-05-06 17:37:42,546] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:37:42,549] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:37:42,574] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36082
[2020-05-06 17:37:42,574] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:42 Listening for VNC connections on TCP port 6335
[2020-05-06 17:37:42,575] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:42 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:37:55,481] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55   other clients:
[2020-05-06 17:37:55,482] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Client Protocol Version 3.8
[2020-05-06 17:37:55,482] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Protocol version sent 3.8, using 3.8
[2020-05-06 17:37:55,483] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:37:55,483] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:37:55,502] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Pixel format for client 127.0.0.1:
[2020-05-06 17:37:55,502] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55   32 bpp, depth 24, little endian
[2020-05-06 17:37:55,502] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:37:55,503] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:37:55,504] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:37:55,504] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:37:55,504] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:37:55,504] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:37:55 Using tight encoding for client 127.0.0.1
[2020-05-06 17:39:15,790] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:39:15,792] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:39:16,128] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36082
[2020-05-06 17:39:16,131] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:39:16,144] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:39:16,144] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36082
[2020-05-06 17:39:16,146] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:39:16,146] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:39:16,149] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:39:16,149] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36167
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36167
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:16 Listening for VNC connections on TCP port 6335
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:16 Listening for VNC connections on TCP port 6335
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:16 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:39:16,190] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:16 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:39:18,492] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   other clients:
[2020-05-06 17:39:18,492] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   other clients:
[2020-05-06 17:39:18,494] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Client Protocol Version 3.8
[2020-05-06 17:39:18,494] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Client Protocol Version 3.8
[2020-05-06 17:39:18,494] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Protocol version sent 3.8, using 3.8
[2020-05-06 17:39:18,494] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Protocol version sent 3.8, using 3.8
[2020-05-06 17:39:18,498] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:39:18,498] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:39:18,498] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:39:18,498] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Pixel format for client 127.0.0.1:
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Pixel format for client 127.0.0.1:
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   32 bpp, depth 24, little endian
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   32 bpp, depth 24, little endian
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:39:18,505] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:39:18,506] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using tight encoding for client 127.0.0.1
[2020-05-06 17:39:18,507] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:39:18 Using tight encoding for client 127.0.0.1
[2020-05-06 17:40:30,846] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:40:30,846] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:40:30,886] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:40:30,886] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:40:30,896] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:40:30,896] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:40:31,230] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36167
[2020-05-06 17:40:31,230] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36167
[2020-05-06 17:40:31,232] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:40:31,232] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:40:31,232] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:40:31,235] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:40:31,235] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:40:31,235] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:40:31,260] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36239
[2020-05-06 17:40:31,260] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36239
[2020-05-06 17:40:31,260] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36239
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP port 6335
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP port 6335
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP port 6335
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:40:31,261] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:31 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:40:33,710] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   other clients:
[2020-05-06 17:40:33,710] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   other clients:
[2020-05-06 17:40:33,710] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   other clients:
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Client Protocol Version 3.8
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Client Protocol Version 3.8
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Client Protocol Version 3.8
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Protocol version sent 3.8, using 3.8
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Protocol version sent 3.8, using 3.8
[2020-05-06 17:40:33,711] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Protocol version sent 3.8, using 3.8
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:40:33,712] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Pixel format for client 127.0.0.1:
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Pixel format for client 127.0.0.1:
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Pixel format for client 127.0.0.1:
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   32 bpp, depth 24, little endian
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   32 bpp, depth 24, little endian
[2020-05-06 17:40:33,716] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   32 bpp, depth 24, little endian
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:40:33,717] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:40:33,718] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using tight encoding for client 127.0.0.1
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using tight encoding for client 127.0.0.1
[2020-05-06 17:40:33,719] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:40:33 Using tight encoding for client 127.0.0.1
[2020-05-06 17:41:18,393] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:41:18,393] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:41:18,393] (DEBUG) VMService.vm_6.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 17:41:18,429] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:41:18,429] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:41:18,429] (INFO) VMService.vm_6.run():333 - ===> Rebooting VM: RancherOS ID: 6 BHYVE_CODE: 0
[2020-05-06 17:41:18,441] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:41:18,441] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:41:18,441] (DEBUG) VMService.vm_6.restart():467 - ==> Reset VM: RancherOS ID: 6 BHYVE_CODE: 255
[2020-05-06 17:41:18,780] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36239
[2020-05-06 17:41:18,780] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36239
[2020-05-06 17:41:18,780] (DEBUG) VMService.vm_6.kill_bhyve_web():458 - ==> Killing WEBVNC: 36239
[2020-05-06 17:41:18,783] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:41:18,783] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:41:18,783] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:41:18,783] (DEBUG) VMService.vm_6.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 17:41:18,786] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:41:18,786] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:41:18,786] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:41:18,786] (DEBUG) VMService.vm_6.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm6A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:26:62:a9 -s 29,fbuf,vncserver,tcp=0.0.0.0:6335,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 6_RancherOS
[2020-05-06 17:41:18,810] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36277
[2020-05-06 17:41:18,810] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36277
[2020-05-06 17:41:18,810] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36277
[2020-05-06 17:41:18,810] (DEBUG) VMService.vm_6.run():306 - ==> Start WEBVNC at port 6235 with pid number 36277
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:41:18,811] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:18 Listening for VNC connections on TCP6 port 6335
[2020-05-06 17:41:20,304] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   other clients:
[2020-05-06 17:41:20,304] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   other clients:
[2020-05-06 17:41:20,304] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   other clients:
[2020-05-06 17:41:20,304] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   other clients:
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Client Protocol Version 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Client Protocol Version 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Client Protocol Version 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Client Protocol Version 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Protocol version sent 3.8, using 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Protocol version sent 3.8, using 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Protocol version sent 3.8, using 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Protocol version sent 3.8, using 3.8
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:41:20,306] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 17:41:20,307] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:41:20,307] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:41:20,307] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:41:20,307] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 17:41:20,309] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Pixel format for client 127.0.0.1:
[2020-05-06 17:41:20,309] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Pixel format for client 127.0.0.1:
[2020-05-06 17:41:20,309] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Pixel format for client 127.0.0.1:
[2020-05-06 17:41:20,309] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Pixel format for client 127.0.0.1:
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   32 bpp, depth 24, little endian
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   32 bpp, depth 24, little endian
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   32 bpp, depth 24, little endian
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   32 bpp, depth 24, little endian
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:41:20,310] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using image quality level 6 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:41:20,311] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using compression level 2 for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:41:20,312] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:41:20,313] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using tight encoding for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using tight encoding for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using tight encoding for client 127.0.0.1
[2020-05-06 17:41:20,314] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:41:20 Using tight encoding for client 127.0.0.1
[2020-05-06 17:43:29,556] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Client 127.0.0.1 gone
[2020-05-06 17:43:29,556] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Client 127.0.0.1 gone
[2020-05-06 17:43:29,556] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Client 127.0.0.1 gone
[2020-05-06 17:43:29,556] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Client 127.0.0.1 gone
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    149 |         0/        0 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    149 |         0/        0 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    149 |         0/        0 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    149 |         0/        0 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  LastRect            :    149 |      1788/     1788 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  LastRect            :    149 |      1788/     1788 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  LastRect            :    149 |      1788/     1788 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  LastRect            :    149 |      1788/     1788 (  0.0%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  tight               :   4260 |    236973/468764592 ( 99.9%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  tight               :   4260 |    236973/468764592 ( 99.9%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  tight               :   4260 |    236973/468764592 ( 99.9%)
[2020-05-06 17:43:29,557] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  tight               :   4260 |    236973/468764592 ( 99.9%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :   4559 |    239016/468766635 ( 99.9%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :   4559 |    239016/468766635 ( 99.9%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :   4559 |    239016/468766635 ( 99.9%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :   4559 |    239016/468766635 ( 99.9%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  KeyEvent            :    176 |      1408/     1408 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  KeyEvent            :    176 |      1408/     1408 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  KeyEvent            :    176 |      1408/     1408 (  0.0%)
[2020-05-06 17:43:29,558] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  KeyEvent            :    176 |      1408/     1408 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  PointerEvent        :     31 |       186/      186 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  PointerEvent        :     31 |       186/      186 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  PointerEvent        :     31 |       186/      186 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  PointerEvent        :     31 |       186/      186 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    150 |      1500/     1500 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    150 |      1500/     1500 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    150 |      1500/     1500 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  FramebufferUpdate   :    150 |      1500/     1500 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 17:43:29,559] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :    359 |      3182/     3182 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :    359 |      3182/     3182 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :    359 |      3182/     3182 (  0.0%)
[2020-05-06 17:43:29,560] (DEBUG) VMService.vm_6.run():315 - RancherOS: 06/05/2020 17:43:29  TOTALS              :    359 |      3182/     3182 (  0.0%)
[2020-05-06 17:43:45,161] (DEBUG) VMService.vm_6.stop():478 - ===> Soft Stop VM: RancherOS ID: 6
[2020-05-06 17:43:45,161] (DEBUG) VMService.vm_6.stop():478 - ===> Soft Stop VM: RancherOS ID: 6
[2020-05-06 17:43:45,161] (DEBUG) VMService.vm_6.stop():478 - ===> Soft Stop VM: RancherOS ID: 6
[2020-05-06 17:43:45,161] (DEBUG) VMService.vm_6.stop():478 - ===> Soft Stop VM: RancherOS ID: 6
[2020-05-06 17:43:45,522] (INFO) VMService.vm_6.run():346 - ===> Error VM: RancherOS ID: 6 BHYVE_CODE: -15
[2020-05-06 17:43:45,522] (INFO) VMService.vm_6.run():346 - ===> Error VM: RancherOS ID: 6 BHYVE_CODE: -15
[2020-05-06 17:43:45,522] (INFO) VMService.vm_6.run():346 - ===> Error VM: RancherOS ID: 6 BHYVE_CODE: -15
[2020-05-06 17:43:45,522] (INFO) VMService.vm_6.run():346 - ===> Error VM: RancherOS ID: 6 BHYVE_CODE: -15
[2020-05-06 17:43:45,536] (ERROR) VMService.vm_6.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 17:43:45,536] (ERROR) VMService.vm_6.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 17:43:45,536] (ERROR) VMService.vm_6.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 17:43:45,536] (ERROR) VMService.vm_6.running():490 - ===> VMM RancherOS is running without bhyve process.
