CI run for rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh

Radicle repository id rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh

Table of Contents

Run log

[2025-09-20T10:17:47Z INFO  ambient] ambient starts
[=3h[=3hBdsDxe: failed to load Boot0001 "UEFI QEMU DVD-ROM QM00005 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x2,0xFFFF,0x0): Not Found
BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
BdsDxe: starting Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
Welcome to GRUB!


INFO: Block devices
NAME   MAJ:MIN RM   SIZE RO TYPE MOUNTPOINTS
vda    254:0    0   100G  0 disk 
├─vda1 254:1    0   953M  0 part 
└─vda2 254:2    0  99.1G  0 part /
vdb    254:16   0   6.1M  1 disk 
vdc    254:32   0   2.4M  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 755.1M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-09-20 10:15 ./
-rw-r--r-- _rad/_rad       958 2025-09-20 10:15 ./plan.yaml
-rwxr-xr-x _rad/_rad   6410400 2025-09-20 10:15 ./run-ci
INFO: Extracted files:
  4587526      4 drwxr-xr-x   2 1001     1001         4096 Sep 20 10:15 .
  4587527      4 -rw-r--r--   1 1001     1001          958 Sep 20 10:15 ./plan.yaml
  4587530   6264 -rwxr-xr-x   1 1001     1001      6410400 Sep 20 10:15 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-09-20T10:16:17Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T10:16:17Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T10:16:17Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T10:16:17Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T10:16:17Z ERROR ambient_execute_plan] ambient-execute-plan starts
RunnablePlan::from_file: filename=plan.yaml
steps:
- action: mkdir
  pathname: /workspace
- action: mkdir
  pathname: /workspace/artifacts
- action: tar_extract
  archive: /dev/vdc
  directory: /workspace/src
- action: tar_extract
  archive: /dev/vdf
  directory: /workspace/deps
- action: tar_extract
  archive: /dev/vde
  directory: /workspace/cache
- action: spawn
  argv:
  - find
  - /workspace
  - -maxdepth
  - '2'
  - -ls
- action: cargo_fmt
- action: cargo_clippy
- action: cargo_build
- action: cargo_test
- action: shell
  shell: |
    git reset --hard
    git clean -fdx
- action: deb
- action: tar_create
  archive: /dev/vde
  directory: /workspace/cache
- action: tar_create
  archive: /dev/vdd
  directory: /workspace/artifacts
executor_drive: /dev/vdb
source_drive: /dev/vdc
artifact_drive: /dev/vdd
cache_drive: /dev/vde
deps_drive: /dev/vdf
workspace_dir: /workspace
source_dir: /workspace/src
deps_dir: /workspace/deps
cache_dir: /workspace/cache
artifacts_dir: /workspace/artifacts


RUN: Action Mkdir {
    pathname: "/workspace",
}
[2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace/artifacts",
    }
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdc",
    directory: "/workspace/src",
}
[2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-09-20T10:16:19Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-09-20T10:16:19Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-09-20T10:16:19Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-09-20T10:17:31Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-09-20T10:17:31Z DEBUG ambient_ci::action] Plan::execute: Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
  2621441      4 drwxr-xr-x   6 root     root         4096 Sep 20 10:16 /workspace
  2621573      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/deps
  2621574      0 -rw-r--r--   1 root     root            0 Sep 16 13:39 /workspace/deps/.package-cache-mutate
  2621577      4 drwxr-xr-x   5 root     root         4096 Sep 16 13:39 /workspace/deps/registry
  2621575    112 -rw-r--r--   1 root     root       114688 Sep 20 10:15 /workspace/deps/.global-cache
  2621576      0 -rw-r--r--   1 root     root            0 Sep 16 13:39 /workspace/deps/.package-cache
  2621443      4 drwxr-xr-x   9 root     root         4096 Sep 20 10:15 /workspace/src
  2621504      4 -rw-r--r--   1 root     root         1194 Sep 20 10:15 /workspace/src/Cargo.toml
  2621483      4 -rw-r--r--   1 root     root         3100 Sep 20 10:15 /workspace/src/INSTALL.md
  2621484      8 -rw-r--r--   1 root     root         6544 Sep 20 10:15 /workspace/src/NEWS.md
  2621482     36 -rw-r--r--   1 root     root        35149 Sep 20 10:15 /workspace/src/COPYING.md
  2621571      4 -rw-r--r--   1 root     root          380 Sep 20 10:15 /workspace/src/ambient.yaml
  2621501      4 -rw-r--r--   1 root     root          755 Sep 20 10:15 /workspace/src/build.rs
  2621505      4 drwxr-xr-x   8 root     root         4096 Sep 20 10:15 /workspace/src/.git
  2621448      4 -rwxr-xr-x   1 root     root          301 Sep 20 10:15 /workspace/src/test.sh
  2621450      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/src/src
  2621500      4 -rw-r--r--   1 root     root         1758 Sep 20 10:15 /workspace/src/README.md
  2621446      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.ambient
  2621444      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.radicle
  2621485      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/src/debian
  2621449     16 -rw-r--r--   1 root     root        16324 Sep 20 10:15 /workspace/src/ambient.md
  2621478      4 -rw-r--r--   1 root     root          224 Sep 20 10:15 /workspace/src/ambient.subplot
  2621572     68 -rw-r--r--   1 root     root        66173 Sep 20 10:15 /workspace/src/Cargo.lock
  2621502      4 -rw-r--r--   1 root     root          118 Sep 20 10:15 /workspace/src/.gitignore
  2621570      4 -rw-r--r--   1 root     root          376 Sep 20 10:15 /workspace/src/ambient-execute-plan.1
  2621481      4 -rw-r--r--   1 root     root         1607 Sep 20 10:15 /workspace/src/ambient.py
  2621503      4 -rw-r--r--   1 root     root          279 Sep 20 10:15 /workspace/src/ambient.1
  2621497      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.woodpecker
  2621569      4 -rw-r--r--   1 root     root          584 Sep 20 10:15 /workspace/src/Makefile
  2621479      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.cargo
  2621442      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:16 /workspace/artifacts
  2647565      4 drwxr-xr-x   5 root     root         4096 Sep 16 13:41 /workspace/cache
  2647566      4 drwxr-xr-x   7 root     root         4096 Sep 19 10:08 /workspace/cache/debug
  2657733      4 -rw-r--r--   1 root     root         1904 Sep 19 10:09 /workspace/cache/.rustc_info.json
  2655904      4 drwxr-xr-x   7 root     root         4096 Sep 16 13:41 /workspace/cache/release
  2657734      4 drwxr-xr-x   3 root     root         4096 Sep 16 13:41 /workspace/cache/x86_64-unknown-linux-musl
RUN: Action finished OK
RUN: Action CargoFmt
[2025-09-20T10:17:32Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
SPAWN: argv=["env", "cargo", "--version"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
env: ‘cargo’: No such file or directory
ERROR: Action failed: failed to execute env
caused by: command failed: "env": exit code 127
ERROR: failed to execute env
caused by: command failed: "env": exit code 127
EXIT CODE: 1
[2025-09-20T10:17:47Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "patch",
  "repository": {
    "id": "rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh",
    "name": "ambient-ci",
    "description": "Ambient command line tool to run CI projects locally",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "action": "Created",
  "patch": {
    "id": "44a555e774502917e8f24cdd1b3da26133e0b529",
    "author": {
      "id": "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV",
      "alias": "liw"
    },
    "title": "feat: add \"context\" for executing runnable actions",
    "state": {
      "status": "open",
      "conflicts": []
    },
    "before": "08111e942568f67f514be38f1f48e0e80ab38cc6",
    "after": "a2f50700a01d254a409f13280c596115ac0b77b3",
    "commits": [
      "a2f50700a01d254a409f13280c596115ac0b77b3"
    ],
    "target": "08111e942568f67f514be38f1f48e0e80ab38cc6",
    "labels": [],
    "assignees": [],
    "revisions": [
      {
        "id": "44a555e774502917e8f24cdd1b3da26133e0b529",
        "author": {
          "id": "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV",
          "alias": "liw"
        },
        "description": "The context contains environment variables, and the source directory,\nto start with. Use context everywhere programs get executed. Major\nbenefit of this to users is that all actions get Rust related\nenvironment variables set, not just `cargo_*` actions. This shouldn't\nbreak anything.\n\nRefactor all code that executes programs, because it's now possible\nto do things in simpler ways.",
        "base": "08111e942568f67f514be38f1f48e0e80ab38cc6",
        "oid": "a2f50700a01d254a409f13280c596115ac0b77b3",
        "timestamp": 1758363342
      }
    ]
  }
}

Ambient stdout

[2025-09-20T10:15:42Z INFO  ambient] ambient starts
[2025-09-20T10:15:42Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-09-20T10:15:42Z DEBUG ambient] complete configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: None,
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-09-20T10:15:42Z DEBUG ambient] configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: None,
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-09-20T10:15:42Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/meta.yaml
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] latest commit: "08111e942568f67f514be38f1f48e0e80ab38cc6"
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] is a git repository
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] git repository is clean
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] current (HEAD) commit: a2f50700a01d254a409f13280c596115ac0b77b3
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] no dry run requested
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] forced run requested
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] run? true
[2025-09-20T10:15:42Z INFO  ambient_ci::run] project rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh: running CI
[2025-09-20T10:15:42Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-09-20T10:15:42Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmpQsg3wL/src",
        dependenciesdir: "/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/dependencies", "cargo", "--version"]
       cwd=/tmp/.tmpQsg3wL/src (exists? true)
       extra_env=[]
cargo 1.90.0 (840b83a10 2025-07-30)
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/dependencies", "cargo", "clippy", "--version"]
       cwd=/tmp/.tmpQsg3wL/src (exists? true)
       extra_env=[]
clippy 0.1.90 (1159e78c47 2025-09-14)
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/dependencies", "rustc", "--version"]
       cwd=/tmp/.tmpQsg3wL/src (exists? true)
       extra_env=[]
rustc 1.90.0 (1159e78c4 2025-09-14)
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/dependencies", "cargo", "fetch"]
       cwd=/tmp/.tmpQsg3wL/src (exists? true)
       extra_env=[]
[2025-09-20T10:15:43Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpRzYdbT/src.tar
[2025-09-20T10:15:43Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpRzYdbT/deps.tar
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] create_executor_vdrive: plan=RunnablePlan {
        steps: [
            Mkdir {
                pathname: "/workspace",
            },
            Mkdir {
                pathname: "/workspace/artifacts",
            },
            TarExtract {
                archive: "/dev/vdc",
                directory: "/workspace/src",
            },
            TarExtract {
                archive: "/dev/vdf",
                directory: "/workspace/deps",
            },
            TarExtract {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            Spawn {
                argv: [
                    "find",
                    "/workspace",
                    "-maxdepth",
                    "2",
                    "-ls",
                ],
            },
            CargoFmt,
            CargoClippy,
            CargoBuild,
            CargoTest,
            Shell {
                shell: "git reset --hard\ngit clean -fdx\n",
            },
            Deb,
            TarCreate {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            TarCreate {
                archive: "/dev/vdd",
                directory: "/workspace/artifacts",
            },
        ],
        executor_drive: Some(
            "/dev/vdb",
        ),
        source_drive: Some(
            "/dev/vdc",
        ),
        artifact_drive: Some(
            "/dev/vdd",
        ),
        cache_drive: Some(
            "/dev/vde",
        ),
        deps_drive: Some(
            "/dev/vdf",
        ),
        workspace_dir: Some(
            "/workspace",
        ),
        source_dir: Some(
            "/workspace/src",
        ),
        deps_dir: Some(
            "/workspace/deps",
        ),
        cache_dir: Some(
            "/workspace/cache",
        ),
        artifacts_dir: Some(
            "/workspace/artifacts",
        ),
    }
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpRzYdbT/ambient-execute-plan/run-ci
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] copy OK: true
[2025-09-20T10:15:51Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpRzYdbT/executor.tar
[2025-09-20T10:16:10Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh, exists? true
[2025-09-20T10:16:10Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log OK
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] with_runcmd called: "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n"
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] LocalDataStoreBuilder: LocalDataStoreBuilder {
        hostname: Some(
            "ambient",
        ),
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] run QEMU
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmprs3KAK/.tmpyG27Mc/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] LocalDataStore: LocalDataStore {
        hostname: "ambient",
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp3cQHOc/meta-data
[2025-09-20T10:16:10Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp3cQHOc/user-data
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmprs3KAK/.tmpyG27Mc/console.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::util] create file /srv/tmp/.tmprs3KAK/.tmpyG27Mc/console.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::util] create file /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log
[2025-09-20T10:16:10Z DEBUG ambient_ci::qemu] run QEMU: QemuArgs {
        args: [
            "-m",
            "7629",
            "-smp",
            "cpus=4",
            "-cpu",
            "kvm64",
            "-machine",
            "type=q35,accel=kvm,usb=off",
            "-uuid",
            "a85c9de7-edc0-4e54-bead-112e5733582c",
            "-boot",
            "strict=on",
            "-name",
            "ambient-ci-vm",
            "-rtc",
            "base=utc,driftfix=slew",
            "-display",
            "none",
            "-device",
            "virtio-rng-pci",
            "-serial",
            "file:/srv/tmp/.tmprs3KAK/.tmpyG27Mc/console.log",
            "-serial",
            "file:/srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/run.log",
            "-drive",
            "if=pflash,format=raw,unit=0,file=/usr/share/ovmf/OVMF.fd,readonly=on",
            "-drive",
            "if=pflash,format=raw,unit=1,file=/srv/tmp/.tmprs3KAK/.tmpyG27Mc/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmprs3KAK/.tmpyG27Mc/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpRzYdbT/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmprs3KAK/.tmpyG27Mc/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpRzYdbT/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpRzYdbT/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpRzYdbT/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpRzYdbT/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-09-20T10:17:38Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-09-20T10:17:38Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-09-20T10:16:17Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T10:16:17Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T10:16:17Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T10:16:17Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T10:16:17Z ERROR ambient_execute_plan] ambient-execute-plan starts
    RunnablePlan::from_file: filename=plan.yaml
    steps:
    - action: mkdir
      pathname: /workspace
    - action: mkdir
      pathname: /workspace/artifacts
    - action: tar_extract
      archive: /dev/vdc
      directory: /workspace/src
    - action: tar_extract
      archive: /dev/vdf
      directory: /workspace/deps
    - action: tar_extract
      archive: /dev/vde
      directory: /workspace/cache
    - action: spawn
      argv:
      - find
      - /workspace
      - -maxdepth
      - '2'
      - -ls
    - action: cargo_fmt
    - action: cargo_clippy
    - action: cargo_build
    - action: cargo_test
    - action: shell
      shell: |
        git reset --hard
        git clean -fdx
    - action: deb
    - action: tar_create
      archive: /dev/vde
      directory: /workspace/cache
    - action: tar_create
      archive: /dev/vdd
      directory: /workspace/artifacts
    executor_drive: /dev/vdb
    source_drive: /dev/vdc
    artifact_drive: /dev/vdd
    cache_drive: /dev/vde
    deps_drive: /dev/vdf
    workspace_dir: /workspace
    source_dir: /workspace/src
    deps_dir: /workspace/deps
    cache_dir: /workspace/cache
    artifacts_dir: /workspace/artifacts
    
    
    RUN: Action Mkdir {
        pathname: "/workspace",
    }
    [2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace/artifacts",
        }
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
    [2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-09-20T10:16:17Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-09-20T10:16:17Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-09-20T10:16:19Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-09-20T10:16:19Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-09-20T10:16:19Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-09-20T10:17:31Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-09-20T10:17:31Z DEBUG ambient_ci::action] Plan::execute: Spawn {
            argv: [
                "find",
                "/workspace",
                "-maxdepth",
                "2",
                "-ls",
            ],
        }
    SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
      2621441      4 drwxr-xr-x   6 root     root         4096 Sep 20 10:16 /workspace
      2621573      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/deps
      2621574      0 -rw-r--r--   1 root     root            0 Sep 16 13:39 /workspace/deps/.package-cache-mutate
      2621577      4 drwxr-xr-x   5 root     root         4096 Sep 16 13:39 /workspace/deps/registry
      2621575    112 -rw-r--r--   1 root     root       114688 Sep 20 10:15 /workspace/deps/.global-cache
      2621576      0 -rw-r--r--   1 root     root            0 Sep 16 13:39 /workspace/deps/.package-cache
      2621443      4 drwxr-xr-x   9 root     root         4096 Sep 20 10:15 /workspace/src
      2621504      4 -rw-r--r--   1 root     root         1194 Sep 20 10:15 /workspace/src/Cargo.toml
      2621483      4 -rw-r--r--   1 root     root         3100 Sep 20 10:15 /workspace/src/INSTALL.md
      2621484      8 -rw-r--r--   1 root     root         6544 Sep 20 10:15 /workspace/src/NEWS.md
      2621482     36 -rw-r--r--   1 root     root        35149 Sep 20 10:15 /workspace/src/COPYING.md
      2621571      4 -rw-r--r--   1 root     root          380 Sep 20 10:15 /workspace/src/ambient.yaml
      2621501      4 -rw-r--r--   1 root     root          755 Sep 20 10:15 /workspace/src/build.rs
      2621505      4 drwxr-xr-x   8 root     root         4096 Sep 20 10:15 /workspace/src/.git
      2621448      4 -rwxr-xr-x   1 root     root          301 Sep 20 10:15 /workspace/src/test.sh
      2621450      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/src/src
      2621500      4 -rw-r--r--   1 root     root         1758 Sep 20 10:15 /workspace/src/README.md
      2621446      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.ambient
      2621444      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.radicle
      2621485      4 drwxr-xr-x   3 root     root         4096 Sep 20 10:15 /workspace/src/debian
      2621449     16 -rw-r--r--   1 root     root        16324 Sep 20 10:15 /workspace/src/ambient.md
      2621478      4 -rw-r--r--   1 root     root          224 Sep 20 10:15 /workspace/src/ambient.subplot
      2621572     68 -rw-r--r--   1 root     root        66173 Sep 20 10:15 /workspace/src/Cargo.lock
      2621502      4 -rw-r--r--   1 root     root          118 Sep 20 10:15 /workspace/src/.gitignore
      2621570      4 -rw-r--r--   1 root     root          376 Sep 20 10:15 /workspace/src/ambient-execute-plan.1
      2621481      4 -rw-r--r--   1 root     root         1607 Sep 20 10:15 /workspace/src/ambient.py
      2621503      4 -rw-r--r--   1 root     root          279 Sep 20 10:15 /workspace/src/ambient.1
      2621497      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.woodpecker
      2621569      4 -rw-r--r--   1 root     root          584 Sep 20 10:15 /workspace/src/Makefile
      2621479      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:15 /workspace/src/.cargo
      2621442      4 drwxr-xr-x   2 root     root         4096 Sep 20 10:16 /workspace/artifacts
      2647565      4 drwxr-xr-x   5 root     root         4096 Sep 16 13:41 /workspace/cache
      2647566      4 drwxr-xr-x   7 root     root         4096 Sep 19 10:08 /workspace/cache/debug
      2657733      4 -rw-r--r--   1 root     root         1904 Sep 19 10:09 /workspace/cache/.rustc_info.json
      2655904      4 drwxr-xr-x   7 root     root         4096 Sep 16 13:41 /workspace/cache/release
      2657734      4 drwxr-xr-x   3 root     root         4096 Sep 16 13:41 /workspace/cache/x86_64-unknown-linux-musl
    RUN: Action finished OK
    RUN: Action CargoFmt
    [2025-09-20T10:17:32Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
    SPAWN: argv=["env", "cargo", "--version"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    env: ‘cargo’: No such file or directory
    ERROR: Action failed: failed to execute env
    caused by: command failed: "env": exit code 127
    ERROR: failed to execute env
    caused by: command failed: "env": exit code 127
    EXIT CODE: 1
    
    ====================
[2025-09-20T10:17:39Z DEBUG ambient_ci::run] remove old cache
[2025-09-20T10:17:39Z DEBUG ambient_ci::run] extract cache
[2025-09-20T10:17:45Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-09-20T10:17:45Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/rad:zwPaQSTBX8hktn22F6tHAZSFH2Fh/meta.yaml
[2025-09-20T10:17:47Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>