CI run for obnam

Radicle repository id rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x

Table of Contents

Run log

[2025-08-16T05:07:09Z 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   1.1M  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 276.4M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-08-16 05:06 ./
-rw-r--r-- _rad/_rad       994 2025-08-16 05:06 ./plan.yaml
-rwxr-xr-x _rad/_rad   3003032 2025-08-16 05:06 ./run-ci
INFO: Extracted files:
  1477809      4 drwxr-xr-x   2 1001     1001         4096 Aug 16 05:06 .
  1477810      4 -rw-r--r--   1 1001     1001          994 Aug 16 05:06 ./plan.yaml
  1477881   2936 -rwxr-xr-x   1 1001     1001      3003032 Aug 16 05:06 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-08-16T05:06:32Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:06:32Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:06:32Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:06:32Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:06:32Z 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_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


RUN: Action Mkdir {
    pathname: "/workspace",
}
[2025-08-16T05:06:32Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-08-16T05:06:32Z 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:06:32Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-08-16T05:06:32Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:06:33Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-08-16T05:06:33Z 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:06 /workspace
  3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/artifacts
  3932696      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:06 /workspace/deps
  3932698     64 -rw-r--r--   1 root     root        65536 Aug 16 05:06 /workspace/deps/.global-cache
  3932697      0 -rw-r--r--   1 root     root            0 Aug 16 05:06 /workspace/deps/.package-cache-mutate
  3932699      0 -rw-r--r--   1 root     root            0 Aug 16 05:06 /workspace/deps/.package-cache
  3932700      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:06 /workspace/deps/registry
  3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:06 /workspace/src
  3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/src/.radicle
  3932689     28 -rw-r--r--   1 root     root        26460 Aug 16 05:06 /workspace/src/Cargo.lock
  3932690      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/src/doc
  3932189      4 -rw-r--r--   1 root     root          490 Aug 16 05:06 /workspace/src/Cargo.toml
  3932190      4 -rw-r--r--   1 root     root         1010 Aug 16 05:06 /workspace/src/obnam.py
  3932188      4 -rw-r--r--   1 root     root           31 Aug 16 05:06 /workspace/src/.gitignore
  3932695      4 -rw-r--r--   1 root     root          224 Aug 16 05:06 /workspace/src/obnam.subplot
  3932686      4 -rw-r--r--   1 root     root          368 Aug 16 05:06 /workspace/src/Makefile
  3932191      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:06 /workspace/src/.git
  3932187      4 -rw-r--r--   1 root     root         3457 Aug 16 05:06 /workspace/src/README.md
  3932166      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:06 /workspace/src/src
  3932687     20 -rw-r--r--   1 root     root        16743 Aug 16 05:06 /workspace/src/obnam.md
  3932688      4 -rw-r--r--   1 root     root          283 Aug 16 05:06 /workspace/src/obnam.yaml
  3942125      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/cache
RUN: Action finished OK
RUN: Action CargoClippy
[2025-08-16T05:06:33Z 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 libc v0.2.171
    Checking cfg-if v1.0.0
   Compiling version_check v0.9.5
   Compiling proc-macro2 v1.0.94
   Compiling unicode-ident v1.0.18
   Compiling typenum v1.18.0
   Compiling generic-array v0.14.7
    Checking bitflags v2.9.0
   Compiling semver v1.0.26
   Compiling serde v1.0.219
   Compiling quote v1.0.40
    Checking getrandom v0.2.16
   Compiling rustix v1.0.3
    Checking rand_core v0.6.4
   Compiling syn v2.0.100
    Checking linux-raw-sys v0.9.3
    Checking foldhash v0.1.5
   Compiling autocfg v1.4.0
    Checking crypto-common v0.1.6
    Checking hashbrown v0.15.3
   Compiling lock_api v0.4.12
   Compiling anyhow v1.0.98
   Compiling getrandom v0.3.1
   Compiling rustc_version v0.4.1
    Checking inout v0.1.4
    Checking utf8parse v0.2.2
   Compiling vcpkg v0.2.15
   Compiling pkg-config v0.3.32
    Checking scopeguard v1.2.0
   Compiling libsqlite3-sys v0.33.0
   Compiling heapless v0.7.17
    Checking anstyle-parse v0.2.6
    Checking cipher v0.4.4
   Compiling serde_yml v0.0.12
    Checking ryu v1.0.20
    Checking subtle v2.6.1
    Checking equivalent v1.0.2
   Compiling serde_json v1.0.140
    Checking is_terminal_polyfill v1.70.1
    Checking anstyle-query v1.1.2
    Checking memchr v2.7.4
    Checking colorchoice v1.0.3
   Compiling thiserror v2.0.12
    Checking anstyle v1.0.10
    Checking itoa v1.0.15
    Checking byteorder v1.5.0
    Checking cpufeatures v0.2.17
    Checking anstream v0.6.18
    Checking hash32 v0.2.1
    Checking universal-hash v0.5.1
    Checking indexmap v2.10.0
    Checking libyml v0.0.5
    Checking terminal_size v0.4.2
    Checking spin v0.9.8
    Checking stable_deref_trait v1.2.0
   Compiling heck v0.5.0
    Checking strsim v0.11.1
    Checking once_cell v1.21.1
    Checking opaque-debug v0.3.1
    Checking fastrand v2.3.0
    Checking adler2 v2.0.0
    Checking clap_lex v0.7.4
    Checking miniz_oxide v0.8.8
   Compiling serde_derive v1.0.219
   Compiling thiserror-impl v2.0.12
    Checking clap_builder v4.5.32
    Checking tempfile v3.20.0
   Compiling clap_derive v4.5.32
    Checking polyval v0.6.2
    Checking aes v0.8.4
    Checking ctr v0.9.2
    Checking hashlink v0.10.0
    Checking aead v0.5.2
    Checking dirs-sys-next v0.1.2
    Checking crc32fast v1.4.2
    Checking cobs v0.2.3
    Checking fallible-streaming-iterator v0.1.9
    Checking fallible-iterator v0.3.0
    Checking zeroize v1.8.1
    Checking smallvec v1.15.0
    Checking clap v4.5.32
    Checking aes-gcm-siv v0.11.1
    Checking flate2 v1.1.2
    Checking rusqlite v0.35.0
    Checking directories-next v2.0.0
    Checking uuid v1.16.0
   Compiling mutants v0.0.3
    Checking clingwrap v0.1.0
    Checking postcard v1.1.1
    Checking obnam v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.27s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-08-16T05:06:49Z 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 cfg-if v1.0.0
   Compiling libc v0.2.171
   Compiling typenum v1.18.0
   Compiling bitflags v2.9.0
   Compiling foldhash v0.1.5
   Compiling linux-raw-sys v0.9.3
   Compiling hashbrown v0.15.3
   Compiling generic-array v0.14.7
   Compiling rustix v1.0.3
   Compiling getrandom v0.2.16
   Compiling serde v1.0.219
   Compiling rand_core v0.6.4
   Compiling scopeguard v1.2.0
   Compiling utf8parse v0.2.2
   Compiling anstyle-parse v0.2.6
   Compiling crypto-common v0.1.6
   Compiling inout v0.1.4
   Compiling lock_api v0.4.12
   Compiling cipher v0.4.4
   Compiling anyhow v1.0.98
   Compiling getrandom v0.3.1
   Compiling is_terminal_polyfill v1.70.1
   Compiling colorchoice v1.0.3
   Compiling ryu v1.0.20
   Compiling memchr v2.7.4
   Compiling anstyle-query v1.1.2
   Compiling itoa v1.0.15
   Compiling equivalent v1.0.2
   Compiling subtle v2.6.1
   Compiling anstyle v1.0.10
   Compiling byteorder v1.5.0
   Compiling cpufeatures v0.2.17
   Compiling hash32 v0.2.1
   Compiling terminal_size v0.4.2
   Compiling anstream v0.6.18
   Compiling universal-hash v0.5.1
   Compiling indexmap v2.10.0
   Compiling libyml v0.0.5
   Compiling spin v0.9.8
   Compiling adler2 v2.0.0
   Compiling clap_lex v0.7.4
   Compiling once_cell v1.21.1
   Compiling fastrand v2.3.0
   Compiling stable_deref_trait v1.2.0
   Compiling opaque-debug v0.3.1
   Compiling strsim v0.11.1
   Compiling polyval v0.6.2
   Compiling serde_yml v0.0.12
   Compiling clap_builder v4.5.32
   Compiling tempfile v3.20.0
   Compiling heapless v0.7.17
   Compiling serde_json v1.0.140
   Compiling miniz_oxide v0.8.8
   Compiling thiserror v2.0.12
   Compiling libsqlite3-sys v0.33.0
   Compiling aes v0.8.4
   Compiling ctr v0.9.2
   Compiling aead v0.5.2
   Compiling hashlink v0.10.0
   Compiling dirs-sys-next v0.1.2
   Compiling crc32fast v1.4.2
   Compiling zeroize v1.8.1
   Compiling cobs v0.2.3
   Compiling fallible-streaming-iterator v0.1.9
   Compiling smallvec v1.15.0
   Compiling fallible-iterator v0.3.0
   Compiling postcard v1.1.1
   Compiling aes-gcm-siv v0.11.1
   Compiling flate2 v1.1.2
   Compiling rusqlite v0.35.0
   Compiling directories-next v2.0.0
   Compiling clap v4.5.32
   Compiling clingwrap v0.1.0
   Compiling uuid v1.16.0
   Compiling obnam v0.1.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.89s
RUN: Action finished OK
RUN: Action CargoTest
[2025-08-16T05:06:59Z DEBUG ambient_ci::action] Plan::execute: CargoTest
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/obnam-8225839b9b294a56)

running 33 tests
test chunk::test::id ... ok
test chunk::test::id_is_comparable_with_itself ... ok
test chunk::test::ciphertext_does_not_contain_plaintext ... ok
test chunk::test::label_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 chunk::test::chunk_round_trip ... 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_credential ... ok
test credential::test::roundtrip_sop_method ... ok
test store::test::finds_no_chunk_in_empty_store ... ok
test store::test::has_no_chunks_initially ... ok
test store::test::nonexistent_dir_is_not_init ... ok
test store::test::finds_chunk_when_labels_match ... 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. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s

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

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 1 test
test src/runner.rs - runner (line 8) ... ok

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

RUN: Action finished OK
RUN: Action Shell {
    shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
}
[2025-08-16T05:07:00Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
    }
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)
       extra_env=[]
+ 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/tmpfrwoc66t
scenario: Inspect an encrypted chunk using key from client chunk
  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 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 generate my.host my.key
  step: when I run obnam --client-key my.secret chunk encrypt --client-name my.host --key-name my.key --label sticky.tape greeting.txt --output chunk.file
  step: when I run obnam --client-key my.secret chunk inspect --filename chunk.file --client-name my.host --key-name my.key
  step: then stdout is valid JSON
  step: then stdout contains ""id":"
  step: then stdout contains ""label":"
  step: then stdout contains ""data": ""
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: Create an OpenPGP software key credential
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file sop-generate
  step: when I run bash sop-generate alice.tsk
  step: when I create directory mychunks
  step: when I run obnam store init
  step: when I run obnam credential list
  step: then stdout is exactly ""
  step: when I run obnam --client-key my.secret credential openpgp-soft alice.tsk
  step: when I run obnam credential list
  step: then stdout isn't exactly ""
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: Initialize a client chunk
  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: then command is successful
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: Initialize a store
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: then directory mychunks does not exist
  step: when I try to run obnam store is
  step: then command fails
  step: when I create directory mychunks
  step: when I try to run obnam store is
  step: then command fails
  step: when I run obnam store init
  step: when I run obnam store is
  step: then exit code is 0
scenario: Extracting a certificate from a key
  step: given an installed obnam
  step: given file alice.key
  step: when I run obnam sop extract-cert rsop alice.key
  step: then stdout isn't exactly ""
scenario: Get path to chunk in store
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file chunk.blob
  step: given a directory mychunks
  step: when I run obnam store init
  step: when I run obnam store add xyzzy data-chunk chunk.blob
  step: when I run obnam store path xyzzy -o chunk.filename
  step: then a file exists whose name is in chunk.filename
scenario: Encryption round trip
  step: given an installed obnam
  step: given file alice.key
  step: given file hello.txt
  step: when I run obnam sop encrypt rsop alice.key hello.txt -o encrypted
  step: when I run ls -l encrypted
  step: when I run cat encrypted
  step: when I run obnam sop decrypt rsop alice.key encrypted
  step: then stdout is exactly "hello, world\n"
scenario: Find chunks using labels
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file chunk.blob
  step: given a directory mychunks
  step: when I run obnam store init
  step: when I run obnam store add xyzzy data-chunk chunk.blob
  step: when I run obnam store add plugh data-chunk chunk.blob
  step: when I run obnam store add advent client-chunk chunk.blob
  step: when I run obnam store find missing
  step: then stdout is exactly ""
  step: when I run obnam store find data-chunk
  step: then stdout contains "xyzzy"
  step: then stdout contains "plugh"
  step: then stdout doesn't contain "advent"
  step: when I run obnam store find client-chunk
  step: then stdout doesn't contain "xyzzy"
  step: then stdout doesn't contain "plugh"
  step: then stdout contains "advent"
scenario: List clients
  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 list
  step: then stdout is exactly "my.host\n"
scenario: Inspect an encoded chunk in 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 --label sticky.tape --key secret greeting.txt --id=chunk0
  step: when I run obnam chunk inspect --id chunk0
  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 --key secret --id chunk0
  step: then stdout is valid JSON
  step: then stdout contains ""id":"
  step: then stdout contains ""label":"
  step: then stdout contains ""data": ""
scenario: Generate new key
  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 generate my.host my.key
  step: when I run obnam --client-key my.secret client show my.host
  step: then stdout contains "my.key"
scenario: Round trip chunk encryption using key from client chunk
  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 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 generate my.host my.key
  step: when I run obnam --client-key my.secret chunk encrypt --client-name my.host --key-name my.key --label sticky.tape greeting.txt --output chunk.file
  step: when I run obnam --client-key my.secret chunk decrypt --client-name my.host --key-name my.key chunk.file
  step: then stdout is exactly "Hello, world.\n"
scenario: Avoid client chunks with the same name
  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: then command is successful
  step: when I try to run obnam --client-key my.secret client init my.host
  step: then command fails
scenario: Chunk encrypt/decrypt round trip via file
  step: given an installed obnam
  step: given file greeting.txt
  step: when I run obnam chunk encrypt --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: Add a chunk to the store.
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file chunk.blob
  step: given a directory mychunks
  step: when I run obnam store init
  step: when I run obnam store add xyzzy data-chunk chunk.blob
  step: when I run obnam store list
  step: then stdout is exactly "xyzzy\n"
scenario: Chunk encrypt/decrypt 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 --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: Remove a chunk from store
  step: given an installed obnam
  step: given file .config/obnam/config.yaml from config.yaml
  step: given file chunk.blob
  step: given a directory mychunks
  step: when I run obnam store init
  step: when I run obnam store add xyzzy data-chunk chunk.blob
  step: when I run obnam store remove xyzzy
  step: when I run obnam store list
  step: then stdout doesn't contain "xyzzy"
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"
OK, all scenarios finished successfully
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:07:01Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-08-16T05:07:02Z 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:07:02Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
RUN: Action finished OK
ambient-execute-plan ends
EXIT CODE: 0
[2025-08-16T05:07:09Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:zbWNQYkQ4QKgdSQcd1tjaemv6d6x",
    "name": "obnam",
    "description": "Explore implementation of backup fundamentals",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "pusher": {
    "id": "did:key:z6MkkYez6QAFWvmdudjrfMXySu1NTpSrraJotCJFwGgkmgx8",
    "alias": "ci0"
  },
  "before": "bf66480a560cc78c2bb4931d5509d00cdaa03215",
  "after": "bf66480a560cc78c2bb4931d5509d00cdaa03215",
  "branch": "main",
  "commits": [
    "bf66480a560cc78c2bb4931d5509d00cdaa03215"
  ]
}

Ambient stdout

[2025-08-16T05:06:17Z INFO  ambient] ambient starts
[2025-08-16T05:06:17Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-08-16T05:06:17Z DEBUG ambient] load files named with --config: /tmp/.tmpM82Kf1/ambient_extra_values.yaml
[2025-08-16T05:06:17Z 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:06:17Z 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:06:17Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/obnam/meta.yaml
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] latest commit: "bf66480a560cc78c2bb4931d5509d00cdaa03215"
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] is a git repository
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] git repository is clean
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] current (HEAD) commit: bf66480a560cc78c2bb4931d5509d00cdaa03215
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] no dry run requested
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] forced run requested
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] run? true
[2025-08-16T05:06:17Z INFO  ambient_ci::run] project obnam: running CI
[2025-08-16T05:06:17Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-08-16T05:06:17Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmptAx2O8/src",
        dependenciesdir: "/srv/ambient-state/obnam/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/obnam/dependencies", "cargo", "fetch", "--locked"]
       cwd=/tmp/.tmptAx2O8/src (exists? true)
       extra_env=[]
    Updating crates.io index
 Downloading crates ...
  Downloaded windows-targets v0.52.6
  Downloaded getrandom v0.3.1
  Downloaded zeroize v1.8.1
  Downloaded uuid v1.16.0
  Downloaded crypto-common v0.1.6
  Downloaded thiserror v2.0.12
  Downloaded aead v0.5.2
  Downloaded cipher v0.4.4
  Downloaded subtle v2.6.1
  Downloaded thiserror-impl v2.0.12
  Downloaded aes v0.8.4
  Downloaded foldhash v0.1.5
  Downloaded fastrand v2.3.0
  Downloaded bitflags v2.9.0
  Downloaded libredox v0.1.4
  Downloaded directories-next v2.0.0
  Downloaded equivalent v1.0.2
  Downloaded ryu v1.0.20
  Downloaded is_terminal_polyfill v1.70.1
  Downloaded terminal_size v0.4.2
  Downloaded clap_lex v0.7.4
  Downloaded dirs-sys-next v0.1.2
  Downloaded anstyle-wincon v3.0.7
  Downloaded anstyle-query v1.1.2
  Downloaded utf8parse v0.2.2
  Downloaded anyhow v1.0.98
  Downloaded aes-gcm-siv v0.11.1
  Downloaded anstream v0.6.18
  Downloaded postcard v1.1.1
  Downloaded anstyle-parse v0.2.6
  Downloaded heapless v0.7.17
  Downloaded miniz_oxide v0.8.8
  Downloaded clap v4.5.32
  Downloaded flate2 v1.1.2
  Downloaded libyml v0.0.5
  Downloaded indexmap v2.10.0
  Downloaded hashbrown v0.15.3
  Downloaded clap_builder v4.5.32
  Downloaded rusqlite v0.35.0
  Downloaded serde_yml v0.0.12
  Downloaded serde_json v1.0.140
  Downloaded vcpkg v0.2.15
  Downloaded memchr v2.7.4
  Downloaded syn v2.0.100
  Downloaded windows_aarch64_gnullvm v0.52.6
  Downloaded windows_i686_gnullvm v0.52.6
  Downloaded windows_x86_64_gnullvm v0.52.6
  Downloaded rustix v1.0.3
  Downloaded wasi v0.13.3+wasi-0.2.2
  Downloaded heck v0.5.0
  Downloaded getrandom v0.2.16
  Downloaded crc32fast v1.4.2
  Downloaded atomic-polyfill v1.0.3
  Downloaded semver v1.0.26
  Downloaded serde_derive v1.0.219
  Downloaded tempfile v3.20.0
  Downloaded unicode-ident v1.0.18
  Downloaded anstyle v1.0.10
  Downloaded clap_derive v4.5.32
  Downloaded rustc_version v0.4.1
  Downloaded windows_x86_64_gnu v0.52.6
  Downloaded windows_x86_64_msvc v0.52.6
  Downloaded windows_i686_gnu v0.52.6
  Downloaded windows_i686_msvc v0.52.6
  Downloaded spin v0.9.8
  Downloaded colorchoice v1.0.3
  Downloaded embedded-io v0.6.1
  Downloaded libc v0.2.171
  Downloaded windows_aarch64_msvc v0.52.6
  Downloaded strsim v0.11.1
  Downloaded wasi v0.11.0+wasi-snapshot-preview1
  Downloaded thiserror-impl v1.0.69
  Downloaded thiserror v1.0.69
  Downloaded redox_users v0.4.6
  Downloaded cobs v0.2.3
  Downloaded critical-section v1.2.0
  Downloaded scopeguard v1.2.0
  Downloaded lock_api v0.4.12
  Downloaded stable_deref_trait v1.2.0
  Downloaded hashlink v0.10.0
  Downloaded smallvec v1.15.0
  Downloaded winapi v0.3.9
  Downloaded version_check v0.9.5
  Downloaded hash32 v0.2.1
  Downloaded adler2 v2.0.0
  Downloaded embedded-io v0.4.0
  Downloaded byteorder v1.5.0
  Downloaded autocfg v1.4.0
  Downloaded fallible-iterator v0.3.0
  Downloaded pkg-config v0.3.32
  Downloaded mutants v0.0.3
  Downloaded errno v0.3.10
  Downloaded serde v1.0.219
  Downloaded wit-bindgen-rt v0.33.0
  Downloaded ctr v0.9.2
  Downloaded cpufeatures v0.2.17
  Downloaded generic-array v0.14.7
  Downloaded rand_core v0.6.4
  Downloaded typenum v1.18.0
  Downloaded universal-hash v0.5.1
  Downloaded polyval v0.6.2
  Downloaded cfg-if v1.0.0
  Downloaded inout v0.1.4
  Downloaded opaque-debug v0.3.1
  Downloaded itoa v1.0.15
  Downloaded once_cell v1.21.1
  Downloaded proc-macro2 v1.0.94
  Downloaded quote v1.0.40
  Downloaded linux-raw-sys v0.9.3
  Downloaded windows-sys v0.59.0
  Downloaded winapi-i686-pc-windows-gnu v0.4.0
  Downloaded winapi-x86_64-pc-windows-gnu v0.4.0
  Downloaded libsqlite3-sys v0.33.0
  Downloaded clingwrap v0.1.0
  Downloaded fallible-streaming-iterator v0.1.9
[2025-08-16T05:06:21Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpnVEAD1/src.tar
[2025-08-16T05:06:21Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpnVEAD1/deps.tar
[2025-08-16T05:06:23Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-08-16T05:06:23Z 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-08-16T05:06:23Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-08-16T05:06:23Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpnVEAD1/ambient-execute-plan/run-ci
[2025-08-16T05:06:23Z DEBUG ambient_ci::run] copy OK: true
[2025-08-16T05:06:23Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpnVEAD1/executor.tar
[2025-08-16T05:06:24Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/obnam/run.log
[2025-08-16T05:06:24Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/obnam, exists? true
[2025-08-16T05:06:24Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/obnam/run.log
[2025-08-16T05:06:24Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/obnam/run.log OK
[2025-08-16T05:06:24Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-08-16T05:06:24Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-08-16T05:06:24Z 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:06:24Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-08-16T05:06:24Z 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:06:24Z DEBUG ambient_ci::qemu] run QEMU
[2025-08-16T05:06:24Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-08-16T05:06:24Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmp4PGSfC/.tmpFFN5Cr/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-08-16T05:06:24Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-08-16T05:06:24Z 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:06:24Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpzJQzz9/meta-data
[2025-08-16T05:06:24Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpzJQzz9/user-data
[2025-08-16T05:06:24Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmp4PGSfC/.tmpFFN5Cr/console.log
[2025-08-16T05:06:24Z DEBUG ambient_ci::util] create file /srv/tmp/.tmp4PGSfC/.tmpFFN5Cr/console.log
[2025-08-16T05:06:24Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/obnam/run.log
[2025-08-16T05:06:24Z DEBUG ambient_ci::util] create file /srv/ambient-state/obnam/run.log
[2025-08-16T05:06:24Z 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/.tmp4PGSfC/.tmpFFN5Cr/console.log",
            "-serial",
            "file:/srv/ambient-state/obnam/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/.tmp4PGSfC/.tmpFFN5Cr/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmp4PGSfC/.tmpFFN5Cr/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpnVEAD1/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmp4PGSfC/.tmpFFN5Cr/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpnVEAD1/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpnVEAD1/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpnVEAD1/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpnVEAD1/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-08-16T05:07:08Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-08-16T05:07:08Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-08-16T05:06:32Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:06:32Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:06:32Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:06:32Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:06:32Z 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_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
    
    
    RUN: Action Mkdir {
        pathname: "/workspace",
    }
    [2025-08-16T05:06:32Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-08-16T05:06:32Z 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:06:32Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-08-16T05:06:32Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-08-16T05:06:32Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:06:33Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-08-16T05:06:33Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-08-16T05:06:33Z 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:06 /workspace
      3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/artifacts
      3932696      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:06 /workspace/deps
      3932698     64 -rw-r--r--   1 root     root        65536 Aug 16 05:06 /workspace/deps/.global-cache
      3932697      0 -rw-r--r--   1 root     root            0 Aug 16 05:06 /workspace/deps/.package-cache-mutate
      3932699      0 -rw-r--r--   1 root     root            0 Aug 16 05:06 /workspace/deps/.package-cache
      3932700      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:06 /workspace/deps/registry
      3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:06 /workspace/src
      3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/src/.radicle
      3932689     28 -rw-r--r--   1 root     root        26460 Aug 16 05:06 /workspace/src/Cargo.lock
      3932690      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/src/doc
      3932189      4 -rw-r--r--   1 root     root          490 Aug 16 05:06 /workspace/src/Cargo.toml
      3932190      4 -rw-r--r--   1 root     root         1010 Aug 16 05:06 /workspace/src/obnam.py
      3932188      4 -rw-r--r--   1 root     root           31 Aug 16 05:06 /workspace/src/.gitignore
      3932695      4 -rw-r--r--   1 root     root          224 Aug 16 05:06 /workspace/src/obnam.subplot
      3932686      4 -rw-r--r--   1 root     root          368 Aug 16 05:06 /workspace/src/Makefile
      3932191      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:06 /workspace/src/.git
      3932187      4 -rw-r--r--   1 root     root         3457 Aug 16 05:06 /workspace/src/README.md
      3932166      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:06 /workspace/src/src
      3932687     20 -rw-r--r--   1 root     root        16743 Aug 16 05:06 /workspace/src/obnam.md
      3932688      4 -rw-r--r--   1 root     root          283 Aug 16 05:06 /workspace/src/obnam.yaml
      3942125      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:06 /workspace/cache
    RUN: Action finished OK
    RUN: Action CargoClippy
    [2025-08-16T05:06:33Z 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 libc v0.2.171
        Checking cfg-if v1.0.0
       Compiling version_check v0.9.5
       Compiling proc-macro2 v1.0.94
       Compiling unicode-ident v1.0.18
       Compiling typenum v1.18.0
       Compiling generic-array v0.14.7
        Checking bitflags v2.9.0
       Compiling semver v1.0.26
       Compiling serde v1.0.219
       Compiling quote v1.0.40
        Checking getrandom v0.2.16
       Compiling rustix v1.0.3
        Checking rand_core v0.6.4
       Compiling syn v2.0.100
        Checking linux-raw-sys v0.9.3
        Checking foldhash v0.1.5
       Compiling autocfg v1.4.0
        Checking crypto-common v0.1.6
        Checking hashbrown v0.15.3
       Compiling lock_api v0.4.12
       Compiling anyhow v1.0.98
       Compiling getrandom v0.3.1
       Compiling rustc_version v0.4.1
        Checking inout v0.1.4
        Checking utf8parse v0.2.2
       Compiling vcpkg v0.2.15
       Compiling pkg-config v0.3.32
        Checking scopeguard v1.2.0
       Compiling libsqlite3-sys v0.33.0
       Compiling heapless v0.7.17
        Checking anstyle-parse v0.2.6
        Checking cipher v0.4.4
       Compiling serde_yml v0.0.12
        Checking ryu v1.0.20
        Checking subtle v2.6.1
        Checking equivalent v1.0.2
       Compiling serde_json v1.0.140
        Checking is_terminal_polyfill v1.70.1
        Checking anstyle-query v1.1.2
        Checking memchr v2.7.4
        Checking colorchoice v1.0.3
       Compiling thiserror v2.0.12
        Checking anstyle v1.0.10
        Checking itoa v1.0.15
        Checking byteorder v1.5.0
        Checking cpufeatures v0.2.17
        Checking anstream v0.6.18
        Checking hash32 v0.2.1
        Checking universal-hash v0.5.1
        Checking indexmap v2.10.0
        Checking libyml v0.0.5
        Checking terminal_size v0.4.2
        Checking spin v0.9.8
        Checking stable_deref_trait v1.2.0
       Compiling heck v0.5.0
        Checking strsim v0.11.1
        Checking once_cell v1.21.1
        Checking opaque-debug v0.3.1
        Checking fastrand v2.3.0
        Checking adler2 v2.0.0
        Checking clap_lex v0.7.4
        Checking miniz_oxide v0.8.8
       Compiling serde_derive v1.0.219
       Compiling thiserror-impl v2.0.12
        Checking clap_builder v4.5.32
        Checking tempfile v3.20.0
       Compiling clap_derive v4.5.32
        Checking polyval v0.6.2
        Checking aes v0.8.4
        Checking ctr v0.9.2
        Checking hashlink v0.10.0
        Checking aead v0.5.2
        Checking dirs-sys-next v0.1.2
        Checking crc32fast v1.4.2
        Checking cobs v0.2.3
        Checking fallible-streaming-iterator v0.1.9
        Checking fallible-iterator v0.3.0
        Checking zeroize v1.8.1
        Checking smallvec v1.15.0
        Checking clap v4.5.32
        Checking aes-gcm-siv v0.11.1
        Checking flate2 v1.1.2
        Checking rusqlite v0.35.0
        Checking directories-next v2.0.0
        Checking uuid v1.16.0
       Compiling mutants v0.0.3
        Checking clingwrap v0.1.0
        Checking postcard v1.1.1
        Checking obnam v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.27s
    RUN: Action finished OK
    RUN: Action CargoBuild
    [2025-08-16T05:06:49Z 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 cfg-if v1.0.0
       Compiling libc v0.2.171
       Compiling typenum v1.18.0
       Compiling bitflags v2.9.0
       Compiling foldhash v0.1.5
       Compiling linux-raw-sys v0.9.3
       Compiling hashbrown v0.15.3
       Compiling generic-array v0.14.7
       Compiling rustix v1.0.3
       Compiling getrandom v0.2.16
       Compiling serde v1.0.219
       Compiling rand_core v0.6.4
       Compiling scopeguard v1.2.0
       Compiling utf8parse v0.2.2
       Compiling anstyle-parse v0.2.6
       Compiling crypto-common v0.1.6
       Compiling inout v0.1.4
       Compiling lock_api v0.4.12
       Compiling cipher v0.4.4
       Compiling anyhow v1.0.98
       Compiling getrandom v0.3.1
       Compiling is_terminal_polyfill v1.70.1
       Compiling colorchoice v1.0.3
       Compiling ryu v1.0.20
       Compiling memchr v2.7.4
       Compiling anstyle-query v1.1.2
       Compiling itoa v1.0.15
       Compiling equivalent v1.0.2
       Compiling subtle v2.6.1
       Compiling anstyle v1.0.10
       Compiling byteorder v1.5.0
       Compiling cpufeatures v0.2.17
       Compiling hash32 v0.2.1
       Compiling terminal_size v0.4.2
       Compiling anstream v0.6.18
       Compiling universal-hash v0.5.1
       Compiling indexmap v2.10.0
       Compiling libyml v0.0.5
       Compiling spin v0.9.8
       Compiling adler2 v2.0.0
       Compiling clap_lex v0.7.4
       Compiling once_cell v1.21.1
       Compiling fastrand v2.3.0
       Compiling stable_deref_trait v1.2.0
       Compiling opaque-debug v0.3.1
       Compiling strsim v0.11.1
       Compiling polyval v0.6.2
       Compiling serde_yml v0.0.12
       Compiling clap_builder v4.5.32
       Compiling tempfile v3.20.0
       Compiling heapless v0.7.17
       Compiling serde_json v1.0.140
       Compiling miniz_oxide v0.8.8
       Compiling thiserror v2.0.12
       Compiling libsqlite3-sys v0.33.0
       Compiling aes v0.8.4
       Compiling ctr v0.9.2
       Compiling aead v0.5.2
       Compiling hashlink v0.10.0
       Compiling dirs-sys-next v0.1.2
       Compiling crc32fast v1.4.2
       Compiling zeroize v1.8.1
       Compiling cobs v0.2.3
       Compiling fallible-streaming-iterator v0.1.9
       Compiling smallvec v1.15.0
       Compiling fallible-iterator v0.3.0
       Compiling postcard v1.1.1
       Compiling aes-gcm-siv v0.11.1
       Compiling flate2 v1.1.2
       Compiling rusqlite v0.35.0
       Compiling directories-next v2.0.0
       Compiling clap v4.5.32
       Compiling clingwrap v0.1.0
       Compiling uuid v1.16.0
       Compiling obnam v0.1.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.89s
    RUN: Action finished OK
    RUN: Action CargoTest
    [2025-08-16T05:06:59Z DEBUG ambient_ci::action] Plan::execute: CargoTest
    SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/obnam-8225839b9b294a56)
    
    running 33 tests
    test chunk::test::id ... ok
    test chunk::test::id_is_comparable_with_itself ... ok
    test chunk::test::ciphertext_does_not_contain_plaintext ... ok
    test chunk::test::label_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 chunk::test::chunk_round_trip ... 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_credential ... ok
    test credential::test::roundtrip_sop_method ... ok
    test store::test::finds_no_chunk_in_empty_store ... ok
    test store::test::has_no_chunks_initially ... ok
    test store::test::nonexistent_dir_is_not_init ... ok
    test store::test::finds_chunk_when_labels_match ... 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. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
    
         Running unittests src/bin/obnam.rs (/workspace/cache/debug/deps/obnam-285dd165ef0b551d)
    
    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 1 test
    test src/runner.rs - runner (line 8) ... ok
    
    test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
    }
    [2025-08-16T05:07:00Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "export PATH=/root/.cargo/bin:/bin:/sbin\nexport CARGO_TARGET_DIR=/workspace/cache\nmake subplot\n",
        }
    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)
           extra_env=[]
    + 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/tmpfrwoc66t
    scenario: Inspect an encrypted chunk using key from client chunk
      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 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 generate my.host my.key
      step: when I run obnam --client-key my.secret chunk encrypt --client-name my.host --key-name my.key --label sticky.tape greeting.txt --output chunk.file
      step: when I run obnam --client-key my.secret chunk inspect --filename chunk.file --client-name my.host --key-name my.key
      step: then stdout is valid JSON
      step: then stdout contains ""id":"
      step: then stdout contains ""label":"
      step: then stdout contains ""data": ""
    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: Create an OpenPGP software key credential
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file sop-generate
      step: when I run bash sop-generate alice.tsk
      step: when I create directory mychunks
      step: when I run obnam store init
      step: when I run obnam credential list
      step: then stdout is exactly ""
      step: when I run obnam --client-key my.secret credential openpgp-soft alice.tsk
      step: when I run obnam credential list
      step: then stdout isn't exactly ""
    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: Initialize a client chunk
      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: then command is successful
    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: Initialize a store
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: then directory mychunks does not exist
      step: when I try to run obnam store is
      step: then command fails
      step: when I create directory mychunks
      step: when I try to run obnam store is
      step: then command fails
      step: when I run obnam store init
      step: when I run obnam store is
      step: then exit code is 0
    scenario: Extracting a certificate from a key
      step: given an installed obnam
      step: given file alice.key
      step: when I run obnam sop extract-cert rsop alice.key
      step: then stdout isn't exactly ""
    scenario: Get path to chunk in store
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file chunk.blob
      step: given a directory mychunks
      step: when I run obnam store init
      step: when I run obnam store add xyzzy data-chunk chunk.blob
      step: when I run obnam store path xyzzy -o chunk.filename
      step: then a file exists whose name is in chunk.filename
    scenario: Encryption round trip
      step: given an installed obnam
      step: given file alice.key
      step: given file hello.txt
      step: when I run obnam sop encrypt rsop alice.key hello.txt -o encrypted
      step: when I run ls -l encrypted
      step: when I run cat encrypted
      step: when I run obnam sop decrypt rsop alice.key encrypted
      step: then stdout is exactly "hello, world\n"
    scenario: Find chunks using labels
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file chunk.blob
      step: given a directory mychunks
      step: when I run obnam store init
      step: when I run obnam store add xyzzy data-chunk chunk.blob
      step: when I run obnam store add plugh data-chunk chunk.blob
      step: when I run obnam store add advent client-chunk chunk.blob
      step: when I run obnam store find missing
      step: then stdout is exactly ""
      step: when I run obnam store find data-chunk
      step: then stdout contains "xyzzy"
      step: then stdout contains "plugh"
      step: then stdout doesn't contain "advent"
      step: when I run obnam store find client-chunk
      step: then stdout doesn't contain "xyzzy"
      step: then stdout doesn't contain "plugh"
      step: then stdout contains "advent"
    scenario: List clients
      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 list
      step: then stdout is exactly "my.host\n"
    scenario: Inspect an encoded chunk in 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 --label sticky.tape --key secret greeting.txt --id=chunk0
      step: when I run obnam chunk inspect --id chunk0
      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 --key secret --id chunk0
      step: then stdout is valid JSON
      step: then stdout contains ""id":"
      step: then stdout contains ""label":"
      step: then stdout contains ""data": ""
    scenario: Generate new key
      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 generate my.host my.key
      step: when I run obnam --client-key my.secret client show my.host
      step: then stdout contains "my.key"
    scenario: Round trip chunk encryption using key from client chunk
      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 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 generate my.host my.key
      step: when I run obnam --client-key my.secret chunk encrypt --client-name my.host --key-name my.key --label sticky.tape greeting.txt --output chunk.file
      step: when I run obnam --client-key my.secret chunk decrypt --client-name my.host --key-name my.key chunk.file
      step: then stdout is exactly "Hello, world.\n"
    scenario: Avoid client chunks with the same name
      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: then command is successful
      step: when I try to run obnam --client-key my.secret client init my.host
      step: then command fails
    scenario: Chunk encrypt/decrypt round trip via file
      step: given an installed obnam
      step: given file greeting.txt
      step: when I run obnam chunk encrypt --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: Add a chunk to the store.
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file chunk.blob
      step: given a directory mychunks
      step: when I run obnam store init
      step: when I run obnam store add xyzzy data-chunk chunk.blob
      step: when I run obnam store list
      step: then stdout is exactly "xyzzy\n"
    scenario: Chunk encrypt/decrypt 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 --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: Remove a chunk from store
      step: given an installed obnam
      step: given file .config/obnam/config.yaml from config.yaml
      step: given file chunk.blob
      step: given a directory mychunks
      step: when I run obnam store init
      step: when I run obnam store add xyzzy data-chunk chunk.blob
      step: when I run obnam store remove xyzzy
      step: when I run obnam store list
      step: then stdout doesn't contain "xyzzy"
    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"
    OK, all scenarios finished successfully
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:07:01Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-08-16T05:07:01Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-08-16T05:07:02Z 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:07:02Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        }
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-08-16T05:07:02Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    RUN: Action finished OK
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-08-16T05:07:09Z DEBUG ambient_ci::qemu] QEMU exit code 0
[2025-08-16T05:07:09Z DEBUG ambient_ci::run] remove old cache
[2025-08-16T05:07:09Z DEBUG ambient_ci::run] extract cache
[2025-08-16T05:07:09Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-08-16T05:07:09Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/obnam/meta.yaml
[2025-08-16T05:07:09Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>