CI run for rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x

Radicle repository id rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x

Table of Contents

Run log

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


INFO: Block devices
NAME   MAJ:MIN RM   SIZE RO TYPE MOUNTPOINTS
vda    254:0    0   100G  0 disk 
├─vda1 254:1    0   953M  0 part 
└─vda2 254:2    0  99.1G  0 part /
vdb    254:16   0   6.1M  1 disk 
vdc    254:32   0   1.3M  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 325.8M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-10-05 08:13 ./
-rw-r--r-- _rad/_rad       990 2025-10-05 08:13 ./plan.yaml
-rwxr-xr-x _rad/_rad   6410400 2025-10-05 08:13 ./run-ci
INFO: Extracted files:
  4587526      4 drwxr-xr-x   2 1001     1001         4096 Oct  5 08:13 .
  4587527      4 -rw-r--r--   1 1001     1001          990 Oct  5 08:13 ./plan.yaml
  4587530   6264 -rwxr-xr-x   1 1001     1001      6410400 Oct  5 08:13 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-10-05T08:13:33Z TRACE ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-05T08:13:33Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-05T08:13:33Z INFO  ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-05T08:13:33Z WARN  ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-05T08:13:33Z ERROR ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 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_clippy
- action: cargo_build
- action: cargo_test
- action: shell
  shell: |
    export PATH=/root/.cargo/bin:/bin:/sbin
    export CARGO_TARGET_DIR=/workspace/cache
    make subplot
- 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


[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
        pathname: "/workspace",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
        pathname: "/workspace",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
        pathname: "/workspace/artifacts",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
[2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
[2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
[2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
       cwd=/workspace/src (exists? true)
  2621441      4 drwxr-xr-x   6 root     root         4096 Oct  5 08:13 /workspace
  2621577      4 drwxr-xr-x   3 root     root         4096 Oct  5 08:13 /workspace/deps
  2621578      0 -rw-r--r--   1 root     root            0 Oct  5 08:13 /workspace/deps/.package-cache-mutate
  2621581      4 drwxr-xr-x   5 root     root         4096 Oct  5 08:13 /workspace/deps/registry
  2621579     80 -rw-r--r--   1 root     root        81920 Oct  5 08:13 /workspace/deps/.global-cache
  2621580      0 -rw-r--r--   1 root     root            0 Oct  5 08:13 /workspace/deps/.package-cache
  2621443      4 drwxr-xr-x   6 root     root         4096 Oct  5 08:13 /workspace/src
  2621471      4 -rw-r--r--   1 root     root          527 Oct  5 08:13 /workspace/src/Cargo.toml
  2621468     36 -rw-r--r--   1 root     root        35149 Oct  5 08:13 /workspace/src/COPYING.md
  2621473      4 drwxr-xr-x   8 root     root         4096 Oct  5 08:13 /workspace/src/.git
  2621567     24 -rw-r--r--   1 root     root        23155 Oct  5 08:13 /workspace/src/obnam.md
  2621570      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/src/doc
  2621447      4 drwxr-xr-x   3 root     root         4096 Oct  5 08:13 /workspace/src/src
  2621469      8 -rw-r--r--   1 root     root         6734 Oct  5 08:13 /workspace/src/README.md
  2621444      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/src/.radicle
  2621569     36 -rw-r--r--   1 root     root        34174 Oct  5 08:13 /workspace/src/Cargo.lock
  2621565     12 -rw-r--r--   1 root     root         9723 Oct  5 08:13 /workspace/src/LICENSE-APACHE
  2621470      4 -rw-r--r--   1 root     root           26 Oct  5 08:13 /workspace/src/.gitignore
  2621576      4 -rw-r--r--   1 root     root          224 Oct  5 08:13 /workspace/src/obnam.subplot
  2621568      4 -rw-r--r--   1 root     root          283 Oct  5 08:13 /workspace/src/obnam.yaml
  2621467      4 -rw-r--r--   1 root     root         1036 Oct  5 08:13 /workspace/src/GETTING_STARTED.md
  2621446      4 -rw-r--r--   1 root     root         1079 Oct  5 08:13 /workspace/src/LICENSE-MIT
  2621566      4 -rw-r--r--   1 root     root          539 Oct  5 08:13 /workspace/src/Makefile
  2621472      4 -rw-r--r--   1 root     root         1010 Oct  5 08:13 /workspace/src/obnam.py
  2621442      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/artifacts
  2632828      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/cache
  2632829      4 -rw-r--r--   1 root     root         1038 Oct  5 08:13 /workspace/cache/.rustc_info.json
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action CargoClippy
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=CargoClippy
[2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
       cwd=/workspace/src (exists? true)
   Compiling libc v0.2.175
    Checking cfg-if v1.0.3
   Compiling proc-macro2 v1.0.101
   Compiling unicode-ident v1.0.19
   Compiling version_check v0.9.5
   Compiling typenum v1.18.0
   Compiling generic-array v0.14.7
   Compiling serde_core v1.0.226
   Compiling quote v1.0.40
    Checking getrandom v0.2.16
   Compiling syn v2.0.106
    Checking rand_core v0.6.4
    Checking memchr v2.7.5
    Checking crypto-common v0.1.6
   Compiling rustix v1.1.2
    Checking bitflags v2.9.4
    Checking linux-raw-sys v0.11.0
   Compiling autocfg v1.5.0
   Compiling serde v1.0.226
   Compiling lock_api v0.4.13
    Checking utf8parse v0.2.2
   Compiling thiserror v2.0.16
   Compiling getrandom v0.3.3
   Compiling semver v1.0.27
    Checking anstyle-parse v0.2.7
   Compiling rustc_version v0.4.1
    Checking aho-corasick v1.1.3
    Checking inout v0.1.4
    Checking anstyle-query v1.1.4
   Compiling pkg-config v0.3.32
    Checking anstyle v1.0.11
    Checking regex-syntax v0.8.6
    Checking colorchoice v1.0.4
   Compiling vcpkg v0.2.15
    Checking is_terminal_polyfill v1.70.1
    Checking scopeguard v1.2.0
    Checking anstream v0.6.20
    Checking cipher v0.4.4
   Compiling heapless v0.7.17
   Compiling libsqlite3-sys v0.35.0
    Checking regex-automata v0.4.11
   Compiling crc32fast v1.5.0
    Checking equivalent v1.0.2
    Checking foldhash v0.1.5
   Compiling serde_derive v1.0.226
   Compiling thiserror-impl v2.0.16
    Checking subtle v2.6.1
    Checking winnow v0.7.13
   Compiling serde_json v1.0.145
    Checking cpufeatures v0.2.17
    Checking hashbrown v0.16.0
    Checking itoa v1.0.15
    Checking ryu v1.0.20
    Checking byteorder v1.5.0
    Checking toml_parser v1.0.3
    Checking hash32 v0.2.1
    Checking regex v1.11.3
    Checking indexmap v2.11.4
    Checking universal-hash v0.5.1
    Checking hashbrown v0.15.5
    Checking spin v0.9.8
    Checking terminal_size v0.4.3
    Checking serde_spanned v1.0.2
    Checking toml_datetime v0.7.2
    Checking dirs-sys-next v0.1.2
    Checking once_cell v1.21.3
    Checking log v0.4.28
    Checking adler2 v2.0.1
    Checking toml_writer v1.0.3
   Compiling heck v0.5.0
    Checking clap_lex v0.7.5
    Checking opaque-debug v0.3.1
    Checking fastrand v2.3.0
    Checking unsafe-libyaml-norway v0.2.15
    Checking stable_deref_trait v1.2.0
    Checking strsim v0.11.1
    Checking tempfile v3.22.0
    Checking clap_builder v4.5.48
    Checking polyval v0.6.2
   Compiling clap_derive v4.5.47
    Checking toml v0.9.7
    Checking serde_norway v0.9.42
    Checking miniz_oxide v0.8.9
    Checking env_filter v0.1.3
    Checking directories-next v2.0.0
    Checking hashlink v0.10.0
    Checking aes v0.8.4
    Checking cobs v0.3.0
    Checking ctr v0.9.2
    Checking aead v0.5.2
    Checking fallible-iterator v0.3.0
    Checking zeroize v1.8.1
    Checking smallvec v1.15.1
    Checking jiff v0.2.15
    Checking fallible-streaming-iterator v0.1.9
    Checking aes-gcm-siv v0.11.1
    Checking rusqlite v0.37.0
    Checking clap v4.5.48
    Checking clingwrap v0.4.0
    Checking postcard v1.1.3
    Checking flate2 v1.1.2
    Checking uuid v1.18.1
   Compiling mutants v0.0.3
    Checking env_logger v0.11.8
    Checking obnam v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.08s
[2025-10-05T08:13:47Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:47Z DEBUG ambient_ci::plan] RUN: Action CargoBuild
[2025-10-05T08:13:47Z DEBUG ambient_ci::action] Plan::execute: plan=CargoBuild
[2025-10-05T08:13:47Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
       cwd=/workspace/src (exists? true)
   Compiling cfg-if v1.0.3
   Compiling libc v0.2.175
   Compiling typenum v1.18.0
   Compiling serde_core v1.0.226
   Compiling bitflags v2.9.4
   Compiling memchr v2.7.5
   Compiling generic-array v0.14.7
   Compiling getrandom v0.2.16
   Compiling rand_core v0.6.4
   Compiling linux-raw-sys v0.11.0
   Compiling crypto-common v0.1.6
   Compiling utf8parse v0.2.2
   Compiling inout v0.1.4
   Compiling anstyle-parse v0.2.7
   Compiling aho-corasick v1.1.3
   Compiling colorchoice v1.0.4
   Compiling is_terminal_polyfill v1.70.1
   Compiling rustix v1.1.2
   Compiling anstyle v1.0.11
   Compiling regex-syntax v0.8.6
   Compiling serde v1.0.226
   Compiling scopeguard v1.2.0
   Compiling anstyle-query v1.1.4
   Compiling anstream v0.6.20
   Compiling lock_api v0.4.13
   Compiling regex-automata v0.4.11
   Compiling thiserror v2.0.16
   Compiling cipher v0.4.4
   Compiling getrandom v0.3.3
   Compiling equivalent v1.0.2
   Compiling subtle v2.6.1
   Compiling cpufeatures v0.2.17
   Compiling ryu v1.0.20
   Compiling hashbrown v0.16.0
   Compiling byteorder v1.5.0
   Compiling itoa v1.0.15
   Compiling foldhash v0.1.5
   Compiling winnow v0.7.13
   Compiling hashbrown v0.15.5
   Compiling indexmap v2.11.4
   Compiling hash32 v0.2.1
   Compiling universal-hash v0.5.1
   Compiling spin v0.9.8
   Compiling terminal_size v0.4.3
   Compiling serde_spanned v1.0.2
   Compiling toml_datetime v0.7.2
   Compiling dirs-sys-next v0.1.2
   Compiling regex v1.11.3
   Compiling opaque-debug v0.3.1
   Compiling unsafe-libyaml-norway v0.2.15
   Compiling toml_parser v1.0.3
   Compiling strsim v0.11.1
   Compiling toml_writer v1.0.3
   Compiling adler2 v2.0.1
   Compiling stable_deref_trait v1.2.0
   Compiling once_cell v1.21.3
   Compiling log v0.4.28
   Compiling clap_lex v0.7.5
   Compiling fastrand v2.3.0
   Compiling env_filter v0.1.3
   Compiling clap_builder v4.5.48
   Compiling tempfile v3.22.0
   Compiling heapless v0.7.17
   Compiling miniz_oxide v0.8.9
   Compiling toml v0.9.7
   Compiling serde_norway v0.9.42
   Compiling polyval v0.6.2
   Compiling directories-next v2.0.0
   Compiling libsqlite3-sys v0.35.0
   Compiling serde_json v1.0.145
   Compiling crc32fast v1.5.0
   Compiling hashlink v0.10.0
   Compiling aes v0.8.4
   Compiling ctr v0.9.2
   Compiling cobs v0.3.0
   Compiling aead v0.5.2
   Compiling fallible-streaming-iterator v0.1.9
   Compiling smallvec v1.15.1
   Compiling zeroize v1.8.1
   Compiling fallible-iterator v0.3.0
   Compiling jiff v0.2.15
   Compiling aes-gcm-siv v0.11.1
   Compiling postcard v1.1.3
   Compiling rusqlite v0.37.0
   Compiling clingwrap v0.4.0
   Compiling flate2 v1.1.2
   Compiling clap v4.5.48
   Compiling uuid v1.18.1
   Compiling env_logger v0.11.8
   Compiling obnam v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.07s
[2025-10-05T08:14:01Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:14:01Z DEBUG ambient_ci::plan] RUN: Action CargoTest
[2025-10-05T08:14:01Z DEBUG ambient_ci::action] Plan::execute: plan=CargoTest
[2025-10-05T08:14:01Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
       cwd=/workspace/src (exists? true)
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/obnam-cb4f95e4084e7c47)

running 35 tests
test chunk::test::id ... ok
test chunk::test::id_is_comparable_with_itself ... ok
test chunk::test::label_round_trip ... ok
test chunk::test::chunk_round_trip ... ok
test chunk::test::metadata ... ok
test chunk::test::metadata_round_trip ... ok
test chunk::test::two_new_chunk_ids_differ ... ok
test cipher::test::key ... ok
test cipher::test::aead_round_trip ... ok
test cipher::test::key_from_string ... ok
test client::test::generates_key ... ok
test client::test::gets_correct_key ... ok
test client::test::has_name ... ok
test client::test::has_no_keys_initially ... ok
test client::test::serialiazion_round_trip ... ok
test client::test::sets_old_versions ... ok
test config::test::merge_files ... ok
test config::test::valideted_config ... ok
test chunk::test::ciphertext_does_not_contain_plaintext ... ok
test plaintext::test::compressed ... ok
test plaintext::test::uncompressed ... ok
test plaintext::test::compression_round_trip ... ok
test store::test::cant_init_nonexistent_dir ... ok
test store::test::empty_dir_is_not_init ... ok
test store::test::adds_chunk ... ok
test credential::test::roundtrip_sop_method ... ok
test credential::test::roundtrip_sop_credential ... ok
test store::test::finds_no_chunk_in_empty_store ... ok
test store::test::finds_chunk_when_labels_match ... ok
test store::test::nonexistent_dir_is_not_init ... ok
test store::test::has_no_chunks_initially ... ok
test store::test::root_dir_is_not_init ... ok
test store::test::inits_empty_dir ... ok
test store::test::finds_no_chunk_when_none_match_label ... ok
test store::test::removes_chunk ... ok

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

     Running unittests src/bin/obnam.rs (/workspace/cache/debug/deps/obnam-f2644399257e9f74)

running 0 tests

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

   Doc-tests obnam

running 0 tests

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

[2025-10-05T08:14:02Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:14:02Z DEBUG ambient_ci::plan] RUN: Action Shell {
        shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
    }
[2025-10-05T08:14:02Z DEBUG ambient_ci::action] Plan::execute: plan=Shell {
        shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
    }
[2025-10-05T08:14:02Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
            "CARGO_TARGET_DIR": "/workspace/cache",
            "CARGO_HOME": "/workspace/deps",
        },
        source_dir: "/workspace/src",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n\n"]
       cwd=/workspace/src (exists? true)
+ export PATH=/root/.cargo/bin:/bin:/sbin
+ PATH=/root/.cargo/bin:/bin:/sbin
+ export CARGO_TARGET_DIR=/workspace/cache
+ CARGO_TARGET_DIR=/workspace/cache
+ make subplot
subplot docgen obnam.subplot --output obnam.html
 INFO Starting Subplot
 INFO Subplot finished successfully
subplot codegen obnam.subplot --output test.py
 INFO Starting Subplot
 INFO Subplot finished successfully
rm -f test.log
python3 test.py --log test.log 
srcdir /workspace/src
datadir /tmp/tmpkhin_ugh
scenario: Chunk encrypt/decrypt with compression round trip via repository
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file greeting.txt
  step: when I create directory mychunks
  step: when I try to run obnam store init
  step: when I run obnam chunk encrypt --compress --key secret --label sticky.tape greeting.txt --id=chunk0
  step: when I run obnam store list
  step: when I run obnam chunk decrypt --key secret --id chunk0
  step: then stdout is exactly "Hello, world.\n"
scenario: Inspect an encoded chunk in file
  step: given an installed obnam
  step: given file greeting.txt
  step: when I run obnam chunk encrypt --label sticky.tape --key secret greeting.txt --output chunk.file
  step: when I run obnam chunk inspect --filename chunk.file
  step: then stdout is valid JSON
  step: then stdout contains ""id":"
  step: then stdout contains ""label":"
  step: then stdout contains ""data": null"
  step: when I run obnam chunk inspect --filename chunk.file --key secret
  step: then stdout is valid JSON
  step: then stdout contains ""id":"
  step: then stdout contains ""label":"
  step: then stdout contains ""data": ""
scenario: Chunk encrypt/decrypt with compression round trip via file
  step: given an installed obnam
  step: given file greeting.txt
  step: when I run obnam chunk encrypt --compress --key secret --label sticky.tape greeting.txt --output chunk.file
  step: when I run obnam chunk decrypt --key secret chunk.file
  step: then stdout is exactly "Hello, world.\n"
scenario: Show client
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: when I create directory mychunks
  step: when I run obnam store init
  step: when I run obnam --client-key my.secret client init my.host
  step: when I run obnam --client-key my.secret client show my.host
  step: then stdout is valid JSON
scenario: Logging level can be set
  step: given an installed obnam
  step: when I run obnam config
  step: then stdout doesn't contain "TRACE"
  step: then stdout doesn't contain "DEBUG"
  step: then stdout contains "INFO"
Traceback (most recent call last):
  File "/workspace/src/test.py", line 4356, in <module>
    main(_scenarios)
  File "/workspace/src/test.py", line 1088, in main
    scen.run(_datadir, extra_env)
  File "/workspace/src/test.py", line 1347, in run
    self._scenario.run(datadir, extra_env)
  File "/workspace/src/test.py", line 925, in run
    step.do(ctx)
  File "/workspace/src/test.py", line 886, in do
    self._function(ctx, **self._args)
  File "/workspace/src/test.py", line 549, in runcmd_stdout_contains
    _runcmd_output_contains(runcmd_get_stdout(ctx), text)
  File "/workspace/src/test.py", line 578, in _runcmd_output_contains
    assert_eq(wanted in actual, True)
  File "/workspace/src/test.py", line 807, in assert_eq
    assert a == b, "expected %r == %r" % (a, b)
           ^^^^^^
AssertionError: expected False == True
make: *** [Makefile:25: subplot] Error 1
[2025-10-05T08:14:02Z ERROR ambient_ci::plan] ERROR: Action failed: failed to execute /bin/bash
[2025-10-05T08:14:02Z ERROR ambient_ci::plan] caused by: command failed: "/bin/bash": exit code 2
ERROR: failed to execute /bin/bash
caused by: command failed: "/bin/bash": exit code 2
EXIT CODE: 1
[2025-10-05T08:14:04Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "patch",
  "repository": {
    "id": "rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x",
    "name": "obnam",
    "description": "Explore implementation of backup fundamentals",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "action": "Created",
  "patch": {
    "id": "0c065974efb775f8929874edea4df947c7b7a1e8",
    "author": {
      "id": "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV",
      "alias": "liw"
    },
    "title": "Use env_logger to log",
    "state": {
      "status": "open",
      "conflicts": []
    },
    "before": "b22a7a92100e2dab3c5efc6afeb5c500703d9a04",
    "after": "9a22bb93b88b6f4932ea4de99893afddea3a267f",
    "commits": [
      "9a22bb93b88b6f4932ea4de99893afddea3a267f",
      "168fc84c9b2fb1aa7df73984296ae8c34b164022",
      "252db1f7651f5f8adc45760b5f410d0b6eacb33d",
      "a51246d3c0fbfc3d8b3a4daa40f24864e96f6a6d",
      "370b3561fc9ce4d2645b3cadeaedfa2711b96489",
      "6f097ce4b19394c3198bca179ca13bd62311aced",
      "18ae010178c51cc2075b033f0701ff842b3f994f",
      "fd57e06dd244b8b78f7bff46c6bcacfa61b8aeb5",
      "058ea32cbaee4744c32d6cc0ea1c96f2460be12a",
      "7f8522da437b2bc3666a92953b8fbad7fbe9bcbc",
      "9cf21b42a07918e7188b8c3b1edf19ed1c1ce17c"
    ],
    "target": "b22a7a92100e2dab3c5efc6afeb5c500703d9a04",
    "labels": [],
    "assignees": [],
    "revisions": [
      {
        "id": "0c065974efb775f8929874edea4df947c7b7a1e8",
        "author": {
          "id": "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV",
          "alias": "liw"
        },
        "description": "",
        "base": "b22a7a92100e2dab3c5efc6afeb5c500703d9a04",
        "oid": "9a22bb93b88b6f4932ea4de99893afddea3a267f",
        "timestamp": 1759651995
      }
    ]
  }
}

Ambient stdout

[2025-10-05T08:13:16Z INFO  ambient] ambient starts
[2025-10-05T08:13:16Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-10-05T08:13:16Z DEBUG ambient] complete configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: None,
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-10-05T08:13:16Z DEBUG ambient] configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: None,
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-10-05T08:13:16Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/meta.yaml
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] no latest commit stored
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] is a git repository
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] git repository is clean
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] current (HEAD) commit: 9a22bb93b88b6f4932ea4de99893afddea3a267f
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] no dry run requested
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] forced run requested
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] run? true
[2025-10-05T08:13:16Z INFO  ambient_ci::run] project rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x: running CI
[2025-10-05T08:13:16Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-10-05T08:13:16Z DEBUG ambient_ci::plan] RUN: Action CargoFetch {
        sourcedir: "/tmp/.tmpBTX8NR/src",
    }
[2025-10-05T08:13:16Z DEBUG ambient_ci::action] Plan::execute: plan=CargoFetch {
        sourcedir: "/tmp/.tmpBTX8NR/src",
    }
[2025-10-05T08:13:16Z DEBUG ambient_ci::action] Plan::execute: context=Context {
        envs: {
            "PATH": "/root/.cargo/bin:/bin:/home/_rad/.radicle/bin:/home/_rad/.cargo/bin",
            "CARGO_TARGET_DIR": "/srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/cache",
            "CARGO_HOME": "/srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/dependencies",
        },
        source_dir: "/tmp/.tmpBTX8NR/src",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/tmp/.tmpBTX8NR/src (exists? true)
cargo 1.90.0 (840b83a10 2025-07-30)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/tmp/.tmpBTX8NR/src (exists? true)
clippy 0.1.90 (1159e78c47 2025-09-14)
SPAWN: argv=["rustc", "--version"]
       cwd=/tmp/.tmpBTX8NR/src (exists? true)
rustc 1.90.0 (1159e78c4 2025-09-14)
SPAWN: argv=["cargo", "fetch"]
       cwd=/tmp/.tmpBTX8NR/src (exists? true)
    Updating crates.io index
 Downloading crates ...
  Downloaded wasm-bindgen v0.2.103
  Downloaded dirs-sys-next v0.1.2
  Downloaded anstream v0.6.20
  Downloaded serde v1.0.226
  Downloaded proc-macro2 v1.0.101
  Downloaded spin v0.9.8
  Downloaded libredox v0.1.10
  Downloaded thiserror-impl v1.0.69
  Downloaded windows-targets v0.53.3
  Downloaded redox_users v0.4.6
  Downloaded syn v2.0.106
  Downloaded universal-hash v0.5.1
  Downloaded thiserror v1.0.69
  Downloaded utf8parse v0.2.2
  Downloaded r-efi v5.3.0
  Downloaded ctr v0.9.2
  Downloaded subtle v2.6.1
  Downloaded once_cell_polyfill v1.70.1
  Downloaded regex-automata v0.4.11
  Downloaded cipher v0.4.4
  Downloaded aead v0.5.2
  Downloaded rustversion v1.0.22
  Downloaded inout v0.1.4
  Downloaded typenum v1.18.0
  Downloaded clap v4.5.48
  Downloaded indexmap v2.11.4
  Downloaded windows-link v0.1.3
  Downloaded hashbrown v0.16.0
  Downloaded clap_builder v4.5.48
  Downloaded env_logger v0.11.8
  Downloaded anstyle-parse v0.2.7
  Downloaded embedded-io v0.6.1
  Downloaded crc32fast v1.5.0
  Downloaded thiserror v2.0.16
  Downloaded bumpalo v3.19.0
  Downloaded serde_norway v0.9.42
  Downloaded lock_api v0.4.13
  Downloaded aes v0.8.4
  Downloaded foldhash v0.1.5
  Downloaded hashlink v0.10.0
  Downloaded fallible-streaming-iterator v0.1.9
  Downloaded semver v1.0.27
  Downloaded stable_deref_trait v1.2.0
  Downloaded fastrand v2.3.0
  Downloaded errno v0.3.14
  Downloaded thiserror-impl v2.0.16
  Downloaded js-sys v0.3.80
  Downloaded adler2 v2.0.1
  Downloaded cobs v0.3.0
  Downloaded hash32 v0.2.1
  Downloaded rustc_version v0.4.1
  Downloaded embedded-io v0.4.0
  Downloaded byteorder v1.5.0
  Downloaded pkg-config v0.3.32
  Downloaded winnow v0.7.13
  Downloaded portable-atomic-util v0.2.4
  Downloaded windows_aarch64_gnullvm v0.53.0
  Downloaded portable-atomic v1.11.1
  Downloaded regex v1.11.3
  Downloaded flate2 v1.1.2
  Downloaded miniz_oxide v0.8.9
  Downloaded jiff-static v0.2.15
  Downloaded memchr v2.7.5
  Downloaded wasip2 v1.0.1+wasi-0.2.4
  Downloaded serde_json v1.0.145
  Downloaded rusqlite v0.37.0
  Downloaded windows_x86_64_gnu v0.53.0
  Downloaded vcpkg v0.2.15
  Downloaded regex-syntax v0.8.6
  Downloaded winapi v0.3.9
  Downloaded rustix v1.1.2
  Downloaded ryu v1.0.20
  Downloaded serde_derive v1.0.226
  Downloaded serde_core v1.0.226
  Downloaded wit-bindgen v0.46.0
  Downloaded getrandom v0.3.3
  Downloaded windows_i686_gnullvm v0.53.0
  Downloaded fallible-iterator v0.3.0
  Downloaded bitflags v2.9.4
  Downloaded critical-section v1.2.0
  Downloaded scopeguard v1.2.0
  Downloaded tempfile v3.22.0
  Downloaded cfg-if v1.0.3
  Downloaded once_cell v1.21.3
  Downloaded colorchoice v1.0.4
  Downloaded autocfg v1.5.0
  Downloaded wasi v0.14.7+wasi-0.2.4
  Downloaded anstyle-query v1.1.4
  Downloaded anstyle-wincon v3.0.10
  Downloaded is_terminal_polyfill v1.70.1
  Downloaded jiff v0.2.15
  Downloaded windows_aarch64_msvc v0.53.0
  Downloaded anstyle v1.0.11
  Downloaded mutants v0.0.3
  Downloaded toml v0.9.7
  Downloaded cpufeatures v0.2.17
  Downloaded libc v0.2.175
  Downloaded generic-array v0.14.7
  Downloaded windows_x86_64_gnullvm v0.53.0
  Downloaded rand_core v0.6.4
  Downloaded crypto-common v0.1.6
  Downloaded heck v0.5.0
  Downloaded clap_derive v4.5.47
  Downloaded unsafe-libyaml-norway v0.2.15
  Downloaded windows_x86_64_msvc v0.53.0
  Downloaded windows_i686_gnu v0.53.0
  Downloaded windows_i686_msvc v0.53.0
  Downloaded toml_datetime v0.7.2
  Downloaded toml_parser v1.0.3
  Downloaded wasi v0.11.1+wasi-snapshot-preview1
  Downloaded getrandom v0.2.16
  Downloaded opaque-debug v0.3.1
  Downloaded version_check v0.9.5
  Downloaded polyval v0.6.2
  Downloaded zeroize v1.8.1
  Downloaded aes-gcm-siv v0.11.1
  Downloaded strsim v0.11.1
  Downloaded serde_spanned v1.0.2
  Downloaded aho-corasick v1.1.3
  Downloaded heapless v0.7.17
  Downloaded smallvec v1.15.1
  Downloaded itoa v1.0.15
  Downloaded log v0.4.28
  Downloaded windows-link v0.2.0
  Downloaded clap_lex v0.7.5
  Downloaded terminal_size v0.4.3
  Downloaded toml_writer v1.0.3
  Downloaded equivalent v1.0.2
  Downloaded env_filter v0.1.3
  Downloaded directories-next v2.0.0
  Downloaded winapi-i686-pc-windows-gnu v0.4.0
  Downloaded winapi-x86_64-pc-windows-gnu v0.4.0
  Downloaded clingwrap v0.4.0
  Downloaded atomic-polyfill v1.0.3
  Downloaded hashbrown v0.15.5
  Downloaded postcard v1.1.3
  Downloaded quote v1.0.40
  Downloaded wasm-bindgen-backend v0.2.103
  Downloaded wasm-bindgen-macro-support v0.2.103
  Downloaded wasm-bindgen-macro v0.2.103
  Downloaded unicode-ident v1.0.19
  Downloaded wasm-bindgen-shared v0.2.103
  Downloaded uuid v1.18.1
  Downloaded windows-sys v0.61.0
  Downloaded windows-sys v0.60.2
  Downloaded linux-raw-sys v0.11.0
  Downloaded libsqlite3-sys v0.35.0
[2025-10-05T08:13:25Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-05T08:13:25Z DEBUG ambient_ci::plan] All actions were performed successfully
[2025-10-05T08:13:25Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpBGaImO/src.tar
[2025-10-05T08:13:25Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpBGaImO/deps.tar
[2025-10-05T08:13:26Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-10-05T08:13:26Z 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",
                ],
            },
            CargoClippy,
            CargoBuild,
            CargoTest,
            Shell {
                shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
            },
            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-10-05T08:13:26Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-10-05T08:13:26Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpBGaImO/ambient-execute-plan/run-ci
[2025-10-05T08:13:26Z DEBUG ambient_ci::run] copy OK: true
[2025-10-05T08:13:26Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpBGaImO/executor.tar
[2025-10-05T08:13:26Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/run.log
[2025-10-05T08:13:26Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x, exists? true
[2025-10-05T08:13:26Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/run.log
[2025-10-05T08:13:26Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/run.log OK
[2025-10-05T08:13:26Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-10-05T08:13:26Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-10-05T08:13:26Z 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-10-05T08:13:26Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-10-05T08:13:26Z 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-10-05T08:13:26Z DEBUG ambient_ci::qemu] run QEMU
[2025-10-05T08:13:26Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-10-05T08:13:26Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmpagRDW1/.tmpcU6YOe/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-10-05T08:13:26Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-10-05T08:13:26Z 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-10-05T08:13:26Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp5xD7YS/meta-data
[2025-10-05T08:13:26Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp5xD7YS/user-data
[2025-10-05T08:13:26Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmpagRDW1/.tmpcU6YOe/console.log
[2025-10-05T08:13:26Z DEBUG ambient_ci::util] create file /srv/tmp/.tmpagRDW1/.tmpcU6YOe/console.log
[2025-10-05T08:13:26Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/run.log
[2025-10-05T08:13:26Z DEBUG ambient_ci::util] create file /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/run.log
[2025-10-05T08:13:26Z 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/.tmpagRDW1/.tmpcU6YOe/console.log",
            "-serial",
            "file:/srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/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/.tmpagRDW1/.tmpcU6YOe/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmpagRDW1/.tmpcU6YOe/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpBGaImO/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmpagRDW1/.tmpcU6YOe/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpBGaImO/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpBGaImO/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpBGaImO/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpBGaImO/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-10-05T08:14:04Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-10-05T08:14:04Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-10-05T08:13:33Z TRACE ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
    [2025-10-05T08:13:33Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
    [2025-10-05T08:13:33Z INFO  ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
    [2025-10-05T08:13:33Z WARN  ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
    [2025-10-05T08:13:33Z ERROR ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 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_clippy
    - action: cargo_build
    - action: cargo_test
    - action: shell
      shell: |
        export PATH=/root/.cargo/bin:/bin:/sbin
        export CARGO_TARGET_DIR=/workspace/cache
        make subplot
    - 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
    
    
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
            pathname: "/workspace",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
            pathname: "/workspace",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
            pathname: "/workspace/artifacts",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
            pathname: "/workspace/artifacts",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    [2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extraction OK
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:33Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-10-05T08:13:33Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    [2025-10-05T08:13:33Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extraction OK
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    [2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-10-05T08:13:34Z TRACE ambient_ci::vdrive] extraction OK
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action Spawn {
            argv: [
                "find",
                "/workspace",
                "-maxdepth",
                "2",
                "-ls",
            ],
        }
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=Spawn {
            argv: [
                "find",
                "/workspace",
                "-maxdepth",
                "2",
                "-ls",
            ],
        }
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
           cwd=/workspace/src (exists? true)
      2621441      4 drwxr-xr-x   6 root     root         4096 Oct  5 08:13 /workspace
      2621577      4 drwxr-xr-x   3 root     root         4096 Oct  5 08:13 /workspace/deps
      2621578      0 -rw-r--r--   1 root     root            0 Oct  5 08:13 /workspace/deps/.package-cache-mutate
      2621581      4 drwxr-xr-x   5 root     root         4096 Oct  5 08:13 /workspace/deps/registry
      2621579     80 -rw-r--r--   1 root     root        81920 Oct  5 08:13 /workspace/deps/.global-cache
      2621580      0 -rw-r--r--   1 root     root            0 Oct  5 08:13 /workspace/deps/.package-cache
      2621443      4 drwxr-xr-x   6 root     root         4096 Oct  5 08:13 /workspace/src
      2621471      4 -rw-r--r--   1 root     root          527 Oct  5 08:13 /workspace/src/Cargo.toml
      2621468     36 -rw-r--r--   1 root     root        35149 Oct  5 08:13 /workspace/src/COPYING.md
      2621473      4 drwxr-xr-x   8 root     root         4096 Oct  5 08:13 /workspace/src/.git
      2621567     24 -rw-r--r--   1 root     root        23155 Oct  5 08:13 /workspace/src/obnam.md
      2621570      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/src/doc
      2621447      4 drwxr-xr-x   3 root     root         4096 Oct  5 08:13 /workspace/src/src
      2621469      8 -rw-r--r--   1 root     root         6734 Oct  5 08:13 /workspace/src/README.md
      2621444      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/src/.radicle
      2621569     36 -rw-r--r--   1 root     root        34174 Oct  5 08:13 /workspace/src/Cargo.lock
      2621565     12 -rw-r--r--   1 root     root         9723 Oct  5 08:13 /workspace/src/LICENSE-APACHE
      2621470      4 -rw-r--r--   1 root     root           26 Oct  5 08:13 /workspace/src/.gitignore
      2621576      4 -rw-r--r--   1 root     root          224 Oct  5 08:13 /workspace/src/obnam.subplot
      2621568      4 -rw-r--r--   1 root     root          283 Oct  5 08:13 /workspace/src/obnam.yaml
      2621467      4 -rw-r--r--   1 root     root         1036 Oct  5 08:13 /workspace/src/GETTING_STARTED.md
      2621446      4 -rw-r--r--   1 root     root         1079 Oct  5 08:13 /workspace/src/LICENSE-MIT
      2621566      4 -rw-r--r--   1 root     root          539 Oct  5 08:13 /workspace/src/Makefile
      2621472      4 -rw-r--r--   1 root     root         1010 Oct  5 08:13 /workspace/src/obnam.py
      2621442      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/artifacts
      2632828      4 drwxr-xr-x   2 root     root         4096 Oct  5 08:13 /workspace/cache
      2632829      4 -rw-r--r--   1 root     root         1038 Oct  5 08:13 /workspace/cache/.rustc_info.json
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:34Z DEBUG ambient_ci::plan] RUN: Action CargoClippy
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: plan=CargoClippy
    [2025-10-05T08:13:34Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.88.0 (873a06493 2025-05-10)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.88 (6b00bc3880 2025-06-23)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.88.0 (6b00bc388 2025-06-23)
    SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
           cwd=/workspace/src (exists? true)
       Compiling libc v0.2.175
        Checking cfg-if v1.0.3
       Compiling proc-macro2 v1.0.101
       Compiling unicode-ident v1.0.19
       Compiling version_check v0.9.5
       Compiling typenum v1.18.0
       Compiling generic-array v0.14.7
       Compiling serde_core v1.0.226
       Compiling quote v1.0.40
        Checking getrandom v0.2.16
       Compiling syn v2.0.106
        Checking rand_core v0.6.4
        Checking memchr v2.7.5
        Checking crypto-common v0.1.6
       Compiling rustix v1.1.2
        Checking bitflags v2.9.4
        Checking linux-raw-sys v0.11.0
       Compiling autocfg v1.5.0
       Compiling serde v1.0.226
       Compiling lock_api v0.4.13
        Checking utf8parse v0.2.2
       Compiling thiserror v2.0.16
       Compiling getrandom v0.3.3
       Compiling semver v1.0.27
        Checking anstyle-parse v0.2.7
       Compiling rustc_version v0.4.1
        Checking aho-corasick v1.1.3
        Checking inout v0.1.4
        Checking anstyle-query v1.1.4
       Compiling pkg-config v0.3.32
        Checking anstyle v1.0.11
        Checking regex-syntax v0.8.6
        Checking colorchoice v1.0.4
       Compiling vcpkg v0.2.15
        Checking is_terminal_polyfill v1.70.1
        Checking scopeguard v1.2.0
        Checking anstream v0.6.20
        Checking cipher v0.4.4
       Compiling heapless v0.7.17
       Compiling libsqlite3-sys v0.35.0
        Checking regex-automata v0.4.11
       Compiling crc32fast v1.5.0
        Checking equivalent v1.0.2
        Checking foldhash v0.1.5
       Compiling serde_derive v1.0.226
       Compiling thiserror-impl v2.0.16
        Checking subtle v2.6.1
        Checking winnow v0.7.13
       Compiling serde_json v1.0.145
        Checking cpufeatures v0.2.17
        Checking hashbrown v0.16.0
        Checking itoa v1.0.15
        Checking ryu v1.0.20
        Checking byteorder v1.5.0
        Checking toml_parser v1.0.3
        Checking hash32 v0.2.1
        Checking regex v1.11.3
        Checking indexmap v2.11.4
        Checking universal-hash v0.5.1
        Checking hashbrown v0.15.5
        Checking spin v0.9.8
        Checking terminal_size v0.4.3
        Checking serde_spanned v1.0.2
        Checking toml_datetime v0.7.2
        Checking dirs-sys-next v0.1.2
        Checking once_cell v1.21.3
        Checking log v0.4.28
        Checking adler2 v2.0.1
        Checking toml_writer v1.0.3
       Compiling heck v0.5.0
        Checking clap_lex v0.7.5
        Checking opaque-debug v0.3.1
        Checking fastrand v2.3.0
        Checking unsafe-libyaml-norway v0.2.15
        Checking stable_deref_trait v1.2.0
        Checking strsim v0.11.1
        Checking tempfile v3.22.0
        Checking clap_builder v4.5.48
        Checking polyval v0.6.2
       Compiling clap_derive v4.5.47
        Checking toml v0.9.7
        Checking serde_norway v0.9.42
        Checking miniz_oxide v0.8.9
        Checking env_filter v0.1.3
        Checking directories-next v2.0.0
        Checking hashlink v0.10.0
        Checking aes v0.8.4
        Checking cobs v0.3.0
        Checking ctr v0.9.2
        Checking aead v0.5.2
        Checking fallible-iterator v0.3.0
        Checking zeroize v1.8.1
        Checking smallvec v1.15.1
        Checking jiff v0.2.15
        Checking fallible-streaming-iterator v0.1.9
        Checking aes-gcm-siv v0.11.1
        Checking rusqlite v0.37.0
        Checking clap v4.5.48
        Checking clingwrap v0.4.0
        Checking postcard v1.1.3
        Checking flate2 v1.1.2
        Checking uuid v1.18.1
       Compiling mutants v0.0.3
        Checking env_logger v0.11.8
        Checking obnam v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.08s
    [2025-10-05T08:13:47Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:13:47Z DEBUG ambient_ci::plan] RUN: Action CargoBuild
    [2025-10-05T08:13:47Z DEBUG ambient_ci::action] Plan::execute: plan=CargoBuild
    [2025-10-05T08:13:47Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.88.0 (873a06493 2025-05-10)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.88 (6b00bc3880 2025-06-23)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.88.0 (6b00bc388 2025-06-23)
    SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
           cwd=/workspace/src (exists? true)
       Compiling cfg-if v1.0.3
       Compiling libc v0.2.175
       Compiling typenum v1.18.0
       Compiling serde_core v1.0.226
       Compiling bitflags v2.9.4
       Compiling memchr v2.7.5
       Compiling generic-array v0.14.7
       Compiling getrandom v0.2.16
       Compiling rand_core v0.6.4
       Compiling linux-raw-sys v0.11.0
       Compiling crypto-common v0.1.6
       Compiling utf8parse v0.2.2
       Compiling inout v0.1.4
       Compiling anstyle-parse v0.2.7
       Compiling aho-corasick v1.1.3
       Compiling colorchoice v1.0.4
       Compiling is_terminal_polyfill v1.70.1
       Compiling rustix v1.1.2
       Compiling anstyle v1.0.11
       Compiling regex-syntax v0.8.6
       Compiling serde v1.0.226
       Compiling scopeguard v1.2.0
       Compiling anstyle-query v1.1.4
       Compiling anstream v0.6.20
       Compiling lock_api v0.4.13
       Compiling regex-automata v0.4.11
       Compiling thiserror v2.0.16
       Compiling cipher v0.4.4
       Compiling getrandom v0.3.3
       Compiling equivalent v1.0.2
       Compiling subtle v2.6.1
       Compiling cpufeatures v0.2.17
       Compiling ryu v1.0.20
       Compiling hashbrown v0.16.0
       Compiling byteorder v1.5.0
       Compiling itoa v1.0.15
       Compiling foldhash v0.1.5
       Compiling winnow v0.7.13
       Compiling hashbrown v0.15.5
       Compiling indexmap v2.11.4
       Compiling hash32 v0.2.1
       Compiling universal-hash v0.5.1
       Compiling spin v0.9.8
       Compiling terminal_size v0.4.3
       Compiling serde_spanned v1.0.2
       Compiling toml_datetime v0.7.2
       Compiling dirs-sys-next v0.1.2
       Compiling regex v1.11.3
       Compiling opaque-debug v0.3.1
       Compiling unsafe-libyaml-norway v0.2.15
       Compiling toml_parser v1.0.3
       Compiling strsim v0.11.1
       Compiling toml_writer v1.0.3
       Compiling adler2 v2.0.1
       Compiling stable_deref_trait v1.2.0
       Compiling once_cell v1.21.3
       Compiling log v0.4.28
       Compiling clap_lex v0.7.5
       Compiling fastrand v2.3.0
       Compiling env_filter v0.1.3
       Compiling clap_builder v4.5.48
       Compiling tempfile v3.22.0
       Compiling heapless v0.7.17
       Compiling miniz_oxide v0.8.9
       Compiling toml v0.9.7
       Compiling serde_norway v0.9.42
       Compiling polyval v0.6.2
       Compiling directories-next v2.0.0
       Compiling libsqlite3-sys v0.35.0
       Compiling serde_json v1.0.145
       Compiling crc32fast v1.5.0
       Compiling hashlink v0.10.0
       Compiling aes v0.8.4
       Compiling ctr v0.9.2
       Compiling cobs v0.3.0
       Compiling aead v0.5.2
       Compiling fallible-streaming-iterator v0.1.9
       Compiling smallvec v1.15.1
       Compiling zeroize v1.8.1
       Compiling fallible-iterator v0.3.0
       Compiling jiff v0.2.15
       Compiling aes-gcm-siv v0.11.1
       Compiling postcard v1.1.3
       Compiling rusqlite v0.37.0
       Compiling clingwrap v0.4.0
       Compiling flate2 v1.1.2
       Compiling clap v4.5.48
       Compiling uuid v1.18.1
       Compiling env_logger v0.11.8
       Compiling obnam v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.07s
    [2025-10-05T08:14:01Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:14:01Z DEBUG ambient_ci::plan] RUN: Action CargoTest
    [2025-10-05T08:14:01Z DEBUG ambient_ci::action] Plan::execute: plan=CargoTest
    [2025-10-05T08:14:01Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.88.0 (873a06493 2025-05-10)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.88 (6b00bc3880 2025-06-23)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.88.0 (6b00bc388 2025-06-23)
    SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
           cwd=/workspace/src (exists? true)
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/obnam-cb4f95e4084e7c47)
    
    running 35 tests
    test chunk::test::id ... ok
    test chunk::test::id_is_comparable_with_itself ... ok
    test chunk::test::label_round_trip ... ok
    test chunk::test::chunk_round_trip ... ok
    test chunk::test::metadata ... ok
    test chunk::test::metadata_round_trip ... ok
    test chunk::test::two_new_chunk_ids_differ ... ok
    test cipher::test::key ... ok
    test cipher::test::aead_round_trip ... ok
    test cipher::test::key_from_string ... ok
    test client::test::generates_key ... ok
    test client::test::gets_correct_key ... ok
    test client::test::has_name ... ok
    test client::test::has_no_keys_initially ... ok
    test client::test::serialiazion_round_trip ... ok
    test client::test::sets_old_versions ... ok
    test config::test::merge_files ... ok
    test config::test::valideted_config ... ok
    test chunk::test::ciphertext_does_not_contain_plaintext ... ok
    test plaintext::test::compressed ... ok
    test plaintext::test::uncompressed ... ok
    test plaintext::test::compression_round_trip ... ok
    test store::test::cant_init_nonexistent_dir ... ok
    test store::test::empty_dir_is_not_init ... ok
    test store::test::adds_chunk ... ok
    test credential::test::roundtrip_sop_method ... ok
    test credential::test::roundtrip_sop_credential ... ok
    test store::test::finds_no_chunk_in_empty_store ... ok
    test store::test::finds_chunk_when_labels_match ... ok
    test store::test::nonexistent_dir_is_not_init ... ok
    test store::test::has_no_chunks_initially ... ok
    test store::test::root_dir_is_not_init ... ok
    test store::test::inits_empty_dir ... ok
    test store::test::finds_no_chunk_when_none_match_label ... ok
    test store::test::removes_chunk ... ok
    
    test result: ok. 35 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
    
         Running unittests src/bin/obnam.rs (/workspace/cache/debug/deps/obnam-f2644399257e9f74)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
       Doc-tests obnam
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    [2025-10-05T08:14:02Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-10-05T08:14:02Z DEBUG ambient_ci::plan] RUN: Action Shell {
            shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
        }
    [2025-10-05T08:14:02Z DEBUG ambient_ci::action] Plan::execute: plan=Shell {
            shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
        }
    [2025-10-05T08:14:02Z DEBUG ambient_ci::action] Plan::execute: context=Context {
            envs: {
                "PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
                "CARGO_TARGET_DIR": "/workspace/cache",
                "CARGO_HOME": "/workspace/deps",
            },
            source_dir: "/workspace/src",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n\n"]
           cwd=/workspace/src (exists? true)
    + export PATH=/root/.cargo/bin:/bin:/sbin
    + PATH=/root/.cargo/bin:/bin:/sbin
    + export CARGO_TARGET_DIR=/workspace/cache
    + CARGO_TARGET_DIR=/workspace/cache
    + make subplot
    subplot docgen obnam.subplot --output obnam.html
     INFO Starting Subplot
     INFO Subplot finished successfully
    subplot codegen obnam.subplot --output test.py
     INFO Starting Subplot
     INFO Subplot finished successfully
    rm -f test.log
    python3 test.py --log test.log 
    srcdir /workspace/src
    datadir /tmp/tmpkhin_ugh
    scenario: Chunk encrypt/decrypt with compression round trip via repository
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file greeting.txt
      step: when I create directory mychunks
      step: when I try to run obnam store init
      step: when I run obnam chunk encrypt --compress --key secret --label sticky.tape greeting.txt --id=chunk0
      step: when I run obnam store list
      step: when I run obnam chunk decrypt --key secret --id chunk0
      step: then stdout is exactly "Hello, world.\n"
    scenario: Inspect an encoded chunk in file
      step: given an installed obnam
      step: given file greeting.txt
      step: when I run obnam chunk encrypt --label sticky.tape --key secret greeting.txt --output chunk.file
      step: when I run obnam chunk inspect --filename chunk.file
      step: then stdout is valid JSON
      step: then stdout contains ""id":"
      step: then stdout contains ""label":"
      step: then stdout contains ""data": null"
      step: when I run obnam chunk inspect --filename chunk.file --key secret
      step: then stdout is valid JSON
      step: then stdout contains ""id":"
      step: then stdout contains ""label":"
      step: then stdout contains ""data": ""
    scenario: Chunk encrypt/decrypt with compression round trip via file
      step: given an installed obnam
      step: given file greeting.txt
      step: when I run obnam chunk encrypt --compress --key secret --label sticky.tape greeting.txt --output chunk.file
      step: when I run obnam chunk decrypt --key secret chunk.file
      step: then stdout is exactly "Hello, world.\n"
    scenario: Show client
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: when I create directory mychunks
      step: when I run obnam store init
      step: when I run obnam --client-key my.secret client init my.host
      step: when I run obnam --client-key my.secret client show my.host
      step: then stdout is valid JSON
    scenario: Logging level can be set
      step: given an installed obnam
      step: when I run obnam config
      step: then stdout doesn't contain "TRACE"
      step: then stdout doesn't contain "DEBUG"
      step: then stdout contains "INFO"
    Traceback (most recent call last):
      File "/workspace/src/test.py", line 4356, in <module>
        main(_scenarios)
      File "/workspace/src/test.py", line 1088, in main
        scen.run(_datadir, extra_env)
      File "/workspace/src/test.py", line 1347, in run
        self._scenario.run(datadir, extra_env)
      File "/workspace/src/test.py", line 925, in run
        step.do(ctx)
      File "/workspace/src/test.py", line 886, in do
        self._function(ctx, **self._args)
      File "/workspace/src/test.py", line 549, in runcmd_stdout_contains
        _runcmd_output_contains(runcmd_get_stdout(ctx), text)
      File "/workspace/src/test.py", line 578, in _runcmd_output_contains
        assert_eq(wanted in actual, True)
      File "/workspace/src/test.py", line 807, in assert_eq
        assert a == b, "expected %r == %r" % (a, b)
               ^^^^^^
    AssertionError: expected False == True
    make: *** [Makefile:25: subplot] Error 1
    [2025-10-05T08:14:02Z ERROR ambient_ci::plan] ERROR: Action failed: failed to execute /bin/bash
    [2025-10-05T08:14:02Z ERROR ambient_ci::plan] caused by: command failed: "/bin/bash": exit code 2
    ERROR: failed to execute /bin/bash
    caused by: command failed: "/bin/bash": exit code 2
    EXIT CODE: 1
    
    ====================
[2025-10-05T08:14:04Z DEBUG ambient_ci::qemu] QEMU: CI run under exit code 1
[2025-10-05T08:14:04Z DEBUG ambient_ci::run] CI run exit code from QEMU: 1
[2025-10-05T08:14:04Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x/meta.yaml
ERROR: CI run failed inside QEMU

Ambient stderr

<empty log>