[2020-05-06 21:04:14,071] (DEBUG) VMService.vm_10.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 21:04:14,074] (DEBUG) VMService.vm_10.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm10A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:63:ae:75 -s 29,fbuf,vncserver,tcp=0.0.0.0:5671,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/coreos_production_iso_image.iso,hd:/dev/zvol/systemtank/CoreOS-nsmx7k 10_CoreOS
[2020-05-06 21:04:14,098] (DEBUG) VMService.vm_10.run():306 - ==> Start WEBVNC at port 5571 with pid number 42126
[2020-05-06 21:04:14,099] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:14 Listening for VNC connections on TCP port 5671
[2020-05-06 21:04:14,099] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:14 Listening for VNC connections on TCP6 port 5671
[2020-05-06 21:04:22,533] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22   other clients:
[2020-05-06 21:04:22,534] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Client Protocol Version 3.8
[2020-05-06 21:04:22,534] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Protocol version sent 3.8, using 3.8
[2020-05-06 21:04:22,535] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 21:04:22,535] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 21:04:22,538] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Pixel format for client 127.0.0.1:
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22   32 bpp, depth 24, little endian
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Using image quality level 6 for client 127.0.0.1
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Using compression level 2 for client 127.0.0.1
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 21:04:22,539] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 21:04:22,540] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 21:04:22,540] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 21:04:22,540] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 21:04:22,540] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 21:04:22,540] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:04:22 Using tight encoding for client 127.0.0.1
[2020-05-06 21:05:51,070] (DEBUG) VMService.vm_10.run():315 - CoreOS: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-05-06 21:05:51,092] (INFO) VMService.vm_10.run():333 - ===> Rebooting VM: CoreOS ID: 10 BHYVE_CODE: 0
[2020-05-06 21:05:51,103] (DEBUG) VMService.vm_10.restart():467 - ==> Reset VM: CoreOS ID: 10 BHYVE_CODE: 255
[2020-05-06 21:05:51,438] (DEBUG) VMService.vm_10.kill_bhyve_web():458 - ==> Killing WEBVNC: 42126
[2020-05-06 21:05:51,440] (DEBUG) VMService.vm_10.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 21:05:51,440] (DEBUG) VMService.vm_10.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 21:05:51,443] (DEBUG) VMService.vm_10.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm10A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:63:ae:75 -s 29,fbuf,vncserver,tcp=0.0.0.0:5671,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/coreos_production_iso_image.iso,hd:/dev/zvol/systemtank/CoreOS-nsmx7k 10_CoreOS
[2020-05-06 21:05:51,443] (DEBUG) VMService.vm_10.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm10A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:63:ae:75 -s 29,fbuf,vncserver,tcp=0.0.0.0:5671,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/iohyve/ISO/coreos_production_iso_image.iso,hd:/dev/zvol/systemtank/CoreOS-nsmx7k 10_CoreOS
[2020-05-06 21:05:51,468] (DEBUG) VMService.vm_10.run():306 - ==> Start WEBVNC at port 5571 with pid number 42225
[2020-05-06 21:05:51,468] (DEBUG) VMService.vm_10.run():306 - ==> Start WEBVNC at port 5571 with pid number 42225
[2020-05-06 21:05:51,468] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:51 Listening for VNC connections on TCP port 5671
[2020-05-06 21:05:51,468] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:51 Listening for VNC connections on TCP port 5671
[2020-05-06 21:05:51,469] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:51 Listening for VNC connections on TCP6 port 5671
[2020-05-06 21:05:51,469] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:51 Listening for VNC connections on TCP6 port 5671
[2020-05-06 21:05:53,803] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   other clients:
[2020-05-06 21:05:53,803] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   other clients:
[2020-05-06 21:05:53,804] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Client Protocol Version 3.8
[2020-05-06 21:05:53,804] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Client Protocol Version 3.8
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Protocol version sent 3.8, using 3.8
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Protocol version sent 3.8, using 3.8
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientSecurityType: executing handler for type 1
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 21:05:53,805] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Pixel format for client 127.0.0.1:
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Pixel format for client 127.0.0.1:
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   32 bpp, depth 24, little endian
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   32 bpp, depth 24, little endian
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using image quality level 6 for client 127.0.0.1
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using image quality level 6 for client 127.0.0.1
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 21:05:53,810] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using compression level 2 for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using compression level 2 for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling LastRect protocol extension for client 127.0.0.1
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 21:05:53,811] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Enabling full-color cursor updates for client 127.0.0.1
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using tight encoding for client 127.0.0.1
[2020-05-06 21:05:53,812] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:05:53 Using tight encoding for client 127.0.0.1
[2020-05-06 21:06:01,165] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Client 127.0.0.1 gone
[2020-05-06 21:06:01,165] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Client 127.0.0.1 gone
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  FramebufferUpdate   :      5 |         0/        0 (  0.0%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  FramebufferUpdate   :      5 |         0/        0 (  0.0%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  LastRect            :      5 |        60/       60 (  0.0%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  LastRect            :      5 |        60/       60 (  0.0%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  tight               :    119 |     10887/ 15730068 ( 99.9%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  tight               :    119 |     10887/ 15730068 ( 99.9%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 21:06:01,166] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  TOTALS              :    130 |     11202/ 15730383 ( 99.9%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  TOTALS              :    130 |     11202/ 15730383 ( 99.9%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01 Statistics             events    Received/ RawEquiv ( saved)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  KeyEvent            :      1 |         8/        8 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  PointerEvent        :     30 |       180/      180 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  PointerEvent        :     30 |       180/      180 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  FramebufferUpdate   :      6 |        60/       60 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  FramebufferUpdate   :      6 |        60/       60 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 21:06:01,167] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-05-06 21:06:01,168] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 21:06:01,168] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-05-06 21:06:01,168] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  TOTALS              :     39 |       336/      336 (  0.0%)
[2020-05-06 21:06:01,168] (DEBUG) VMService.vm_10.run():315 - CoreOS: 06/05/2020 21:06:01  TOTALS              :     39 |       336/      336 (  0.0%)
[2020-05-06 21:06:06,746] (DEBUG) VMService.vm_10.stop():478 - ===> Soft Stop VM: CoreOS ID: 10
[2020-05-06 21:06:06,746] (DEBUG) VMService.vm_10.stop():478 - ===> Soft Stop VM: CoreOS ID: 10
[2020-05-06 21:06:07,104] (INFO) VMService.vm_10.run():346 - ===> Error VM: CoreOS ID: 10 BHYVE_CODE: -15
[2020-05-06 21:06:07,104] (INFO) VMService.vm_10.run():346 - ===> Error VM: CoreOS ID: 10 BHYVE_CODE: -15
[2020-05-06 21:06:07,115] (ERROR) VMService.vm_10.running():490 - ===> VMM CoreOS is running without bhyve process.
[2020-05-06 21:06:07,115] (ERROR) VMService.vm_10.running():490 - ===> VMM CoreOS is running without bhyve process.
