Skip to content

Instantly share code, notes, and snippets.

@rwmjones
Created August 19, 2022 11:26
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/084d4abd35e76a4c8b7eab7b7c42b53d to your computer and use it in GitHub Desktop.
Save rwmjones/084d4abd35e76a4c8b7eab7b7c42b53d 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:39883"
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-19T12:20:29+01:00\" level=debug msg=\"About to check libvirt version\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Validating network\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Validating storage pool\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+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-19T12:20:29+01:00\" level=debug msg=\"Preparing /home/rjones/.crc/machines/crc/crc.qcow2 for machine use\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"image creation took 188.207934ms\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc/machines/crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc/machines\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones/.crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Verifying executable bit set on /home/rjones\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Verifying executable bit set on /home\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Defining VM...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Found q35 machine type\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Found q35 machine type\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+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="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:44523"
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-19T12:20:29+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+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-19T12:20:29+01:00\" level=debug msg=\"Starting VM crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Validating network\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:29+01:00\" level=debug msg=\"Validating storage pool\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"IP address: 192.168.130.11\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+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-19T12:20:35+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-19T12:20:35+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) Calling .GetIP"
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+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-19T12:20:35+01:00\" level=debug msg=\"GetIP called for crc\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+01:00\" level=debug msg=\"Getting current state...\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:35+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: dial tcp 192.168.130.11:22: connect: no route to host, output: "
level=debug msg="error: Temporary error: ssh command error:\ncommand : exit 0\nerr : dial tcp 192.168.130.11:22: connect: no route to host\n - sleeping 1s"
level=debug msg="retry loop: attempt 1"
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: dial tcp 192.168.130.11:22: connect: no route to host, output: "
level=debug msg="error: Temporary error: ssh command error:\ncommand : exit 0\nerr : dial tcp 192.168.130.11:22: connect: no route to host\n - sleeping 1s"
level=debug msg="retry loop: attempt 2"
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: dial tcp 192.168.130.11:22: connect: no route to host, output: "
level=debug msg="error: Temporary error: ssh command error:\ncommand : exit 0\nerr : dial tcp 192.168.130.11:22: connect: no route to host\n - sleeping 1s"
level=debug msg="retry loop: attempt 3"
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: dial tcp 192.168.130.11:22: connect: no route to host, output: "
level=debug msg="error: Temporary error: ssh command error:\ncommand : exit 0\nerr : dial tcp 192.168.130.11:22: connect: no route to host\n - sleeping 1s"
level=debug msg="retry loop: attempt 4"
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: dial tcp 192.168.130.11:22: connect: no route to host, output: "
level=debug msg="error: Temporary error: ssh command error:\ncommand : exit 0\nerr : dial tcp 192.168.130.11:22: connect: no route to host\n - sleeping 1s"
level=debug msg="retry loop: attempt 5"
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-19T12:20:52+01:00\" level=debug msg=\"Found x86_64 hypervisor with 'hvm' capabilities\""
level=debug msg="(crc) DBG | time=\"2022-08-19T12:20:52+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 11:20:58 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 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 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 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 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: <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 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 1"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 2"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 3"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 4"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 5"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 6"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 7"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 8"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 9"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="retry loop: attempt 10"
level=debug msg="Running SSH command: timeout 5s oc get cm --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 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig\nerr : Process exited with status 124\n - sleeping 1s"
level=debug msg="RetryAfter timeout after 11 tries"
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-19T12:24:27+01:00\" level=debug msg=\"Closing plugin on server side\""
Temporary error: ssh command error:
command : timeout 5s oc get cm --context admin --cluster crc --kubeconfig /opt/kubeconfig
err : Process exited with status 124
(x11)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment