CI run for clab

Radicle repository id rad:z2aW1bujxH96GsWdKBcFqDpzSNnUS

Table of Contents

Run log

[2025-08-08T09:07:36Z 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
sr0     11:0    1   370K  0 rom  
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   2.8M  1 disk 
vdc    254:32   0   420K  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 227.9M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-08-08 09:05 ./
-rw-r--r-- _rad/_rad      1449 2025-08-08 09:05 ./plan.yaml
-rwxr-xr-x _rad/_rad   2949784 2025-08-08 09:05 ./run-ci
INFO: Extracted files:
  1477881      4 drwxr-xr-x   2 1001     1001         4096 Aug  8 09:05 .
  1477809      4 -rw-r--r--   1 1001     1001         1449 Aug  8 09:05 ./plan.yaml
  1477810   2884 -rwxr-xr-x   1 1001     1001      2949784 Aug  8 09:05 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-08-08T09:06:07Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-08-08T09:06:07Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-08-08T09:06:07Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-08-08T09:06:07Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-08-08T09:06:07Z 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: |
    subplot docgen clab.subplot -o /workspace/artifacts/clab.html
- action: shell
  shell: |
    export DEBEMAIL=liw@liw.fi
    export DEBFULLNAME="Lars Wirzenius"
    export HOME=/root
    export PATH="/root/.cargo/bin:/bin:/sbin"
    export SOPASS_LOG=trace
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps

    V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
    T="$(date -u "+%Y%m%dT%H%M%S")"
    version="$V.ci$T-1"
    dch -v "$version" "CI build under Ambient."
    dch -r ''
- 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-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace/artifacts",
    }
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdc",
    directory: "/workspace/src",
}
[2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-08-08T09:06:18Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-08T09:06:18Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-08T09:06:18Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-08-08T09:06:32Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-08-08T09:06:32Z 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=[]
  3932161      4 drwxr-xr-x   6 root     root         4096 Aug  8 09:06 /workspace
  3932162      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:06 /workspace/artifacts
  3932525      4 drwxr-xr-x   3 root     root         4096 Aug  8 09:05 /workspace/deps
  3932527     56 -rw-r--r--   1 root     root        57344 Aug  8 09:05 /workspace/deps/.global-cache
  3932526      0 -rw-r--r--   1 root     root            0 Jun 28 03:37 /workspace/deps/.package-cache-mutate
  3932528      0 -rw-r--r--   1 root     root            0 Jun  7 10:44 /workspace/deps/.package-cache
  3932529      4 drwxr-xr-x   5 root     root         4096 Jun  7 10:44 /workspace/deps/registry
  3932163      4 drwxr-xr-x   7 root     root         4096 Aug  8 09:05 /workspace/src
  3932173      4 -rw-r--r--   1 root     root          235 Aug  8 09:05 /workspace/src/clab.subplot
  3932164      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/.radicle
  3932524     16 -rw-r--r--   1 root     root        14233 Aug  8 09:05 /workspace/src/Cargo.lock
  3932184      4 -rw-r--r--   1 root     root          356 Aug  8 09:05 /workspace/src/Cargo.toml
  3932183      4 -rw-r--r--   1 root     root           44 Aug  8 09:05 /workspace/src/.gitignore
  3932522      4 -rw-r--r--   1 root     root          191 Aug  8 09:05 /workspace/src/sample.yaml
  3932185      4 drwxr-xr-x   8 root     root         4096 Aug  8 09:05 /workspace/src/.git
  3932174      4 drwxr-xr-x   3 root     root         4096 Aug  8 09:05 /workspace/src/debian
  3932167      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/subplot
  3932170      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/src
  3932523      4 -rw-r--r--   1 root     root         2771 Aug  8 09:05 /workspace/src/clab.md
  3932166      4 -rwxr-xr-x   1 root     root          810 Aug  8 09:05 /workspace/src/check
  3939110      4 drwxr-xr-x   4 root     root         4096 Jun  7 10:44 /workspace/cache
  3940876      4 drwxr-xr-x   7 root     root         4096 Aug  5 14:05 /workspace/cache/release
  3939111      4 drwxr-xr-x   7 root     root         4096 Aug  5 14:05 /workspace/cache/debug
  3941859      4 -rw-r--r--   1 root     root         1034 Aug  5 14:05 /workspace/cache/.rustc_info.json
RUN: Action finished OK
RUN: Action CargoFmt
[2025-08-08T09:06:32Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
SPAWN: argv=["cargo", "fmt", "--check"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
RUN: Action finished OK
RUN: Action CargoClippy
[2025-08-08T09:06:32Z DEBUG ambient_ci::action] Plan::execute: CargoClippy
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
   Compiling proc-macro2 v1.0.71
   Compiling unicode-ident v1.0.12
   Compiling version_check v0.9.4
   Compiling libc v0.2.151
   Compiling syn v1.0.109
   Compiling proc-macro-error-attr v1.0.4
   Compiling proc-macro-error v1.0.4
   Compiling autocfg v1.1.0
   Compiling serde v1.0.193
   Compiling indexmap v1.9.3
    Checking unicode-width v0.1.11
   Compiling unicode-segmentation v1.10.1
   Compiling quote v1.0.33
   Compiling rustix v0.38.28
   Compiling syn v2.0.43
    Checking atty v0.2.14
   Compiling heck v0.3.3
    Checking textwrap v0.11.0
    Checking bitflags v2.4.1
    Checking hashbrown v0.12.3
   Compiling anyhow v1.0.77
    Checking bitflags v1.3.2
    Checking cfg-if v1.0.0
    Checking vec_map v0.8.2
    Checking linked-hash-map v0.5.6
    Checking linux-raw-sys v0.4.12
    Checking ansi_term v0.12.1
    Checking strsim v0.8.0
    Checking clap v2.34.0
   Compiling serde_derive v1.0.193
    Checking yaml-rust v0.4.5
    Checking dirs-sys-next v0.1.2
    Checking lazy_static v1.4.0
    Checking fastrand v2.0.1
    Checking ryu v1.0.16
    Checking tempfile v3.9.0
    Checking directories-next v2.0.0
   Compiling structopt-derive v0.4.18
    Checking structopt v0.3.26
    Checking serde_yaml v0.8.26
    Checking clab v0.6.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.17s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-08-08T09:06:48Z DEBUG ambient_ci::action] Plan::execute: CargoBuild
SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
   Compiling libc v0.2.151
   Compiling unicode-width v0.1.11
   Compiling linux-raw-sys v0.4.12
   Compiling hashbrown v0.12.3
   Compiling textwrap v0.11.0
   Compiling vec_map v0.8.2
   Compiling ansi_term v0.12.1
   Compiling bitflags v1.3.2
   Compiling cfg-if v1.0.0
   Compiling bitflags v2.4.1
   Compiling strsim v0.8.0
   Compiling linked-hash-map v0.5.6
   Compiling rustix v0.38.28
   Compiling yaml-rust v0.4.5
   Compiling indexmap v1.9.3
   Compiling atty v0.2.14
   Compiling clap v2.34.0
   Compiling dirs-sys-next v0.1.2
   Compiling serde v1.0.193
   Compiling fastrand v2.0.1
   Compiling ryu v1.0.16
   Compiling lazy_static v1.4.0
   Compiling structopt v0.3.26
   Compiling tempfile v3.9.0
   Compiling anyhow v1.0.77
   Compiling directories-next v2.0.0
   Compiling serde_yaml v0.8.26
   Compiling clab v0.6.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.80s
RUN: Action finished OK
RUN: Action CargoTest
[2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: CargoTest
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/clab-62144d060356c8ce)

running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

     Running unittests src/main.rs (/workspace/cache/debug/deps/clab-e8828f6cd798d352)

running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

   Doc-tests clab

running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

RUN: Action finished OK
RUN: Action Shell {
    shell: "subplot docgen clab.subplot -o /workspace/artifacts/clab.html\n",
}
[2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "subplot docgen clab.subplot -o /workspace/artifacts/clab.html\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nsubplot docgen clab.subplot -o /workspace/artifacts/clab.html\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ subplot docgen clab.subplot -o /workspace/artifacts/clab.html
 INFO Starting Subplot
 INFO Subplot finished successfully
RUN: Action finished OK
RUN: Action Shell {
    shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
}
[2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ export HOME=/root
+ HOME=/root
+ export PATH=/root/.cargo/bin:/bin:/sbin
+ PATH=/root/.cargo/bin:/bin:/sbin
+ export SOPASS_LOG=trace
+ SOPASS_LOG=trace
+ export CARGO_TARGET_DIR=/workspace/cache
+ CARGO_TARGET_DIR=/workspace/cache
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
++ sed 's/-[^-]*$//'
++ dpkg-parsechangelog -SVersion
+ V=0.6.0
++ date -u +%Y%m%dT%H%M%S
+ T=20250808T090655
+ version=0.6.0.ci20250808T090655-1
+ dch -v 0.6.0.ci20250808T090655-1 'CI build under Ambient.'
+ dch -r ''
RUN: Action finished OK
RUN: Action Deb
[2025-08-08T09:06:55Z DEBUG ambient_ci::action] Plan::execute: Deb
SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
+ echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ /bin/env
DEBFULLNAME=Lars Wirzenius
CARGO_TARGET_DIR=/workspace/cache
PWD=/workspace/src
SYSTEMD_EXEC_PID=288
LANG=C.UTF-8
CARGO_HOME=/workspace/deps
DEBEMAIL=liw@liw.fi
INVOCATION_ID=bbbfa7944d0e42418fde6729bac4e708
SHLVL=2
JOURNAL_STREAM=8:928
PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
OLDPWD=/
_=/bin/env
+ command -v cargo
/root/.cargo/bin/cargo
+ command -v rustc
/root/.cargo/bin/rustc
+ cargo --version
cargo 1.89.0 (c24e10642 2025-06-23)
+ rustc --version
rustc 1.89.0 (29483883e 2025-08-04)
++ dpkg-parsechangelog -SSource
+ name=clab
++ dpkg-parsechangelog -SVersion
+ version=0.6.0.ci20250808T090655-1
++ echo 0.6.0.ci20250808T090655-1
++ sed 's/-[^-]*$//'
+ uv=0.6.0.ci20250808T090655
++ dpkg --print-architecture
+ arch=amd64
+ orig=../clab_0.6.0.ci20250808T090655.orig.tar.xz
+ deb=../clab_0.6.0.ci20250808T090655-1_amd64.deb
+ changes=../clab_0.6.0.ci20250808T090655-1_amd64.changes
+ git archive HEAD
+ xz
+ dpkg-buildpackage -us -uc
dpkg-buildpackage: info: source package clab
dpkg-buildpackage: info: source version 0.6.0.ci20250808T090655-1
dpkg-buildpackage: info: source distribution unstable
dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
dpkg-buildpackage: info: host architecture amd64
 dpkg-source --before-build .
 debian/rules clean
dh clean
   dh_clean
 dpkg-source -b .
dpkg-source: info: using source format '3.0 (quilt)'
dpkg-source: info: building clab using existing ./clab_0.6.0.ci20250808T090655.orig.tar.xz
dpkg-source: info: building clab in clab_0.6.0.ci20250808T090655-1.debian.tar.xz
dpkg-source: info: building clab in clab_0.6.0.ci20250808T090655-1.dsc
 debian/rules build
dh build
   dh_update_autotools_config
   dh_autoreconf
   debian/rules override_dh_auto_build
make[1]: Entering directory '/workspace/src'
true
make[1]: Leaving directory '/workspace/src'
   debian/rules override_dh_auto_test
make[1]: Entering directory '/workspace/src'
echo disabled: ./check
disabled: ./check
make[1]: Leaving directory '/workspace/src'
   create-stamp debian/debhelper-build-stamp
 debian/rules binary
dh binary
   dh_testroot
   dh_prep
   debian/rules override_dh_auto_install
make[1]: Entering directory '/workspace/src'
cargo install --path=. --root=debian/clab --offline
  Installing clab v0.6.0 (/workspace/src)
     Locking 61 packages to latest compatible versions
   Compiling proc-macro2 v1.0.71
   Compiling unicode-ident v1.0.12
   Compiling version_check v0.9.4
   Compiling libc v0.2.151
   Compiling syn v1.0.109
   Compiling proc-macro-error-attr v1.0.4
   Compiling proc-macro-error v1.0.4
   Compiling autocfg v1.1.0
   Compiling serde v1.0.193
   Compiling indexmap v1.9.3
   Compiling quote v1.0.33
   Compiling unicode-segmentation v1.10.1
   Compiling syn v2.0.43
   Compiling rustix v0.38.28
   Compiling unicode-width v0.1.11
   Compiling atty v0.2.14
   Compiling textwrap v0.11.0
   Compiling heck v0.3.3
   Compiling hashbrown v0.12.3
   Compiling serde_derive v1.0.193
   Compiling ansi_term v0.12.1
   Compiling bitflags v1.3.2
   Compiling vec_map v0.8.2
   Compiling strsim v0.8.0
   Compiling cfg-if v1.0.0
   Compiling linux-raw-sys v0.4.12
   Compiling bitflags v2.4.1
   Compiling linked-hash-map v0.5.6
   Compiling anyhow v1.0.77
   Compiling yaml-rust v0.4.5
   Compiling structopt-derive v0.4.18
   Compiling clap v2.34.0
   Compiling dirs-sys-next v0.1.2
   Compiling lazy_static v1.4.0
   Compiling ryu v1.0.16
   Compiling fastrand v2.0.1
   Compiling tempfile v3.9.0
   Compiling serde_yaml v0.8.26
   Compiling directories-next v2.0.0
   Compiling structopt v0.3.26
   Compiling clab v0.6.0 (/workspace/src)
    Finished `release` profile [optimized] target(s) in 14.39s
  Installing debian/clab/bin/clab
   Installed package `clab v0.6.0 (/workspace/src)` (executable `clab`)
warning: be sure to add `debian/clab/bin` to your PATH to be able to run the installed binaries
find debian/clab -name '.crates*' -delete
make[1]: Leaving directory '/workspace/src'
   dh_installdocs
   dh_installchangelogs
   dh_perl
   dh_link
   dh_strip_nondeterminism
   dh_compress
   dh_fixperms
   dh_missing
   dh_strip
   dh_makeshlibs
   dh_shlibdeps
   dh_installdeb
   dh_gencontrol
dpkg-gencontrol: warning: Built-Using field of package clab: substitution variable ${cargo:Built-Using} used, but is not defined
dpkg-gencontrol: warning: Built-Using field of package clab: substitution variable ${cargo:Built-Using} used, but is not defined
   dh_md5sums
   dh_builddeb
dpkg-deb: building package 'clab' in '../clab_0.6.0.ci20250808T090655-1_amd64.deb'.
dpkg-deb: building package 'clab-dbgsym' in '../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb'.
 dpkg-genbuildinfo -O../clab_0.6.0.ci20250808T090655-1_amd64.buildinfo
 dpkg-genchanges -O../clab_0.6.0.ci20250808T090655-1_amd64.changes
dpkg-genchanges: info: including full source code in upload
 dpkg-source --after-build .
dpkg-buildpackage: info: full upload (original source is included)
+ ls -l ..
total 524
drwxr-xr-x 2 root root   4096 Aug  8 09:06 artifacts
drwxr-xr-x 4 root root   4096 Jun  7 10:44 cache
-rw-r--r-- 1 root root  57392 Aug  8 09:07 clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb
-rw-r--r-- 1 root root   1512 Aug  8 09:06 clab_0.6.0.ci20250808T090655-1.debian.tar.xz
-rw-r--r-- 1 root root    936 Aug  8 09:06 clab_0.6.0.ci20250808T090655-1.dsc
-rw-r--r-- 1 root root   6897 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.buildinfo
-rw-r--r-- 1 root root   2279 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.changes
-rw-r--r-- 1 root root 422072 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.deb
-rw-r--r-- 1 root root   8940 Aug  8 09:06 clab_0.6.0.ci20250808T090655.orig.tar.xz
drwxr-xr-x 3 root root   4096 Aug  8 09:05 deps
drwxr-xr-x 7 root root   4096 Aug  8 09:05 src
+ for x in ../*.deb
+ dpkg -c ../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-08 09:06 ./
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/.build-id/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/.build-id/95/
-rw-r--r-- root/root    286704 2025-08-08 09:06 ./usr/lib/debug/.build-id/95/7cdd4a94b267d3d4c7e166c7bea11f4f03855e.debug
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/
lrwxrwxrwx root/root         0 2025-08-08 09:06 ./usr/share/doc/clab-dbgsym -> clab
+ for x in ../*.deb
+ dpkg -c ../clab_0.6.0.ci20250808T090655-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-08 09:06 ./
drwxr-xr-x root/root         0 2025-08-08 09:06 ./bin/
-rwxr-xr-x root/root   1225440 2025-08-08 09:06 ./bin/clab
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/
drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/clab/
-rw-r--r-- root/root       231 2025-08-08 09:06 ./usr/share/doc/clab/changelog.Debian.gz
-rw-r--r-- root/root       955 2025-08-08 09:05 ./usr/share/doc/clab/copyright
+ mv ../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb ../clab_0.6.0.ci20250808T090655-1.debian.tar.xz ../clab_0.6.0.ci20250808T090655-1.dsc ../clab_0.6.0.ci20250808T090655-1_amd64.buildinfo ../clab_0.6.0.ci20250808T090655-1_amd64.changes ../clab_0.6.0.ci20250808T090655-1_amd64.deb ../clab_0.6.0.ci20250808T090655.orig.tar.xz /workspace/artifacts
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-08T09:07:16Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vdd",
    directory: "/workspace/artifacts",
}
[2025-08-08T09:07:18Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
RUN: Action finished OK
ambient-execute-plan ends
EXIT CODE: 0
[2025-08-08T09:07:36Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z2aW1bujxH96GsWdKBcFqDpzSNnUS",
    "name": "clab",
    "description": "Command line address book",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "pusher": {
    "id": "did:key:z6MkkYez6QAFWvmdudjrfMXySu1NTpSrraJotCJFwGgkmgx8",
    "alias": "ci0"
  },
  "before": "f60d4b52cf9d265c46ecc00ef6c3eccc9d74e1c0",
  "after": "f60d4b52cf9d265c46ecc00ef6c3eccc9d74e1c0",
  "branch": "main",
  "commits": [
    "f60d4b52cf9d265c46ecc00ef6c3eccc9d74e1c0"
  ]
}

Ambient stdout

[2025-08-08T09:05:26Z INFO  ambient] ambient starts
[2025-08-08T09:05:26Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-08-08T09:05:26Z DEBUG ambient] load files named with --config: /tmp/.tmpP5Gdbe/ambient_extra_values.yaml
[2025-08-08T09:05:26Z 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: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi",
        ),
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        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-08-08T09:05:26Z 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: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi",
        ),
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        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-08-08T09:05:26Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/clab/meta.yaml
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] latest commit: "f60d4b52cf9d265c46ecc00ef6c3eccc9d74e1c0"
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] is a git repository
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] git repository is clean
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] current (HEAD) commit: f60d4b52cf9d265c46ecc00ef6c3eccc9d74e1c0
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] no dry run requested
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] forced run requested
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] run? true
[2025-08-08T09:05:26Z INFO  ambient_ci::run] project clab: running CI
[2025-08-08T09:05:26Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-08-08T09:05:26Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmpsI1AbE/src",
        dependenciesdir: "/srv/ambient-state/clab/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/clab/dependencies", "cargo", "fetch", "--locked"]
       cwd=/tmp/.tmpsI1AbE/src (exists? true)
       extra_env=[]
[2025-08-08T09:05:27Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpHNy4WI/src.tar
[2025-08-08T09:05:27Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpHNy4WI/deps.tar
[2025-08-08T09:05:33Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-08-08T09:05:33Z 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: "subplot docgen clab.subplot -o /workspace/artifacts/clab.html\n",
            },
            Shell {
                shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\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-08-08T09:05:33Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-08-08T09:05:33Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpHNy4WI/ambient-execute-plan/run-ci
[2025-08-08T09:05:33Z DEBUG ambient_ci::run] copy OK: true
[2025-08-08T09:05:33Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpHNy4WI/executor.tar
[2025-08-08T09:05:44Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/clab/run.log
[2025-08-08T09:05:44Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/clab, exists? true
[2025-08-08T09:05:44Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/clab/run.log
[2025-08-08T09:05:44Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/clab/run.log OK
[2025-08-08T09:05:44Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-08-08T09:05:44Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-08-08T09:05:44Z 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-08-08T09:05:44Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-08-08T09:05:44Z 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-08-08T09:05:44Z DEBUG ambient_ci::qemu] run QEMU
[2025-08-08T09:05:44Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-08-08T09:05:44Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmpdVAKH0/.tmpxTaISq/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-08-08T09:05:45Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-08-08T09:05:45Z 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-08-08T09:05:45Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpECSBAu/meta-data
[2025-08-08T09:05:45Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpECSBAu/user-data
[2025-08-08T09:05:45Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmpdVAKH0/.tmpxTaISq/console.log
[2025-08-08T09:05:45Z DEBUG ambient_ci::util] create file /srv/tmp/.tmpdVAKH0/.tmpxTaISq/console.log
[2025-08-08T09:05:45Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/clab/run.log
[2025-08-08T09:05:45Z DEBUG ambient_ci::util] create file /srv/ambient-state/clab/run.log
[2025-08-08T09:05:45Z 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/.tmpdVAKH0/.tmpxTaISq/console.log",
            "-serial",
            "file:/srv/ambient-state/clab/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/.tmpdVAKH0/.tmpxTaISq/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmpdVAKH0/.tmpxTaISq/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpHNy4WI/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmpdVAKH0/.tmpxTaISq/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpHNy4WI/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpHNy4WI/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpHNy4WI/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpHNy4WI/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-08-08T09:07:33Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-08-08T09:07:33Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-08-08T09:06:07Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-08-08T09:06:07Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-08-08T09:06:07Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-08T09:06:07Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-08T09:06:07Z 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: |
        subplot docgen clab.subplot -o /workspace/artifacts/clab.html
    - action: shell
      shell: |
        export DEBEMAIL=liw@liw.fi
        export DEBFULLNAME="Lars Wirzenius"
        export HOME=/root
        export PATH="/root/.cargo/bin:/bin:/sbin"
        export SOPASS_LOG=trace
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
    
        V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
        T="$(date -u "+%Y%m%dT%H%M%S")"
        version="$V.ci$T-1"
        dch -v "$version" "CI build under Ambient."
        dch -r ''
    - 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-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace/artifacts",
        }
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
    [2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-08-08T09:06:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-08-08T09:06:07Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-08-08T09:06:18Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-08T09:06:18Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-08T09:06:18Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-08-08T09:06:32Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-08-08T09:06:32Z 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=[]
      3932161      4 drwxr-xr-x   6 root     root         4096 Aug  8 09:06 /workspace
      3932162      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:06 /workspace/artifacts
      3932525      4 drwxr-xr-x   3 root     root         4096 Aug  8 09:05 /workspace/deps
      3932527     56 -rw-r--r--   1 root     root        57344 Aug  8 09:05 /workspace/deps/.global-cache
      3932526      0 -rw-r--r--   1 root     root            0 Jun 28 03:37 /workspace/deps/.package-cache-mutate
      3932528      0 -rw-r--r--   1 root     root            0 Jun  7 10:44 /workspace/deps/.package-cache
      3932529      4 drwxr-xr-x   5 root     root         4096 Jun  7 10:44 /workspace/deps/registry
      3932163      4 drwxr-xr-x   7 root     root         4096 Aug  8 09:05 /workspace/src
      3932173      4 -rw-r--r--   1 root     root          235 Aug  8 09:05 /workspace/src/clab.subplot
      3932164      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/.radicle
      3932524     16 -rw-r--r--   1 root     root        14233 Aug  8 09:05 /workspace/src/Cargo.lock
      3932184      4 -rw-r--r--   1 root     root          356 Aug  8 09:05 /workspace/src/Cargo.toml
      3932183      4 -rw-r--r--   1 root     root           44 Aug  8 09:05 /workspace/src/.gitignore
      3932522      4 -rw-r--r--   1 root     root          191 Aug  8 09:05 /workspace/src/sample.yaml
      3932185      4 drwxr-xr-x   8 root     root         4096 Aug  8 09:05 /workspace/src/.git
      3932174      4 drwxr-xr-x   3 root     root         4096 Aug  8 09:05 /workspace/src/debian
      3932167      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/subplot
      3932170      4 drwxr-xr-x   2 root     root         4096 Aug  8 09:05 /workspace/src/src
      3932523      4 -rw-r--r--   1 root     root         2771 Aug  8 09:05 /workspace/src/clab.md
      3932166      4 -rwxr-xr-x   1 root     root          810 Aug  8 09:05 /workspace/src/check
      3939110      4 drwxr-xr-x   4 root     root         4096 Jun  7 10:44 /workspace/cache
      3940876      4 drwxr-xr-x   7 root     root         4096 Aug  5 14:05 /workspace/cache/release
      3939111      4 drwxr-xr-x   7 root     root         4096 Aug  5 14:05 /workspace/cache/debug
      3941859      4 -rw-r--r--   1 root     root         1034 Aug  5 14:05 /workspace/cache/.rustc_info.json
    RUN: Action finished OK
    RUN: Action CargoFmt
    [2025-08-08T09:06:32Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
    SPAWN: argv=["cargo", "fmt", "--check"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    RUN: Action finished OK
    RUN: Action CargoClippy
    [2025-08-08T09:06:32Z DEBUG ambient_ci::action] Plan::execute: CargoClippy
    SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
       Compiling proc-macro2 v1.0.71
       Compiling unicode-ident v1.0.12
       Compiling version_check v0.9.4
       Compiling libc v0.2.151
       Compiling syn v1.0.109
       Compiling proc-macro-error-attr v1.0.4
       Compiling proc-macro-error v1.0.4
       Compiling autocfg v1.1.0
       Compiling serde v1.0.193
       Compiling indexmap v1.9.3
        Checking unicode-width v0.1.11
       Compiling unicode-segmentation v1.10.1
       Compiling quote v1.0.33
       Compiling rustix v0.38.28
       Compiling syn v2.0.43
        Checking atty v0.2.14
       Compiling heck v0.3.3
        Checking textwrap v0.11.0
        Checking bitflags v2.4.1
        Checking hashbrown v0.12.3
       Compiling anyhow v1.0.77
        Checking bitflags v1.3.2
        Checking cfg-if v1.0.0
        Checking vec_map v0.8.2
        Checking linked-hash-map v0.5.6
        Checking linux-raw-sys v0.4.12
        Checking ansi_term v0.12.1
        Checking strsim v0.8.0
        Checking clap v2.34.0
       Compiling serde_derive v1.0.193
        Checking yaml-rust v0.4.5
        Checking dirs-sys-next v0.1.2
        Checking lazy_static v1.4.0
        Checking fastrand v2.0.1
        Checking ryu v1.0.16
        Checking tempfile v3.9.0
        Checking directories-next v2.0.0
       Compiling structopt-derive v0.4.18
        Checking structopt v0.3.26
        Checking serde_yaml v0.8.26
        Checking clab v0.6.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.17s
    RUN: Action finished OK
    RUN: Action CargoBuild
    [2025-08-08T09:06:48Z DEBUG ambient_ci::action] Plan::execute: CargoBuild
    SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
       Compiling libc v0.2.151
       Compiling unicode-width v0.1.11
       Compiling linux-raw-sys v0.4.12
       Compiling hashbrown v0.12.3
       Compiling textwrap v0.11.0
       Compiling vec_map v0.8.2
       Compiling ansi_term v0.12.1
       Compiling bitflags v1.3.2
       Compiling cfg-if v1.0.0
       Compiling bitflags v2.4.1
       Compiling strsim v0.8.0
       Compiling linked-hash-map v0.5.6
       Compiling rustix v0.38.28
       Compiling yaml-rust v0.4.5
       Compiling indexmap v1.9.3
       Compiling atty v0.2.14
       Compiling clap v2.34.0
       Compiling dirs-sys-next v0.1.2
       Compiling serde v1.0.193
       Compiling fastrand v2.0.1
       Compiling ryu v1.0.16
       Compiling lazy_static v1.4.0
       Compiling structopt v0.3.26
       Compiling tempfile v3.9.0
       Compiling anyhow v1.0.77
       Compiling directories-next v2.0.0
       Compiling serde_yaml v0.8.26
       Compiling clab v0.6.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.80s
    RUN: Action finished OK
    RUN: Action CargoTest
    [2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: CargoTest
    SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/clab-62144d060356c8ce)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
         Running unittests src/main.rs (/workspace/cache/debug/deps/clab-e8828f6cd798d352)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
       Doc-tests clab
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "subplot docgen clab.subplot -o /workspace/artifacts/clab.html\n",
    }
    [2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "subplot docgen clab.subplot -o /workspace/artifacts/clab.html\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nsubplot docgen clab.subplot -o /workspace/artifacts/clab.html\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + subplot docgen clab.subplot -o /workspace/artifacts/clab.html
     INFO Starting Subplot
     INFO Subplot finished successfully
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
    [2025-08-08T09:06:54Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + export HOME=/root
    + HOME=/root
    + export PATH=/root/.cargo/bin:/bin:/sbin
    + PATH=/root/.cargo/bin:/bin:/sbin
    + export SOPASS_LOG=trace
    + SOPASS_LOG=trace
    + export CARGO_TARGET_DIR=/workspace/cache
    + CARGO_TARGET_DIR=/workspace/cache
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    ++ sed 's/-[^-]*$//'
    ++ dpkg-parsechangelog -SVersion
    + V=0.6.0
    ++ date -u +%Y%m%dT%H%M%S
    + T=20250808T090655
    + version=0.6.0.ci20250808T090655-1
    + dch -v 0.6.0.ci20250808T090655-1 'CI build under Ambient.'
    + dch -r ''
    RUN: Action finished OK
    RUN: Action Deb
    [2025-08-08T09:06:55Z DEBUG ambient_ci::action] Plan::execute: Deb
    SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    + echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
    PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + /bin/env
    DEBFULLNAME=Lars Wirzenius
    CARGO_TARGET_DIR=/workspace/cache
    PWD=/workspace/src
    SYSTEMD_EXEC_PID=288
    LANG=C.UTF-8
    CARGO_HOME=/workspace/deps
    DEBEMAIL=liw@liw.fi
    INVOCATION_ID=bbbfa7944d0e42418fde6729bac4e708
    SHLVL=2
    JOURNAL_STREAM=8:928
    PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    OLDPWD=/
    _=/bin/env
    + command -v cargo
    /root/.cargo/bin/cargo
    + command -v rustc
    /root/.cargo/bin/rustc
    + cargo --version
    cargo 1.89.0 (c24e10642 2025-06-23)
    + rustc --version
    rustc 1.89.0 (29483883e 2025-08-04)
    ++ dpkg-parsechangelog -SSource
    + name=clab
    ++ dpkg-parsechangelog -SVersion
    + version=0.6.0.ci20250808T090655-1
    ++ echo 0.6.0.ci20250808T090655-1
    ++ sed 's/-[^-]*$//'
    + uv=0.6.0.ci20250808T090655
    ++ dpkg --print-architecture
    + arch=amd64
    + orig=../clab_0.6.0.ci20250808T090655.orig.tar.xz
    + deb=../clab_0.6.0.ci20250808T090655-1_amd64.deb
    + changes=../clab_0.6.0.ci20250808T090655-1_amd64.changes
    + git archive HEAD
    + xz
    + dpkg-buildpackage -us -uc
    dpkg-buildpackage: info: source package clab
    dpkg-buildpackage: info: source version 0.6.0.ci20250808T090655-1
    dpkg-buildpackage: info: source distribution unstable
    dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
    dpkg-buildpackage: info: host architecture amd64
     dpkg-source --before-build .
     debian/rules clean
    dh clean
       dh_clean
     dpkg-source -b .
    dpkg-source: info: using source format '3.0 (quilt)'
    dpkg-source: info: building clab using existing ./clab_0.6.0.ci20250808T090655.orig.tar.xz
    dpkg-source: info: building clab in clab_0.6.0.ci20250808T090655-1.debian.tar.xz
    dpkg-source: info: building clab in clab_0.6.0.ci20250808T090655-1.dsc
     debian/rules build
    dh build
       dh_update_autotools_config
       dh_autoreconf
       debian/rules override_dh_auto_build
    make[1]: Entering directory '/workspace/src'
    true
    make[1]: Leaving directory '/workspace/src'
       debian/rules override_dh_auto_test
    make[1]: Entering directory '/workspace/src'
    echo disabled: ./check
    disabled: ./check
    make[1]: Leaving directory '/workspace/src'
       create-stamp debian/debhelper-build-stamp
     debian/rules binary
    dh binary
       dh_testroot
       dh_prep
       debian/rules override_dh_auto_install
    make[1]: Entering directory '/workspace/src'
    cargo install --path=. --root=debian/clab --offline
      Installing clab v0.6.0 (/workspace/src)
         Locking 61 packages to latest compatible versions
       Compiling proc-macro2 v1.0.71
       Compiling unicode-ident v1.0.12
       Compiling version_check v0.9.4
       Compiling libc v0.2.151
       Compiling syn v1.0.109
       Compiling proc-macro-error-attr v1.0.4
       Compiling proc-macro-error v1.0.4
       Compiling autocfg v1.1.0
       Compiling serde v1.0.193
       Compiling indexmap v1.9.3
       Compiling quote v1.0.33
       Compiling unicode-segmentation v1.10.1
       Compiling syn v2.0.43
       Compiling rustix v0.38.28
       Compiling unicode-width v0.1.11
       Compiling atty v0.2.14
       Compiling textwrap v0.11.0
       Compiling heck v0.3.3
       Compiling hashbrown v0.12.3
       Compiling serde_derive v1.0.193
       Compiling ansi_term v0.12.1
       Compiling bitflags v1.3.2
       Compiling vec_map v0.8.2
       Compiling strsim v0.8.0
       Compiling cfg-if v1.0.0
       Compiling linux-raw-sys v0.4.12
       Compiling bitflags v2.4.1
       Compiling linked-hash-map v0.5.6
       Compiling anyhow v1.0.77
       Compiling yaml-rust v0.4.5
       Compiling structopt-derive v0.4.18
       Compiling clap v2.34.0
       Compiling dirs-sys-next v0.1.2
       Compiling lazy_static v1.4.0
       Compiling ryu v1.0.16
       Compiling fastrand v2.0.1
       Compiling tempfile v3.9.0
       Compiling serde_yaml v0.8.26
       Compiling directories-next v2.0.0
       Compiling structopt v0.3.26
       Compiling clab v0.6.0 (/workspace/src)
        Finished `release` profile [optimized] target(s) in 14.39s
      Installing debian/clab/bin/clab
       Installed package `clab v0.6.0 (/workspace/src)` (executable `clab`)
    warning: be sure to add `debian/clab/bin` to your PATH to be able to run the installed binaries
    find debian/clab -name '.crates*' -delete
    make[1]: Leaving directory '/workspace/src'
       dh_installdocs
       dh_installchangelogs
       dh_perl
       dh_link
       dh_strip_nondeterminism
       dh_compress
       dh_fixperms
       dh_missing
       dh_strip
       dh_makeshlibs
       dh_shlibdeps
       dh_installdeb
       dh_gencontrol
    dpkg-gencontrol: warning: Built-Using field of package clab: substitution variable ${cargo:Built-Using} used, but is not defined
    dpkg-gencontrol: warning: Built-Using field of package clab: substitution variable ${cargo:Built-Using} used, but is not defined
       dh_md5sums
       dh_builddeb
    dpkg-deb: building package 'clab' in '../clab_0.6.0.ci20250808T090655-1_amd64.deb'.
    dpkg-deb: building package 'clab-dbgsym' in '../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb'.
     dpkg-genbuildinfo -O../clab_0.6.0.ci20250808T090655-1_amd64.buildinfo
     dpkg-genchanges -O../clab_0.6.0.ci20250808T090655-1_amd64.changes
    dpkg-genchanges: info: including full source code in upload
     dpkg-source --after-build .
    dpkg-buildpackage: info: full upload (original source is included)
    + ls -l ..
    total 524
    drwxr-xr-x 2 root root   4096 Aug  8 09:06 artifacts
    drwxr-xr-x 4 root root   4096 Jun  7 10:44 cache
    -rw-r--r-- 1 root root  57392 Aug  8 09:07 clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb
    -rw-r--r-- 1 root root   1512 Aug  8 09:06 clab_0.6.0.ci20250808T090655-1.debian.tar.xz
    -rw-r--r-- 1 root root    936 Aug  8 09:06 clab_0.6.0.ci20250808T090655-1.dsc
    -rw-r--r-- 1 root root   6897 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.buildinfo
    -rw-r--r-- 1 root root   2279 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.changes
    -rw-r--r-- 1 root root 422072 Aug  8 09:07 clab_0.6.0.ci20250808T090655-1_amd64.deb
    -rw-r--r-- 1 root root   8940 Aug  8 09:06 clab_0.6.0.ci20250808T090655.orig.tar.xz
    drwxr-xr-x 3 root root   4096 Aug  8 09:05 deps
    drwxr-xr-x 7 root root   4096 Aug  8 09:05 src
    + for x in ../*.deb
    + dpkg -c ../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/.build-id/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/lib/debug/.build-id/95/
    -rw-r--r-- root/root    286704 2025-08-08 09:06 ./usr/lib/debug/.build-id/95/7cdd4a94b267d3d4c7e166c7bea11f4f03855e.debug
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/
    lrwxrwxrwx root/root         0 2025-08-08 09:06 ./usr/share/doc/clab-dbgsym -> clab
    + for x in ../*.deb
    + dpkg -c ../clab_0.6.0.ci20250808T090655-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./bin/
    -rwxr-xr-x root/root   1225440 2025-08-08 09:06 ./bin/clab
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/
    drwxr-xr-x root/root         0 2025-08-08 09:06 ./usr/share/doc/clab/
    -rw-r--r-- root/root       231 2025-08-08 09:06 ./usr/share/doc/clab/changelog.Debian.gz
    -rw-r--r-- root/root       955 2025-08-08 09:05 ./usr/share/doc/clab/copyright
    + mv ../clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb ../clab_0.6.0.ci20250808T090655-1.debian.tar.xz ../clab_0.6.0.ci20250808T090655-1.dsc ../clab_0.6.0.ci20250808T090655-1_amd64.buildinfo ../clab_0.6.0.ci20250808T090655-1_amd64.changes ../clab_0.6.0.ci20250808T090655-1_amd64.deb ../clab_0.6.0.ci20250808T090655.orig.tar.xz /workspace/artifacts
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-08T09:07:16Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-08-08T09:07:16Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
    [2025-08-08T09:07:18Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        }
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-08-08T09:07:18Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    RUN: Action finished OK
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-08-08T09:07:33Z DEBUG ambient_ci::qemu] QEMU exit code 0
[2025-08-08T09:07:33Z DEBUG ambient_ci::run] remove old cache
[2025-08-08T09:07:33Z DEBUG ambient_ci::run] extract cache
[2025-08-08T09:07:34Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-08-08T09:07:34Z DEBUG ambient_ci::action] Plan::execute: Dput {
        artifactsdir: "/srv/ambient-state/clab/artifacts",
        dput_target: Some(
            "apt.liw.fi",
        ),
    }
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab-dbgsym_0.6.0.ci20250808T090655-1_amd64.deb
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab.html
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1_amd64.deb
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1_amd64.changes
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1_amd64.buildinfo
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1.dsc
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655.orig.tar.xz
[2025-08-08T09:07:34Z DEBUG ambient_ci::util] found /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1.debian.tar.xz
[2025-08-08T09:07:34Z INFO  ambient_ci::util] dput apt.liw.fi /srv/ambient-state/clab/artifacts/clab_0.6.0.ci20250808T090655-1_amd64.changes
[2025-08-08T09:07:35Z DEBUG ambient_ci::action] Plan::execute: Rsync {
        artifactsdir: "/srv/ambient-state/clab/artifacts",
        rsync_target: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi/clab",
        ),
    }
[2025-08-08T09:07:35Z INFO  ambient_ci::util] rsync /srv/ambient-state/clab/artifacts/. -> ci@http.liw.fi:/srv/http/doc.liw.fi/clab/.
[2025-08-08T09:07:35Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/clab/meta.yaml
[2025-08-08T09:07:35Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>