Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

macos-13 not working #41

Open
matiktli opened this issue Sep 21, 2024 · 9 comments
Open

macos-13 not working #41

matiktli opened this issue Sep 21, 2024 · 9 comments
Labels
bug Something isn't working dependencies Pull requests that update a dependency file help wanted Extra attention is needed need-more-info Needs more info.

Comments

@matiktli
Copy link

I am creating issue again as according to: #37 it should be fixed.
And indeed it worked once but then start failing again.
Here are the logs:

Run douglascamata/setup-docker-macos-action@v1-alpha
Run arch_name=$(uname -m)
Running on supported architecture: x86_64
Run echo "::group::Updating Homebrew"
Updating Homebrew
Run if [ $INPUT_LIMA == "latest" ]
Installing Lima version v0.23.2
Run if [ $INPUT_COLIMA == "latest" ]
Installing Colima version v0.7.5
Run brew unlink python@3 || true
Unlinking /usr/local/Cellar/[email protected]/3.12.6... 13 symlinks removed.
Uninstalling /usr/local/Cellar/[email protected]/3.12.6... (3,262 files, 63.4MB)
==> Downloading https://ghcr.io/v2/homebrew/core/python/3.12/manifests/3.12.6
==> Fetching [email protected]
==> Downloading https://ghcr.io/v2/homebrew/core/python/3.12/blobs/sha256:8abd20343a066df8d2be5c81c4a2a968838003cf40da8838c052255747d704eb
==> Pouring [email protected]
==> /usr/local/Cellar/[email protected]/3.12.6/bin/python3.12 -Im ensurepip
==> /usr/local/Cellar/[email protected]/3.12.6/bin/python3.12 -Im pip install -v --no-
==> Caveats
Python has been installed as
  /usr/local/bin/python3

Unversioned symlinks `python`, `python-config`, `pip` etc. pointing to
`python3`, `python3-config`, `pip3` etc., respectively, have been installed into
  /usr/local/opt/[email protected]/libexec/bin

See: https://docs.brew.sh/Homebrew-and-Python
==> Summary
🍺  /usr/local/Cellar/[email protected]/3.12.6: 3,261 files, 63.4MB
Warning: HOMEBREW_NO_INSTALLED_DEPENDENTS_CHECK is set: not checking for outdated
dependents or dependents with broken linkage!
Warning: HOMEBREW_NO_INSTALLED_DEPENDENTS_CHECK is set: not checking for outdated
dependents or dependents with broken linkage!

Run echo "::group::Installing QEMU, Docker client, and Docker Compose"
Installing QEMU, Docker client, and Docker Compose
Run mkdir -p ~/.docker/cli-plugins
Run CPU_COUNT=$(sysctl -n hw.ncpu)
Starting Colima with args: --cpu 4 --memory 14 --arch x86_64
  time="2024-09-21T13:01:21Z" level=info msg="starting colima"
  time="2024-09-21T13:01:21Z" level=info msg="runtime: docker"
  time="2024-09-21T13:01:23Z" level=info msg="creating and starting ..." context=vm
  time="2024-09-21T13:01:23Z" level=info msg="downloading disk image ..." context=vm
  
                                                                             0.0%
                                                                             0.9%
  #                                                                          2.0%
  #                                                                          2.2%
  ##                                                                         4.2%
  ###                                                                        5.2%
  ####                                                                       6.2%
  #####                                                                      7.1%
  #####                                                                      8.1%
  ######                                                                     9.1%
  #######                                                                   [10](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:11).2%
  ########                                                                  11.2%
  ########                                                                  12.3%
  #########                                                                 13.4%
  ##########                                                                14.3%
  ###########                                                               15.3%
  ###########                                                               16.3%
  ############                                                              17.4%
  #############                                                             18.4%
  #############                                                             19.2%
  ##############                                                            20.7%
  ###############                                                           21.7%
  ################                                                          22.9%
  #################                                                         [24](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:26).0%
  #################                                                         25.0%
  ##################                                                        26.0%
  ###################                                                       27.2%
  ####################                                                      28.3%
  #####################                                                     29.6%
  #####################                                                     30.5%
  ######################                                                    31.5%
  #######################                                                   [32](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:35).5%
  ########################                                                  33.9%
  #########################                                                 34.9%
  #########################                                                 35.7%
  ##########################                                                37.4%
  ###########################                                               38.8%
  ############################                                              39.9%
  #############################                                             40.9%
  ##############################                                            41.9%
  ###############################                                           43.1%
  ################################                                          44.9%
  #################################                                         46.0%
  #################################                                         47.1%
  ##################################                                        48.1%
  ###################################                                       49.2%
  ####################################                                      50.3%
  #####################################                                     51.5%
  #####################################                                     52.5%
  ######################################                                    53.6%
  #######################################                                   54.6%
  #######################################                                   55.5%
  ########################################                                  56.4%
  #########################################                                 57.5%
  ##########################################                                58.4%
  ##########################################                                59.6%
  ###########################################                               60.6%
  ############################################                              61.6%
  #############################################                             62.8%
  #############################################                             [63](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:67).8%
  ##############################################                            64.8%
  ###############################################                           65.9%
  ################################################                          67.2%
  #################################################                         68.2%
  #################################################                         69.3%
  ##################################################                        70.4%
  ###################################################                       71.1%
  ####################################################                      72.9%
  #####################################################                     73.8%
  #####################################################                     74.8%
  ######################################################                    75.8%
  #######################################################                   77.0%
  ########################################################                  78.2%
  #########################################################                 79.3%
  #########################################################                 [80](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:85).4%
  ##########################################################                81.8%
  ###########################################################               82.7%
  ############################################################              83.7%
  ############################################################              84.7%
  #############################################################             85.6%
  ##############################################################            86.6%
  ###############################################################           87.7%
  ###############################################################           88.7%
  ################################################################          89.5%
  #################################################################         90.9%
  ##################################################################        91.8%
  ##################################################################        92.7%
  ###################################################################       93.9%
  ####################################################################      95.2%
  #####################################################################     96.3%
  ######################################################################    97.7%
  #######################################################################   98.9%
  ######################################################################## 100.0%
  time="[202](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:211)4-09-21T13:01:40Z" level=info msg="Terminal is not available, proceeding without opening an editor"
  time="2024-09-21T13:01:41Z" level=info msg="Starting the instance \"colima\" with VM driver \"qemu\""
  time="2024-09-21T13:01:41Z" level=info msg="QEMU binary \"/usr/local/bin/qemu-system-x86_64\" seems properly signed with the \"com.apple.security.hypervisor\" entitlement"
  time="2024-09-21T13:01:41Z" level=info msg="Attempting to download the image" arch=x86_64 digest= location=/Users/runner/Library/Caches/colima/caches/e6a9564d615abecd0b900517181080ab06de3cdbc367f4848d10f18693ce3ce5.raw
  time="2024-09-21T13:01:41Z" level=info msg="Downloaded the image from \"/Users/runner/Library/Caches/colima/caches/e6a9564d615abecd0b900517181080ab06de3cdbc367f4848d10f18693ce3ce5.raw\""
  time="2024-09-21T13:01:43Z" level=info msg="[hostagent] hostagent socket created at /Users/runner/.colima/_lima/colima/ha.sock"
  time="2024-09-21T13:01:44Z" level=info msg="[hostagent] Using system firmware (\"/usr/local/share/qemu/edk2-x86_64-code.fd\")"
  time="2024-09-21T13:01:44Z" level=info msg="[hostagent] Starting QEMU (hint: to watch the boot progress, see \"/Users/runner/.colima/_lima/colima/serial*.log\")"
  time="2024-09-21T13:01:44Z" level=info msg="SSH Local Port: 49245"
  time="2024-09-21T13:01:44Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:01:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:04Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:14Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:24Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:44Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:02:46Z" level=info msg="[hostagent] The essential requirement 1 of 4 is satisfied"
  time="2024-09-21T13:02:46Z" level=info msg="[hostagent] Waiting for the essential requirement 2 of 4: \"user session is ready for ssh\""
  time="2024-09-21T13:03:04Z" level=info msg="[hostagent] Waiting for the essential requirement 2 of 4: \"user session is ready for ssh\""
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] The essential requirement 2 of 4 is satisfied"
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] Waiting for the essential requirement 3 of 4: \"sshfs binary to be installed\""
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] The essential requirement 3 of 4 is satisfied"
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] Waiting for the essential requirement 4 of 4: \"fuse to \\\"allow_other\\\" as user\""
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] The essential requirement 4 of 4 is satisfied"
  time="2024-09-21T13:03:05Z" level=info msg="[hostagent] Mounting \"/Users/runner\" on \"/Users/runner\""
  time="2024-09-21T13:03:06Z" level=info msg="[hostagent] Mounting \"/tmp/colima\" on \"/tmp/colima\""
  time="2024-09-21T13:03:06Z" level=info msg="[hostagent] Waiting for the guest agent to be running"
  time="2024-09-21T13:03:06Z" level=info msg="[hostagent] Forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/default/docker.sock\" (host)"
  time="2024-09-21T13:03:06Z" level=info msg="[hostagent] Forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/docker.sock\" (host)"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Forwarding \"/run/lima-guestagent.sock\" (guest) to \"/Users/runner/.colima/_lima/colima/ga.sock\" (host)"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Guest agent is running"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Waiting for the final requirement 1 of 1: \"boot scripts must have finished\""
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Not forwarding TCP 127.0.0.53:53"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Not forwarding TCP 127.0.0.54:53"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] Not forwarding TCP [::]:22"
  time="2024-09-21T13:03:07Z" level=info msg="[hostagent] The final requirement 1 of 1 is satisfied"
  time="2024-09-21T13:03:07Z" level=info msg="READY. Run `limactl shell colima` to open the shell."
  installing: arm64 OK
  {
    "supported": [
      "linux/amd64",
      "linux/arm64",
      "linux/386"
    ],
    "emulators": [
      "python3.12",
      "qemu-aarch64"
    ]
  }
  time="2024-09-21T13:03:07Z" level=info msg="provisioning ..." context=docker
  colima
  Successfully created context "colima"
  colima
  Current context is now "colima"
  time="2024-09-21T13:03:13Z" level=info msg="starting ..." context=docker
  time="2024-09-21T13:03:21Z" level=info msg="Sending SIGINT to hostagent process 8983"
  time="2024-09-21T13:03:21Z" level=info msg="Waiting for the host agent and the driver processes to shut down"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Received SIGINT, shutting down the host agent"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Shutting down the host agent"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Stopping forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/default/docker.sock\" (host)"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Stopping forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/docker.sock\" (host)"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Stopping forwarding \"/run/lima-guestagent.sock\" (guest) to \"/Users/runner/.colima/_lima/colima/ga.sock\" (host)"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Unmounting \"/Users/runner\""
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Unmounting \"/tmp/colima\""
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Shutting down QEMU with ACPI"
  time="2024-09-21T13:03:21Z" level=info msg="[hostagent] Sending QMP system_powerdown command"
  time="2024-09-21T13:03:24Z" level=info msg="[hostagent] QEMU has exited"
  time="2024-09-21T13:03:30Z" level=info msg="Using the existing instance \"colima\""
  time="2024-09-21T13:03:30Z" level=info msg="Starting the instance \"colima\" with VM driver \"qemu\""
  time="2024-09-21T13:03:30Z" level=info msg="QEMU binary \"/usr/local/bin/qemu-system-x86_64\" seems properly signed with the \"com.apple.security.hypervisor\" entitlement"
  time="2024-09-21T13:03:31Z" level=info msg="[hostagent] hostagent socket created at /Users/runner/.colima/_lima/colima/ha.sock"
  time="2024-09-21T13:03:31Z" level=info msg="[hostagent] Using system firmware (\"/usr/local/share/qemu/edk2-x86_64-code.fd\")"
  time="2024-09-21T13:03:31Z" level=info msg="[hostagent] Starting QEMU (hint: to watch the boot progress, see \"/Users/runner/.colima/_lima/colima/serial*.log\")"
  time="2024-09-21T13:03:31Z" level=info msg="SSH Local Port: [492](https://github.com/uberkaretki/equipment-svc/actions/runs/10972764520/job/30469397204#step:3:505)66"
  time="2024-09-21T13:03:31Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:03:41Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:03:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:04:07Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:04:19Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:04:32Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:04:43Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:04:56Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:05:07Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:05:20Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:05:32Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:05:45Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:05:56Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:06:07Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:06:20Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:06:33Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:06:44Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:06:55Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:07:08Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:07:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:07:33Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:07:44Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:07:57Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:08:10Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:08:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:08:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:08:45Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:08:56Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:09:10Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:09:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:09:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:09:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:10:14Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:10:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:10:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:11:14Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:11:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:11:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:12:14Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:12:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:12:54Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:13:14Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-21T13:13:30Z" level=fatal msg="did not receive an event with the \"running\" status"
  time="2024-09-21T13:13:30Z" level=fatal msg="error starting docker: error at 'starting': exit status 1"
  Error: Process completed with exit code 1.
@matiktli
Copy link
Author

it worked again, seems it is unstable

@douglascamata douglascamata added bug Something isn't working help wanted Extra attention is needed dependencies Pull requests that update a dependency file need-more-info Needs more info. labels Sep 24, 2024
@douglascamata
Copy link
Owner

@matiktli is it still unstable for you?

@adfoster-r7
Copy link

It looks like it's unstable for me; sometimes the action succeeds - and sometimes it fails 😄
This was after migrating to macos-13 and using the following setup:

      - uses: douglascamata/setup-docker-macos-action@8d5fa43892aed7eee4effcdea113fd53e4d4bf83
        with:
          upgrade-qemu: false
          lima: v0.22.0
          colima: v0.7.3

Note that I set upgrade-qemu as false, as it looks like when it's set to true it overrides the qemu 9.0.2 pin

A diff of the success and failure scenarios doesn't show any useful difference, just the end where the SSH requirement isn't met

Not working 
 time="2024-09-25T21:02:10Z" level=info msg="Starting the instance \"colima\" with VM driver \"qemu\""
  time="2024-09-25T21:02:10Z" level=info msg="QEMU binary \"/usr/local/bin/qemu-system-x86_64\" seems properly signed with the \"com.apple.security.hypervisor\" entitlement"
  time="2024-09-25T21:02:10Z" level=info msg="[hostagent] hostagent socket created at /Users/runner/.colima/_lima/colima/ha.sock"
  time="2024-09-25T21:02:11Z" level=info msg="[hostagent] Using system firmware (\"/usr/local/share/qemu/edk2-x86_64-code.fd\")"
  time="2024-09-25T21:02:11Z" level=info msg="[hostagent] Starting QEMU (hint: to watch the boot progress, see \"/Users/runner/.colima/_lima/colima/serial*.log\")"
  time="2024-09-25T21:02:11Z" level=info msg="SSH Local Port: 49269"
  time="2024-09-25T21:02:11Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:34Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:47Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:58Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:03:11Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:03:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:03:33Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:03:46Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:03:59Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:04:10Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:04:24Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:04:35Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:04:48Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:04:59Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:05:12Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:05:23Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:05:36Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:05:48Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:06:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:06:12Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:06:25Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:06:36Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:06:49Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:07:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:07:12Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:07:25Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:07:38Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:07:49Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:08:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:08:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:08:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:09:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:09:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:09:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:10:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:10:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:10:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:11:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:11:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:11:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:12:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:12:10Z" level=fatal msg="did not receive an event with the \"running\" status"
  time="2024-09-25T21:12:10Z" level=fatal msg="error starting docker: error at 'starting': exit status 1"
Working 
  time="2024-09-25T21:01:38Z" level=info msg="Starting the instance \"colima\" with VM driver \"qemu\""
  time="2024-09-25T21:01:38Z" level=info msg="QEMU binary \"/usr/local/bin/qemu-system-x86_64\" seems properly signed with the \"com.apple.security.hypervisor\" entitlement"
  time="2024-09-25T21:01:38Z" level=info msg="[hostagent] hostagent socket created at /Users/runner/.colima/_lima/colima/ha.sock"
  time="2024-09-25T21:01:39Z" level=info msg="[hostagent] Using system firmware (\"/usr/local/share/qemu/edk2-x86_64-code.fd\")"
  time="2024-09-25T21:01:39Z" level=info msg="[hostagent] Starting QEMU (hint: to watch the boot progress, see \"/Users/runner/.colima/_lima/colima/serial*.log\")"
  time="2024-09-25T21:01:39Z" level=info msg="SSH Local Port: 49270"
  time="2024-09-25T21:01:39Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:01:49Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:15Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:26Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:37Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-09-25T21:02:37Z" level=info msg="[hostagent] The essential requirement 1 of 4 is satisfied"
  time="2024-09-25T21:02:37Z" level=info msg="[hostagent] Waiting for the essential requirement 2 of 4: \"user session is ready for ssh\""
  time="2024-09-25T21:02:37Z" level=info msg="[hostagent] The essential requirement 2 of 4 is satisfied"
  time="2024-09-25T21:02:37Z" level=info msg="[hostagent] Waiting for the essential requirement 3 of 4: \"sshfs binary to be installed\""
  time="2024-09-25T21:02:38Z" level=info msg="[hostagent] The essential requirement 3 of 4 is satisfied"
  time="2024-09-25T21:02:38Z" level=info msg="[hostagent] Waiting for the essential requirement 4 of 4: \"/etc/fuse.conf (/etc/fuse3.conf) to contain \\\"user_allow_other\\\"\""
  time="2024-09-25T21:02:38Z" level=info msg="[hostagent] The essential requirement 4 of 4 is satisfied"
  time="2024-09-25T21:02:38Z" level=info msg="[hostagent] Mounting \"/Users/runner\" on \"/Users/runner\""
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Mounting \"/tmp/colima\" on \"/tmp/colima\""
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Waiting for the guest agent to be running"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/default/docker.sock\" (host)"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Forwarding \"/var/run/docker.sock\" (guest) to \"/Users/runner/.colima/docker.sock\" (host)"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Forwarding \"/run/lima-guestagent.sock\" (guest) to \"/Users/runner/.colima/_lima/colima/ga.sock\" (host)"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Guest agent is running"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Waiting for the final requirement 1 of 1: \"boot scripts must have finished\""
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Not forwarding TCP 127.0.0.53:53"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Not forwarding TCP 127.0.0.54:53"
  time="2024-09-25T21:02:39Z" level=info msg="[hostagent] Not forwarding TCP [::]:22"
  time="2024-09-25T21:02:42Z" level=info msg="[hostagent] The final requirement 1 of 1 is satisfied"
  time="2024-09-25T21:02:43Z" level=info msg="READY. Run `limactl shell colima` to open the shell."
  installing: arm64 OK
  {
    "supported": [
      "linux/amd64",
      "linux/arm64",
      "linux/386"
    ],
    "emulators": [
      "python3.12",
      "qemu-aarch64"
    ]
  }
  time="2024-09-25T21:02:44Z" level=info msg=done

@jiayuasu jiayuasu mentioned this issue Oct 23, 2024
Merged
@joffrey-bion
Copy link

joffrey-bion commented Nov 19, 2024 •
edited
Loading

Facing a similar issue on macos-13 (stuck at requirement 1 of 4: ssh):
https://github.com/joffrey-bion/chrome-devtools-kotlin/actions/runs/11903386111/job/33170247096

Note that I'm also seeing QEMU did not exit in 3m0s, forcibly killing QEMU:

Starting Colima with args: --cpu 4 --memory 14 --arch x86_64
  time="2024-11-19T00:02:36Z" level=info msg="starting colima"
  time="2024-11-19T00:02:36Z" level=info msg="runtime: docker"
  time="2024-11-19T00:02:37Z" level=info msg="creating and starting ..." context=vm
  time="2024-11-19T00:02:37Z" level=info msg="downloading disk image ..." context=vm
  
                                                                             0.0%
  ##                                                                         3.0%
  ##                                                                         3.8%
  #####                                                                      8.3%
  #######                                                                   11.0%
  #########                                                                 13.5%
  ###########                                                               16.1%
  ##############                                                            20.2%
  #################                                                         24.1%
  ###################                                                       26.7%
  #####################                                                     29.9%
  ########################                                                  33.5%
  ##########################                                                37.5%
  #############################                                             41.1%
  ###############################                                           44.4%
  #################################                                         46.9%
  ####################################                                      50.7%
  #####################################                                     51.5%
  ######################################                                    54.1%
  #########################################                                 57.8%
  ###########################################                               59.9%
  #############################################                             63.6%
  ###############################################                           66.2%
  ##################################################                        69.6%
  ####################################################                      72.2%
  ######################################################                    75.8%
  #########################################################                 79.3%
  ##########################################################                81.0%
  #############################################################             84.9%
  ##############################################################            87.1%
  ################################################################          89.8%
  ###################################################################       93.6%
  #####################################################################     96.3%
  #######################################################################   99.0%
  ######################################################################## 100.0%
  time="2024-11-19T00:02:48Z" level=info msg="Terminal is not available, proceeding without opening an editor"
  time="2024-11-19T00:02:49Z" level=info msg="Starting the instance \"colima\" with VM driver \"qemu\""
  time="2024-11-19T00:02:49Z" level=info msg="QEMU binary \"/usr/local/bin/qemu-system-x86_64\" seems properly signed with the \"com.apple.security.hypervisor\" entitlement"
  time="2024-11-19T00:02:49Z" level=info msg="Attempting to download the image" arch=x86_64 digest= location=/Users/runner/Library/Caches/colima/caches/40038760d0ff0848a2701d6166d511a5feb910c4d1335ffe[436](https://github.com/joffrey-bion/chrome-devtools-kotlin/actions/runs/11903386111/job/33170247096#step:4:449)cccace4953087.raw
  time="2024-11-19T00:02:49Z" level=info msg="Downloaded the image from \"/Users/runner/Library/Caches/colima/caches/40038760d0ff0848a2701d6166d511a5feb910c4d1335ffe436cccace4953087.raw\""
  time="2024-11-19T00:02:51Z" level=info msg="[hostagent] hostagent socket created at /Users/runner/.colima/_lima/colima/ha.sock"
  time="2024-11-19T00:02:51Z" level=info msg="[hostagent] Using system firmware (\"/usr/local/share/qemu/edk2-x86_64-code.fd\")"
  time="2024-11-19T00:02:51Z" level=info msg="[hostagent] Starting QEMU (hint: to watch the boot progress, see \"/Users/runner/.colima/_lima/colima/serial*.log\")"
  time="2024-11-19T00:02:51Z" level=info msg="SSH Local Port: 49244"
  time="2024-11-19T00:02:51Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:11Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:31Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:41Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:03:51Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:11Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:31Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:41Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:04:51Z" level=info msg="[hostagent] Driver stopped due to error: \"Get \\\"http://lima/services/dhcp/leases\\\": context deadline exceeded\""
  time="2024-11-19T00:04:51Z" level=info msg="[hostagent] Shutting down the host agent"
  time="2024-11-19T00:04:51Z" level=warning msg="[hostagent] failed to exit SSH master" error="failed to execute `ssh -O exit -p 49244 127.0.0.1`, out=\"Control socket connect(/Users/runner/.colima/_lima/colima/ssh.sock): No such file or directory\\r\\n\": exit status 255"
  time="2024-11-19T00:04:51Z" level=info msg="[hostagent] Shutting down QEMU with the power button"
  time="2024-11-19T00:04:51Z" level=warning msg="[hostagent] Failed to remove SSH binding for port 49244"
  time="2024-11-19T00:04:51Z" level=info msg="[hostagent] Sending QMP system_powerdown command"
  time="2024-11-19T00:04:51Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:11Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:21Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:31Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:41Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:05:51Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:01Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:12Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:32Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:06:52Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:02Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:12Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:22Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:32Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:42Z" level=info msg="[hostagent] Waiting for the essential requirement 1 of 4: \"ssh\""
  time="2024-11-19T00:07:51Z" level=warning msg="[hostagent] QEMU did not exit in 3m0s, forcibly killing QEMU"
  time="2024-11-19T00:07:51Z" level=info msg="[hostagent] QEMU has exited, after killing forcibly" error="signal: killed"
  time="2024-11-19T00:07:51Z" level=fatal msg="exiting, status={Running:false Degraded:false Exiting:true Errors:[] SSHLocalPort:0} (hint: see \"/Users/runner/.colima/_lima/colima/ha.stderr.log\")"
  time="2024-11-19T00:07:51Z" level=fatal msg="error starting vm: error at 'creating and starting': exit status 1"

@douglascamata
Copy link
Owner

@joffrey-bion: thanks for the report, I will give it a look this week. Saving here your versions in case the logs disappear by then:

  • Lima v1.0.1
  • Colima v0.8.0
  • QEMU 9.0.2

I think it could be time to let QEMU upgrade beyond this version. 🤔

@joffrey-bion
Copy link

Ah sorry I should have copied more of the logs, thanks for saving this.

I didn't touch the default value for the QEMU update input because of the performance overhead. The action is already taking 6min for me when it works, so I didn't want to make this worse. But if it removes the flakiness it's definitely a win. I can try that next time.

@douglascamata
Copy link
Owner

@joffrey-bion no worries! Indeed the QEMU installation is a huge bottleneck. Plus it's kinda difficult to cache all this Homebrew stuff. I already spent a lot of time trying to figure out how to cache it, but something always goes wrong. :(

Honestly I can't wait for Github to start adding M3/M4 action runners. They support nested virtualization and with this I will be able to drop support for Intel-based macOS runners.

@joffrey-bion
Copy link

joffrey-bion commented Nov 19, 2024 •
edited
Loading

Honestly I can't wait for Github to start adding M3/M4 action runners. They support nested virtualization and with this I will be able to drop support for Intel-based macOS runners.

@douglascamata hopefully they will pre-install Colima again. It was already there in macos-12 runners, so I didn't even need your action, just a colima start and brew install docker.

I don't understand why it's no longer there in macos-13.

@joffrey-bion
Copy link

Plus it's kinda difficult to cache all this Homebrew stuff. I already spent a lot of time trying to figure out how to cache it, but something always goes wrong

I was about to try to do that, so thanks for letting me know that I would probably waste my time 😆 This saves me some pointless hassle.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working dependencies Pull requests that update a dependency file help wanted Extra attention is needed need-more-info Needs more info.
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@douglascamata @joffrey-bion @matiktli @adfoster-r7