Skip to content

Instantly share code, notes, and snippets.

@anjannath
Created August 5, 2022 09:27
Show Gist options
  • Save anjannath/8b8883c126cfd62e5ea74813f9d8e57f to your computer and use it in GitHub Desktop.
Save anjannath/8b8883c126cfd62e5ea74813f9d8e57f to your computer and use it in GitHub Desktop.
$ 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: <nil>, output:
INFO CRC VM is running
DEBU Running SSH command: cat /home/core/.ssh/authorized_keys
DEBU SSH command results: err: <nil>, output: ecdsa-sha2-nistp521 AAAAE2VjZHNhLXNoYTItbmlzdHA1MjEAAAAIbmlzdHA1MjEAAACFBAHh2xcoxHAv++m2dJmO52Q1Mbt0qSZQUS2Q5eV3apFdwvae1M53FBGs6SXXYAFZhFfdyeVGQdPs5DU5GsW449yZ6QA9Hi+OIAnLZQ933icVoFkShia3DrEvsXIudMy7wSRezGUsnx9vS9EbIs3ZJ/cHZFpoYK3fbckcGtga5iifS5fr4A==
DEBU Running SSH command: realpath /dev/disk/by-label/root
DEBU SSH command results: err: <nil>, 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: <nil>, 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: <nil>, 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: <nil>, 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: <hidden>
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
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment