$ crc start --log-level debug DEBU CRC version: 2.6.0.999+2686dd6e DEBU OpenShift version: 4.11.0-0.okd-2022-07-29-154152 DEBU Podman version: 4.1.1 DEBU Running 'crc start' DEBU Total memory of system is 14139076608 bytes DEBU Unable to find out if a new version is available: Invalid Semantic Version DEBU Checking file: /home/anjan/.crc/machines/crc/.crc-exist DEBU Found binary path at /home/anjan/.crc/bin/crc-driver-libvirt DEBU Launching plugin server for driver libvirt DEBU Plugin server listening at address 127.0.0.1:40785 DEBU () Calling .GetVersion DEBU Using API Version 1 DEBU () Calling .SetConfigRaw DEBU () Calling .GetMachineName DEBU (crc) Calling .GetBundleName DEBU (crc) Calling .GetState DEBU (crc) DBG | time="2022-08-05T05:25:16-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:16-04:00" level=debug msg="Fetching VM..." DEBU Making call to close driver server DEBU (crc) Calling .Close DEBU Successfully made call to close driver server DEBU (crc) DBG | time="2022-08-05T05:25:16-04:00" level=debug msg="Closing plugin on server side" DEBU Making call to close connection to plugin binary DEBU Checking if systemd-resolved.service is running DEBU Running 'systemctl status systemd-resolved.service' DEBU Command failed: exit status 3 DEBU stdout: * systemd-resolved.service - Network Name Resolution Loaded: loaded (/usr/lib/systemd/system/systemd-resolved.service; disabled; vendor preset: disabled) Active: inactive (dead) Docs: man:systemd-resolved.service(8) https://www.freedesktop.org/wiki/Software/systemd/resolved https://www.freedesktop.org/wiki/Software/systemd/writing-network-configuration-managers https://www.freedesktop.org/wiki/Software/systemd/writing-resolver-clients DEBU stderr: INFO Checking if running as non-root INFO Checking if running inside WSL2 INFO Checking if crc-admin-helper executable is cached DEBU Running '/home/anjan/.crc/bin/crc-admin-helper-linux --version' DEBU Found crc-admin-helper-linux version 0.0.11 DEBU crc-admin-helper executable already cached INFO Checking for obsolete admin-helper executable DEBU Checking if an older admin-helper executable is installed DEBU No older admin-helper executable found INFO Checking if running on a supported CPU architecture DEBU GOARCH is amd64 GOOS is linux INFO Checking minimum RAM requirements DEBU Total memory of system is 14139076608 bytes INFO Checking if crc executable symlink exists INFO Checking if Virtualization is enabled DEBU Checking if the vmx/svm flags are present in /proc/cpuinfo DEBU CPU virtualization flags are good INFO Checking if KVM is enabled DEBU Checking if /dev/kvm exists DEBU /dev/kvm was found INFO Checking if libvirt is installed DEBU Checking if 'virsh' is available DEBU 'virsh' was found in /usr/bin/virsh DEBU Checking 'virsh capabilities' for libvirtd/qemu availability DEBU Running 'virsh --readonly --connect qemu:///system capabilities' DEBU Found x86_64 hypervisor with 'hvm' capabilities INFO Checking if user is part of libvirt group DEBU Checking if current user is part of the libvirt group DEBU Current user is already in the libvirt group INFO Checking if active user/process is currently part of the libvirt group INFO Checking if libvirt daemon is running DEBU Checking if libvirtd service is running DEBU Running 'systemctl status virtqemud.socket' DEBU Command failed: exit status 3 DEBU stdout: * virtqemud.socket - Libvirt qemu local socket Loaded: loaded (/usr/lib/systemd/system/virtqemud.socket; disabled; vendor preset: disabled) Active: inactive (dead) Listen: /run/libvirt/virtqemud-sock (Stream) DEBU stderr: DEBU virtqemud.socket is neither running nor listening DEBU Running 'systemctl status libvirtd.socket' DEBU libvirtd.socket is running INFO Checking if a supported libvirt version is installed DEBU Checking if libvirt version is >=3.4.0 DEBU Running 'virsh -v' INFO Checking if crc-driver-libvirt is installed DEBU Checking if crc-driver-libvirt is installed DEBU Running '/home/anjan/.crc/bin/crc-driver-libvirt version' DEBU Found crc-driver-libvirt version 0.13.5 DEBU crc-driver-libvirt is already installed INFO Checking crc daemon systemd socket units DEBU Checking crc daemon systemd socket units DEBU Checking if crc-http.socket is running DEBU Running 'systemctl --user status crc-http.socket' DEBU crc-http.socket is listening DEBU Checking if crc-http.socket has the expected content DEBU Checking if crc-vsock.socket is running DEBU Running 'systemctl --user status crc-vsock.socket' DEBU crc-vsock.socket is listening DEBU Checking if crc-vsock.socket has the expected content INFO Checking if systemd-networkd is running DEBU Checking if systemd-networkd.service is running DEBU Running 'systemctl status systemd-networkd.service' DEBU Command failed: exit status 4 DEBU stdout: DEBU stderr: Unit systemd-networkd.service could not be found. DEBU systemd-networkd.service is not running INFO Checking if NetworkManager is installed DEBU Checking if 'nmcli' is available DEBU 'nmcli' was found in /usr/bin/nmcli INFO Checking if NetworkManager service is running DEBU Checking if NetworkManager.service is running DEBU Running 'systemctl status NetworkManager.service' DEBU NetworkManager.service is already running INFO Checking if /etc/NetworkManager/conf.d/crc-nm-dnsmasq.conf exists DEBU Checking NetworkManager configuration DEBU NetworkManager configuration is good INFO Checking if /etc/NetworkManager/dnsmasq.d/crc.conf exists DEBU Checking dnsmasq configuration DEBU dnsmasq configuration is good INFO Checking if libvirt 'crc' network is available DEBU Checking if libvirt 'crc' network exists DEBU Running 'virsh --connect qemu:///system net-info crc' DEBU Checking if libvirt 'crc' definition is up to date DEBU Running 'virsh --connect qemu:///system net-dumpxml --inactive crc' DEBU libvirt 'crc' network has the expected value INFO Checking if libvirt 'crc' network is active DEBU Checking if libvirt 'crc' network is active DEBU Running 'virsh --connect qemu:///system net-info crc' DEBU libvirt 'crc' network is already active DEBU Checking file: /home/anjan/.crc/machines/crc/.crc-exist INFO Loading bundle: crc_podman_libvirt_4.1.1_amd64... DEBU Checking file: /home/anjan/.crc/machines/crc/.crc-exist DEBU Found binary path at /home/anjan/.crc/bin/crc-driver-libvirt DEBU Launching plugin server for driver libvirt DEBU Plugin server listening at address 127.0.0.1:41105 DEBU () Calling .GetVersion DEBU Using API Version 1 DEBU () Calling .SetConfigRaw DEBU () Calling .GetMachineName DEBU (crc) Calling .GetBundleName DEBU (crc) Calling .GetState DEBU (crc) DBG | time="2022-08-05T05:25:17-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:17-04:00" level=debug msg="Fetching VM..." DEBU Updating CRC VM configuration DEBU (crc) Calling .GetConfigRaw DEBU (crc) Calling .Start DEBU (crc) DBG | time="2022-08-05T05:25:17-04:00" level=debug msg="Starting VM crc" DEBU (crc) DBG | time="2022-08-05T05:25:17-04:00" level=debug msg="Validating network" DEBU (crc) DBG | time="2022-08-05T05:25:17-04:00" level=debug msg="Validating storage pool" DEBU (crc) DBG | time="2022-08-05T05:25:22-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:22-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:22-04:00" level=debug msg="Waiting for machine to come up 0/60" DEBU (crc) DBG | time="2022-08-05T05:25:25-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:25-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:25-04:00" level=debug msg="Waiting for machine to come up 1/60" DEBU (crc) DBG | time="2022-08-05T05:25:28-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:28-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:28-04:00" level=debug msg="Waiting for machine to come up 2/60" DEBU (crc) DBG | time="2022-08-05T05:25:31-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:31-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:31-04:00" level=debug msg="Waiting for machine to come up 3/60" DEBU (crc) DBG | time="2022-08-05T05:25:34-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:34-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:34-04:00" level=debug msg="Waiting for machine to come up 4/60" DEBU (crc) DBG | time="2022-08-05T05:25:37-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:37-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:37-04:00" level=debug msg="Waiting for machine to come up 5/60" DEBU (crc) DBG | time="2022-08-05T05:25:40-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:40-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:40-04:00" level=debug msg="Waiting for machine to come up 6/60" DEBU (crc) DBG | time="2022-08-05T05:25:43-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:43-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:43-04:00" level=debug msg="Waiting for machine to come up 7/60" DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="IP address: 192.168.130.11" DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=info msg="Found IP for machine: 192.168.130.11" DEBU (crc) Calling .GetConfigRaw DEBU Waiting for machine to be running, this may take a few minutes... DEBU retry loop: attempt 0 DEBU (crc) Calling .GetState DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="Getting current state..." DEBU Machine is up and running! DEBU (crc) Calling .GetState DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="Getting current state..." DEBU (crc) Calling .GetIP DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="IP address: 192.168.130.11" INFO CRC instance is running with IP 192.168.130.11 DEBU (crc) Calling .GetIP DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="Getting current state..." DEBU Waiting until ssh is available DEBU retry loop: attempt 0 DEBU Running SSH command: exit 0 DEBU (crc) DBG | time="2022-08-05T05:25:46-04:00" level=debug msg="IP address: 192.168.130.11" DEBU Using ssh private keys: [/home/anjan/.crc/machines/crc/id_ecdsa /home/anjan/.crc/cache/crc_podman_libvirt_4.1.1_amd64/id_ecdsa_crc] DEBU SSH command results: err: , output: INFO CRC VM is running DEBU Running SSH command: cat /home/core/.ssh/authorized_keys DEBU SSH command results: err: , output: ecdsa-sha2-nistp521 AAAAE2VjZHNhLXNoYTItbmlzdHA1MjEAAAAIbmlzdHA1MjEAAACFBAHh2xcoxHAv++m2dJmO52Q1Mbt0qSZQUS2Q5eV3apFdwvae1M53FBGs6SXXYAFZhFfdyeVGQdPs5DU5GsW449yZ6QA9Hi+OIAnLZQ933icVoFkShia3DrEvsXIudMy7wSRezGUsnx9vS9EbIs3ZJ/cHZFpoYK3fbckcGtga5iifS5fr4A== DEBU Running SSH command: realpath /dev/disk/by-label/root DEBU SSH command results: err: , output: /dev/vda4 DEBU Using root access: Growing /dev/vda4 partition DEBU Running SSH command: sudo /usr/bin/growpart /dev/vda 4 DEBU SSH command results: err: Process exited with status 1, output: NOCHANGE: partition 4 is size 63961055. it cannot be grown DEBU No free space after /dev/vda4, nothing to do DEBU Configuring shared directories DEBU (crc) Calling .GetSharedDirs DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Found x86_64 hypervisor with 'hvm' capabilities" DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Found q35 machine type" INFO Configuring shared directories DEBU Using root access: Creating /home/anjan DEBU Running SSH command: sudo mkdir -p /home/anjan DEBU SSH command results: err: , output: DEBU Mounting tag dir0 at /home/anjan DEBU Using root access: Mounting /home/anjan DEBU Running SSH command: sudo mount -o context="system_u:object_r:container_file_t:s0" -t virtiofs dir0 /home/anjan DEBU SSH command results: err: , output: DEBU Using root access: make root Podman socket accessible DEBU Running SSH command: sudo chmod 777 /run/podman/ /run/podman/podman.sock DEBU SSH command results: err: , output: DEBU Running '/home/anjan/.crc/bin/crc-admin-helper-linux rm podman.crc.testing' DEBU Running '/home/anjan/.crc/bin/crc-admin-helper-linux add 192.168.130.11 podman.crc.testing' INFO Adding new bearer token for cockpit webconsole DEBU Creating /home/core/cockpit-bearer-token with permissions 0600 in the CRC VM DEBU Running SSH command: DEBU SSH command succeeded DEBU Checking file: /home/anjan/.crc/machines/crc/.crc-exist DEBU Found binary path at /home/anjan/.crc/bin/crc-driver-libvirt DEBU Launching plugin server for driver libvirt DEBU Plugin server listening at address 127.0.0.1:33575 DEBU () Calling .GetVersion DEBU Using API Version 1 DEBU () Calling .SetConfigRaw DEBU () Calling .GetMachineName DEBU (crc) Calling .GetBundleName DEBU (crc) Calling .GetIP DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="GetIP called for crc" DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Getting current state..." DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Fetching VM..." DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="IP address: 192.168.130.11" DEBU Making call to close driver server DEBU (crc) Calling .Close DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Closing plugin on server side" DEBU Successfully made call to close driver server DEBU Making call to close connection to plugin binary DEBU Running '/home/anjan/.crc/bin/oc/podman-remote system connection add --identity /home/anjan/.crc/machines/crc/id_ecdsa crc ssh://core@192.168.130.11:22/run/user/1000/podman/podman.sock' DEBU Running '/home/anjan/.crc/bin/oc/podman-remote system connection default crc' DEBU Running '/home/anjan/.crc/bin/oc/podman-remote system connection add --identity /home/anjan/.crc/machines/crc/id_ecdsa crc-root ssh://core@192.168.130.11:22/run/podman/podman.sock' DEBU Making call to close driver server DEBU (crc) Calling .Close DEBU (crc) DBG | time="2022-08-05T05:25:47-04:00" level=debug msg="Closing plugin on server side" DEBU Successfully made call to close driver server DEBU Making call to close connection to plugin binary podman runtime is now running. Use the 'podman' command line interface: $ eval $(crc podman-env) $ podman-remote COMMAND