crc: docs: Document that nested virtualization is unsupported

db2oltp@ubuntu:/crc/crc-linux-1.23.1-amd64$ more /home/db2oltp/.crc/crc.log
time="2021-03-09T10:32:01-08:00" level=debug msg="CodeReady Containers version: 1.23.1+be17b141\n"
time="2021-03-09T10:32:01-08:00" level=debug msg="OpenShift version: 4.7.0 (embedded in executable)\n"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running 'crc cleanup'"
time="2021-03-09T10:32:01-08:00" level=info msg="Removing vsock configuration"
time="2021-03-09T10:32:01-08:00" level=info msg="Removing 'crc' network from libvirt"
time="2021-03-09T10:32:01-08:00" level=debug msg="Removing libvirt 'crc' network"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-destroy crc'"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-undefine crc'"
time="2021-03-09T10:32:01-08:00" level=debug msg="libvirt 'crc' network removed"
time="2021-03-09T10:32:01-08:00" level=info msg="Removing /etc/NetworkManager/dispatcher.d/99-crc.sh file"
time="2021-03-09T10:32:01-08:00" level=debug msg="Checking if 'nmcli' is available"
time="2021-03-09T10:32:01-08:00" level=debug msg="'nmcli' was found in /usr/bin/nmcli"
time="2021-03-09T10:32:01-08:00" level=debug msg="Checking if 'nmcli' is available"
time="2021-03-09T10:32:01-08:00" level=debug msg="'nmcli' was found in /usr/bin/nmcli"
time="2021-03-09T10:32:01-08:00" level=debug msg="Removing NetworkManager configuration file: /etc/NetworkManager/dispatcher.d/99-crc.sh"
time="2021-03-09T10:32:01-08:00" level=info msg="Using root access: Removing NetworkManager configuration file in /etc/NetworkManager/dispatcher.d/99-crc.sh"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running '/usr/bin/sudo rm -fr /etc/NetworkManager/dispatcher.d/99-crc.sh'"
time="2021-03-09T10:32:01-08:00" level=debug msg="Reloading NetworkManager"
time="2021-03-09T10:32:01-08:00" level=info msg="Using root access: Executing systemctl daemon-reload command"
time="2021-03-09T10:32:01-08:00" level=debug msg="Running '/usr/bin/sudo systemctl daemon-reload'"
time="2021-03-09T10:32:02-08:00" level=info msg="Using root access: Executing systemctl reload NetworkManager"
time="2021-03-09T10:32:02-08:00" level=debug msg="Running '/usr/bin/sudo systemctl reload NetworkManager'"
time="2021-03-09T10:32:02-08:00" level=info msg="Cleaning up AppArmor configuration"
time="2021-03-09T10:32:02-08:00" level=info msg="Using root access: Updating AppArmor configuration"
time="2021-03-09T10:32:02-08:00" level=info msg="Using root access: Changing permissions for /etc/apparmor.d/libvirt/TEMPLATE.qemu to 644 "
time="2021-03-09T10:32:02-08:00" level=debug msg="Running '/usr/bin/sudo chmod 644 /etc/apparmor.d/libvirt/TEMPLATE.qemu'"
time="2021-03-09T10:32:02-08:00" level=info msg="Removing the crc VM if exists"
time="2021-03-09T10:32:02-08:00" level=debug msg="Running 'virsh --connect qemu:///system domstate crc'"
time="2021-03-09T10:32:02-08:00" level=debug msg="Command failed: exit status 1"
time="2021-03-09T10:32:02-08:00" level=debug msg="stdout: \n"
time="2021-03-09T10:32:02-08:00" level=debug msg="stderr: error: failed to get domain 'crc'\n"
time="2021-03-09T10:32:02-08:00" level=info msg="Removing pull secret from the keyring"
time="2021-03-09T10:32:02-08:00" level=info msg="Removing older logs"
time="2021-03-09T10:32:02-08:00" level=debug msg="Deleting /home/db2oltp/.crc/crc.log_20210309103153 log file"
time="2021-03-09T10:32:02-08:00" level=info msg="Removing CRC Machine Instance directory"
time="2021-03-09T10:32:02-08:00" level=debug msg="Deleting machines directory"
time="2021-03-09T10:32:02-08:00" level=info msg="Removing hosts file records added by CRC"
time="2021-03-09T10:32:02-08:00" level=debug msg="Running '/home/db2oltp/.crc/bin/admin-helper-linux clean .crc.testing .apps-crc.testing'"
time="2021-03-09T10:32:11-08:00" level=debug msg="CodeReady Containers version: 1.23.1+be17b141\n"
time="2021-03-09T10:32:11-08:00" level=debug msg="OpenShift version: 4.7.0 (embedded in executable)\n"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'crc setup'"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if running as non-root"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if podman remote executable is cached"
time="2021-03-09T10:32:11-08:00" level=debug msg="Currently podman remote is not supported"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if admin-helper executable is cached"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/home/db2oltp/.crc/bin/admin-helper-linux --version'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Found admin-helper-linux version 0.0.2"
time="2021-03-09T10:32:11-08:00" level=debug msg="admin-helper executable already cached"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking minimum RAM requirements"
time="2021-03-09T10:32:11-08:00" level=debug msg="Total memory of system is 36236324864 bytes"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if Virtualization is enabled"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if the vmx/svm flags are present in /proc/cpuinfo"
time="2021-03-09T10:32:11-08:00" level=debug msg="CPU virtualization flags are good"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if KVM is enabled"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if /dev/kvm exists"
time="2021-03-09T10:32:11-08:00" level=debug msg="/dev/kvm was found"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if libvirt is installed"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if 'virsh' is available"
time="2021-03-09T10:32:11-08:00" level=debug msg="'virsh' was found in /usr/bin/virsh"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking 'virsh capabilities' for libvirtd/qemu availability"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'virsh capabilities'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Found x86_64 hypervisor with 'hvm' capabilities"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if user is part of libvirt group"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if current user is part of the libvirt group"
time="2021-03-09T10:32:11-08:00" level=debug msg="Current user is already in the libvirt group"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if libvirt daemon is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if libvirtd service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'systemctl status virtqemud.socket'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Command failed: exit status 4"
time="2021-03-09T10:32:11-08:00" level=debug msg="stdout: "
time="2021-03-09T10:32:11-08:00" level=debug msg="stderr: Unit virtqemud.socket could not be found.\n"
time="2021-03-09T10:32:11-08:00" level=debug msg="virtqemud.socket is neither running nor listening"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'systemctl status libvirtd.socket'"
time="2021-03-09T10:32:11-08:00" level=debug msg="libvirtd.socket is running"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if a supported libvirt version is installed"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if libvirt version is >=3.4.0"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'virsh -v'"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if crc-driver-libvirt is installed"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if crc-driver-libvirt is installed"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/home/db2oltp/.crc/bin/crc-driver-libvirt version'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Found crc-driver-libvirt version 0.12.14"
time="2021-03-09T10:32:11-08:00" level=debug msg="crc-driver-libvirt is already installed"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if AppArmor is configured"
time="2021-03-09T10:32:11-08:00" level=debug msg="AppArmor profile not configured"
time="2021-03-09T10:32:11-08:00" level=info msg="Updating AppArmor configuration"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Updating AppArmor configuration"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Changing permissions for /etc/apparmor.d/libvirt/TEMPLATE.qemu to 644 "
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/usr/bin/sudo chmod 644 /etc/apparmor.d/libvirt/TEMPLATE.qemu'"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if systemd-networkd is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if systemd-networkd.service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'systemctl status systemd-networkd.service'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Command failed: exit status 3"
time="2021-03-09T10:32:11-08:00" level=debug msg="stdout: * systemd-networkd.service - Network Service\n     Loaded: loaded (/lib/systemd/system/systemd-networkd.service; disabled; vendor preset: ena
bled)\n     Active: inactive (dead)\nTriggeredBy: * systemd-networkd.socket\n       Docs: man:systemd-networkd.service(8)\n"
time="2021-03-09T10:32:11-08:00" level=debug msg="stderr: "
time="2021-03-09T10:32:11-08:00" level=debug msg="systemd-networkd.service is not running"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if NetworkManager is installed"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if 'nmcli' is available"
time="2021-03-09T10:32:11-08:00" level=debug msg="'nmcli' was found in /usr/bin/nmcli"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if NetworkManager service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if NetworkManager.service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'systemctl status NetworkManager.service'"
time="2021-03-09T10:32:11-08:00" level=debug msg="NetworkManager.service is already running"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if dnsmasq configurations file exist for NetworkManager"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if the systemd-resolved service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if systemd-resolved.service is running"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running 'systemctl status systemd-resolved.service'"
time="2021-03-09T10:32:11-08:00" level=debug msg="systemd-resolved.service is already running"
time="2021-03-09T10:32:11-08:00" level=info msg="Checking if /etc/NetworkManager/dispatcher.d/99-crc.sh exists"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking NetworkManager dispatcher file for crc network"
time="2021-03-09T10:32:11-08:00" level=debug msg="File not found: /etc/NetworkManager/dispatcher.d/99-crc.sh: stat /etc/NetworkManager/dispatcher.d/99-crc.sh: no such file or directory"
time="2021-03-09T10:32:11-08:00" level=info msg="Writing NetworkManager dispatcher file for crc"
time="2021-03-09T10:32:11-08:00" level=debug msg="Fixing NetworkManager dispatcher configuration"
time="2021-03-09T10:32:11-08:00" level=debug msg="Checking if 'nmcli' is available"
time="2021-03-09T10:32:11-08:00" level=debug msg="'nmcli' was found in /usr/bin/nmcli"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Writing NetworkManager configuration to /etc/NetworkManager/dispatcher.d/99-crc.sh"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Changing permissions for /etc/NetworkManager/dispatcher.d/99-crc.sh to 755 "
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/usr/bin/sudo chmod 755 /etc/NetworkManager/dispatcher.d/99-crc.sh'"
time="2021-03-09T10:32:11-08:00" level=debug msg="Reloading NetworkManager"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Executing systemctl daemon-reload command"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/usr/bin/sudo systemctl daemon-reload'"
time="2021-03-09T10:32:11-08:00" level=info msg="Using root access: Executing systemctl reload NetworkManager"
time="2021-03-09T10:32:11-08:00" level=debug msg="Running '/usr/bin/sudo systemctl reload NetworkManager'"
time="2021-03-09T10:32:12-08:00" level=debug msg="NetworkManager dispatcher configuration fixed"
time="2021-03-09T10:32:12-08:00" level=info msg="Checking if libvirt 'crc' network is available"
time="2021-03-09T10:32:12-08:00" level=debug msg="Checking if libvirt 'crc' network exists"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="Command failed: exit status 1"
time="2021-03-09T10:32:12-08:00" level=debug msg="stdout: \n"
time="2021-03-09T10:32:12-08:00" level=debug msg="stderr: error: failed to get network 'crc'\nerror: Network not found: no network with matching name 'crc'\n"
time="2021-03-09T10:32:12-08:00" level=debug msg="Libvirt network crc not found"
time="2021-03-09T10:32:12-08:00" level=info msg="Setting up libvirt 'crc' network"
time="2021-03-09T10:32:12-08:00" level=debug msg="Creating libvirt 'crc' network"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-destroy crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="Command failed: exit status 1"
time="2021-03-09T10:32:12-08:00" level=debug msg="stdout: \n"
time="2021-03-09T10:32:12-08:00" level=debug msg="stderr: error: failed to get network 'crc'\nerror: Network not found: no network with matching name 'crc'\n"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-undefine crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="Command failed: exit status 1"
time="2021-03-09T10:32:12-08:00" level=debug msg="stdout: \n"
time="2021-03-09T10:32:12-08:00" level=debug msg="stderr: error: failed to get network 'crc'\nerror: Network not found: no network with matching name 'crc'\n"
time="2021-03-09T10:32:12-08:00" level=debug msg="libvirt 'crc' network created"
time="2021-03-09T10:32:12-08:00" level=info msg="Checking if libvirt 'crc' network is active"
time="2021-03-09T10:32:12-08:00" level=debug msg="Checking if libvirt 'crc' network is active"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="Libvirt crc network is not active"
time="2021-03-09T10:32:12-08:00" level=info msg="Starting libvirt 'crc' network"
time="2021-03-09T10:32:12-08:00" level=debug msg="Starting libvirt 'crc' network"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-start crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-autostart crc'"
time="2021-03-09T10:32:12-08:00" level=debug msg="libvirt 'crc' network started"
time="2021-03-09T10:32:12-08:00" level=info msg="Checking if CRC bundle is extracted in '$HOME/.crc'"
time="2021-03-09T10:32:12-08:00" level=info msg="Checking if /home/db2oltp/.crc/cache/crc_libvirt_4.7.0.crcbundle exists"
time="2021-03-09T10:32:12-08:00" level=debug msg="/home/db2oltp/.crc/cache/crc_libvirt_4.7.0.crcbundle exists"
time="2021-03-09T10:32:20-08:00" level=debug msg="CodeReady Containers version: 1.23.1+be17b141\n"
time="2021-03-09T10:32:20-08:00" level=debug msg="OpenShift version: 4.7.0 (embedded in executable)\n"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'crc start'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Total memory of system is 36236324864 bytes"
time="2021-03-09T10:32:20-08:00" level=debug msg="No new version available. The latest version is 1.23.1"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if running as non-root"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if podman remote executable is cached"
time="2021-03-09T10:32:20-08:00" level=debug msg="Currently podman remote is not supported"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if admin-helper executable is cached"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running '/home/db2oltp/.crc/bin/admin-helper-linux --version'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Found admin-helper-linux version 0.0.2"
time="2021-03-09T10:32:20-08:00" level=debug msg="admin-helper executable already cached"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking minimum RAM requirements"
time="2021-03-09T10:32:20-08:00" level=debug msg="Total memory of system is 36236324864 bytes"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if Virtualization is enabled"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if the vmx/svm flags are present in /proc/cpuinfo"
time="2021-03-09T10:32:20-08:00" level=debug msg="CPU virtualization flags are good"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if KVM is enabled"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if /dev/kvm exists"
time="2021-03-09T10:32:20-08:00" level=debug msg="/dev/kvm was found"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if libvirt is installed"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if 'virsh' is available"
time="2021-03-09T10:32:20-08:00" level=debug msg="'virsh' was found in /usr/bin/virsh"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking 'virsh capabilities' for libvirtd/qemu availability"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'virsh capabilities'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Found x86_64 hypervisor with 'hvm' capabilities"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if user is part of libvirt group"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if current user is part of the libvirt group"
time="2021-03-09T10:32:20-08:00" level=debug msg="Current user is already in the libvirt group"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if libvirt daemon is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if libvirtd service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'systemctl status virtqemud.socket'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Command failed: exit status 4"
time="2021-03-09T10:32:20-08:00" level=debug msg="stdout: "
time="2021-03-09T10:32:20-08:00" level=debug msg="stderr: Unit virtqemud.socket could not be found.\n"
time="2021-03-09T10:32:20-08:00" level=debug msg="virtqemud.socket is neither running nor listening"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'systemctl status libvirtd.socket'"
time="2021-03-09T10:32:20-08:00" level=debug msg="libvirtd.socket is running"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if a supported libvirt version is installed"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if libvirt version is >=3.4.0"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'virsh -v'"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if crc-driver-libvirt is installed"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if crc-driver-libvirt is installed"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running '/home/db2oltp/.crc/bin/crc-driver-libvirt version'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Found crc-driver-libvirt version 0.12.14"
time="2021-03-09T10:32:20-08:00" level=debug msg="crc-driver-libvirt is already installed"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if AppArmor is configured"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if systemd-networkd is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if systemd-networkd.service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'systemctl status systemd-networkd.service'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Command failed: exit status 3"
time="2021-03-09T10:32:20-08:00" level=debug msg="stdout: * systemd-networkd.service - Network Service\n     Loaded: loaded (/lib/systemd/system/systemd-networkd.service; disabled; vendor preset: ena
bled)\n     Active: inactive (dead)\nTriggeredBy: * systemd-networkd.socket\n       Docs: man:systemd-networkd.service(8)\n"
time="2021-03-09T10:32:20-08:00" level=debug msg="stderr: "
time="2021-03-09T10:32:20-08:00" level=debug msg="systemd-networkd.service is not running"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if NetworkManager is installed"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if 'nmcli' is available"
time="2021-03-09T10:32:20-08:00" level=debug msg="'nmcli' was found in /usr/bin/nmcli"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if NetworkManager service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if NetworkManager.service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'systemctl status NetworkManager.service'"
time="2021-03-09T10:32:20-08:00" level=debug msg="NetworkManager.service is already running"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if dnsmasq configurations file exist for NetworkManager"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if the systemd-resolved service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if systemd-resolved.service is running"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'systemctl status systemd-resolved.service'"
time="2021-03-09T10:32:20-08:00" level=debug msg="systemd-resolved.service is already running"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if /etc/NetworkManager/dispatcher.d/99-crc.sh exists"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking NetworkManager dispatcher file for crc network"
time="2021-03-09T10:32:20-08:00" level=debug msg="Dispatcher file has the expected content"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if libvirt 'crc' network is available"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if libvirt 'crc' network exists"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if libvirt 'crc' definition is up to date"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-dumpxml --inactive crc'"
time="2021-03-09T10:32:20-08:00" level=debug msg="libvirt 'crc' network has the expected value"
time="2021-03-09T10:32:20-08:00" level=info msg="Checking if libvirt 'crc' network is active"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking if libvirt 'crc' network is active"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
time="2021-03-09T10:32:20-08:00" level=debug msg="libvirt 'crc' network is already active"
time="2021-03-09T10:32:20-08:00" level=debug msg="Checking file: /home/db2oltp/.crc/machines/crc/.crc-exist"
time="2021-03-09T10:32:20-08:00" level=debug msg="Using secret from configuration"
time="2021-03-09T10:32:20-08:00" level=info msg="Loading bundle: crc_libvirt_4.7.0.crcbundle ..."
time="2021-03-09T10:32:20-08:00" level=info msg="Creating CodeReady Containers VM for OpenShift 4.7.0..."
time="2021-03-09T10:32:20-08:00" level=debug msg="Found binary path at /home/db2oltp/.crc/bin/crc-driver-libvirt"
time="2021-03-09T10:32:20-08:00" level=debug msg="Launching plugin server for driver libvirt"
time="2021-03-09T10:32:20-08:00" level=debug msg="Plugin server listening at address 127.0.0.1:46879"
time="2021-03-09T10:32:20-08:00" level=debug msg="() Calling .GetVersion"
time="2021-03-09T10:32:20-08:00" level=debug msg="Using API Version 1"
time="2021-03-09T10:32:20-08:00" level=debug msg="() Calling .SetConfigRaw"
time="2021-03-09T10:32:20-08:00" level=debug msg="() Calling .GetMachineName"
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) Calling .GetMachineName"
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) Calling .DriverName"
time="2021-03-09T10:32:20-08:00" level=debug msg="Running pre-create checks..."
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) Calling .PreCreateCheck"
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"About to check libvirt version\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Validating network\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Validating storage pool\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) Calling .GetConfigRaw"
time="2021-03-09T10:32:20-08:00" level=debug msg="Creating machine..."
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) Calling .Create"
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Preparing /home/db2oltp/.crc/machines/crc/crc.qcow2 for machine use\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"image creation took 10.743084ms\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Verifying executable bit set on /home/db2oltp/.crc/machines/crc\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Setting executable bit set on /home/db2oltp/.crc/machines/crc\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Verifying executable bit set on /home/db2oltp/.crc/machines\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Setting executable bit set on /home/db2oltp/.crc/machines\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Verifying executable bit set on /home/db2oltp/.crc\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Verifying executable bit set on /home/db2oltp\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Verifying executable bit set on /home\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Defining VM...\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Found q35 machine type\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Adding the file: /home/db2oltp/.crc/machines/crc/.crc-exist\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"resizeDiskImage(85899345920)\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"resizing volume to 85899345920\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Starting VM crc\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Validating network\""
time="2021-03-09T10:32:20-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:20-08:00\" level=debug msg=\"Validating storage pool\""
time="2021-03-09T10:32:21-08:00" level=debug msg="(crc) DBG | time=\"2021-03-09T10:32:21-08:00\" level=warning msg=\"Failed to start: virError(Code=1, Domain=10, Message='internal error: process exit
ed while connecting to monitor: 2021-03-09T18:32:21.007888Z qemu-system-x86_64: error: failed to set MSR 0x48d to 0x5600000016\\nqemu-system-x86_64: /build/qemu-RSjBPs/qemu-5.0/target/i386/kvm.c:2695
: kvm_buf_set_msrs: Assertion `ret == cpu->kvm_msr_buf->nmsrs' failed.')\""
time="2021-03-09T10:32:21-08:00" level=debug msg="Making call to close driver server"
time="2021-03-09T10:32:21-08:00" level=debug msg="(crc) Calling .Close"
time="2021-03-09T10:32:21-08:00" level=debug msg="Successfully made call to close driver server"
time="2021-03-09T10:32:21-08:00" level=debug msg="Making call to close connection to plugin binary"