CI run for repeatcmd

Radicle repository id rad:z34Lk5Z18mw8gyfMwJvNPjA1ZwJgd

Table of Contents

Run log

[2025-08-16T05:21:08Z 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   2.9M  1 disk 
vdc    254:32   0   520K  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 128.1M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-08-16 05:20 ./
-rw-r--r-- _rad/_rad      1371 2025-08-16 05:20 ./plan.yaml
-rwxr-xr-x _rad/_rad   3003032 2025-08-16 05:20 ./run-ci
INFO: Extracted files:
  1477809      4 drwxr-xr-x   2 1001     1001         4096 Aug 16 05:20 .
  1477810      4 -rw-r--r--   1 1001     1001         1371 Aug 16 05:20 ./plan.yaml
  1477881   2936 -rwxr-xr-x   1 1001     1001      3003032 Aug 16 05:20 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-08-16T05:20:13Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:20:13Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:20:13Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:20:13Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:20:13Z 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: shell
  shell: |
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps
    export PATH="/root/.cargo/bin:$PATH"
    if ! make; then cat test.log; exit 1; fi

    export DEBEMAIL=liw@liw.fi
    export DEBFULLNAME="Lars Wirzenius"

    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 ''

    git status --ignored
    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-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-08-16T05:20:13Z 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-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-08-16T05:20:13Z 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 16 05:20 /workspace
  3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:20 /workspace/artifacts
  3932548      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:20 /workspace/deps
  3932550     56 -rw-r--r--   1 root     root        57344 Aug 16 05:20 /workspace/deps/.global-cache
  3932549      0 -rw-r--r--   1 root     root            0 Aug 16 05:20 /workspace/deps/.package-cache-mutate
  3932551      0 -rw-r--r--   1 root     root            0 Aug 16 05:19 /workspace/deps/.package-cache
  3932552      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:20 /workspace/deps/registry
  3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:19 /workspace/src
  3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:19 /workspace/src/.radicle
  3932547     20 -rw-r--r--   1 root     root        16921 Aug 16 05:19 /workspace/src/Cargo.lock
  3932182      4 -rw-r--r--   1 root     root          392 Aug 16 05:19 /workspace/src/Cargo.toml
  3932181      4 -rw-r--r--   1 root     root            8 Aug 16 05:19 /workspace/src/.gitignore
  3932166      4 -rw-r--r--   1 root     root         1325 Aug 16 05:19 /workspace/src/LICENSE.md
  3932546      4 -rw-r--r--   1 root     root           86 Aug 16 05:19 /workspace/src/Makefile
  3932183      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:19 /workspace/src/.git
  3932169      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:19 /workspace/src/debian
  3932180      4 -rw-r--r--   1 root     root          657 Aug 16 05:19 /workspace/src/README.md
  3932167      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:19 /workspace/src/src
  3936843      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:20 /workspace/cache
RUN: Action finished OK
RUN: Action CargoFmt
[2025-08-16T05:20:14Z 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-16T05:20:14Z 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.95
   Compiling unicode-ident v1.0.18
   Compiling rustix v1.0.7
   Compiling libc v0.2.172
    Checking linux-raw-sys v0.9.4
    Checking bitflags v2.9.0
   Compiling anyhow v1.0.98
   Compiling serde v1.0.219
   Compiling quote v1.0.40
   Compiling syn v2.0.101
   Compiling getrandom v0.3.3
   Compiling version_check v0.9.5
    Checking utf8parse v0.2.2
    Checking once_cell v1.21.3
   Compiling serde_yml v0.0.12
    Checking anstyle-parse v0.2.6
    Checking is_terminal_polyfill v1.70.1
    Checking equivalent v1.0.2
    Checking cfg-if v1.0.1
    Checking hashbrown v0.15.4
    Checking memchr v2.7.5
    Checking anstyle v1.0.10
   Compiling thiserror v2.0.12
   Compiling portable-atomic v1.11.1
    Checking anstyle-query v1.1.2
    Checking itoa v1.0.15
    Checking colorchoice v1.0.3
    Checking ryu v1.0.20
   Compiling serde_json v1.0.140
    Checking anstream v0.6.18
    Checking indexmap v2.10.0
    Checking terminal_size v0.4.2
    Checking libyml v0.0.5
    Checking clap_lex v0.7.4
    Checking strsim v0.11.1
    Checking unicode-width v0.2.1
    Checking fastrand v2.3.0
   Compiling heck v0.5.0
    Checking tempfile v3.20.0
    Checking console v0.15.11
    Checking clap_builder v4.5.38
    Checking number_prefix v0.4.0
    Checking indicatif v0.17.11
   Compiling serde_derive v1.0.219
   Compiling thiserror-impl v2.0.12
   Compiling clap_derive v4.5.32
    Checking clap v4.5.38
    Checking clingwrap v0.1.0
    Checking repeatcmd v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.05s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-08-16T05:20:26Z 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 bitflags v2.9.0
   Compiling linux-raw-sys v0.9.4
   Compiling libc v0.2.172
   Compiling utf8parse v0.2.2
   Compiling once_cell v1.21.3
   Compiling anstyle-parse v0.2.6
   Compiling serde v1.0.219
   Compiling anyhow v1.0.98
   Compiling rustix v1.0.7
   Compiling ryu v1.0.20
   Compiling is_terminal_polyfill v1.70.1
   Compiling anstyle-query v1.1.2
   Compiling cfg-if v1.0.1
   Compiling itoa v1.0.15
   Compiling equivalent v1.0.2
   Compiling hashbrown v0.15.4
   Compiling colorchoice v1.0.3
   Compiling memchr v2.7.5
   Compiling anstyle v1.0.10
   Compiling anstream v0.6.18
   Compiling indexmap v2.10.0
   Compiling getrandom v0.3.3
   Compiling libyml v0.0.5
   Compiling terminal_size v0.4.2
   Compiling fastrand v2.3.0
   Compiling strsim v0.11.1
   Compiling unicode-width v0.2.1
   Compiling clap_lex v0.7.4
   Compiling serde_yml v0.0.12
   Compiling console v0.15.11
   Compiling clap_builder v4.5.38
   Compiling serde_json v1.0.140
   Compiling tempfile v3.20.0
   Compiling thiserror v2.0.12
   Compiling portable-atomic v1.11.1
   Compiling number_prefix v0.4.0
   Compiling indicatif v0.17.11
   Compiling clingwrap v0.1.0
   Compiling clap v4.5.38
   Compiling repeatcmd v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.27s
RUN: Action finished OK
RUN: Action Shell {
    shell: "export CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n",
}
[2025-08-16T05:20:34Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "export CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ export CARGO_TARGET_DIR=/workspace/cache
+ CARGO_TARGET_DIR=/workspace/cache
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ export PATH=/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ PATH=/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ make
cargo clippy --all-targets -- -Dwarnings
    Checking repeatcmd v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.19s
cargo build --all-targets
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.11s
cargo test
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
     Running unittests src/main.rs (/workspace/cache/debug/deps/repeatcmd-39fa93362b962fdb)

running 0 tests

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

+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
++ dpkg-parsechangelog -SVersion
++ sed 's/-[^-]*$//'
+ V=0.1.0
++ date -u +%Y%m%dT%H%M%S
+ T=20250816T052035
+ version=0.1.0.ci20250816T052035-1
+ dch -v 0.1.0.ci20250816T052035-1 'CI build under Ambient.'
+ dch -r ''
+ git status --ignored
HEAD detached at fc8d54d
Changes not staged for commit:
  (use "git add <file>..." to update what will be committed)
  (use "git restore <file>..." to discard changes in working directory)
	modified:   debian/changelog

no changes added to commit (use "git add" and/or "git commit -a")
+ git clean -fdx
RUN: Action finished OK
RUN: Action Deb
[2025-08-16T05:20:35Z 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=276
LANG=C.UTF-8
CARGO_HOME=/workspace/deps
DEBEMAIL=liw@liw.fi
INVOCATION_ID=487b6315b92c424ba43a1c02d2d5a6f2
SHLVL=2
JOURNAL_STREAM=8:14002
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=repeatcmd
++ dpkg-parsechangelog -SVersion
+ version=0.1.0.ci20250816T052035-1
++ echo 0.1.0.ci20250816T052035-1
++ sed 's/-[^-]*$//'
+ uv=0.1.0.ci20250816T052035
++ dpkg --print-architecture
+ arch=amd64
+ orig=../repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
+ deb=../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
+ changes=../repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
+ xz
+ git archive HEAD
+ dpkg-buildpackage -us -uc
dpkg-buildpackage: info: source package repeatcmd
dpkg-buildpackage: info: source version 0.1.0.ci20250816T052035-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_auto_clean
   dh_clean
 dpkg-source -b .
dpkg-source: info: using source format '3.0 (quilt)'
dpkg-source: info: building repeatcmd using existing ./repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
dpkg-source: info: building repeatcmd in repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz
dpkg-source: info: building repeatcmd in repeatcmd_0.1.0.ci20250816T052035-1.dsc
 debian/rules build
dh build
   dh_update_autotools_config
   dh_autoreconf
   dh_auto_configure
   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 tests are disabled, for now
tests are disabled, for now
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'
mkdir -p debian/repeatcmd/usr
cargo install --locked --offline --path=. --root=debian/repeatcmd/usr
  Installing repeatcmd v0.1.0 (/workspace/src)
   Compiling proc-macro2 v1.0.95
   Compiling unicode-ident v1.0.18
   Compiling rustix v1.0.7
   Compiling libc v0.2.172
   Compiling bitflags v2.9.0
   Compiling linux-raw-sys v0.9.4
   Compiling serde v1.0.219
   Compiling anyhow v1.0.98
   Compiling quote v1.0.40
   Compiling utf8parse v0.2.2
   Compiling getrandom v0.3.3
   Compiling syn v2.0.101
   Compiling once_cell v1.21.3
   Compiling version_check v0.9.5
   Compiling anstyle-parse v0.2.6
   Compiling serde_yml v0.0.12
   Compiling anstyle v1.0.10
   Compiling itoa v1.0.15
   Compiling hashbrown v0.15.4
   Compiling serde_json v1.0.140
   Compiling is_terminal_polyfill v1.70.1
   Compiling portable-atomic v1.11.1
   Compiling colorchoice v1.0.3
   Compiling ryu v1.0.20
   Compiling memchr v2.7.5
   Compiling equivalent v1.0.2
   Compiling thiserror v2.0.12
   Compiling anstyle-query v1.1.2
   Compiling cfg-if v1.0.1
   Compiling anstream v0.6.18
   Compiling indexmap v2.10.0
   Compiling terminal_size v0.4.2
   Compiling libyml v0.0.5
   Compiling unicode-width v0.2.1
   Compiling heck v0.5.0
   Compiling strsim v0.11.1
   Compiling fastrand v2.3.0
   Compiling serde_derive v1.0.219
   Compiling thiserror-impl v2.0.12
   Compiling clap_lex v0.7.4
   Compiling clap_builder v4.5.38
   Compiling tempfile v3.20.0
   Compiling clap_derive v4.5.32
   Compiling console v0.15.11
   Compiling number_prefix v0.4.0
   Compiling indicatif v0.17.11
   Compiling clap v4.5.38
   Compiling clingwrap v0.1.0
   Compiling repeatcmd v0.1.0 (/workspace/src)
    Finished `release` profile [optimized] target(s) in 20.62s
  Installing debian/repeatcmd/usr/bin/repeatcmd
   Installed package `repeatcmd v0.1.0 (/workspace/src)` (executable `repeatcmd`)
warning: be sure to add `debian/repeatcmd/usr/bin` to your PATH to be able to run the installed binaries
find debian/*/ -name '.crates*.*' -delete
make[1]: Leaving directory '/workspace/src'
   dh_installdocs
   dh_installchangelogs
   dh_lintian
   dh_perl
   dh_link
   dh_strip_nondeterminism
   dh_compress
   dh_fixperms
   dh_missing
   dh_strip
   dh_makeshlibs
   dh_shlibdeps
   dh_installdeb
   dh_gencontrol
   dh_md5sums
   dh_builddeb
dpkg-deb: building package 'repeatcmd-dbgsym' in '../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb'.
dpkg-deb: building package 'repeatcmd' in '../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb'.
 dpkg-genbuildinfo -O../repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo
 dpkg-genchanges -O../repeatcmd_0.1.0.ci20250816T052035-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 500
drwxr-xr-x 2 root root   4096 Aug 16 05:20 artifacts
drwxr-xr-x 4 root root   4096 Aug 16 05:20 cache
drwxr-xr-x 3 root root   4096 Aug 16 05:20 deps
-rw-r--r-- 1 root root  57532 Aug 16 05:20 repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb
-rw-r--r-- 1 root root   1744 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz
-rw-r--r-- 1 root root    903 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035-1.dsc
-rw-r--r-- 1 root root   5674 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo
-rw-r--r-- 1 root root   2392 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
-rw-r--r-- 1 root root 400588 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
-rw-r--r-- 1 root root   9116 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
drwxr-xr-x 6 root root   4096 Aug 16 05:19 src
+ for x in ../*.deb
+ dpkg -c ../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-16 05:20 ./
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/.build-id/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/.build-id/25/
-rw-r--r-- root/root    274984 2025-08-16 05:20 ./usr/lib/debug/.build-id/25/abc75982be1cdf8ed88ec2fa717f69b7945cef.debug
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/
lrwxrwxrwx root/root         0 2025-08-16 05:20 ./usr/share/doc/repeatcmd-dbgsym -> repeatcmd
+ for x in ../*.deb
+ dpkg -c ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-16 05:20 ./
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/bin/
-rwxr-xr-x root/root   1168176 2025-08-16 05:20 ./usr/bin/repeatcmd
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/repeatcmd/
-rw-r--r-- root/root       204 2025-08-16 05:20 ./usr/share/doc/repeatcmd/changelog.Debian.gz
-rw-r--r-- root/root       337 2025-08-16 05:19 ./usr/share/doc/repeatcmd/copyright
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/lintian/
drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/lintian/overrides/
-rw-r--r-- root/root        81 2025-08-16 05:19 ./usr/share/lintian/overrides/repeatcmd
+ mv ../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb ../repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz ../repeatcmd_0.1.0.ci20250816T052035-1.dsc ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb ../repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz /workspace/artifacts
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:21:00Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vdd",
    directory: "/workspace/artifacts",
}
[2025-08-16T05:21:01Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
RUN: Action finished OK
ambient-execute-plan ends
EXIT CODE: 0
[2025-08-16T05:21:08Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z34Lk5Z18mw8gyfMwJvNPjA1ZwJgd",
    "name": "repeatcmd",
    "description": "Run command repeatedly until it succeeds",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "pusher": {
    "id": "did:key:z6MkkYez6QAFWvmdudjrfMXySu1NTpSrraJotCJFwGgkmgx8",
    "alias": "ci0"
  },
  "before": "fc8d54d6826f0977b4ca02bf4645b3871fbc5b1c",
  "after": "fc8d54d6826f0977b4ca02bf4645b3871fbc5b1c",
  "branch": "main",
  "commits": [
    "fc8d54d6826f0977b4ca02bf4645b3871fbc5b1c"
  ]
}

Ambient stdout

[2025-08-16T05:19:56Z INFO  ambient] ambient starts
[2025-08-16T05:19:56Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-08-16T05:19:56Z DEBUG ambient] load files named with --config: /tmp/.tmpsHnUnX/ambient_extra_values.yaml
[2025-08-16T05:19:56Z 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",
        ),
        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-16T05:19:56Z 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",
        ),
        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-16T05:19:56Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/repeatcmd/meta.yaml
[2025-08-16T05:19:56Z DEBUG ambient_ci::run] latest commit: "fc8d54d6826f0977b4ca02bf4645b3871fbc5b1c"
[2025-08-16T05:19:56Z DEBUG ambient_ci::run] is a git repository
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] git repository is clean
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] current (HEAD) commit: fc8d54d6826f0977b4ca02bf4645b3871fbc5b1c
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] no dry run requested
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] forced run requested
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] run? true
[2025-08-16T05:19:57Z INFO  ambient_ci::run] project repeatcmd: running CI
[2025-08-16T05:19:57Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-08-16T05:19:57Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmpO1ePS7/src",
        dependenciesdir: "/srv/ambient-state/repeatcmd/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/repeatcmd/dependencies", "cargo", "fetch", "--locked"]
       cwd=/tmp/.tmpO1ePS7/src (exists? true)
       extra_env=[]
    Updating crates.io index
 Downloading crates ...
  Downloaded web-time v1.1.0
  Downloaded unicode-ident v1.0.18
  Downloaded wasm-bindgen-macro v0.2.100
  Downloaded wasm-bindgen v0.2.100
  Downloaded indicatif v0.17.11
  Downloaded wasm-bindgen-shared v0.2.100
  Downloaded version_check v0.9.5
  Downloaded heck v0.5.0
  Downloaded serde_derive v1.0.219
  Downloaded cfg-if v1.0.1
  Downloaded windows-targets v0.52.6
  Downloaded console v0.15.11
  Downloaded thiserror-impl v2.0.12
  Downloaded wit-bindgen-rt v0.39.0
  Downloaded clap_derive v4.5.32
  Downloaded anstyle-parse v0.2.6
  Downloaded log v0.4.27
  Downloaded serde v1.0.219
  Downloaded memchr v2.7.5
  Downloaded rustix v1.0.7
  Downloaded anstyle-query v1.1.2
  Downloaded anstyle-wincon v3.0.7
  Downloaded itoa v1.0.15
  Downloaded is_terminal_polyfill v1.70.1
  Downloaded ryu v1.0.20
  Downloaded anyhow v1.0.98
  Downloaded portable-atomic v1.11.1
  Downloaded js-sys v0.3.77
  Downloaded once_cell v1.21.3
  Downloaded utf8parse v0.2.2
  Downloaded equivalent v1.0.2
  Downloaded indexmap v2.10.0
  Downloaded unicode-width v0.2.1
  Downloaded number_prefix v0.4.0
  Downloaded bumpalo v3.19.0
  Downloaded wasi v0.14.2+wasi-0.2.4
  Downloaded tempfile v3.20.0
  Downloaded fastrand v2.3.0
  Downloaded windows_i686_gnullvm v0.52.6
  Downloaded clap_builder v4.5.38
  Downloaded windows_x86_64_msvc v0.52.6
  Downloaded serde_json v1.0.140
  Downloaded hashbrown v0.15.4
  Downloaded libyml v0.0.5
  Downloaded windows_x86_64_gnullvm v0.52.6
  Downloaded serde_yml v0.0.12
  Downloaded windows_x86_64_gnu v0.52.6
  Downloaded windows_aarch64_msvc v0.52.6
  Downloaded libc v0.2.172
  Downloaded windows_i686_msvc v0.52.6
  Downloaded windows_aarch64_gnullvm v0.52.6
  Downloaded colorchoice v1.0.3
  Downloaded anstream v0.6.18
  Downloaded anstyle v1.0.10
  Downloaded clap_lex v0.7.4
  Downloaded strsim v0.11.1
  Downloaded terminal_size v0.4.2
  Downloaded clap v4.5.38
  Downloaded r-efi v5.3.0
  Downloaded getrandom v0.3.3
  Downloaded bitflags v2.9.0
  Downloaded errno v0.3.11
  Downloaded thiserror v2.0.12
  Downloaded encode_unicode v1.0.0
  Downloaded proc-macro2 v1.0.95
  Downloaded syn v2.0.101
  Downloaded quote v1.0.40
  Downloaded wasm-bindgen-backend v0.2.100
  Downloaded wasm-bindgen-macro-support v0.2.100
  Downloaded windows-sys v0.59.0
  Downloaded windows_i686_gnu v0.52.6
  Downloaded linux-raw-sys v0.9.4
  Downloaded clingwrap v0.1.0
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpdXj94d/src.tar
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpdXj94d/deps.tar
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-08-16T05:20:03Z 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,
            Shell {
                shell: "export CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\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-08-16T05:20:03Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpdXj94d/ambient-execute-plan/run-ci
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] copy OK: true
[2025-08-16T05:20:03Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpdXj94d/executor.tar
[2025-08-16T05:20:03Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/repeatcmd/run.log
[2025-08-16T05:20:03Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/repeatcmd, exists? true
[2025-08-16T05:20:03Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/repeatcmd/run.log
[2025-08-16T05:20:03Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/repeatcmd/run.log OK
[2025-08-16T05:20:03Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-08-16T05:20:03Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-08-16T05:20:03Z 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-16T05:20:03Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-08-16T05:20:03Z 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-16T05:20:03Z DEBUG ambient_ci::qemu] run QEMU
[2025-08-16T05:20:03Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-08-16T05:20:03Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmp2DuxlQ/.tmpdAFogj/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-08-16T05:20:03Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-08-16T05:20:03Z 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-16T05:20:03Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpgA8GMX/meta-data
[2025-08-16T05:20:03Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpgA8GMX/user-data
[2025-08-16T05:20:03Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmp2DuxlQ/.tmpdAFogj/console.log
[2025-08-16T05:20:03Z DEBUG ambient_ci::util] create file /srv/tmp/.tmp2DuxlQ/.tmpdAFogj/console.log
[2025-08-16T05:20:03Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/repeatcmd/run.log
[2025-08-16T05:20:03Z DEBUG ambient_ci::util] create file /srv/ambient-state/repeatcmd/run.log
[2025-08-16T05:20:03Z 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/.tmp2DuxlQ/.tmpdAFogj/console.log",
            "-serial",
            "file:/srv/ambient-state/repeatcmd/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/.tmp2DuxlQ/.tmpdAFogj/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmp2DuxlQ/.tmpdAFogj/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpdXj94d/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmp2DuxlQ/.tmpdAFogj/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpdXj94d/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpdXj94d/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpdXj94d/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpdXj94d/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-08-16T05:21:06Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-08-16T05:21:06Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-08-16T05:20:13Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:20:13Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:20:13Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:20:13Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:20:13Z 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: shell
      shell: |
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
        export PATH="/root/.cargo/bin:$PATH"
        if ! make; then cat test.log; exit 1; fi
    
        export DEBEMAIL=liw@liw.fi
        export DEBFULLNAME="Lars Wirzenius"
    
        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 ''
    
        git status --ignored
        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-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-08-16T05:20:13Z 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-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:20:13Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-08-16T05:20:13Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-08-16T05:20:13Z 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 16 05:20 /workspace
      3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:20 /workspace/artifacts
      3932548      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:20 /workspace/deps
      3932550     56 -rw-r--r--   1 root     root        57344 Aug 16 05:20 /workspace/deps/.global-cache
      3932549      0 -rw-r--r--   1 root     root            0 Aug 16 05:20 /workspace/deps/.package-cache-mutate
      3932551      0 -rw-r--r--   1 root     root            0 Aug 16 05:19 /workspace/deps/.package-cache
      3932552      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:20 /workspace/deps/registry
      3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:19 /workspace/src
      3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:19 /workspace/src/.radicle
      3932547     20 -rw-r--r--   1 root     root        16921 Aug 16 05:19 /workspace/src/Cargo.lock
      3932182      4 -rw-r--r--   1 root     root          392 Aug 16 05:19 /workspace/src/Cargo.toml
      3932181      4 -rw-r--r--   1 root     root            8 Aug 16 05:19 /workspace/src/.gitignore
      3932166      4 -rw-r--r--   1 root     root         1325 Aug 16 05:19 /workspace/src/LICENSE.md
      3932546      4 -rw-r--r--   1 root     root           86 Aug 16 05:19 /workspace/src/Makefile
      3932183      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:19 /workspace/src/.git
      3932169      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:19 /workspace/src/debian
      3932180      4 -rw-r--r--   1 root     root          657 Aug 16 05:19 /workspace/src/README.md
      3932167      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:19 /workspace/src/src
      3936843      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:20 /workspace/cache
    RUN: Action finished OK
    RUN: Action CargoFmt
    [2025-08-16T05:20:14Z 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-16T05:20:14Z 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.95
       Compiling unicode-ident v1.0.18
       Compiling rustix v1.0.7
       Compiling libc v0.2.172
        Checking linux-raw-sys v0.9.4
        Checking bitflags v2.9.0
       Compiling anyhow v1.0.98
       Compiling serde v1.0.219
       Compiling quote v1.0.40
       Compiling syn v2.0.101
       Compiling getrandom v0.3.3
       Compiling version_check v0.9.5
        Checking utf8parse v0.2.2
        Checking once_cell v1.21.3
       Compiling serde_yml v0.0.12
        Checking anstyle-parse v0.2.6
        Checking is_terminal_polyfill v1.70.1
        Checking equivalent v1.0.2
        Checking cfg-if v1.0.1
        Checking hashbrown v0.15.4
        Checking memchr v2.7.5
        Checking anstyle v1.0.10
       Compiling thiserror v2.0.12
       Compiling portable-atomic v1.11.1
        Checking anstyle-query v1.1.2
        Checking itoa v1.0.15
        Checking colorchoice v1.0.3
        Checking ryu v1.0.20
       Compiling serde_json v1.0.140
        Checking anstream v0.6.18
        Checking indexmap v2.10.0
        Checking terminal_size v0.4.2
        Checking libyml v0.0.5
        Checking clap_lex v0.7.4
        Checking strsim v0.11.1
        Checking unicode-width v0.2.1
        Checking fastrand v2.3.0
       Compiling heck v0.5.0
        Checking tempfile v3.20.0
        Checking console v0.15.11
        Checking clap_builder v4.5.38
        Checking number_prefix v0.4.0
        Checking indicatif v0.17.11
       Compiling serde_derive v1.0.219
       Compiling thiserror-impl v2.0.12
       Compiling clap_derive v4.5.32
        Checking clap v4.5.38
        Checking clingwrap v0.1.0
        Checking repeatcmd v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.05s
    RUN: Action finished OK
    RUN: Action CargoBuild
    [2025-08-16T05:20:26Z 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 bitflags v2.9.0
       Compiling linux-raw-sys v0.9.4
       Compiling libc v0.2.172
       Compiling utf8parse v0.2.2
       Compiling once_cell v1.21.3
       Compiling anstyle-parse v0.2.6
       Compiling serde v1.0.219
       Compiling anyhow v1.0.98
       Compiling rustix v1.0.7
       Compiling ryu v1.0.20
       Compiling is_terminal_polyfill v1.70.1
       Compiling anstyle-query v1.1.2
       Compiling cfg-if v1.0.1
       Compiling itoa v1.0.15
       Compiling equivalent v1.0.2
       Compiling hashbrown v0.15.4
       Compiling colorchoice v1.0.3
       Compiling memchr v2.7.5
       Compiling anstyle v1.0.10
       Compiling anstream v0.6.18
       Compiling indexmap v2.10.0
       Compiling getrandom v0.3.3
       Compiling libyml v0.0.5
       Compiling terminal_size v0.4.2
       Compiling fastrand v2.3.0
       Compiling strsim v0.11.1
       Compiling unicode-width v0.2.1
       Compiling clap_lex v0.7.4
       Compiling serde_yml v0.0.12
       Compiling console v0.15.11
       Compiling clap_builder v4.5.38
       Compiling serde_json v1.0.140
       Compiling tempfile v3.20.0
       Compiling thiserror v2.0.12
       Compiling portable-atomic v1.11.1
       Compiling number_prefix v0.4.0
       Compiling indicatif v0.17.11
       Compiling clingwrap v0.1.0
       Compiling clap v4.5.38
       Compiling repeatcmd v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.27s
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "export CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n",
    }
    [2025-08-16T05:20:34Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "export CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport PATH=\"/root/.cargo/bin:$PATH\"\nif ! make; then cat test.log; exit 1; fi\n\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\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\ngit status --ignored\ngit clean -fdx\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + export CARGO_TARGET_DIR=/workspace/cache
    + CARGO_TARGET_DIR=/workspace/cache
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + export PATH=/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + PATH=/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + make
    cargo clippy --all-targets -- -Dwarnings
        Checking repeatcmd v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.19s
    cargo build --all-targets
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.11s
    cargo test
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
         Running unittests src/main.rs (/workspace/cache/debug/deps/repeatcmd-39fa93362b962fdb)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    ++ dpkg-parsechangelog -SVersion
    ++ sed 's/-[^-]*$//'
    + V=0.1.0
    ++ date -u +%Y%m%dT%H%M%S
    + T=20250816T052035
    + version=0.1.0.ci20250816T052035-1
    + dch -v 0.1.0.ci20250816T052035-1 'CI build under Ambient.'
    + dch -r ''
    + git status --ignored
    HEAD detached at fc8d54d
    Changes not staged for commit:
      (use "git add <file>..." to update what will be committed)
      (use "git restore <file>..." to discard changes in working directory)
    	modified:   debian/changelog
    
    no changes added to commit (use "git add" and/or "git commit -a")
    + git clean -fdx
    RUN: Action finished OK
    RUN: Action Deb
    [2025-08-16T05:20:35Z 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=276
    LANG=C.UTF-8
    CARGO_HOME=/workspace/deps
    DEBEMAIL=liw@liw.fi
    INVOCATION_ID=487b6315b92c424ba43a1c02d2d5a6f2
    SHLVL=2
    JOURNAL_STREAM=8:14002
    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=repeatcmd
    ++ dpkg-parsechangelog -SVersion
    + version=0.1.0.ci20250816T052035-1
    ++ echo 0.1.0.ci20250816T052035-1
    ++ sed 's/-[^-]*$//'
    + uv=0.1.0.ci20250816T052035
    ++ dpkg --print-architecture
    + arch=amd64
    + orig=../repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
    + deb=../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
    + changes=../repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
    + xz
    + git archive HEAD
    + dpkg-buildpackage -us -uc
    dpkg-buildpackage: info: source package repeatcmd
    dpkg-buildpackage: info: source version 0.1.0.ci20250816T052035-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_auto_clean
       dh_clean
     dpkg-source -b .
    dpkg-source: info: using source format '3.0 (quilt)'
    dpkg-source: info: building repeatcmd using existing ./repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
    dpkg-source: info: building repeatcmd in repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz
    dpkg-source: info: building repeatcmd in repeatcmd_0.1.0.ci20250816T052035-1.dsc
     debian/rules build
    dh build
       dh_update_autotools_config
       dh_autoreconf
       dh_auto_configure
       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 tests are disabled, for now
    tests are disabled, for now
    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'
    mkdir -p debian/repeatcmd/usr
    cargo install --locked --offline --path=. --root=debian/repeatcmd/usr
      Installing repeatcmd v0.1.0 (/workspace/src)
       Compiling proc-macro2 v1.0.95
       Compiling unicode-ident v1.0.18
       Compiling rustix v1.0.7
       Compiling libc v0.2.172
       Compiling bitflags v2.9.0
       Compiling linux-raw-sys v0.9.4
       Compiling serde v1.0.219
       Compiling anyhow v1.0.98
       Compiling quote v1.0.40
       Compiling utf8parse v0.2.2
       Compiling getrandom v0.3.3
       Compiling syn v2.0.101
       Compiling once_cell v1.21.3
       Compiling version_check v0.9.5
       Compiling anstyle-parse v0.2.6
       Compiling serde_yml v0.0.12
       Compiling anstyle v1.0.10
       Compiling itoa v1.0.15
       Compiling hashbrown v0.15.4
       Compiling serde_json v1.0.140
       Compiling is_terminal_polyfill v1.70.1
       Compiling portable-atomic v1.11.1
       Compiling colorchoice v1.0.3
       Compiling ryu v1.0.20
       Compiling memchr v2.7.5
       Compiling equivalent v1.0.2
       Compiling thiserror v2.0.12
       Compiling anstyle-query v1.1.2
       Compiling cfg-if v1.0.1
       Compiling anstream v0.6.18
       Compiling indexmap v2.10.0
       Compiling terminal_size v0.4.2
       Compiling libyml v0.0.5
       Compiling unicode-width v0.2.1
       Compiling heck v0.5.0
       Compiling strsim v0.11.1
       Compiling fastrand v2.3.0
       Compiling serde_derive v1.0.219
       Compiling thiserror-impl v2.0.12
       Compiling clap_lex v0.7.4
       Compiling clap_builder v4.5.38
       Compiling tempfile v3.20.0
       Compiling clap_derive v4.5.32
       Compiling console v0.15.11
       Compiling number_prefix v0.4.0
       Compiling indicatif v0.17.11
       Compiling clap v4.5.38
       Compiling clingwrap v0.1.0
       Compiling repeatcmd v0.1.0 (/workspace/src)
        Finished `release` profile [optimized] target(s) in 20.62s
      Installing debian/repeatcmd/usr/bin/repeatcmd
       Installed package `repeatcmd v0.1.0 (/workspace/src)` (executable `repeatcmd`)
    warning: be sure to add `debian/repeatcmd/usr/bin` to your PATH to be able to run the installed binaries
    find debian/*/ -name '.crates*.*' -delete
    make[1]: Leaving directory '/workspace/src'
       dh_installdocs
       dh_installchangelogs
       dh_lintian
       dh_perl
       dh_link
       dh_strip_nondeterminism
       dh_compress
       dh_fixperms
       dh_missing
       dh_strip
       dh_makeshlibs
       dh_shlibdeps
       dh_installdeb
       dh_gencontrol
       dh_md5sums
       dh_builddeb
    dpkg-deb: building package 'repeatcmd-dbgsym' in '../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb'.
    dpkg-deb: building package 'repeatcmd' in '../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb'.
     dpkg-genbuildinfo -O../repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo
     dpkg-genchanges -O../repeatcmd_0.1.0.ci20250816T052035-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 500
    drwxr-xr-x 2 root root   4096 Aug 16 05:20 artifacts
    drwxr-xr-x 4 root root   4096 Aug 16 05:20 cache
    drwxr-xr-x 3 root root   4096 Aug 16 05:20 deps
    -rw-r--r-- 1 root root  57532 Aug 16 05:20 repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb
    -rw-r--r-- 1 root root   1744 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz
    -rw-r--r-- 1 root root    903 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035-1.dsc
    -rw-r--r-- 1 root root   5674 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo
    -rw-r--r-- 1 root root   2392 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
    -rw-r--r-- 1 root root 400588 Aug 16 05:21 repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
    -rw-r--r-- 1 root root   9116 Aug 16 05:20 repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
    drwxr-xr-x 6 root root   4096 Aug 16 05:19 src
    + for x in ../*.deb
    + dpkg -c ../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/.build-id/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/lib/debug/.build-id/25/
    -rw-r--r-- root/root    274984 2025-08-16 05:20 ./usr/lib/debug/.build-id/25/abc75982be1cdf8ed88ec2fa717f69b7945cef.debug
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/
    lrwxrwxrwx root/root         0 2025-08-16 05:20 ./usr/share/doc/repeatcmd-dbgsym -> repeatcmd
    + for x in ../*.deb
    + dpkg -c ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/bin/
    -rwxr-xr-x root/root   1168176 2025-08-16 05:20 ./usr/bin/repeatcmd
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/doc/repeatcmd/
    -rw-r--r-- root/root       204 2025-08-16 05:20 ./usr/share/doc/repeatcmd/changelog.Debian.gz
    -rw-r--r-- root/root       337 2025-08-16 05:19 ./usr/share/doc/repeatcmd/copyright
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/lintian/
    drwxr-xr-x root/root         0 2025-08-16 05:20 ./usr/share/lintian/overrides/
    -rw-r--r-- root/root        81 2025-08-16 05:19 ./usr/share/lintian/overrides/repeatcmd
    + mv ../repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb ../repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz ../repeatcmd_0.1.0.ci20250816T052035-1.dsc ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes ../repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb ../repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz /workspace/artifacts
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:21:00Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-08-16T05:21:00Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
    [2025-08-16T05:21:01Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        }
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-08-16T05:21:01Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    RUN: Action finished OK
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-08-16T05:21:06Z DEBUG ambient_ci::qemu] QEMU exit code 0
[2025-08-16T05:21:06Z DEBUG ambient_ci::run] remove old cache
[2025-08-16T05:21:06Z DEBUG ambient_ci::run] extract cache
[2025-08-16T05:21:06Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-08-16T05:21:06Z DEBUG ambient_ci::action] Plan::execute: Dput {
        artifactsdir: "/srv/ambient-state/repeatcmd/artifacts",
        dput_target: Some(
            "apt.liw.fi",
        ),
    }
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1_amd64.deb
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035.orig.tar.xz
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd-dbgsym_0.1.0.ci20250816T052035-1_amd64.deb
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1_amd64.buildinfo
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1.dsc
[2025-08-16T05:21:06Z DEBUG ambient_ci::util] found /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1.debian.tar.xz
[2025-08-16T05:21:06Z INFO  ambient_ci::util] dput apt.liw.fi /srv/ambient-state/repeatcmd/artifacts/repeatcmd_0.1.0.ci20250816T052035-1_amd64.changes
[2025-08-16T05:21:07Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/repeatcmd/meta.yaml
[2025-08-16T05:21:08Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>