Skip to content

Instantly share code, notes, and snippets.

@rwmjones
Created August 19, 2022 14:44
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save rwmjones/2c220e873879debbeb65fe4b864678d4 to your computer and use it in GitHub Desktop.
Save rwmjones/2c220e873879debbeb65fe4b864678d4 to your computer and use it in GitHub Desktop.
level=debug msg="CRC version: 2.7.1+8b30b973\n"
level=debug msg="OpenShift version: 4.11.0\n"
level=debug msg="Podman version: 4.1.1\n"
level=debug msg="Running 'crc start'"
level=debug msg="Total memory of system is 16378507264 bytes"
level=debug msg="No new version available. The latest version is 2.7.1"
level=debug msg="Checking file: /home/rjones/.crc/machines/crc/.crc-exist"
level=debug msg="Checking if systemd-resolved.service is running"
level=debug msg="Running 'systemctl status systemd-resolved.service'"
level=debug msg="systemd-resolved.service is already running"
level=info msg="Checking if running as non-root"
level=info msg="Checking if running inside WSL2"
level=info msg="Checking if crc-admin-helper executable is cached"
level=debug msg="Running '/home/rjones/.crc/bin/crc-admin-helper-linux --version'"
level=debug msg="Found crc-admin-helper-linux version 0.0.11"
level=debug msg="crc-admin-helper executable already cached"
level=info msg="Checking for obsolete admin-helper executable"
level=debug msg="Checking if an older admin-helper executable is installed"
level=debug msg="No older admin-helper executable found"
level=info msg="Checking if running on a supported CPU architecture"
level=debug msg="GOARCH is amd64 GOOS is linux"
level=info msg="Checking minimum RAM requirements"
level=debug msg="Total memory of system is 16378507264 bytes"
level=info msg="Checking if crc executable symlink exists"
level=info msg="Checking if Virtualization is enabled"
level=debug msg="Checking if the vmx/svm flags are present in /proc/cpuinfo"
level=debug msg="CPU virtualization flags are good"
level=info msg="Checking if KVM is enabled"
level=debug msg="Checking if /dev/kvm exists"
level=debug msg="/dev/kvm was found"
level=info msg="Checking if libvirt is installed"
level=debug msg="Checking if 'virsh' is available"
level=debug msg="'virsh' was found in /usr/bin/virsh"
level=debug msg="Checking 'virsh capabilities' for libvirtd/qemu availability"
level=debug msg="Running 'virsh --readonly --connect qemu:///system capabilities'"
level=debug msg="Found x86_64 hypervisor with 'hvm' capabilities"
level=info msg="Checking if user is part of libvirt group"
level=debug msg="Checking if current user is part of the libvirt group"
level=debug msg="Current user is already in the libvirt group"
level=info msg="Checking if active user/process is currently part of the libvirt group"
level=info msg="Checking if libvirt daemon is running"
level=debug msg="Checking if libvirtd service is running"
level=debug msg="Running 'systemctl status virtqemud.socket'"
level=debug msg="virtqemud.socket is running"
level=info msg="Checking if a supported libvirt version is installed"
level=debug msg="Checking if libvirt version is >=3.4.0"
level=debug msg="Running 'virsh -v'"
level=info msg="Checking if crc-driver-libvirt is installed"
level=debug msg="Checking if crc-driver-libvirt is installed"
level=debug msg="Running '/home/rjones/.crc/bin/crc-driver-libvirt version'"
level=debug msg="Found crc-driver-libvirt version 0.13.5"
level=debug msg="crc-driver-libvirt is already installed"
level=info msg="Checking crc daemon systemd socket units"
level=debug msg="Checking crc daemon systemd socket units"
level=debug msg="Checking if crc-http.socket is running"
level=debug msg="Running 'systemctl --user status crc-http.socket'"
level=debug msg="crc-http.socket is listening"
level=debug msg="Checking if crc-http.socket has the expected content"
level=debug msg="Checking if crc-vsock.socket is running"
level=debug msg="Running 'systemctl --user status crc-vsock.socket'"
level=debug msg="crc-vsock.socket is listening"
level=debug msg="Checking if crc-vsock.socket has the expected content"
level=info msg="Checking if systemd-networkd is running"
level=debug msg="Checking if systemd-networkd.service is running"
level=debug msg="Running 'systemctl status systemd-networkd.service'"
level=debug msg="Command failed: exit status 3"
level=debug msg="stdout: * systemd-networkd.service - Network Configuration\n Loaded: loaded (/usr/lib/systemd/system/systemd-networkd.service; disabled; preset: disabled)\n Active: inactive (dead)\nTriggeredBy: * systemd-networkd.socket\n Docs: man:systemd-networkd.service(8)\n"
level=debug msg="stderr: "
level=debug msg="systemd-networkd.service is not running"
level=info msg="Checking if NetworkManager is installed"
level=debug msg="Checking if 'nmcli' is available"
level=debug msg="'nmcli' was found in /usr/bin/nmcli"
level=info msg="Checking if NetworkManager service is running"
level=debug msg="Checking if NetworkManager.service is running"
level=debug msg="Running 'systemctl status NetworkManager.service'"
level=debug msg="NetworkManager.service is already running"
level=info msg="Checking if dnsmasq configurations file exist for NetworkManager"
level=info msg="Checking if the systemd-resolved service is running"
level=debug msg="Checking if systemd-resolved.service is running"
level=debug msg="Running 'systemctl status systemd-resolved.service'"
level=debug msg="systemd-resolved.service is already running"
level=info msg="Checking if /etc/NetworkManager/dispatcher.d/99-crc.sh exists"
level=debug msg="Checking NetworkManager dispatcher file for crc network"
level=debug msg="Dispatcher file has the expected content"
level=info msg="Checking if libvirt 'crc' network is available"
level=debug msg="Checking if libvirt 'crc' network exists"
level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
level=debug msg="Checking if libvirt 'crc' definition is up to date"
level=debug msg="Running 'virsh --connect qemu:///system net-dumpxml --inactive crc'"
level=debug msg="libvirt 'crc' network has the expected value"
level=info msg="Checking if libvirt 'crc' network is active"
level=debug msg="Checking if libvirt 'crc' network is active"
level=debug msg="Running 'virsh --connect qemu:///system net-info crc'"
level=debug msg="libvirt 'crc' network is already active"
level=debug msg="Checking file: /home/rjones/.crc/machines/crc/.crc-exist"
level=info msg="Loading bundle: crc_libvirt_4.11.0_amd64..."
level=debug msg="Cannot load secret from configuration: empty path"
level=debug msg="Cannot load secret from keyring: The name is not activatable"
CRC requires a pull secret to download content from Red Hat.
You can copy it from the Pull Secret section of https://console.redhat.com/openshift/create/local.
? Please enter the pull secret [?25l78[?25h**************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************************
level=warning msg="Cannot add pull secret to keyring: The name is not activatable"
level=info msg="Creating CRC VM for openshift 4.11.0..."
level=debug msg="Found binary path at /home/rjones/.crc/bin/crc-driver-libvirt"
level=debug msg="Launching plugin server for driver libvirt"
level=debug msg="Plugin server listening at address 127.0.0.1:39265"
level=debug msg="() Calling .GetVersion"
level=debug msg="Using API Version 1"
level=debug msg="() Calling .SetConfigRaw"
level=debug msg="() Calling .GetMachineName"
level=debug msg="(crc) Calling .GetMachineName"
level=debug msg="(crc) Calling .DriverName"
level=debug msg="Running pre-create checks..."
level=debug msg="(crc) Calling .PreCreateCheck"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"About to check libvirt version\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Validating network\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Validating storage pool\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Could not find storage pool 'crc', trying to create it\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Creating storage pool\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=info msg=\"Creating storage pool with XML <pool type=\\\"dir\\\">\\n <name>crc</name>\\n <target>\\n <path>/home/rjones/.crc/machines/crc</path>\\n </target>\\n</pool>\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Activating pool 'crc'\""
level=debug msg="(crc) Calling .GetConfigRaw"
level=debug msg="Creating machine..."
level=debug msg="(crc) Calling .Create"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Preparing /home/rjones/.crc/machines/crc/crc.qcow2 for machine use\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"image creation took 212.801821ms\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc/machines/crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc/machines\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Verifying executable bit set on /home\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Defining VM...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Found q35 machine type\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Found q35 machine type\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"disk image capacity is already 33285996544 bytes\""
level=info msg="Generating new SSH key pair..."
level=info msg="Generating new password for the kubeadmin user"
level=debug msg="Created /home/rjones/.crc/machines/crc/.crc-exist"
level=debug msg="Machine successfully created"
level=debug msg="Making call to close driver server"
level=debug msg="(crc) Calling .Close"
level=debug msg="Successfully made call to close driver server"
level=debug msg="Making call to close connection to plugin binary"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Closing plugin on server side\""
level=debug msg="Checking file: /home/rjones/.crc/machines/crc/.crc-exist"
level=debug msg="Found binary path at /home/rjones/.crc/bin/crc-driver-libvirt"
level=debug msg="Launching plugin server for driver libvirt"
level=debug msg="Plugin server listening at address 127.0.0.1:46263"
level=debug msg="() Calling .GetVersion"
level=debug msg="Using API Version 1"
level=debug msg="() Calling .SetConfigRaw"
level=debug msg="() Calling .GetMachineName"
level=debug msg="(crc) Calling .GetBundleName"
level=debug msg="(crc) Calling .GetState"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:15+01:00\" level=debug msg=\"Fetching VM...\""
level=info msg="Starting CRC VM for openshift 4.11.0..."
level=debug msg="Updating CRC VM configuration"
level=debug msg="(crc) Calling .GetConfigRaw"
level=debug msg="(crc) Calling .Start"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:16+01:00\" level=debug msg=\"Starting VM crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:16+01:00\" level=debug msg=\"Validating network\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:16+01:00\" level=debug msg=\"Validating storage pool\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:22+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:22+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:22+01:00\" level=debug msg=\"Waiting for machine to come up 0/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:25+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:25+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:25+01:00\" level=debug msg=\"Waiting for machine to come up 1/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:28+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:28+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:28+01:00\" level=debug msg=\"Waiting for machine to come up 2/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:31+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:31+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:31+01:00\" level=debug msg=\"Waiting for machine to come up 3/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:34+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:34+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:34+01:00\" level=debug msg=\"Waiting for machine to come up 4/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:37+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:37+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:37+01:00\" level=debug msg=\"Waiting for machine to come up 5/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:40+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:40+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:40+01:00\" level=debug msg=\"Waiting for machine to come up 6/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:43+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:43+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:43+01:00\" level=debug msg=\"Waiting for machine to come up 7/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:46+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:46+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:46+01:00\" level=debug msg=\"Waiting for machine to come up 8/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:49+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:49+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:49+01:00\" level=debug msg=\"Waiting for machine to come up 9/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:52+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:52+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:52+01:00\" level=debug msg=\"Waiting for machine to come up 10/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:55+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:55+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:55+01:00\" level=debug msg=\"Waiting for machine to come up 11/60\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"IP address: 192.168.130.11\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=info msg=\"Found IP for machine: 192.168.130.11\""
level=debug msg="(crc) Calling .GetConfigRaw"
level=debug msg="Waiting for machine to be running, this may take a few minutes..."
level=debug msg="retry loop: attempt 0"
level=debug msg="(crc) Calling .GetState"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="Machine is up and running!"
level=debug msg="(crc) Calling .GetState"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) Calling .GetIP"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"IP address: 192.168.130.11\""
level=info msg="CRC instance is running with IP 192.168.130.11"
level=debug msg="(crc) Calling .GetIP"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:34:58+01:00\" level=debug msg=\"IP address: 192.168.130.11\""
level=debug msg="Waiting until ssh is available"
level=debug msg="retry loop: attempt 0"
level=debug msg="Running SSH command: exit 0"
level=debug msg="Using ssh private keys: [/home/rjones/.crc/machines/crc/id_ecdsa /home/rjones/.crc/cache/crc_libvirt_4.11.0_amd64/id_ecdsa_crc]"
level=debug msg="SSH command results: err: <nil>, output: "
level=info msg="CRC VM is running"
level=debug msg="Running SSH command: cat /home/core/.ssh/authorized_keys"
level=debug msg="SSH command results: err: <nil>, output: ecdsa-sha2-nistp521 AAAAE2VjZHNhLXNoYTItbmlzdHA1MjEAAAAIbmlzdHA1MjEAAACFBAAdrp6u8Ogq3sucd8H2TUMA3Nhrh0Emvsm173O7OaAAvWg7D3O6U9kKhwK87HjXMVdXmvcTNZJGpgwikC5b9za0DwGzw+BLra6wT8R+NdNcL1g0la4CJJ7dfnHKitScVliiO06bvohkw59fBISwXW0OYJPmyGLwqXZjzjfFJiohiavzZA== core\n"
level=info msg="Updating authorized keys..."
level=debug msg="Creating /home/core/.ssh/authorized_keys with permissions 0644 in the CRC VM"
level=debug msg="Running SSH command: <hidden>"
level=debug msg="SSH command succeeded"
level=debug msg="Running SSH command: rm /home/core/.ssh/authorized_keys.d/ignition"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="Running SSH command: realpath /dev/disk/by-label/root"
level=debug msg="SSH command results: err: <nil>, output: /dev/vda4\n"
level=debug msg="Using root access: Growing /dev/vda4 partition"
level=debug msg="Running SSH command: sudo /usr/bin/growpart /dev/vda 4"
level=debug msg="SSH command results: err: Process exited with status 1, output: NOCHANGE: partition 4 is size 63961055. it cannot be grown\n"
level=debug msg="No free space after /dev/vda4, nothing to do"
level=debug msg="Configuring shared directories"
level=debug msg="(crc) Calling .GetSharedDirs"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:35:00+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T15:35:00+01:00\" level=debug msg=\"Found q35 machine type\""
level=info msg="Configuring shared directories"
level=debug msg="Using root access: Creating /home/rjones"
level=debug msg="Running SSH command: sudo mkdir -p /home/rjones"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Mounting tag dir0 at /home/rjones"
level=debug msg="Using root access: Mounting /home/rjones"
level=debug msg="Running SSH command: sudo mount -o context=\"system_u:object_r:container_file_t:s0\" -t virtiofs dir0 /home/rjones"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Using root access: make root Podman socket accessible"
level=debug msg="Running SSH command: sudo chmod 777 /run/podman/ /run/podman/podman.sock"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Creating /var/srv/dnsmasq.conf with permissions 0644 in the CRC VM"
level=debug msg="Running SSH command: <hidden>"
level=debug msg="SSH command succeeded"
level=debug msg="Running SSH command: systemctl status crc-dnsmasq.service"
level=debug msg="SSH command results: err: Process exited with status 3, output: ● crc-dnsmasq.service - Podman container-afd58aa85e12b450531c909d9c941e4381365fa303d41f696469ca5ebbc75f3f.service\n Loaded: loaded (/etc/systemd/system/crc-dnsmasq.service; disabled; vendor preset: disabled)\n Active: inactive (dead)\n Docs: man:podman-generate-systemd(1)\n"
level=debug msg="Using root access: Executing systemctl enable crc-dnsmasq.service"
level=debug msg="Running SSH command: sudo systemctl enable crc-dnsmasq.service"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Using root access: Executing systemctl daemon-reload command"
level=debug msg="Running SSH command: sudo systemctl daemon-reload"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Using root access: Executing systemctl start crc-dnsmasq.service"
level=debug msg="Running SSH command: sudo systemctl start crc-dnsmasq.service"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Running '/home/rjones/.crc/bin/crc-admin-helper-linux rm api.crc.testing oauth-openshift.apps-crc.testing console-openshift-console.apps-crc.testing downloads-openshift-console.apps-crc.testing canary-openshift-ingress-canary.apps-crc.testing default-route-openshift-image-registry.apps-crc.testing'"
level=debug msg="Running '/home/rjones/.crc/bin/crc-admin-helper-linux add 192.168.130.11 api.crc.testing oauth-openshift.apps-crc.testing console-openshift-console.apps-crc.testing downloads-openshift-console.apps-crc.testing canary-openshift-ingress-canary.apps-crc.testing default-route-openshift-image-registry.apps-crc.testing'"
level=debug msg="Running SSH command: cat /etc/resolv.conf"
level=debug msg="SSH command results: err: <nil>, output: # Generated by NetworkManager\nnameserver 192.168.130.1\n"
level=debug msg="Creating /etc/resolv.conf with permissions 0644 in the CRC VM"
level=debug msg="Running SSH command: <hidden>"
level=debug msg="SSH command succeeded"
level=debug msg="retry loop: attempt 0"
level=debug msg="Running SSH command: host -R 3 foo.apps-crc.testing"
level=debug msg="SSH command results: err: <nil>, output: foo.apps-crc.testing has address 192.168.130.11\n"
level=info msg="Check internal and public DNS query..."
level=debug msg="Running SSH command: curl --head quay.io"
level=debug msg="SSH command results: err: <nil>, output: HTTP/1.1 301 Moved Permanently\r\nServer: awselb/2.0\r\nDate: Fri, 19 Aug 2022 14:35:17 GMT\r\nContent-Type: text/html\r\nContent-Length: 134\r\nConnection: keep-alive\r\nLocation: https://quay.io:443/\r\n\r\n"
level=info msg="Check DNS query from host..."
level=debug msg="api.crc.testing resolved to [192.168.130.11]"
level=debug msg="foo.apps-crc.testing resolved to [192.168.130.11]"
level=info msg="Verifying validity of the kubelet certificates..."
level=debug msg="Running SSH command: date --date=\"$(sudo openssl x509 -in /var/lib/kubelet/pki/kubelet-client-current.pem -noout -enddate | cut -d= -f 2)\" --iso-8601=seconds"
level=debug msg="SSH command results: err: <nil>, output: 2022-09-08T06:27:59+00:00\n"
level=debug msg="Running SSH command: date --date=\"$(sudo openssl x509 -in /var/lib/kubelet/pki/kubelet-server-current.pem -noout -enddate | cut -d= -f 2)\" --iso-8601=seconds"
level=debug msg="SSH command results: err: <nil>, output: 2022-09-08T06:31:57+00:00\n"
level=debug msg="Running SSH command: date --date=\"$(sudo openssl x509 -in /etc/kubernetes/static-pod-resources/kube-apiserver-certs/configmaps/aggregator-client-ca/ca-bundle.crt -noout -enddate | cut -d= -f 2)\" --iso-8601=seconds"
level=debug msg="SSH command results: err: <nil>, output: 2022-09-08T06:33:01+00:00\n"
level=info msg="Starting kubelet service"
level=debug msg="Using root access: Executing systemctl daemon-reload command"
level=debug msg="Running SSH command: sudo systemctl daemon-reload"
level=debug msg="SSH command results: err: <nil>, output: "
level=debug msg="Using root access: Executing systemctl start kubelet"
level=debug msg="Running SSH command: sudo systemctl start kubelet"
level=debug msg="SSH command results: err: <nil>, output: "
level=info msg="Waiting for kube-apiserver availability... [takes around 2min]"
level=debug msg="retry loop: attempt 0"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 1"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 2"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 3"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 4"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 5"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 6"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 7"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 8"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 9"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 10"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 11"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 12"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 13"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 14"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 15"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 16"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 17"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 18"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 19"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 20"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 21"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 22"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 23"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 24"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 25"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 1, output: "
level=debug msg="The connection to the server api.crc.testing:6443 was refused - did you specify the right host or port?\n"
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 1\n - sleeping 1s"
level=debug msg="retry loop: attempt 26"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 124, output: "
level=debug
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 27"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 124, output: "
level=debug
level=debug msg="error: Temporary error: ssh command error:\ncommand : timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 28"
level=debug msg="Running SSH command: timeout 30s oc get nodes --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: <nil>, output: NAME STATUS ROLES AGE VERSION\ncrc-9jm8r-master-0 Ready master,worker 11d v1.24.0+9546431\n"
level=debug msg="NAME STATUS ROLES AGE VERSION\ncrc-9jm8r-master-0 Ready master,worker 11d v1.24.0+9546431\n"
level=debug msg="Waiting for availability of resource type 'cm'"
level=debug msg="retry loop: attempt 0"
level=debug msg="Running SSH command: timeout 30s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: <nil>, output: NAME DATA AGE\nkube-root-ca.crt 1 11d\nopenshift-service-ca.crt 1 11d\n"
level=debug msg="NAME DATA AGE\nkube-root-ca.crt 1 11d\nopenshift-service-ca.crt 1 11d\n"
level=debug msg="Running SSH command: timeout 30s oc delete -n openshift-machine-config-operator cm machine-config-controller --context admin --cluster crc --kubeconfig /opt/kubeconfig"
level=debug msg="SSH command results: err: Process exited with status 124, output: "
level=debug msg="Making call to close driver server"
level=debug msg="(crc) Calling .Close"
level=debug msg="Successfully made call to close driver server"
level=debug msg="(crc) DBG | time=\"2022-08-19T15:38:48+01:00\" level=debug msg=\"Closing plugin on server side\""
level=debug msg="Making call to close connection to plugin binary"
ssh command error:
command : timeout 30s oc delete -n openshift-machine-config-operator cm machine-config-controller --context admin --cluster crc --kubeconfig /opt/kubeconfig
err : Process exited with status 124
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment