[2020-05-06 18:31:20,386] (DEBUG) VMService.vm_9.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 18:31:20,389] (DEBUG) VMService.vm_9.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm9A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:74:8b:a8 -s 29,fbuf,vncserver,tcp=0.0.0.0:5840,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros_uefi.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 9_RancherOS
[2020-05-06 18:31:20,410] (DEBUG) VMService.vm_9.run():306 - ==> Start WEBVNC at port 5740 with pid number 37821
[2020-05-06 18:31:20,411] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:20 Listening for VNC connections on TCP port 5840
[2020-05-06 18:31:20,411] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:20 Listening for VNC connections on TCP6 port 5840
[2020-05-06 18:31:38,191] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38   other clients:
[2020-05-06 18:31:38,192] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Client Protocol Version 3.8
[2020-05-06 18:31:38,192] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Protocol version sent 3.8, using 3.8
[2020-05-06 18:31:38,192] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 18:31:38,192] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 18:31:38,196] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Pixel format for client 127.0.0.1:
[2020-05-06 18:31:38,196] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38   32 bpp, depth 24, little endian
[2020-05-06 18:31:38,196] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 18:31:38,196] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Using image quality level 6 for client 127.0.0.1
[2020-05-06 18:31:38,196] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Using compression level 2 for client 127.0.0.1
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 18:31:38,197] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 18:31:38,198] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:31:38 Using tight encoding for client 127.0.0.1
[2020-05-06 18:32:10,287] (DEBUG) VMService.vm_9.run():315 - RancherOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 18:32:10,289] (INFO) VMService.vm_9.run():333 - ===> Rebooting VM: RancherOS ID: 9 BHYVE_CODE: 0
[2020-05-06 18:32:10,299] (DEBUG) VMService.vm_9.restart():467 - ==> Reset VM: RancherOS ID: 9 BHYVE_CODE: 255
[2020-05-06 18:32:10,639] (DEBUG) VMService.vm_9.kill_bhyve_web():458 - ==> Killing WEBVNC: 37821
[2020-05-06 18:32:10,641] (DEBUG) VMService.vm_9.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 18:32:10,641] (DEBUG) VMService.vm_9.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 18:32:10,654] (DEBUG) VMService.vm_9.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm9A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:74:8b:a8 -s 29,fbuf,vncserver,tcp=0.0.0.0:5840,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros_uefi.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 9_RancherOS
[2020-05-06 18:32:10,654] (DEBUG) VMService.vm_9.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm9A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap1,mac=00:a0:98:74:8b:a8 -s 29,fbuf,vncserver,tcp=0.0.0.0:5840,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/rancheros_uefi.iso,hd:/dev/zvol/systemtank/vm/RancherOS-y463pr 9_RancherOS
[2020-05-06 18:32:10,680] (DEBUG) VMService.vm_9.run():306 - ==> Start WEBVNC at port 5740 with pid number 37878
[2020-05-06 18:32:10,680] (DEBUG) VMService.vm_9.run():306 - ==> Start WEBVNC at port 5740 with pid number 37878
[2020-05-06 18:32:10,680] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:10 Listening for VNC connections on TCP port 5840
[2020-05-06 18:32:10,680] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:10 Listening for VNC connections on TCP port 5840
[2020-05-06 18:32:10,681] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:10 Listening for VNC connections on TCP6 port 5840
[2020-05-06 18:32:10,681] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:10 Listening for VNC connections on TCP6 port 5840
[2020-05-06 18:32:12,535] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   other clients:
[2020-05-06 18:32:12,535] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   other clients:
[2020-05-06 18:32:12,537] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Client Protocol Version 3.8
[2020-05-06 18:32:12,537] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Client Protocol Version 3.8
[2020-05-06 18:32:12,537] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Protocol version sent 3.8, using 3.8
[2020-05-06 18:32:12,537] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Protocol version sent 3.8, using 3.8
[2020-05-06 18:32:12,538] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 18:32:12,538] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 18:32:12,538] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 18:32:12,538] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 18:32:12,543] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Pixel format for client 127.0.0.1:
[2020-05-06 18:32:12,543] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Pixel format for client 127.0.0.1:
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   32 bpp, depth 24, little endian
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   32 bpp, depth 24, little endian
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using image quality level 6 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using image quality level 6 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using compression level 2 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using compression level 2 for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 18:32:12,544] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 18:32:12,545] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 18:32:12,546] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using tight encoding for client 127.0.0.1
[2020-05-06 18:32:12,546] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 18:32:12 Using tight encoding for client 127.0.0.1
[2020-05-06 21:06:02,181] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Client 127.0.0.1 gone
[2020-05-06 21:06:02,181] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Client 127.0.0.1 gone
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  FramebufferUpdate   :      6 |         0/        0 (  0.0%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  FramebufferUpdate   :      6 |         0/        0 (  0.0%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  LastRect            :      6 |        72/       72 (  0.0%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  LastRect            :      6 |        72/       72 (  0.0%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  tight               :    112 |     10942/ 18875712 ( 99.9%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  tight               :    112 |     10942/ 18875712 ( 99.9%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 21:06:02,182] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  TOTALS              :    125 |     11269/ 18876039 ( 99.9%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  TOTALS              :    125 |     11269/ 18876039 ( 99.9%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  PointerEvent        :    191 |      1146/     1146 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  PointerEvent        :    191 |      1146/     1146 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  FramebufferUpdate   :      7 |        70/       70 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  FramebufferUpdate   :      7 |        70/       70 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 21:06:02,183] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 21:06:02,184] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 21:06:02,184] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 21:06:02,184] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  TOTALS              :    201 |      1312/     1312 (  0.0%)
[2020-05-06 21:06:02,184] (DEBUG) VMService.vm_9.run():315 - RancherOS: 06/05/2020 21:06:02  TOTALS              :    201 |      1312/     1312 (  0.0%)
[2020-05-06 21:06:17,496] (DEBUG) VMService.vm_9.stop():478 - ===> Soft Stop VM: RancherOS ID: 9
[2020-05-06 21:06:17,496] (DEBUG) VMService.vm_9.stop():478 - ===> Soft Stop VM: RancherOS ID: 9
[2020-05-06 21:06:17,874] (INFO) VMService.vm_9.run():346 - ===> Error VM: RancherOS ID: 9 BHYVE_CODE: -15
[2020-05-06 21:06:17,874] (INFO) VMService.vm_9.run():346 - ===> Error VM: RancherOS ID: 9 BHYVE_CODE: -15
[2020-05-06 21:06:17,885] (ERROR) VMService.vm_9.running():490 - ===> VMM RancherOS is running without bhyve process.
[2020-05-06 21:06:17,885] (ERROR) VMService.vm_9.running():490 - ===> VMM RancherOS is running without bhyve process.
