[2020-04-20 00:09:05,973] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-20 00:09:05,976] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,e1000,tap2,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,ahci,cd:/mnt/rancheros-bhyve-v1.4.2.img,hd:/dev/zvol/systemtank/vm/DockerHost-zw3ta 5_DockerHost
[2020-04-20 00:09:06,000] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 57086
[2020-04-20 00:09:06,000] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:05 Listening for VNC connections on TCP port 6084
[2020-04-20 00:09:06,001] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:05 Listening for VNC connections on TCP6 port 6084
[2020-04-20 00:09:16,106] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16   other clients:
[2020-04-20 00:09:16,108] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Client Protocol Version 3.8
[2020-04-20 00:09:16,109] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Protocol version sent 3.8, using 3.8
[2020-04-20 00:09:16,112] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 00:09:16,112] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 00:09:16,227] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Pixel format for client 127.0.0.1:
[2020-04-20 00:09:16,228] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16   32 bpp, depth 24, little endian
[2020-04-20 00:09:16,228] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 00:09:16,333] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Using image quality level 6 for client 127.0.0.1
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Using compression level 2 for client 127.0.0.1
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 00:09:16,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 00:09:16,335] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 00:09:16,335] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 00:09:16,335] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 00:09:16,335] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:09:16 Using tight encoding for client 127.0.0.1
[2020-04-20 00:14:08,545] (DEBUG) VMService.vm_5.stop():478 - ===> Soft Stop VM: DockerHost ID: 5
[2020-04-20 00:14:08,965] (INFO) VMService.vm_5.run():346 - ===> Error VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-20 00:14:08,976] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 00:14:08,988] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 00:14:08,992] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 00:14:08,994] (DEBUG) VMService.vm_5.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 27173490688
[2020-04-20 00:14:08,994] (WARNING) VMService.vm_5.destroy_vm():353 - ===> Destroying VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-20 00:14:10,292] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-20 00:14:10,295] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap2,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta -s 3:1,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img 5_DockerHost
[2020-04-20 00:14:10,318] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 57297
[2020-04-20 00:14:10,319] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:10 Listening for VNC connections on TCP port 6084
[2020-04-20 00:14:10,319] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:10 Listening for VNC connections on TCP6 port 6084
[2020-04-20 00:14:23,518] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23   other clients:
[2020-04-20 00:14:23,521] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Client Protocol Version 3.8
[2020-04-20 00:14:23,522] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Protocol version sent 3.8, using 3.8
[2020-04-20 00:14:23,524] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 00:14:23,524] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 00:14:23,571] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Pixel format for client 127.0.0.1:
[2020-04-20 00:14:23,571] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23   32 bpp, depth 24, little endian
[2020-04-20 00:14:23,571] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 00:14:23,707] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Using image quality level 6 for client 127.0.0.1
[2020-04-20 00:14:23,707] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 00:14:23,707] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Using compression level 2 for client 127.0.0.1
[2020-04-20 00:14:23,707] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 00:14:23,707] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 00:14:23,708] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:23 Using tight encoding for client 127.0.0.1
[2020-04-20 00:14:41,764] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41 Client 127.0.0.1 gone
[2020-04-20 00:14:41,765] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 00:14:41,765] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 00:14:41,765] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 00:14:41,765] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  tight               :      8 |       428/  3145824 (100.0%)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  TOTALS              :     11 |       695/  3146091 (100.0%)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  PointerEvent        :     42 |       252/      252 (  0.0%)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 00:14:41,766] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 00:14:41,767] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 00:14:41,767] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:14:41  TOTALS              :     46 |       360/      360 (  0.0%)
[2020-04-20 00:14:50,264] (DEBUG) VMService.vm_5.stop():478 - ===> Soft Stop VM: DockerHost ID: 5
[2020-04-20 00:14:50,631] (INFO) VMService.vm_5.run():346 - ===> Error VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-20 00:14:50,649] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 00:14:50,656] (DEBUG) VMService.vm_5.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 27173490688
[2020-04-20 00:14:50,657] (WARNING) VMService.vm_5.destroy_vm():353 - ===> Destroying VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-20 00:15:56,308] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-20 00:15:56,310] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap2,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img -s 3:1,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta 5_DockerHost
[2020-04-20 00:15:56,334] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 57386
[2020-04-20 00:15:56,334] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:15:56 Listening for VNC connections on TCP port 6084
[2020-04-20 00:15:56,335] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:15:56 Listening for VNC connections on TCP6 port 6084
[2020-04-20 00:16:03,895] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03   other clients:
[2020-04-20 00:16:03,897] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03 Client Protocol Version 3.8
[2020-04-20 00:16:03,897] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03 Protocol version sent 3.8, using 3.8
[2020-04-20 00:16:03,898] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 00:16:03,898] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 00:16:03,966] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03 Pixel format for client 127.0.0.1:
[2020-04-20 00:16:03,966] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03   32 bpp, depth 24, little endian
[2020-04-20 00:16:03,966] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:03   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 00:16:04,066] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Using image quality level 6 for client 127.0.0.1
[2020-04-20 00:16:04,066] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 00:16:04,066] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Using compression level 2 for client 127.0.0.1
[2020-04-20 00:16:04,066] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 00:16:04,067] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:04 Using tight encoding for client 127.0.0.1
[2020-04-20 00:16:31,735] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31 Client 127.0.0.1 gone
[2020-04-20 00:16:31,735] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 00:16:31,736] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 00:16:31,736] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 00:16:31,736] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  tight               :      8 |       428/  3145824 (100.0%)
[2020-04-20 00:16:31,736] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 00:16:31,736] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  TOTALS              :     11 |       695/  3146091 (100.0%)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  PointerEvent        :     71 |       426/      426 (  0.0%)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 00:16:31,737] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 00:16:31  TOTALS              :     75 |       534/      534 (  0.0%)
[2020-04-20 07:06:39,732] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-20 07:06:39,734] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap1,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img -s 3:1,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta 5_DockerHost
[2020-04-20 07:06:39,755] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 2241
[2020-04-20 07:06:39,755] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 07:06:39 Listening for VNC connections on TCP port 6084
[2020-04-20 07:06:39,756] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 07:06:39 Listening for VNC connections on TCP6 port 6084
[2020-04-20 08:20:06,841] (DEBUG) VMService.vm_5.run():315 - DockerHost: fbuf frame buffer base: 0x882e00000 [sz 16777216]
[2020-04-20 08:20:06,841] (DEBUG) VMService.vm_5.stop():474 - ===> Force Stop VM: DockerHost ID: 5 BHYVE_CODE: None
[2020-04-20 08:20:07,196] (INFO) VMService.vm_5.run():338 - ===> Powered off VM: DockerHost ID: 5 BHYVE_CODE: 1
[2020-04-20 08:20:07,210] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 08:20:07,216] (DEBUG) VMService.vm_5.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 31468457984
[2020-04-20 08:20:07,217] (WARNING) VMService.vm_5.destroy_vm():353 - ===> Destroying VM: DockerHost ID: 5 BHYVE_CODE: 1
[2020-04-20 08:20:11,819] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-20 08:20:11,822] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap1,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta -s 3:1,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img 5_DockerHost
[2020-04-20 08:20:11,841] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 9026
[2020-04-20 08:20:11,843] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:11 Listening for VNC connections on TCP port 6084
[2020-04-20 08:20:11,843] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:11 Listening for VNC connections on TCP6 port 6084
[2020-04-20 08:20:26,714] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26   other clients:
[2020-04-20 08:20:26,715] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Client Protocol Version 3.8
[2020-04-20 08:20:26,716] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Protocol version sent 3.8, using 3.8
[2020-04-20 08:20:26,716] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 08:20:26,716] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 08:20:26,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Pixel format for client 127.0.0.1:
[2020-04-20 08:20:26,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26   32 bpp, depth 24, little endian
[2020-04-20 08:20:26,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 08:20:26,832] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Using image quality level 6 for client 127.0.0.1
[2020-04-20 08:20:26,832] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 08:20:26,832] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Using compression level 2 for client 127.0.0.1
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 08:20:26,833] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 08:20:26,834] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 08:20:26,834] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:20:26 Using tight encoding for client 127.0.0.1
[2020-04-20 08:21:09,040] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09 Client 127.0.0.1 gone
[2020-04-20 08:21:09,040] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 08:21:09,040] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 08:21:09,040] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  tight               :      8 |       428/  3145824 (100.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  TOTALS              :     11 |       695/  3146091 (100.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  PointerEvent        :    179 |      1074/     1074 (  0.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 08:21:09,041] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 08:21:09,042] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 08:21:09  TOTALS              :    183 |      1182/     1182 (  0.0%)
[2020-04-20 13:07:07,433] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   other clients:
[2020-04-20 13:07:07,440] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Client Protocol Version 3.8
[2020-04-20 13:07:07,440] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Protocol version sent 3.8, using 3.8
[2020-04-20 13:07:07,441] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 13:07:07,441] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 13:07:07,456] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Pixel format for client 127.0.0.1:
[2020-04-20 13:07:07,456] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   32 bpp, depth 24, little endian
[2020-04-20 13:07:07,456] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 13:07:07,555] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using image quality level 6 for client 127.0.0.1
[2020-04-20 13:07:07,555] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using compression level 2 for client 127.0.0.1
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 13:07:07,556] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using tight encoding for client 127.0.0.1
[2020-04-20 13:07:07,576] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   other clients:
[2020-04-20 13:07:07,576] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,577] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   other clients:
[2020-04-20 13:07:07,577] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,577] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,590] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   other clients:
[2020-04-20 13:07:07,591] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,591] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,591] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,592] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Client Protocol Version 3.8
[2020-04-20 13:07:07,592] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Protocol version sent 3.8, using 3.8
[2020-04-20 13:07:07,592] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Client Protocol Version 3.8
[2020-04-20 13:07:07,592] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Protocol version sent 3.8, using 3.8
[2020-04-20 13:07:07,593] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Client Protocol Version 3.8
[2020-04-20 13:07:07,593] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Protocol version sent 3.8, using 3.8
[2020-04-20 13:07:07,593] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 13:07:07,593] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 13:07:07,594] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 13:07:07,594] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 13:07:07,594] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 13:07:07,594] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 13:07:07,595] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   other clients:
[2020-04-20 13:07:07,595] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,595] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,595] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,595] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07      127.0.0.1
[2020-04-20 13:07:07,596] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Client Protocol Version 3.8
[2020-04-20 13:07:07,596] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Protocol version sent 3.8, using 3.8
[2020-04-20 13:07:07,596] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: executing handler for type 1
[2020-04-20 13:07:07,596] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientSecurityType: returning securityResult for client rfb version >= 3.8
[2020-04-20 13:07:07,616] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Pixel format for client 127.0.0.1:
[2020-04-20 13:07:07,616] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   32 bpp, depth 24, little endian
[2020-04-20 13:07:07,616] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 13:07:07,616] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Pixel format for client 127.0.0.1:
[2020-04-20 13:07:07,616] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   32 bpp, depth 24, little endian
[2020-04-20 13:07:07,617] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 13:07:07,626] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Pixel format for client 127.0.0.1:
[2020-04-20 13:07:07,626] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   32 bpp, depth 24, little endian
[2020-04-20 13:07:07,627] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 13:07:07,629] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Pixel format for client 127.0.0.1:
[2020-04-20 13:07:07,629] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   32 bpp, depth 24, little endian
[2020-04-20 13:07:07,629] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07   true colour: max r 255 g 255 b 255, shift r 16 g 8 b 0
[2020-04-20 13:07:07,718] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using image quality level 6 for client 127.0.0.1
[2020-04-20 13:07:07,719] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 13:07:07,719] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using compression level 2 for client 127.0.0.1
[2020-04-20 13:07:07,719] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,719] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,719] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using image quality level 6 for client 127.0.0.1
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using compression level 2 for client 127.0.0.1
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 13:07:07,720] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 13:07:07,721] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 13:07:07,722] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 13:07:07,722] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 13:07:07,722] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using tight encoding for client 127.0.0.1
[2020-04-20 13:07:07,722] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using tight encoding for client 127.0.0.1
[2020-04-20 13:07:07,726] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using image quality level 6 for client 127.0.0.1
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using compression level 2 for client 127.0.0.1
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 13:07:07,727] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using tight encoding for client 127.0.0.1
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using image quality level 6 for client 127.0.0.1
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using JPEG subsampling 0, Q79 for client 127.0.0.1
[2020-04-20 13:07:07,728] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using compression level 2 for client 127.0.0.1
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling NewFBSize protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling LastRect protocol extension for client 127.0.0.1
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEFE)
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFECC)
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC8)
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 rfbProcessClientNormalMessage: ignoring unsupported encoding type Enc(0xFFFFFEC7)
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Enabling full-color cursor updates for client 127.0.0.1
[2020-04-20 13:07:07,729] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:07 Using tight encoding for client 127.0.0.1
[2020-04-20 13:07:11,021] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11 Client 127.0.0.1 gone
[2020-04-20 13:07:11,022] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 13:07:11,022] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 13:07:11,022] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 13:07:11,022] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  tight               :     22 |      3742/  3145992 ( 99.9%)
[2020-04-20 13:07:11,023] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 13:07:11,023] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  TOTALS              :     25 |      4009/  3146259 ( 99.9%)
[2020-04-20 13:07:11,023] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 13:07:11,023] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 13:07:11,023] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 13:07:11,024] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 13:07:11,024] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:11  TOTALS              :      4 |       108/      108 (  0.0%)
[2020-04-20 13:07:12,636] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12 Client 127.0.0.1 gone
[2020-04-20 13:07:12,636] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 13:07:12,636] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 13:07:12,636] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  tight               :     22 |      3742/  3145992 ( 99.9%)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  TOTALS              :     25 |      4009/  3146259 ( 99.9%)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 13:07:12,637] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 13:07:12,638] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 13:07:12,638] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:12  TOTALS              :      4 |       108/      108 (  0.0%)
[2020-04-20 13:07:13,957] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13 Client 127.0.0.1 gone
[2020-04-20 13:07:13,957] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  tight               :     22 |      3742/  3145992 ( 99.9%)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  TOTALS              :     25 |      4009/  3146259 ( 99.9%)
[2020-04-20 13:07:13,958] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 13:07:13,959] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 13:07:13,959] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 13:07:13,959] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 13:07:13,959] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:13  TOTALS              :      4 |       108/      108 (  0.0%)
[2020-04-20 13:07:15,498] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15 Client 127.0.0.1 gone
[2020-04-20 13:07:15,498] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15 Statistics             events    Transmit/ RawEquiv ( saved)
[2020-04-20 13:07:15,498] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  FramebufferUpdate   :      1 |         0/        0 (  0.0%)
[2020-04-20 13:07:15,499] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  LastRect            :      1 |        12/       12 (  0.0%)
[2020-04-20 13:07:15,499] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  tight               :     22 |      3742/  3145992 ( 99.9%)
[2020-04-20 13:07:15,499] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  RichCursor          :      1 |       255/      255 (  0.0%)
[2020-04-20 13:07:15,499] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  TOTALS              :     25 |      4009/  3146259 ( 99.9%)
[2020-04-20 13:07:15,499] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15 Statistics             events    Received/ RawEquiv ( saved)
[2020-04-20 13:07:15,500] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  FramebufferUpdate   :      2 |        20/       20 (  0.0%)
[2020-04-20 13:07:15,500] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  SetEncodings        :      1 |        68/       68 (  0.0%)
[2020-04-20 13:07:15,500] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  SetPixelFormat      :      1 |        20/       20 (  0.0%)
[2020-04-20 13:07:15,500] (DEBUG) VMService.vm_5.run():315 - DockerHost: 20/04/2020 13:07:15  TOTALS              :      4 |       108/      108 (  0.0%)
[2020-04-20 13:07:35,068] (DEBUG) VMService.vm_5.stop():478 - ===> Soft Stop VM: DockerHost ID: 5
[2020-04-20 13:07:35,423] (INFO) VMService.vm_5.run():346 - ===> Error VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-20 13:07:35,439] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-04-20 13:07:35,446] (DEBUG) VMService.vm_5.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 31468457984
[2020-04-20 13:07:35,448] (WARNING) VMService.vm_5.destroy_vm():353 - ===> Destroying VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-04-23 15:20:36,324] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-23 15:20:36,327] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap1,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta -s 3:1,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img 5_DockerHost
[2020-04-23 15:20:36,348] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 2261
[2020-04-23 15:20:36,349] (DEBUG) VMService.vm_5.run():315 - DockerHost: 23/04/2020 15:20:36 Listening for VNC connections on TCP port 6084
[2020-04-23 15:20:36,349] (DEBUG) VMService.vm_5.run():315 - DockerHost: 23/04/2020 15:20:36 Listening for VNC connections on TCP6 port 6084
[2020-04-28 17:00:34,518] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-04-28 17:00:34,575] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap1,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta -s 3:1,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img 5_DockerHost
[2020-04-28 17:00:34,666] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 2095
[2020-04-28 17:00:34,666] (DEBUG) VMService.vm_5.run():315 - DockerHost: 28/04/2020 17:00:34 Listening for VNC connections on TCP port 6084
[2020-04-28 17:00:34,666] (DEBUG) VMService.vm_5.run():315 - DockerHost: 28/04/2020 17:00:34 Listening for VNC connections on TCP6 port 6084
[2020-05-06 10:06:12,237] (DEBUG) VMService.vm_5.run():179 - ====> NIC_ATTACH: igb0
[2020-05-06 10:06:12,245] (DEBUG) VMService.vm_5.run():293 - Starting bhyve: bhyve -A -H -w -c 2 -m 2048 -s 0:0,hostbridge -s 31,lpc -l com1,/dev/nmdm5A -l bootrom,/usr/local/share/uefi-firmware/BHYVE_UEFI.fd -s 4,virtio-net,tap1,mac=00:a0:98:53:cd:26 -s 29,fbuf,vncserver,tcp=0.0.0.0:6084,w=1024,h=768,, -s 30,xhci,tablet -s 3:0,virtio-blk,/dev/zvol/systemtank/vm/DockerHost-zw3ta -s 3:1,virtio-blk,/mnt/systemtank/vm/rancheros-bhyve-v1.4.2.img 5_DockerHost
[2020-05-06 10:06:12,776] (DEBUG) VMService.vm_5.run():306 - ==> Start WEBVNC at port 5984 with pid number 2248
[2020-05-06 10:06:12,776] (DEBUG) VMService.vm_5.run():315 - DockerHost: 06/05/2020 10:06:12 Listening for VNC connections on TCP port 6084
[2020-05-06 10:06:12,776] (DEBUG) VMService.vm_5.run():315 - DockerHost: 06/05/2020 10:06:12 Listening for VNC connections on TCP6 port 6084
[2020-05-06 16:58:45,291] (DEBUG) VMService.vm_5.stop():478 - ===> Soft Stop VM: DockerHost ID: 5
[2020-05-06 16:58:45,682] (INFO) VMService.vm_5.run():346 - ===> Error VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-05-06 16:58:45,694] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-05-06 16:58:45,725] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-05-06 16:58:45,736] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
[2020-05-06 16:58:45,757] (DEBUG) VMService.vm_5.__teardown_guest_vmemory():376 - ===> Give back guest memory to ARC: 29941903616
[2020-05-06 16:58:45,758] (WARNING) VMService.vm_5.destroy_vm():353 - ===> Destroying VM: DockerHost ID: 5 BHYVE_CODE: -15
[2020-05-06 16:58:45,767] (ERROR) VMService.vm_5.running():490 - ===> VMM DockerHost is running without bhyve process.
